2025-01-03 15:08:37.633167 97.13% [NOTICE] switch_channel.c:1142 New Channel sofia/default/8888@twisha.astppbilling.org:5067 [58ea0ef2-75c0-4334-806e-abd0b24832ba] 2025-01-03 15:08:37.633167 97.13% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8888@twisha.astppbilling.org:5067) Running State Change CS_NEW (Cur 1 Tot 61) 2025-01-03 15:08:37.633167 97.13% [INFO] sofia.c:10459 sofia/default/8888@twisha.astppbilling.org:5067 receiving invite from 103.238.108.187:3736 version: 1.10.10-release 64bit call-id: NmMzZDEyNDk4NDViMzExMzFlZTkxZDcyOWU3YzQ5M2U. 2025-01-03 15:08:37.633167 97.13% [DEBUG] sofia.c:10553 verifying acl "default" for ip/port 103.238.108.187:0. 2025-01-03 15:08:37.633167 97.13% [WARNING] sofia_reg.c:1842 SIP auth challenge (INVITE) on sofia profile 'default' for [26220818@twisha.astppbilling.org] from ip 103.238.108.187 2025-01-03 15:08:37.633167 97.13% [DEBUG] switch_core_state_machine.c:600 (sofia/default/8888@twisha.astppbilling.org:5067) State NEW 2025-01-03 15:08:37.633167 97.13% [DEBUG] sofia.c:2419 detaching session 58ea0ef2-75c0-4334-806e-abd0b24832ba 2025-01-03 15:08:37.813172 97.17% [DEBUG] sofia.c:2532 Re-attaching to session 58ea0ef2-75c0-4334-806e-abd0b24832ba 2025-01-03 15:08:37.813172 97.17% [INFO] sofia.c:10459 sofia/default/8888@twisha.astppbilling.org:5067 receiving invite from 103.238.108.187:3736 version: 1.10.10-release 64bit call-id: NmMzZDEyNDk4NDViMzExMzFlZTkxZDcyOWU3YzQ5M2U. 2025-01-03 15:08:37.813172 97.17% [DEBUG] sofia.c:10553 verifying acl "default" for ip/port 103.238.108.187:0. 2025-01-03 15:08:37.853135 97.17% [DEBUG] sofia.c:7493 Channel sofia/default/8888@twisha.astppbilling.org:5067 entering state [received][100] 2025-01-03 15:08:37.853135 97.17% [DEBUG] sofia.c:7503 Remote SDP: v=0 o=Z 0 0 IN IP4 103.238.108.187 s=Z c=IN IP4 103.238.108.187 t=0 0 m=audio 3550 RTP/AVP 3 110 8 0 98 101 a=rtpmap:110 speex/8000 a=rtpmap:98 iLBC/8000 a=fmtp:98 mode=20 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1] 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1] 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1] 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1] 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMA:8:8000:20:64000:1] 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMU:0:8000:20:64000:1] 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_core_media.c:3734 Set Codec sofia/default/8888@twisha.astppbilling.org:5067 PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_core_codec.c:111 sofia/default/8888@twisha.astppbilling.org:5067 Original read codec set to PCMA:8 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_core_media.c:5856 sofia/default/8888@twisha.astppbilling.org:5067 Set 2833 dtmf send payload to 101 recv payload to 101 2025-01-03 15:08:37.853135 97.17% [DEBUG] sofia.c:7927 (sofia/default/8888@twisha.astppbilling.org:5067) State Change CS_NEW -> CS_INIT 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8888@twisha.astppbilling.org:5067) Running State Change CS_INIT (Cur 1 Tot 61) 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_core_state_machine.c:624 (sofia/default/8888@twisha.astppbilling.org:5067) State INIT 2025-01-03 15:08:37.853135 97.17% [DEBUG] mod_sofia.c:97 sofia/default/8888@twisha.astppbilling.org:5067 SOFIA INIT 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_core_state_machine.c:40 sofia/default/8888@twisha.astppbilling.org:5067 Standard INIT 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_core_state_machine.c:48 (sofia/default/8888@twisha.astppbilling.org:5067) State Change CS_INIT -> CS_ROUTING 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_core_state_machine.c:624 (sofia/default/8888@twisha.astppbilling.org:5067) State INIT going to sleep 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8888@twisha.astppbilling.org:5067) Running State Change CS_ROUTING (Cur 1 Tot 61) 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_channel.c:2399 (sofia/default/8888@twisha.astppbilling.org:5067) Callstate Change DOWN -> RINGING 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_core_state_machine.c:640 (sofia/default/8888@twisha.astppbilling.org:5067) State ROUTING 2025-01-03 15:08:37.853135 97.17% [DEBUG] mod_sofia.c:158 sofia/default/8888@twisha.astppbilling.org:5067 SOFIA ROUTING 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_core_state_machine.c:230 sofia/default/8888@twisha.astppbilling.org:5067 Standard ROUTING 2025-01-03 15:08:37.853135 97.17% [INFO] mod_dialplan_xml.c:639 Processing 8888 <8888>->26220818 in context default 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes') 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2025-01-03 15:08:37.853135 97.17% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [xml_handler] Params: Event-Name: REQUEST_PARAMS Core-UUID: edf8bffe-4afe-4182-860d-7fe6c801b9f7 FreeSWITCH-Hostname: ASHISH-DEVELOPMENT FreeSWITCH-Switchname: ASHISH-DEVELOPMENT FreeSWITCH-IPv4: 65.21.153.211 FreeSWITCH-IPv6: 2a01%3A4f9%3Ac010%3Ad02c%3A%3A1 Event-Date-Local: 2025-01-03%2015%3A08%3A37 Event-Date-GMT: Fri,%2003%20Jan%202025%2015%3A08%3A37%20GMT Event-Date-Timestamp: 1735916917853135 Event-Calling-File: mod_dialplan_xml.c Event-Calling-Function: dialplan_xml_locate Event-Calling-Line-Number: 610 Event-Sequence: 67653 Channel-State: CS_ROUTING Channel-Call-State: RINGING Channel-State-Number: 2 Channel-Name: sofia/default/8888%40twisha.astppbilling.org%3A5067 Unique-ID: 58ea0ef2-75c0-4334-806e-abd0b24832ba Call-Direction: inbound Presence-Call-Direction: inbound Channel-HIT-Dialplan: true Channel-Presence-ID: 8888%40twisha.astppbilling.org Channel-Call-UUID: 58ea0ef2-75c0-4334-806e-abd0b24832ba Answer-State: ringing Channel-Read-Codec-Name: PCMA Channel-Read-Codec-Rate: 8000 Channel-Read-Codec-Bit-Rate: 64000 Channel-Write-Codec-Name: PCMA Channel-Write-Codec-Rate: 8000 Channel-Write-Codec-Bit-Rate: 64000 Caller-Direction: inbound Caller-Logical-Direction: inbound Caller-Username: 8888 Caller-Dialplan: XML Caller-Caller-ID-Name: 8888 Caller-Caller-ID-Number: 8888 Caller-Orig-Caller-ID-Name: 8888 Caller-Orig-Caller-ID-Number: 8888 Caller-Network-Addr: 103.238.108.187 Caller-ANI: 8888 Caller-Destination-Number: 26220818 Caller-Unique-ID: 58ea0ef2-75c0-4334-806e-abd0b24832ba Caller-Source: mod_sofia Caller-Context: default Caller-Channel-Name: sofia/default/8888%40twisha.astppbilling.org%3A5067 Caller-Profile-Index: 1 Caller-Profile-Created-Time: 1735916917813172 Caller-Channel-Created-Time: 1735916917813172 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: 58ea0ef2-75c0-4334-806e-abd0b24832ba variable_session_id: 61 variable_sip_from_params: transport%3DUDP variable_sip_from_user: 8888 variable_sip_from_port: 5067 variable_sip_from_uri: 8888%40twisha.astppbilling.org%3A5067 variable_sip_from_host: twisha.astppbilling.org variable_video_media_flow: disabled variable_text_media_flow: disabled variable_channel_name: sofia/default/8888%40twisha.astppbilling.org%3A5067 variable_sip_call_id: NmMzZDEyNDk4NDViMzExMzFlZTkxZDcyOWU3YzQ5M2U. variable_sip_local_network_addr: 65.21.153.211 variable_sip_network_ip: 103.238.108.187 variable_sip_network_port: 3736 variable_sip_invite_stamp: 1735916917813172 variable_sip_received_ip: 103.238.108.187 variable_sip_received_port: 3736 variable_sip_via_protocol: udp variable_sip_authorized: true variable_Event-Name: REQUEST_PARAMS variable_Core-UUID: edf8bffe-4afe-4182-860d-7fe6c801b9f7 variable_FreeSWITCH-Hostname: ASHISH-DEVELOPMENT variable_FreeSWITCH-Switchname: ASHISH-DEVELOPMENT variable_FreeSWITCH-IPv4: 65.21.153.211 variable_FreeSWITCH-IPv6: 2a01%3A4f9%3Ac010%3Ad02c%3A%3A1 variable_Event-Date-Local: 2025-01-03%2015%3A08%3A37 variable_Event-Date-GMT: Fri,%2003%20Jan%202025%2015%3A08%3A37%20GMT variable_Event-Date-Timestamp: 1735916917813172 variable_Event-Calling-File: sofia.c variable_Event-Calling-Function: sofia_handle_sip_i_invite variable_Event-Calling-Line-Number: 10722 variable_Event-Sequence: 67644 variable_sip_number_alias: 8888 variable_sip_auth_username: 8888 variable_sip_auth_realm: twisha.astppbilling.org variable_number_alias: 8888 variable_requested_user_name: 8888 variable_requested_domain_name: twisha.astppbilling.org variable_user_context: default variable_sipcall: true variable_sip_user: 8888 variable_accountcode: 8719627029 variable_user_name: 8888 variable_domain_name: twisha.astppbilling.org variable_sip_from_user_stripped: 8888 variable_sip_from_tag: d5314c29 variable_sofia_profile_name: default variable_sofia_profile_url: sip%3Amod_sofia%4065.21.153.211%3A5067 variable_recovery_profile_name: default variable_sip_full_via: SIP/2.0/UDP%20103.238.108.187%3A3736%3Bbranch%3Dz9hG4bK-d8754z-1de58faa0969f25f-1---d8754z- variable_sip_full_from: %3Csip%3A8888%40twisha.astppbilling.org%3A5067%3Btransport%3DUDP%3E%3Btag%3Dd5314c29 variable_sip_full_to: %3Csip%3A26220818%40twisha.astppbilling.org%3A5067%3Btransport%3DUDP%3E variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20BYE,%20NOTIFY,%20REFER,%20MESSAGE,%20OPTIONS,%20INFO,%20SUBSCRIBE variable_sip_req_params: transport%3DUDP variable_sip_req_user: 26220818 variable_sip_req_port: 5067 variable_sip_req_uri: 26220818%40twisha.astppbilling.org%3A5067 variable_sip_req_host: twisha.astppbilling.org variable_sip_to_params: transport%3DUDP variable_sip_to_user: 26220818 variable_sip_to_port: 5067 variable_sip_to_uri: 26220818%40twisha.astppbilling.org%3A5067 variable_sip_to_host: twisha.astppbilling.org variable_sip_contact_params: transport%3DUDP variable_sip_contact_user: 8888 variable_sip_contact_port: 3736 variable_sip_contact_uri: 8888%40103.238.108.187%3A3736 variable_sip_contact_host: 103.238.108.187 variable_sip_user_agent: Z%203.3.25608%20r25552 variable_sip_via_host: 103.238.108.187 variable_sip_via_port: 3736 variable_max_forwards: 70 variable_presence_id: 8888%40twisha.astppbilling.org variable_switch_r_sdp: v%3D0%0D%0Ao%3DZ%200%200%20IN%20IP4%20103.238.108.187%0D%0As%3DZ%0D%0Ac%3DIN%20IP4%20103.238.108.187%0D%0At%3D0%200%0D%0Am%3Daudio%203550%20RTP/AVP%203%20110%208%200%2098%20101%0D%0Aa%3Drtpmap%3A110%20speex/8000%0D%0Aa%3Drtpmap%3A98%20iLBC/8000%0D%0Aa%3Dfmtp%3A98%20mode%3D20%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-15%0D%0A variable_ep_codec_string: CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b variable_rtp_use_codec_string: PCMA,PCMU variable_remote_video_media_flow: inactive variable_remote_text_media_flow: inactive variable_remote_audio_media_flow: sendrecv variable_audio_media_flow: sendrecv variable_remote_media_ip: 103.238.108.187 variable_remote_media_port: 3550 variable_rtp_audio_recv_pt: 8 variable_rtp_use_codec_name: PCMA variable_rtp_use_codec_rate: 8000 variable_rtp_use_codec_ptime: 20 variable_rtp_use_codec_channels: 1 variable_rtp_last_audio_codec_string: PCMA%408000h%4020i%401c variable_read_codec: PCMA variable_original_read_codec: PCMA variable_read_rate: 8000 variable_original_read_rate: 8000 variable_write_codec: PCMA variable_write_rate: 8000 variable_dtmf_type: rfc2833 variable_endpoint_disposition: RECEIVED variable_call_uuid: 58ea0ef2-75c0-4334-806e-abd0b24832ba Hunt-Direction: inbound Hunt-Logical-Direction: inbound Hunt-Username: 8888 Hunt-Dialplan: XML Hunt-Caller-ID-Name: 8888 Hunt-Caller-ID-Number: 8888 Hunt-Orig-Caller-ID-Name: 8888 Hunt-Orig-Caller-ID-Number: 8888 Hunt-Network-Addr: 103.238.108.187 Hunt-ANI: 8888 Hunt-Destination-Number: 26220818 Hunt-Unique-ID: 58ea0ef2-75c0-4334-806e-abd0b24832ba Hunt-Source: mod_sofia Hunt-Context: default Hunt-Channel-Name: sofia/default/8888%40twisha.astppbilling.org%3A5067 Hunt-Profile-Index: 1 Hunt-Profile-Created-Time: 1735916917813172 Hunt-Channel-Created-Time: 1735916917813172 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 2025-01-03 15:08:37.853135 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] [xml_REQUEST] section: dialplan 2025-01-03 15:08:37.853135 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] [xml_REQUEST] key_value: 2025-01-03 15:08:37.853135 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] [xml_REQUEST] key_name: 2025-01-03 15:08:37.853135 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] [xml_REQUEST] tag_name: 2025-01-03 15:08:37.853135 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0 2025-01-03 15:08:37.853135 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 26220818 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] test123 :26220818 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '26220818' limit 1 2025-01-03 15:08:37.853135 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '26220818' AND status=0 limit 1 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '26220818' and status=0 LIMIT 1 2025-01-03 15:08:37.873109 97.17% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] Custom Caller ID 2025-01-03 15:08:37.873109 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number : 8888 / 8888 2025-01-03 15:08:37.873109 97.17% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 1 AND status=0 limit 1 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"1/00301,2/00302,6/00306,8/00308,+/00".....26220818 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"1/00301,2/00302,6/00306,8/00308,+/00".....26220818 2025-01-03 15:08:37.873109 97.17% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 26220818 2025-01-03 15:08:37.873109 97.17% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 003026220818 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"1/00301,2/00302,6/00306,8/00308,+/00".....003026220818 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"1/00301,2/00302,6/00306,8/00308,+/00".....003026220818 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"1/00301,2/00302,6/00306,8/00308,+/00".....003026220818 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,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,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="003026220818" LIMIT 1 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,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 ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="26220818" AND domains.domain="twisha.astppbilling.org" limit 1 2025-01-03 15:08:37.873109 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound 2025-01-03 15:08:37.873109 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 8719627029] 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET get_acc_info] Query :SELECT count(id) as id FROM local_number WHERE number =26220818 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "8719627029" AND deleted = 0 limit 1 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^26220818.*' OR blocked_patterns = '^2622081.*' OR blocked_patterns = '^262208.*' OR blocked_patterns = '^26220.*' OR blocked_patterns = '^2622.*' OR blocked_patterns = '^262.*' OR blocked_patterns = '^26.*' OR blocked_patterns = '^2.*' OR blocked_patterns ='--') AND accountid = 77 limit 1 2025-01-03 15:08:37.873109 97.17% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id : 1 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 1 AND status=0 limit 1 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"1/00301,2/00302,6/00306,8/00308,+/00".....26220818 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"1/00301,2/00302,6/00306,8/00308,+/00".....26220818 2025-01-03 15:08:37.873109 97.17% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 26220818 2025-01-03 15:08:37.873109 97.17% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 003026220818 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"1/00301,2/00302,6/00306,8/00308,+/00".....003026220818 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"1/00301,2/00302,6/00306,8/00308,+/00".....003026220818 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"1/00301,2/00302,6/00306,8/00308,+/00".....003026220818 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="003026220818" AND (accountid = 0 OR status = 1) LIMIT 1 2025-01-03 15:08:37.873109 97.17% [NOTICE] switch_cpp.cpp:1465 [ASTPP] Localization Id: 3 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 3 AND status=0 limit 1 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"2/003572,9/003579".....26220818 2025-01-03 15:08:37.873109 97.17% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 26220818 2025-01-03 15:08:37.873109 97.17% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 0035726220818 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"2/003572,9/003579".....0035726220818 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE P.is_terminated = 0 AND (patterns = '^0035726220818.*' OR patterns = '^003572622081.*' OR patterns = '^00357262208.*' OR patterns = '^0035726220.*' OR patterns = '^003572622.*' OR patterns = '^00357262.*' OR patterns = '^0035726.*' OR patterns = '^003572.*' OR patterns = '^00357.*' OR patterns = '^0035.*' OR patterns = '^003.*' OR patterns = '^00.*' OR patterns = '^0.*' OR patterns ='--') AND accountid = 77 ORDER BY LENGTH(PKGPTR.patterns) DESC 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [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 ="0035726220818" AND A.accountid = '77' limit 1 2025-01-03 15:08:37.873109 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 0035726220818 2025-01-03 15:08:37.873109 97.17% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 1 AND status=0 limit 1 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"1/00301,2/00302,6/00306,8/00308,+/00".....0035726220818 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"1/00301,2/00302,6/00306,8/00308,+/00".....0035726220818 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"1/00301,2/00302,6/00306,8/00308,+/00".....0035726220818 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"1/00301,2/00302,6/00306,8/00308,+/00".....0035726220818 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"1/00301,2/00302,6/00306,8/00308,+/00".....0035726220818 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,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,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="0035726220818" LIMIT 1 2025-01-03 15:08:37.873109 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : inbound 2025-01-03 15:08:37.873109 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '0035726220818' OR number = '003572622081' OR number = '00357262208' OR number = '0035726220' OR number = '003572622' OR number = '00357262' OR number = '0035726' OR number = '003572' OR number = '00357' OR number = '0035' OR number = '003' OR number = '00' OR number = '0' OR number ='--') OR (number = '0035726220818*' OR number = '003572622081*' OR number = '00357262208*' OR number = '0035726220*' OR number = '003572622*' OR number = '00357262*' OR number = '0035726*' OR number = '003572*' OR number = '00357*' OR number = '0035*' OR number = '003*' OR number = '00*' OR number = '0*' OR number ='--') OR (number = '8888' OR number = '888' OR number = '88' OR number = '8' OR number ='--') OR (number = '8888*' OR number = '888*' OR number = '88*' OR number = '8*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC 2025-01-03 15:08:37.893129 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1 2025-01-03 15:08:37.893129 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '0035726220818' OR routing_prefix = '003572622081' OR routing_prefix = '00357262208' OR routing_prefix = '0035726220' OR routing_prefix = '003572622' OR routing_prefix = '00357262' OR routing_prefix = '0035726' OR routing_prefix = '003572' OR routing_prefix = '00357' OR routing_prefix = '0035' OR routing_prefix = '003' OR routing_prefix = '00' OR routing_prefix = '0' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information =================== 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 77 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 8719627029 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 14.9813 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid] 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 4 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] ======================================================== 2025-01-03 15:08:37.893129 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 4 AND status = 0 2025-01-03 15:08:37.893129 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :inbound 2025-01-03 15:08:37.893129 97.17% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1 2025-01-03 15:08:37.893129 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 1 AND status=0 limit 1 2025-01-03 15:08:37.893129 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"1/00301,2/00302,6/00306,8/00308,+/00".....0035726220818 2025-01-03 15:08:37.893129 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"1/00301,2/00302,6/00306,8/00308,+/00".....0035726220818 2025-01-03 15:08:37.893129 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"1/00301,2/00302,6/00306,8/00308,+/00".....0035726220818 2025-01-03 15:08:37.893129 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"1/00301,2/00302,6/00306,8/00308,+/00".....0035726220818 2025-01-03 15:08:37.893129 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"1/00301,2/00302,6/00306,8/00308,+/00".....0035726220818 2025-01-03 15:08:37.893129 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,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,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="0035726220818" LIMIT 1 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Direction : inbound 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] Markup : 20 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information =================== 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 75 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 1 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : Paphos Peyia 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 49 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 77 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================ 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your14.9813 balance Accountid 77 !!! 2025-01-03 15:08:37.893129 97.17% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Call Is Free - Assigning Max Length :: 200 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 200 minutes 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] INB_FREE:TRUE 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] free_inbound:1 2025-01-03 15:08:37.893129 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 77 AND status=0 LIMIT 1 2025-01-03 15:08:37.893129 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "77" AND deleted = 0 limit 1 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML:77 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Userinfo XML:77 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML : 77 2025-01-03 15:08:37.893129 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_domain_info] Query :SELECT * FROM domains WHERE accountid = 77 limit 1 2025-01-03 15:08:37.893129 97.17% [WARNING] switch_cpp.cpp:1465 [ASTPP] [user_domain] User Domain : twisha.astppbilling.org 2025-01-03 15:08:37.893129 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] ssssssssssssssssssssssssssssssssssssssssss 2025-01-03 15:08:37.893129 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Timecondition Id : 3 2025-01-03 15:08:37.893129 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET_TIME_CONDITION_QUERY] Query :SELECT * from time_condition WHERE id = '3' and status =0 LIMIT 1 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Failover Timecondition : [{"extensions":"123464343232#","no_answer_call_type":"12"}] 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Timecondition : [{"hour":"00-23","minute":"01-59","wday":"2-6","mday":"1-31","mon":"1-12","year":"2025-2030","extensions":"18","no_answer_call_type":"10","priority":"1"}] 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Priority : 1 2025-01-03 15:08:37.893129 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET_TIME_CONDITION_QUERY] Query :SELECT * from timezone WHERE id = '176' LIMIT 1 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Condition Str : time-of-day= "00:01-23:59" wday= "2-6" mday= "1-31" mon= "1-12" year= "2025-2030" 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] Maxlength : 200 2025-01-03 15:08:37.893129 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 1 2025-01-03 15:08:37.893129 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] Call Direction : inbound 2025-01-03 15:08:37.893129 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET get_record_info] Query :SELECT sip_device_routing.is_recording FROM sip_devices AS sip_devices, accounts AS accounts ,sip_device_routing AS sip_device_routing WHERE sip_devices.accountid = accounts.id AND sip_devices.id = sip_device_routing.sip_device_id AND sip_devices.username = "3" AND accounts.status = 0 2025-01-03 15:08:37.893129 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_domain_info] Query :SELECT * FROM domains WHERE accountid = 77 limit 1 2025-01-03 15:08:37.893129 97.17% [WARNING] switch_cpp.cpp:1465 [ASTPP] [XML] User Domain : twisha.astppbilling.org 2025-01-03 15:08:37.893129 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_accounts_info] Query :SELECT * FROM accounts WHERE id = 77 and status = 0 limit 1 2025-01-03 15:08:37.913138 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [XML] Custom Function Name :: custom_inbound_11 2025-01-03 15:08:37.913138 97.17% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] TimeCondition Id : 3 2025-01-03 15:08:37.913138 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET_TIME_CONDITION_QUERY] Query :SELECT * from time_condition WHERE id = '3' and status =0 LIMIT 1 2025-01-03 15:08:37.913138 97.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Generated XML:
Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 parsing [default->Saint Cyprus] continue=true Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Date/Time Match (PASS) [Saint Cyprus] break=never Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(office_status=open) INLINE EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(office_status=open) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [office_status]=[open] Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(extensions=123464343232#) INLINE EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(extensions=123464343232#) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [extensions]=[123464343232#] Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(no_answer_did_number=0035726220818) INLINE EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(no_answer_did_number=0035726220818) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [no_answer_did_number]=[0035726220818] Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(no_answer_call_type=12) INLINE EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(no_answer_call_type=12) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [no_answer_call_type]=[12] Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 parsing [default->Saint Cyprus] continue=true Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Absolute Condition [Saint Cyprus] Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(timezone=Europe/Athens) INLINE EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(timezone=Europe/Athens) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [timezone]=[Europe/Athens] Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Date/Time Match (PASS) [Saint Cyprus] break=never Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(office_status=open) INLINE EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(office_status=open) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [office_status]=[open] Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(extensions=18) INLINE EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(extensions=18) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [extensions]=[18] Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(no_answer_did_number=0035726220818) INLINE EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(no_answer_did_number=0035726220818) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [no_answer_did_number]=[0035726220818] Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(no_answer_call_type=10) INLINE EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(no_answer_call_type=10) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [no_answer_call_type]=[10] Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 parsing [default->tod route, x0035726220818] continue=false Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Regex (FAIL) [tod route, x0035726220818] destination_number(26220818) =~ /^(\+?)?(0035726220818)$/ break=on-false Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 parsing [default->office is open] continue=false Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Regex (FAIL) [office is open] destination_number(26220818) =~ /^(\+?)?(0035726220818_open)$/ break=on-false Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 parsing [default->0035726220818] continue=false Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Regex (PASS) [0035726220818] destination_number(26220818) =~ /26220818/ break=on-false Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(effective_destination_number=0035726220818) Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(bridge_pre_execute_bleg_app=sched_hangup) Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(bridge_pre_execute_bleg_data=+12000 normal_clearing) Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(callstart=2025-01-03 15:08:37) Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(hangup_after_bridge=true) Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(continue_on_fail=TRUE) Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(account_id=77) Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(parent_id=0) Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(entity_id=0) Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(call_processed=internal) Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(call_direction=inbound) Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(accountname=default) Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(filename1=astpp.pbx.lua) Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(origination_rates_did=ID:75|CODE:^0035726220818.*|DESTINATION:Paphos Peyia|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:1|CT:11|COST:0|INC:1|INITIALBLOCK:1|RATEGROUP:0|MARKUP:20|CI:49|ACCID:77) Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(origination_rates=0) Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(original_caller_id_name=8888) Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(original_caller_id_number=8888) Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action limit(db 77 user_77 20 ) Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(effective_caller_id_name=8888) Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(effective_caller_id_number=8888) Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action set(receiver_accid=77) Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action export(domain_name=twisha.astppbilling.org) Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action export(sip_to_host=twisha.astppbilling.org) Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action limit(db 0035726220818 did_0035726220818 20 ) Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action export(presence_data=x|||Anastasia(8719627029)|||||||||DID|||103.238.108.187) Dialplan: sofia/default/8888@twisha.astppbilling.org:5067 Action export(call_type=11) 2025-01-03 15:08:37.913138 97.17% [DEBUG] switch_core_state_machine.c:281 (sofia/default/8888@twisha.astppbilling.org:5067) State Change CS_ROUTING -> CS_EXECUTE 2025-01-03 15:08:37.913138 97.17% [DEBUG] switch_core_state_machine.c:640 (sofia/default/8888@twisha.astppbilling.org:5067) State ROUTING going to sleep 2025-01-03 15:08:37.913138 97.17% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8888@twisha.astppbilling.org:5067) Running State Change CS_EXECUTE (Cur 1 Tot 61) 2025-01-03 15:08:37.913138 97.17% [DEBUG] switch_core_state_machine.c:647 (sofia/default/8888@twisha.astppbilling.org:5067) State EXECUTE 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_sofia.c:213 sofia/default/8888@twisha.astppbilling.org:5067 SOFIA EXECUTE 2025-01-03 15:08:37.913138 97.17% [DEBUG] switch_core_state_machine.c:323 sofia/default/8888@twisha.astppbilling.org:5067 Standard EXECUTE EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(effective_destination_number=0035726220818) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [effective_destination_number]=[0035726220818] EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(bridge_pre_execute_bleg_app=sched_hangup) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [bridge_pre_execute_bleg_app]=[sched_hangup] EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(bridge_pre_execute_bleg_data=+12000 normal_clearing) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [bridge_pre_execute_bleg_data]=[+12000 normal_clearing] EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(callstart=2025-01-03 15:08:37) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [callstart]=[2025-01-03 15:08:37] EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(hangup_after_bridge=true) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [hangup_after_bridge]=[true] EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(continue_on_fail=TRUE) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [continue_on_fail]=[TRUE] EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(account_id=77) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [account_id]=[77] EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(parent_id=0) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [parent_id]=[0] EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(entity_id=0) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [entity_id]=[0] EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(call_processed=internal) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [call_processed]=[internal] EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(call_direction=inbound) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [call_direction]=[inbound] EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(accountname=default) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [accountname]=[default] EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(filename1=astpp.pbx.lua) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [filename1]=[astpp.pbx.lua] EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(origination_rates_did=ID:75|CODE:^0035726220818.*|DESTINATION:Paphos Peyia|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:1|CT:11|COST:0|INC:1|INITIALBLOCK:1|RATEGROUP:0|MARKUP:20|CI:49|ACCID:77) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [origination_rates_did]=[ID:75|CODE:^0035726220818.*|DESTINATION:Paphos Peyia|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:1|CT:11|COST:0|INC:1|INITIALBLOCK:1|RATEGROUP:0|MARKUP:20|CI:49|ACCID:77] EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(origination_rates=0) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [origination_rates]=[0] EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(original_caller_id_name=8888) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [original_caller_id_name]=[8888] EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(original_caller_id_number=8888) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [original_caller_id_number]=[8888] EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 limit(db 77 user_77 20 ) 2025-01-03 15:08:37.913138 97.17% [DEBUG] switch_limit.c:124 incr called: 77_user_77 max:20, interval:0 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_db.c:194 Usage for 77_user_77 is now 1/20 EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(effective_caller_id_name=8888) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [effective_caller_id_name]=[8888] EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(effective_caller_id_number=8888) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [effective_caller_id_number]=[8888] EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 set(receiver_accid=77) 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_dptools.c:1671 SET sofia/default/8888@twisha.astppbilling.org:5067 [receiver_accid]=[77] EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 export(domain_name=twisha.astppbilling.org) 2025-01-03 15:08:37.913138 97.17% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [domain_name]=[twisha.astppbilling.org] EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 export(sip_to_host=twisha.astppbilling.org) 2025-01-03 15:08:37.913138 97.17% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [sip_to_host]=[twisha.astppbilling.org] EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 limit(db 0035726220818 did_0035726220818 20 ) 2025-01-03 15:08:37.913138 97.17% [DEBUG] switch_limit.c:124 incr called: 0035726220818_did_0035726220818 max:20, interval:0 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_db.c:194 Usage for 0035726220818_did_0035726220818 is now 1/20 EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 export(presence_data=x|||Anastasia(8719627029)|||||||||DID|||103.238.108.187) 2025-01-03 15:08:37.913138 97.17% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [presence_data]=[x|||Anastasia(8719627029)|||||||||DID|||103.238.108.187] EXECUTE [depth=0] sofia/default/8888@twisha.astppbilling.org:5067 export(call_type=11) 2025-01-03 15:08:37.913138 97.17% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [call_type]=[11] 2025-01-03 15:08:37.913138 97.17% [NOTICE] switch_core_state_machine.c:382 sofia/default/8888@twisha.astppbilling.org:5067 has executed the last dialplan instruction, hanging up. 2025-01-03 15:08:37.913138 97.17% [NOTICE] switch_core_state_machine.c:384 Hangup sofia/default/8888@twisha.astppbilling.org:5067 [CS_EXECUTE] [NORMAL_CLEARING] 2025-01-03 15:08:37.913138 97.17% [DEBUG] switch_core_state_machine.c:647 (sofia/default/8888@twisha.astppbilling.org:5067) State EXECUTE going to sleep 2025-01-03 15:08:37.913138 97.17% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8888@twisha.astppbilling.org:5067) Running State Change CS_HANGUP (Cur 1 Tot 61) 2025-01-03 15:08:37.913138 97.17% [DEBUG] switch_core_state_machine.c:844 (sofia/default/8888@twisha.astppbilling.org:5067) Callstate Change RINGING -> HANGUP 2025-01-03 15:08:37.913138 97.17% [DEBUG] switch_core_state_machine.c:846 (sofia/default/8888@twisha.astppbilling.org:5067) State HANGUP 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_sofia.c:469 Channel sofia/default/8888@twisha.astppbilling.org:5067 hanging up, cause: NORMAL_CLEARING 2025-01-03 15:08:37.913138 97.17% [DEBUG] mod_sofia.c:614 Responding to INVITE with: 480 2025-01-03 15:08:37.913138 97.17% [DEBUG] switch_core_state_machine.c:59 sofia/default/8888@twisha.astppbilling.org:5067 Standard HANGUP, cause: NORMAL_CLEARING 2025-01-03 15:08:37.913138 97.17% [DEBUG] switch_core_state_machine.c:846 (sofia/default/8888@twisha.astppbilling.org:5067) State HANGUP going to sleep 2025-01-03 15:08:37.913138 97.17% [DEBUG] switch_core_state_machine.c:616 (sofia/default/8888@twisha.astppbilling.org:5067) State Change CS_HANGUP -> CS_REPORTING 2025-01-03 15:08:37.913138 97.17% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8888@twisha.astppbilling.org:5067) Running State Change CS_REPORTING (Cur 1 Tot 61) 2025-01-03 15:08:37.913138 97.17% [DEBUG] switch_core_state_machine.c:932 (sofia/default/8888@twisha.astppbilling.org:5067) State REPORTING 2025-01-03 15:08:37.913138 97.17% [INFO] mod_json_cdr.c:272 Process [58ea0ef2-75c0-4334-806e-abd0b24832ba.cdr.json] 2025-01-03 15:08:37.953117 97.17% [DEBUG] switch_core_state_machine.c:168 sofia/default/8888@twisha.astppbilling.org:5067 Standard REPORTING, cause: NORMAL_CLEARING 2025-01-03 15:08:37.953117 97.17% [DEBUG] switch_core_state_machine.c:932 (sofia/default/8888@twisha.astppbilling.org:5067) State REPORTING going to sleep 2025-01-03 15:08:37.953117 97.17% [DEBUG] switch_core_state_machine.c:607 (sofia/default/8888@twisha.astppbilling.org:5067) State Change CS_REPORTING -> CS_DESTROY 2025-01-03 15:08:37.953117 97.17% [DEBUG] switch_core_session.c:1744 Session 61 (sofia/default/8888@twisha.astppbilling.org:5067) Locked, Waiting on external entities 2025-01-03 15:08:37.953117 97.17% [NOTICE] switch_core_session.c:1762 Session 61 (sofia/default/8888@twisha.astppbilling.org:5067) Ended 2025-01-03 15:08:37.953117 97.17% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/8888@twisha.astppbilling.org:5067 [CS_DESTROY] 2025-01-03 15:08:37.953117 97.17% [DEBUG] switch_core_state_machine.c:735 (sofia/default/8888@twisha.astppbilling.org:5067) Running State Change CS_DESTROY (Cur 0 Tot 61) 2025-01-03 15:08:37.953117 97.17% [DEBUG] switch_core_state_machine.c:745 (sofia/default/8888@twisha.astppbilling.org:5067) State DESTROY 2025-01-03 15:08:37.953117 97.17% [DEBUG] mod_sofia.c:380 sofia/default/8888@twisha.astppbilling.org:5067 SOFIA DESTROY 2025-01-03 15:08:37.953117 97.17% [DEBUG] switch_core_state_machine.c:175 sofia/default/8888@twisha.astppbilling.org:5067 Standard DESTROY 2025-01-03 15:08:37.953117 97.17% [DEBUG] switch_core_state_machine.c:745 (sofia/default/8888@twisha.astppbilling.org:5067) State DESTROY going to sleep