2022-12-16 11:39:33.207380 [INFO] mod_dialplan_xml.c:637 Processing 1000 <1000>->319000980 in context default
2022-12-16 11:39:33.207380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
2022-12-16 11:39:33.207380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
2022-12-16 11:39:33.207380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
2022-12-16 11:39:33.207380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
2022-12-16 11:39:33.227380 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [xml_handler] Params:
Event-Name: REQUEST_PARAMS
Core-UUID: c687ffac-2ae7-42f3-9408-8ea92987c97f
FreeSWITCH-Hostname: multitenant-1
FreeSWITCH-Switchname: multitenant-1
FreeSWITCH-IPv4: 185.249.40.236
FreeSWITCH-IPv6: %3A%3A1
Event-Date-Local: 2022-12-16%2011%3A39%3A33
Event-Date-GMT: Fri,%2016%20Dec%202022%2010%3A39%3A33%20GMT
Event-Date-Timestamp: 1671187173207380
Event-Calling-File: mod_dialplan_xml.c
Event-Calling-Function: dialplan_xml_locate
Event-Calling-Line-Number: 608
Event-Sequence: 639842
Channel-State: CS_ROUTING
Channel-Call-State: RINGING
Channel-State-Number: 2
Channel-Name: sofia/default/183284%40213.144.239.110%3A9933
Unique-ID: 501160ca-0747-4211-b6aa-8caed8d6b7cc
Call-Direction: inbound
Presence-Call-Direction: inbound
Channel-HIT-Dialplan: true
Channel-Presence-ID: 183284%40213.144.239.110
Channel-Call-UUID: 501160ca-0747-4211-b6aa-8caed8d6b7cc
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: 31232051002
Caller-Dialplan: XML
Caller-Caller-ID-Name: Anonymous
Caller-Caller-ID-Number: 31232051002
Caller-Orig-Caller-ID-Name: Anonymous
Caller-Orig-Caller-ID-Number: 31232051002
Caller-Network-Addr: 213.144.239.110
Caller-ANI: 31232051002
Caller-Destination-Number: 352621453076
Caller-Unique-ID: 501160ca-0747-4211-b6aa-8caed8d6b7cc
Caller-Source: mod_sofia
Caller-Context: default
Caller-Channel-Name: sofia/default/183284%40213.144.239.110%3A9933
Caller-Profile-Index: 1
Caller-Profile-Created-Time: 1671187173207380
Caller-Channel-Created-Time: 1671187173207380
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: false
Caller-Privacy-Hide-Name: true
Caller-Privacy-Hide-Number: true
variable_direction: inbound
variable_uuid: 501160ca-0747-4211-b6aa-8caed8d6b7cc
variable_session_id: 7226
variable_sip_from_user: 183284
variable_sip_from_port: 9933
variable_sip_from_uri: 183284%40213.144.239.110%3A9933
variable_sip_from_host: 213.144.239.110
variable_video_media_flow: disabled
variable_text_media_flow: disabled
variable_channel_name: sofia/default/183284%40213.144.239.110%3A9933
variable_sip_call_id: 6428d50963019eb768f7ae97398eaf86%40213.144.239.110%3A9933
variable_sip_local_network_addr: 185.249.40.236
variable_sip_network_ip: 213.144.239.110
variable_sip_network_port: 9933
variable_sip_invite_stamp: 1671187173207380
variable_sip_received_ip: 213.144.239.110
variable_sip_received_port: 9933
variable_sip_via_protocol: udp
variable_sip_authorized: true
variable_sip_acl_authed_by: default
variable_sip_from_user_stripped: 183284
variable_sip_from_tag: as484c2d4d
variable_sofia_profile_name: default
variable_sofia_profile_url: sip%3Amod_sofia%40185.249.40.236%3A5060
variable_recovery_profile_name: default
variable_sip_Remote-Party-ID: %22Anonymous%22%20%3Csip%3A31232051002%40213.144.239.110%3E%3Bparty%3Dcalling%3Bprivacy%3Doff%3Bscreen%3Dno
variable_sip_cid_type: rpid
variable_sip_full_via: SIP/2.0/UDP%20213.144.239.110%3A9933%3Bbranch%3Dz9hG4bK39b1428a%3Brport%3D9933
variable_sip_from_display: Anonymous
variable_sip_full_from: %22Anonymous%22%20%3Csip%3A183284%40213.144.239.110%3A9933%3E%3Btag%3Das484c2d4d
variable_sip_full_to: %3Csip%3A352621453076%40emolife138.yoursipcloud.com%3E
variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20OPTIONS,%20BYE,%20REFER,%20SUBSCRIBE,%20NOTIFY,%20INFO,%20PUBLISH
variable_sip_req_user: 352621453076
variable_sip_req_uri: 352621453076%40emolife138.yoursipcloud.com
variable_sip_req_host: emolife138.yoursipcloud.com
variable_sip_to_user: 352621453076
variable_sip_to_uri: 352621453076%40emolife138.yoursipcloud.com
variable_sip_to_host: emolife138.yoursipcloud.com
variable_sip_contact_user: 183284
variable_sip_contact_port: 9933
variable_sip_contact_uri: 183284%40213.144.239.110%3A9933
variable_sip_contact_host: 213.144.239.110
variable_sip_user_agent: FPBX-2.11.0(11.4.0)
variable_sip_via_host: 213.144.239.110
variable_sip_via_port: 9933
variable_sip_via_rport: 9933
variable_max_forwards: 70
variable_presence_id: 183284%40213.144.239.110
variable_sip_Privacy: id
variable_switch_r_sdp: v%3D0%0D%0Ao%3Droot%201703048574%201703048574%20IN%20IP4%20213.144.239.110%0D%0As%3DAsterisk%20PBX%2011.4.0%0D%0Ac%3DIN%20IP4%20213.144.239.110%0D%0At%3D0%200%0D%0Am%3Daudio%2017212%20RTP/AVP%200%208%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0Aa%3Dptime%3A20%0D%0A
variable_ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%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: 213.144.239.110
variable_remote_media_port: 17212
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: 501160ca-0747-4211-b6aa-8caed8d6b7cc
Hunt-Direction: inbound
Hunt-Logical-Direction: inbound
Hunt-Username: 31232051002
Hunt-Dialplan: XML
Hunt-Caller-ID-Name: Anonymous
Hunt-Caller-ID-Number: 31232051002
Hunt-Orig-Caller-ID-Name: Anonymous
Hunt-Orig-Caller-ID-Number: 31232051002
Hunt-Network-Addr: 213.144.239.110
Hunt-ANI: 31232051002
Hunt-Destination-Number: 352621453076
Hunt-Unique-ID: 501160ca-0747-4211-b6aa-8caed8d6b7cc
Hunt-Source: mod_sofia
Hunt-Context: default
Hunt-Channel-Name: sofia/default/183284%40213.144.239.110%3A9933
Hunt-Profile-Index: 1
Hunt-Profile-Created-Time: 1671187173207380
Hunt-Channel-Created-Time: 1671187173207380
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: false
Hunt-Privacy-Hide-Name: true
Hunt-Privacy-Hide-Number: true


2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] key_value: 
2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] section: dialplan
2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] tag_name: 
2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] key_name: 
2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Dialed number : 352621453076
2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '352621453076' limit 1
2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '352621453076' AND status=0 limit 1
2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '352621453076' and status=0 LIMIT 1
2022-12-16 11:39:33.227380 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [xml_handler] Params:
Event-Name: REQUEST_PARAMS
Core-UUID: c687ffac-2ae7-42f3-9408-8ea92987c97f
FreeSWITCH-Hostname: multitenant-1
FreeSWITCH-Switchname: multitenant-1
FreeSWITCH-IPv4: 185.249.40.236
FreeSWITCH-IPv6: %3A%3A1
Event-Date-Local: 2022-12-16%2011%3A39%3A33
Event-Date-GMT: Fri,%2016%20Dec%202022%2010%3A39%3A33%20GMT
Event-Date-Timestamp: 1671187173207380
Event-Calling-File: mod_dialplan_xml.c
Event-Calling-Function: dialplan_xml_locate
Event-Calling-Line-Number: 608
Event-Sequence: 639849
Channel-State: CS_ROUTING
Channel-Call-State: RINGING
Channel-State-Number: 2
Channel-Name: sofia/default/1000%40141826.yoursipcloud.com
Unique-ID: 6220a791-8f80-4e4b-9751-c5905c23eede
Call-Direction: inbound
Presence-Call-Direction: inbound
Channel-HIT-Dialplan: true
Channel-Presence-ID: 1000%40141826.yoursipcloud.com
Channel-Call-UUID: 6220a791-8f80-4e4b-9751-c5905c23eede
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: 1000
Caller-Dialplan: XML
Caller-Caller-ID-Name: 1000
Caller-Caller-ID-Number: 1000
Caller-Orig-Caller-ID-Name: 1000
Caller-Orig-Caller-ID-Number: 1000
Caller-Network-Addr: 10.0.192.5
Caller-ANI: 1000
Caller-Destination-Number: 319000980
Caller-Unique-ID: 6220a791-8f80-4e4b-9751-c5905c23eede
Caller-Source: mod_sofia
Caller-Context: default
Caller-Channel-Name: sofia/default/1000%40141826.yoursipcloud.com
Caller-Profile-Index: 1
Caller-Profile-Created-Time: 1671187173207380
Caller-Channel-Created-Time: 1671187173207380
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: 6220a791-8f80-4e4b-9751-c5905c23eede
variable_session_id: 7224
variable_sip_from_params: transport%3DTCP
variable_sip_from_user: 1000
variable_sip_from_uri: 1000%40141826.yoursipcloud.com
variable_sip_from_host: 141826.yoursipcloud.com
variable_video_media_flow: disabled
variable_text_media_flow: disabled
variable_channel_name: sofia/default/1000%40141826.yoursipcloud.com
variable_sip_call_id: rqYzQ8oALqUXPRlXr1qEQw..
variable_sip_local_network_addr: 185.249.40.236
variable_sip_network_ip: 10.0.192.5
variable_sip_network_port: 57036
variable_sip_invite_stamp: 1671187173187379
variable_sip_received_ip: 10.0.192.5
variable_sip_received_port: 57036
variable_sip_via_protocol: tcp
variable_sip_authorized: true
variable_Event-Name: REQUEST_PARAMS
variable_Core-UUID: c687ffac-2ae7-42f3-9408-8ea92987c97f
variable_FreeSWITCH-Hostname: multitenant-1
variable_FreeSWITCH-Switchname: multitenant-1
variable_FreeSWITCH-IPv4: 185.249.40.236
variable_FreeSWITCH-IPv6: %3A%3A1
variable_Event-Date-Local: 2022-12-16%2011%3A39%3A33
variable_Event-Date-GMT: Fri,%2016%20Dec%202022%2010%3A39%3A33%20GMT
variable_Event-Date-Timestamp: 1671187173187379
variable_Event-Calling-File: sofia.c
variable_Event-Calling-Function: sofia_handle_sip_i_invite
variable_Event-Calling-Line-Number: 10542
variable_Event-Sequence: 639832
variable_sip_number_alias: 1000
variable_sip_auth_username: 1000
variable_sip_auth_realm: 141826.yoursipcloud.com
variable_number_alias: 1000
variable_requested_user_name: 1000
variable_requested_domain_name: 141826.yoursipcloud.com
variable_user_context: default
variable_sipcall: true
variable_sip_user: 1000
variable_accountcode: 141826
variable_user_name: 1000
variable_domain_name: 141826.yoursipcloud.com
variable_sip_from_user_stripped: 1000
variable_sip_from_tag: b47dd93b
variable_sofia_profile_name: default
variable_sofia_profile_url: sip%3Amod_sofia%40185.249.40.236%3A5060
variable_recovery_profile_name: default
variable_sip_full_via: SIP/2.0/TCP%2010.0.192.5%3A59984%3Bbranch%3Dz9hG4bK-524287-1---b7c232bf1a630aec%3Brport%3D57036
variable_sip_recover_via: SIP/2.0/TCP%2010.0.192.5%3A59984%3Bbranch%3Dz9hG4bK-524287-1---b7c232bf1a630aec%3Brport%3D57036
variable_sip_full_from: %3Csip%3A1000%40141826.yoursipcloud.com%3Btransport%3DTCP%3E%3Btag%3Db47dd93b
variable_sip_full_to: %3Csip%3A319000980%40141826.yoursipcloud.com%3E
variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20BYE,%20NOTIFY,%20REFER,%20MESSAGE,%20OPTIONS,%20INFO,%20SUBSCRIBE
variable_sip_req_params: transport%3DTCP
variable_sip_req_user: 319000980
variable_sip_req_uri: 319000980%40141826.yoursipcloud.com
variable_sip_req_host: 141826.yoursipcloud.com
variable_sip_to_user: 319000980
variable_sip_to_uri: 319000980%40141826.yoursipcloud.com
variable_sip_to_host: 141826.yoursipcloud.com
variable_sip_contact_params: transport%3Dtcp
variable_sip_contact_user: 1000
variable_sip_contact_port: 59984
variable_sip_contact_uri: 1000%4010.0.192.5%3A59984
variable_sip_contact_host: 10.0.192.5
variable_sip_user_agent: Z%205.5.13%20v2.10.18.3
variable_sip_via_host: 10.0.192.5
variable_sip_via_port: 59984
variable_sip_via_rport: 57036
variable_max_forwards: 70
variable_presence_id: 1000%40141826.yoursipcloud.com
variable_sip_nat_detected: true
variable_switch_r_sdp: v%3D0%0D%0Ao%3DZ%200%20414229242%20IN%20IP4%2010.0.192.5%0D%0As%3DZ%0D%0Ac%3DIN%20IP4%2010.0.192.5%0D%0At%3D0%200%0D%0Am%3Daudio%2061032%20RTP/AVP%20106%209%2098%20101%200%208%203%0D%0Aa%3Drtpmap%3A106%20opus/48000/2%0D%0Aa%3Dfmtp%3A106%20sprop-maxcapturerate%3D16000%3B%20minptime%3D20%3B%20useinbandfec%3D1%0D%0Aa%3Drtpmap%3A98%20telephone-event/48000%0D%0Aa%3Dfmtp%3A98%200-16%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0A
variable_ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%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: 10.0.192.5
variable_remote_media_port: 61032
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: 6220a791-8f80-4e4b-9751-c5905c23eede
Hunt-Direction: inbound
Hunt-Logical-Direction: inbound
Hunt-Username: 1000
Hunt-Dialplan: XML
Hunt-Caller-ID-Name: 1000
Hunt-Caller-ID-Number: 1000
Hunt-Orig-Caller-ID-Name: 1000
Hunt-Orig-Caller-ID-Number: 1000
Hunt-Network-Addr: 10.0.192.5
Hunt-ANI: 1000
Hunt-Destination-Number: 319000980
Hunt-Unique-ID: 6220a791-8f80-4e4b-9751-c5905c23eede
Hunt-Source: mod_sofia
Hunt-Context: default
Hunt-Channel-Name: sofia/default/1000%40141826.yoursipcloud.com
Hunt-Profile-Index: 1
Hunt-Profile-Created-Time: 1671187173207380
Hunt-Channel-Created-Time: 1671187173207380
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


2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] key_name: 
2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] tag_name: 
2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] section: dialplan
2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] key_value: 
2022-12-16 11:39:33.227380 [WARNING] switch_cpp.cpp:1447 [ASTPP] [FAXER] Custom Caller ID 
2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Caller Id name / number  : Anonymous / 31232051002
2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [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 ="352621453076" LIMIT 1
2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Dialed number : 319000980
2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '319000980' limit 1
2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [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="352621453076"  AND domains.domain="emolife138.yoursipcloud.com" limit 1
2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '319000980' AND status=0 limit 1
2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Call direction : outbound
2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [IPAUTHENTICATION] Query :SELECT ip_map.*, (SELECT number FROM accounts where id=accountid AND status=0 AND deleted=0) AS account_code FROM ip_map WHERE INET_ATON("213.144.239.110") BETWEEN(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) & 0xffffffff ^((0x1 <<(32 -  SUBSTRING_INDEX(`ip`, '/', -1))) -1 )) AND(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) |((0x100000000 >> SUBSTRING_INDEX(`ip`,'/', -1)) -1))  AND "352621453076"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '319000980' and status=0 LIMIT 1
2022-12-16 11:39:33.227380 [WARNING] switch_cpp.cpp:1447 [ASTPP] [FAXER] Custom Caller ID 
2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Caller Id name / number  : 1000 / 1000
2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [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 ="319000980" LIMIT 1
2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [Accountcode : 183284]
2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "183284" AND deleted = 0 limit 1
2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [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="319000980"  AND domains.domain="141826.yoursipcloud.com" limit 1
2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Call direction : outbound
2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [Accountcode : 141826]
2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "141826" AND deleted = 0 limit 1
2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^352621453076.*' OR blocked_patterns = '^35262145307.*' OR blocked_patterns = '^3526214530.*' OR blocked_patterns = '^352621453.*' OR blocked_patterns = '^35262145.*' OR blocked_patterns = '^3526214.*' OR blocked_patterns = '^352621.*' OR blocked_patterns = '^35262.*' OR blocked_patterns = '^3526.*' OR blocked_patterns = '^352.*' OR blocked_patterns = '^35.*' OR blocked_patterns = '^3.*' OR blocked_patterns ='--') AND accountid = 199 limit 1 
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="352621453076" AND (accountid = 0 OR status = 1) LIMIT 1
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [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 = '^352621453076.*' OR patterns = '^35262145307.*' OR patterns = '^3526214530.*' OR patterns = '^352621453.*' OR patterns = '^35262145.*' OR patterns = '^3526214.*' OR patterns = '^352621.*' OR patterns = '^35262.*' OR patterns = '^3526.*' OR patterns = '^352.*' OR patterns = '^35.*' OR patterns = '^3.*' OR patterns ='--') AND accountid = 199 ORDER BY LENGTH(PKGPTR.patterns) DESC
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [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 ="352621453076" AND A.accountid = '199' limit 1
2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 352621453076
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [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 ="352621453076" LIMIT 1
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [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="352621453076"  AND domains.domain="emolife138.yoursipcloud.com" limit 1
2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] New Call Direction : outbound
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '352621453076' OR number = '35262145307' OR number = '3526214530' OR number = '352621453' OR number = '35262145' OR number = '3526214' OR number = '352621' OR number = '35262' OR number = '3526' OR number = '352' OR number = '35' OR number = '3' OR number ='--') OR (number = '352621453076*' OR number = '35262145307*' OR number = '3526214530*' OR number = '352621453*' OR number = '35262145*' OR number = '3526214*' OR number = '352621*' OR number = '35262*' OR number = '3526*' OR number = '352*' OR number = '35*' OR number = '3*' OR number ='--') OR (number = '31232051002' OR number = '3123205100' OR number = '312320510' OR number = '31232051' OR number = '3123205' OR number = '312320' OR number = '31232' OR number = '3123' OR number = '312' OR number = '31' OR number = '3' OR number ='--') OR (number = '31232051002*' OR number = '3123205100*' OR number = '312320510*' OR number = '31232051*' OR number = '3123205*' OR number = '312320*' OR number = '31232*' OR number = '3123*' OR number = '312*' OR number = '31*' OR number = '3*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Call Barring Status : -1
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '352621453076' OR routing_prefix = '35262145307' OR routing_prefix = '3526214530' OR routing_prefix = '352621453' OR routing_prefix = '35262145' OR routing_prefix = '3526214' OR routing_prefix = '352621' OR routing_prefix = '35262' OR routing_prefix = '3526' OR routing_prefix = '352' OR routing_prefix = '35' OR routing_prefix = '3' OR routing_prefix ='--') AND status = 0 and reseller_id = 189 limit 1
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^319000980.*' OR blocked_patterns = '^31900098.*' OR blocked_patterns = '^3190009.*' OR blocked_patterns = '^319000.*' OR blocked_patterns = '^31900.*' OR blocked_patterns = '^3190.*' OR blocked_patterns = '^319.*' OR blocked_patterns = '^31.*' OR blocked_patterns = '^3.*' OR blocked_patterns ='--') AND accountid = 1178 limit 1 
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="319000980" AND (accountid = 0 OR status = 1) LIMIT 1
2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Account Information ===================
2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP] User id : 199
2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP] Account code : 183284
2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP] Balance : 835.74349
2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP] Ratecard id : 25
2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP] CID Pool : 0
2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP] ========================================================
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 25 AND status = 0
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] call_direction :outbound
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 25 AND status = 0
2022-12-16 11:39:33.247382 [NOTICE] switch_cpp.cpp:1447 [ASTPP] Localization Id: 1
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [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 
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^352621453076.*' OR pattern = '^35262145307.*' OR pattern = '^3526214530.*' OR pattern = '^352621453.*' OR pattern = '^35262145.*' OR pattern = '^3526214.*' OR pattern = '^352621.*' OR pattern = '^35262.*' OR pattern = '^3526.*' OR pattern = '^352.*' OR pattern = '^35.*' OR pattern = '^3.*' OR pattern ='--') AND status = 0 AND effective_date <= UTC_TIMESTAMP() AND (pricelist_id = 25 OR accountid=199)  ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC,effective_date DESC LIMIT 1
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_LOCALIZATION] Query :"0/31,+/".....319000980
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_LOCALIZATION] Query :"0/31,+/".....319000980
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [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 = '^319000980.*' OR patterns = '^31900098.*' OR patterns = '^3190009.*' OR patterns = '^319000.*' OR patterns = '^31900.*' OR patterns = '^3190.*' OR patterns = '^319.*' OR patterns = '^31.*' OR patterns = '^3.*' OR patterns ='--') AND accountid = 1178 ORDER BY LENGTH(PKGPTR.patterns) DESC
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [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 ="319000980" AND A.accountid = '1178' limit 1
2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP]  Call Direction : outbound
2022-12-16 11:39:33.247382 [WARNING] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Rates Not Found : 
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP]  Post Cdrs : 0
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET RESELLERID] Query :SELECT reseller_id FROM accounts WHERE id = 199
2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 319000980
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [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 ="319000980" LIMIT 1
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [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="352621453076">
<condition field="destination_number" expression="352621453076">
<action application="log" data="WARNING Accountcode . Dialed number (352621453076)  origination rates not found!!"/>
<action application="set" data="original_caller_id_name=Anonymous"/>
<action application="set" data="original_caller_id_number=Anonymous"/>
<action application="set" data="error_cdr=1"/>
<action application="set" data="callstart=2022-12-16 10:39:33"/>
<action application="set" data="account_id=199"/>
<action application="set" data="parent_id=189"/>
<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=352621453076"/>
<action application="set" data="last_bridge_hangup_cause=ORIGINATION_RATE_NOT_FOUND"/>
<action application="hangup" data="ORIGINATION_RATE_NOT_FOUND"/>
</condition>
</extension>
</context>
</section>
</document>
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [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="319000980"  AND domains.domain="141826.yoursipcloud.com" limit 1
Dialplan: sofia/default/183284@213.144.239.110:9933 parsing [default->352621453076] continue=false
Dialplan: sofia/default/183284@213.144.239.110:9933 Regex (PASS) [352621453076] destination_number(352621453076) =~ /352621453076/ break=on-false
Dialplan: sofia/default/183284@213.144.239.110:9933 Action log(WARNING Accountcode . Dialed number (352621453076)  origination rates not found!!) 
Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(original_caller_id_name=Anonymous) 
Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(original_caller_id_number=Anonymous) 
Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(error_cdr=1) 
Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(callstart=2022-12-16 10:39:33) 
Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(account_id=199) 
Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(parent_id=189) 
Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(call_direction=outbound) 
Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(sip_ignore_remote_cause=true) 
Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(call_processed=internal) 
Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(effective_destination_number=352621453076) 
Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(last_bridge_hangup_cause=ORIGINATION_RATE_NOT_FOUND) 
Dialplan: sofia/default/183284@213.144.239.110:9933 Action hangup(ORIGINATION_RATE_NOT_FOUND) 
2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:287 (sofia/default/183284@213.144.239.110:9933) State Change CS_ROUTING -> CS_EXECUTE
2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:644 (sofia/default/183284@213.144.239.110:9933) State ROUTING going to sleep
2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:585 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_EXECUTE (Cur 29 Tot 7226)
2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:651 (sofia/default/183284@213.144.239.110:9933) State EXECUTE
2022-12-16 11:39:33.247382 [DEBUG] mod_sofia.c:209 sofia/default/183284@213.144.239.110:9933 SOFIA EXECUTE
2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:329 sofia/default/183284@213.144.239.110:9933 Standard EXECUTE
EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 log(WARNING Accountcode . Dialed number (352621453076)  origination rates not found!!)
2022-12-16 11:39:33.247382 [WARNING] mod_dptools.c:1866 Accountcode . Dialed number (352621453076)  origination rates not found!!
EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(original_caller_id_name=Anonymous)
2022-12-16 11:39:33.247382 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [original_caller_id_name]=[Anonymous]
EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(original_caller_id_number=Anonymous)
2022-12-16 11:39:33.247382 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [original_caller_id_number]=[Anonymous]
EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(error_cdr=1)
2022-12-16 11:39:33.247382 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [error_cdr]=[1]
EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(callstart=2022-12-16 10:39:33)
2022-12-16 11:39:33.247382 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [callstart]=[2022-12-16 10:39:33]
EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(account_id=199)
2022-12-16 11:39:33.247382 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [account_id]=[199]
EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(parent_id=189)
2022-12-16 11:39:33.247382 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [parent_id]=[189]
EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(call_direction=outbound)
2022-12-16 11:39:33.247382 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [call_direction]=[outbound]
EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(sip_ignore_remote_cause=true)
2022-12-16 11:39:33.247382 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [sip_ignore_remote_cause]=[true]
EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(call_processed=internal)
2022-12-16 11:39:33.247382 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [call_processed]=[internal]
EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(effective_destination_number=352621453076)
2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] New Call Direction : outbound
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '319000980' OR number = '31900098' OR number = '3190009' OR number = '319000' OR number = '31900' OR number = '3190' OR number = '319' OR number = '31' OR number = '3' OR number ='--') OR (number = '319000980*' OR number = '31900098*' OR number = '3190009*' OR number = '319000*' OR number = '31900*' OR number = '3190*' OR number = '319*' OR number = '31*' OR number = '3*' OR number ='--') OR (number = '1000' OR number = '100' OR number = '10' OR number = '1' OR number ='--') OR (number = '1000*' OR number = '100*' OR number = '10*' OR number = '1*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
2022-12-16 11:39:33.247382 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [effective_destination_number]=[352621453076]
EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(last_bridge_hangup_cause=ORIGINATION_RATE_NOT_FOUND)
2022-12-16 11:39:33.247382 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [last_bridge_hangup_cause]=[ORIGINATION_RATE_NOT_FOUND]
EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 hangup(ORIGINATION_RATE_NOT_FOUND)
2022-12-16 11:39:33.247382 [NOTICE] mod_dptools.c:1380 Hangup sofia/default/183284@213.144.239.110:9933 [CS_EXECUTE] [NORMAL_CLEARING]
2022-12-16 11:39:33.247382 [DEBUG] switch_core_session.c:2905 sofia/default/183284@213.144.239.110:9933 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:651 (sofia/default/183284@213.144.239.110:9933) State EXECUTE going to sleep
2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:585 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_HANGUP (Cur 29 Tot 7226)
2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:848 (sofia/default/183284@213.144.239.110:9933) Callstate Change RINGING -> HANGUP
2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:850 (sofia/default/183284@213.144.239.110:9933) State HANGUP
2022-12-16 11:39:33.247382 [DEBUG] mod_sofia.c:453 Channel sofia/default/183284@213.144.239.110:9933 hanging up, cause: NORMAL_CLEARING
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Call Barring Status : -1
2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '319000980' OR routing_prefix = '31900098' OR routing_prefix = '3190009' OR routing_prefix = '319000' OR routing_prefix = '31900' OR routing_prefix = '3190' OR routing_prefix = '319' OR routing_prefix = '31' OR routing_prefix = '3' OR routing_prefix ='--') AND status = 0 and reseller_id = 12 limit 1
2022-12-16 11:39:33.247382 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 480
2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:60 sofia/default/183284@213.144.239.110:9933 Standard HANGUP, cause: NORMAL_CLEARING
2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:850 (sofia/default/183284@213.144.239.110:9933) State HANGUP going to sleep
2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:620 (sofia/default/183284@213.144.239.110:9933) State Change CS_HANGUP -> CS_REPORTING
2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:585 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_REPORTING (Cur 29 Tot 7226)
2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:936 (sofia/default/183284@213.144.239.110:9933) State REPORTING
2022-12-16 11:39:33.247382 [INFO] mod_json_cdr.c:271 Process [501160ca-0747-4211-b6aa-8caed8d6b7cc.cdr.json]
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Account Information ===================
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] User id : 1178
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Account code : 141826
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Balance : 95.05
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Ratecard id : 6
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] CID Pool : 0
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] ========================================================
2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 6 AND status = 0
2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] call_direction :outbound
2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 6 AND status = 0
2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^319000980.*' OR pattern = '^31900098.*' OR pattern = '^3190009.*' OR pattern = '^319000.*' OR pattern = '^31900.*' OR pattern = '^3190.*' OR pattern = '^319.*' OR pattern = '^31.*' OR pattern = '^3.*' OR pattern ='--') AND status = 0 AND effective_date <= UTC_TIMESTAMP() AND (pricelist_id = 6 OR accountid=1178)  ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC,effective_date DESC LIMIT 1
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP]  Call Direction : outbound
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Rates Information ===================
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] ID : 3742429
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Connectcost : 0.07200
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Includedseconds : 1
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Cost : 0.05400
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Comment : Netherland Special
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Country Id : 136
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Accid : 1178
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Trunk ID: 
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Routing type: 
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] ================================================================
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Your95.05 balance Accountid 1178 !!!
2022-12-16 11:39:33.267384 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Limiting Call To Config Max Length 100 mins!
2022-12-16 11:39:33.267384 [NOTICE] switch_cpp.cpp:1447 [ASTPP] FINDING LIMIT FOR RESELLER: 12
2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "12" AND deleted = 0 limit 1
2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^319000980.*' OR blocked_patterns = '^31900098.*' OR blocked_patterns = '^3190009.*' OR blocked_patterns = '^319000.*' OR blocked_patterns = '^31900.*' OR blocked_patterns = '^3190.*' OR blocked_patterns = '^319.*' OR blocked_patterns = '^31.*' OR blocked_patterns = '^3.*' OR blocked_patterns ='--') AND accountid = 12 limit 1 
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Reseller Information ===================
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] User id : 12
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Account code : 3847299300
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Balance : 98925.58531
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Ratecard id : 44
2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 44 AND status = 0
2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] call_direction :outbound
2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 44 AND status = 0
2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^319000980.*' OR pattern = '^31900098.*' OR pattern = '^3190009.*' OR pattern = '^319000.*' OR pattern = '^31900.*' OR pattern = '^3190.*' OR pattern = '^319.*' OR pattern = '^31.*' OR pattern = '^3.*' OR pattern ='--') AND status = 0 AND effective_date <= UTC_TIMESTAMP() AND (pricelist_id = 44 OR accountid=12)  ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC,effective_date DESC LIMIT 1
2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP]  Call Direction : outbound
2022-12-16 11:39:33.267384 [WARNING] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Rates Not Found : 
2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [ASTPP]  Post Cdrs : 0
2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET RESELLERID] Query :SELECT reseller_id FROM accounts WHERE id = 1178
2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [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="319000980">
<condition field="destination_number" expression="319000980">
<action application="log" data="WARNING Accountcode 141826. Dialed number (319000980)  origination rates not found!!"/>
<action application="set" data="original_caller_id_name=1000"/>
<action application="set" data="original_caller_id_number=1000"/>
<action application="set" data="error_cdr=1"/>
<action application="set" data="callstart=2022-12-16 10:39:33"/>
<action application="set" data="account_id=1178"/>
<action application="set" data="parent_id=12"/>
<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=319000980"/>
<action application="set" data="last_bridge_hangup_cause=ORIGINATION_RATE_NOT_FOUND"/>
<action application="hangup" data="ORIGINATION_RATE_NOT_FOUND"/>
</condition>
</extension>
</context>
</section>
</document>
Dialplan: sofia/default/1000@141826.yoursipcloud.com parsing [default->319000980] continue=false
Dialplan: sofia/default/1000@141826.yoursipcloud.com Regex (PASS) [319000980] destination_number(319000980) =~ /319000980/ break=on-false
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action log(WARNING Accountcode 141826. Dialed number (319000980)  origination rates not found!!) 
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(original_caller_id_name=1000) 
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(original_caller_id_number=1000) 
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(error_cdr=1) 
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(callstart=2022-12-16 10:39:33) 
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(account_id=1178) 
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(parent_id=12) 
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(call_direction=outbound) 
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(sip_ignore_remote_cause=true) 
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(call_processed=internal) 
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(effective_destination_number=319000980) 
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(last_bridge_hangup_cause=ORIGINATION_RATE_NOT_FOUND) 
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action hangup(ORIGINATION_RATE_NOT_FOUND) 
2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:287 (sofia/default/1000@141826.yoursipcloud.com) State Change CS_ROUTING -> CS_EXECUTE
2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:644 (sofia/default/1000@141826.yoursipcloud.com) State ROUTING going to sleep
2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:585 (sofia/default/1000@141826.yoursipcloud.com) Running State Change CS_EXECUTE (Cur 29 Tot 7226)
2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:651 (sofia/default/1000@141826.yoursipcloud.com) State EXECUTE
2022-12-16 11:39:33.267384 [DEBUG] mod_sofia.c:209 sofia/default/1000@141826.yoursipcloud.com SOFIA EXECUTE
2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:329 sofia/default/1000@141826.yoursipcloud.com Standard EXECUTE
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com log(WARNING Accountcode 141826. Dialed number (319000980)  origination rates not found!!)
2022-12-16 11:39:33.267384 [WARNING] mod_dptools.c:1866 Accountcode 141826. Dialed number (319000980)  origination rates not found!!
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(original_caller_id_name=1000)
2022-12-16 11:39:33.267384 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [original_caller_id_name]=[1000]
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(original_caller_id_number=1000)
2022-12-16 11:39:33.267384 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [original_caller_id_number]=[1000]
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(error_cdr=1)
2022-12-16 11:39:33.267384 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [error_cdr]=[1]
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(callstart=2022-12-16 10:39:33)
2022-12-16 11:39:33.267384 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [callstart]=[2022-12-16 10:39:33]
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(account_id=1178)
2022-12-16 11:39:33.267384 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [account_id]=[1178]
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(parent_id=12)
2022-12-16 11:39:33.267384 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [parent_id]=[12]
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(call_direction=outbound)
2022-12-16 11:39:33.267384 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [call_direction]=[outbound]
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(sip_ignore_remote_cause=true)
2022-12-16 11:39:33.267384 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [sip_ignore_remote_cause]=[true]
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(call_processed=internal)
2022-12-16 11:39:33.267384 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [call_processed]=[internal]
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(effective_destination_number=319000980)
2022-12-16 11:39:33.267384 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [effective_destination_number]=[319000980]
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(last_bridge_hangup_cause=ORIGINATION_RATE_NOT_FOUND)
2022-12-16 11:39:33.267384 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [last_bridge_hangup_cause]=[ORIGINATION_RATE_NOT_FOUND]
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com hangup(ORIGINATION_RATE_NOT_FOUND)
2022-12-16 11:39:33.267384 [NOTICE] mod_dptools.c:1380 Hangup sofia/default/1000@141826.yoursipcloud.com [CS_EXECUTE] [NORMAL_CLEARING]
2022-12-16 11:39:33.267384 [DEBUG] switch_core_session.c:2905 sofia/default/1000@141826.yoursipcloud.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:651 (sofia/default/1000@141826.yoursipcloud.com) State EXECUTE going to sleep
2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:585 (sofia/default/1000@141826.yoursipcloud.com) Running State Change CS_HANGUP (Cur 29 Tot 7226)
2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:848 (sofia/default/1000@141826.yoursipcloud.com) Callstate Change RINGING -> HANGUP
2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:850 (sofia/default/1000@141826.yoursipcloud.com) State HANGUP
2022-12-16 11:39:33.267384 [DEBUG] mod_sofia.c:453 Channel sofia/default/1000@141826.yoursipcloud.com hanging up, cause: NORMAL_CLEARING
2022-12-16 11:39:33.267384 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 480
2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:60 sofia/default/1000@141826.yoursipcloud.com Standard HANGUP, cause: NORMAL_CLEARING
2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:850 (sofia/default/1000@141826.yoursipcloud.com) State HANGUP going to sleep
2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:620 (sofia/default/1000@141826.yoursipcloud.com) State Change CS_HANGUP -> CS_REPORTING
2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:585 (sofia/default/1000@141826.yoursipcloud.com) Running State Change CS_REPORTING (Cur 29 Tot 7226)
2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:936 (sofia/default/1000@141826.yoursipcloud.com) State REPORTING
2022-12-16 11:39:33.267384 [INFO] mod_json_cdr.c:271 Process [6220a791-8f80-4e4b-9751-c5905c23eede.cdr.json]
2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:174 sofia/default/183284@213.144.239.110:9933 Standard REPORTING, cause: NORMAL_CLEARING
2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:936 (sofia/default/183284@213.144.239.110:9933) State REPORTING going to sleep
2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:611 (sofia/default/183284@213.144.239.110:9933) State Change CS_REPORTING -> CS_DESTROY
2022-12-16 11:39:33.287384 [DEBUG] switch_core_session.c:1726 Session 7226 (sofia/default/183284@213.144.239.110:9933) Locked, Waiting on external entities
2022-12-16 11:39:33.287384 [NOTICE] switch_core_session.c:1744 Session 7226 (sofia/default/183284@213.144.239.110:9933) Ended
2022-12-16 11:39:33.287384 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/183284@213.144.239.110:9933 [CS_DESTROY]
2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:739 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_DESTROY (Cur 28 Tot 7226)
2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:749 (sofia/default/183284@213.144.239.110:9933) State DESTROY
2022-12-16 11:39:33.287384 [DEBUG] mod_sofia.c:364 sofia/default/183284@213.144.239.110:9933 SOFIA DESTROY
2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:181 sofia/default/183284@213.144.239.110:9933 Standard DESTROY
2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:749 (sofia/default/183284@213.144.239.110:9933) State DESTROY going to sleep
2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:174 sofia/default/1000@141826.yoursipcloud.com Standard REPORTING, cause: NORMAL_CLEARING
2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:936 (sofia/default/1000@141826.yoursipcloud.com) State REPORTING going to sleep
2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:611 (sofia/default/1000@141826.yoursipcloud.com) State Change CS_REPORTING -> CS_DESTROY
2022-12-16 11:39:33.287384 [DEBUG] switch_core_session.c:1726 Session 7224 (sofia/default/1000@141826.yoursipcloud.com) Locked, Waiting on external entities
2022-12-16 11:39:33.287384 [NOTICE] switch_core_session.c:1744 Session 7224 (sofia/default/1000@141826.yoursipcloud.com) Ended
2022-12-16 11:39:33.287384 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/1000@141826.yoursipcloud.com [CS_DESTROY]
2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:739 (sofia/default/1000@141826.yoursipcloud.com) Running State Change CS_DESTROY (Cur 27 Tot 7226)
2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:749 (sofia/default/1000@141826.yoursipcloud.com) State DESTROY
2022-12-16 11:39:33.287384 [DEBUG] mod_sofia.c:364 sofia/default/1000@141826.yoursipcloud.com SOFIA DESTROY
2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:181 sofia/default/1000@141826.yoursipcloud.com Standard DESTROY
2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:749 (sofia/default/1000@141826.yoursipcloud.com) State DESTROY going to sleep
2022-12-16 11:39:33.347379 [DEBUG] sofia.c:7326 Channel sofia/default/31555334124 entering state [proceeding][180]
2022-12-16 11:39:33.347379 [NOTICE] sofia.c:7437 Ring-Ready sofia/default/31555334124!
2022-12-16 11:39:33.347379 [DEBUG] switch_channel.c:3437 (sofia/default/31555334124) Callstate Change DOWN -> RINGING
2022-12-16 11:39:33.347379 [NOTICE] mod_sofia.c:2498 Ring-Ready sofia/default/201@261408.yoursipcloud.com!
2022-12-16 11:39:33.347379 [DEBUG] sofia.c:7326 Channel sofia/default/201@261408.yoursipcloud.com entering state [early][180]
2022-12-16 11:39:33.347379 [NOTICE] switch_ivr_originate.c:556 Ring Ready sofia/default/201@261408.yoursipcloud.com!
2022-12-16 11:39:34.247389 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [200@262378.yoursipcloud.com] from ip 95.211.34.130
2022-12-16 11:39:34.287379 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [201@260278.yoursipcloud.com] from ip 45.129.144.58
2022-12-16 11:39:35.167383 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [202@260278.yoursipcloud.com] from ip 45.129.144.58
2022-12-16 11:39:36.527394 [WARNING] switch_core_state_machine.c:688 3dafb827-ca78-40a5-b2fe-5fcadc55f8c2 sofia/default/4005@185.249.40.236 Abandoned
2022-12-16 11:39:36.527394 [NOTICE] switch_core_state_machine.c:691 Hangup sofia/default/4005@185.249.40.236 [CS_NEW] [WRONG_CALL_STATE]
2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:585 (sofia/default/4005@185.249.40.236) Running State Change CS_HANGUP (Cur 27 Tot 7226)
2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:848 (sofia/default/4005@185.249.40.236) Callstate Change DOWN -> HANGUP
2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:850 (sofia/default/4005@185.249.40.236) State HANGUP
2022-12-16 11:39:36.527394 [DEBUG] mod_sofia.c:453 Channel sofia/default/4005@185.249.40.236 hanging up, cause: WRONG_CALL_STATE
2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:60 sofia/default/4005@185.249.40.236 Standard HANGUP, cause: WRONG_CALL_STATE
2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:850 (sofia/default/4005@185.249.40.236) State HANGUP going to sleep
2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:620 (sofia/default/4005@185.249.40.236) State Change CS_HANGUP -> CS_REPORTING
2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:585 (sofia/default/4005@185.249.40.236) Running State Change CS_REPORTING (Cur 27 Tot 7226)
2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:936 (sofia/default/4005@185.249.40.236) State REPORTING
2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:174 sofia/default/4005@185.249.40.236 Standard REPORTING, cause: WRONG_CALL_STATE
2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:936 (sofia/default/4005@185.249.40.236) State REPORTING going to sleep
2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:611 (sofia/default/4005@185.249.40.236) State Change CS_REPORTING -> CS_DESTROY
2022-12-16 11:39:36.527394 [DEBUG] switch_core_session.c:1726 Session 7222 (sofia/default/4005@185.249.40.236) Locked, Waiting on external entities
2022-12-16 11:39:36.527394 [NOTICE] switch_core_session.c:1744 Session 7222 (sofia/default/4005@185.249.40.236) Ended
2022-12-16 11:39:36.527394 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/4005@185.249.40.236 [CS_DESTROY]
2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:739 (sofia/default/4005@185.249.40.236) Running State Change CS_DESTROY (Cur 26 Tot 7226)
2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:749 (sofia/default/4005@185.249.40.236) State DESTROY
2022-12-16 11:39:36.527394 [DEBUG] mod_sofia.c:364 sofia/default/4005@185.249.40.236 SOFIA DESTROY
2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:181 sofia/default/4005@185.249.40.236 Standard DESTROY
2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:749 (sofia/default/4005@185.249.40.236) State DESTROY going to sleep
2022-12-16 11:39:36.967386 [DEBUG] sofia.c:7326 Channel sofia/default/31713412309 entering state [completing][200]
2022-12-16 11:39:36.967386 [DEBUG] sofia.c:7336 Remote SDP:
v=0
o=- 2410390152 1 IN IP4 185.249.40.253
s=-
c=IN IP4 185.249.40.253
t=0 0
m=audio 27614 RTP/AVP 8 101
a=rtpmap:101 telephone-event/8000
a=ptime:20

2022-12-16 11:39:36.967386 [DEBUG] sofia.c:7326 Channel sofia/default/31713412309 entering state [ready][200]
2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/31713412309 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
2022-12-16 11:39:36.967386 [DEBUG] switch_core_codec.c:111 sofia/default/31713412309 Original read codec set to PCMA:8
2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:5911 sofia/default/31713412309 Set 2833 dtmf send payload to 101 recv payload to 101
2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/31713412309] 185.249.40.236 port 23844 -> 185.249.40.253 port 27614 codec: 8 ms: 20
2022-12-16 11:39:36.967386 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:8977 sofia/default/31713412309 Set 2833 dtmf send payload to 101
2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:8984 sofia/default/31713412309 Set 2833 dtmf receive payload to 101
2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:9007 sofia/default/31713412309 Set rtp dtmf delay to 40
2022-12-16 11:39:36.967386 [NOTICE] sofia.c:8504 Channel [sofia/default/31713412309] has been answered
2022-12-16 11:39:36.967386 [DEBUG] switch_channel.c:3865 (sofia/default/31713412309) Callstate Change RINGING -> ACTIVE
2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/31725037039@pilkes142.yoursipcloud.com] 185.249.40.236 port 31310 -> 54.36.114.148 port 9860 codec: 0 ms: 20
2022-12-16 11:39:36.967386 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:8977 sofia/default/31725037039@pilkes142.yoursipcloud.com Set 2833 dtmf send payload to 101
2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:8984 sofia/default/31725037039@pilkes142.yoursipcloud.com Set 2833 dtmf receive payload to 101
2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:9007 sofia/default/31725037039@pilkes142.yoursipcloud.com Set rtp dtmf delay to 40
2022-12-16 11:39:36.967386 [DEBUG] mod_sofia.c:898 Local SDP sofia/default/31725037039@pilkes142.yoursipcloud.com:
v=0
o=FreeSWITCH 1671155866 1671155867 IN IP4 185.249.40.236
s=FreeSWITCH
c=IN IP4 185.249.40.236
t=0 0
m=audio 31310 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

2022-12-16 11:39:36.967386 [NOTICE] switch_ivr_originate.c:3794 Channel [sofia/default/31725037039@pilkes142.yoursipcloud.com] has been answered
2022-12-16 11:39:36.967386 [DEBUG] switch_channel.c:3865 (sofia/default/31725037039@pilkes142.yoursipcloud.com) Callstate Change RINGING -> ACTIVE
2022-12-16 11:39:36.967386 [DEBUG] sofia.c:7326 Channel sofia/default/31725037039@pilkes142.yoursipcloud.com entering state [completed][200]
2022-12-16 11:39:36.967386 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/default/31713412309]
2022-12-16 11:39:36.967386 [DEBUG] switch_ivr_bridge.c:1793 (sofia/default/31713412309) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2022-12-16 11:39:36.967386 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31713412309) Running State Change CS_EXCHANGE_MEDIA (Cur 26 Tot 7226)
2022-12-16 11:39:36.967386 [DEBUG] switch_core_state_machine.c:654 (sofia/default/31713412309) State EXCHANGE_MEDIA
2022-12-16 11:39:36.967386 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA
2022-12-16 11:39:37.027380 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
2022-12-16 11:39:37.047381 [DEBUG] sofia.c:7326 Channel sofia/default/31725037039@pilkes142.yoursipcloud.com entering state [ready][200]
2022-12-16 11:39:37.087378 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
2022-12-16 11:39:37.167383 [DEBUG] switch_ivr_bridge.c:655 sofia/default/31713412309 Bridge execute app sched_hangup(+6000 normal_clearing)
2022-12-16 11:39:37.187379 [DEBUG] switch_ivr.c:632 sofia/default/31713412309 Command Execute [depth=0] sched_hangup(+6000 normal_clearing)
EXECUTE [depth=0] sofia/default/31713412309 sched_hangup(+6000 normal_clearing)
2022-12-16 11:39:37.187379 [DEBUG] switch_scheduler.c:252 Added task 1368 switch_ivr_schedule_hangup (99458322-5246-4e2b-b789-d7ec0f273153) to run at 1671193177
2022-12-16 11:39:38.107392 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [201@00135948.yoursipcloud.com] from ip 185.249.42.190
2022-12-16 11:39:38.787380 [NOTICE] switch_channel.c:1118 New Channel sofia/default/1000@141826.yoursipcloud.com [d03b5e8f-d15c-4cc9-910e-3bcca4ed1d57]
2022-12-16 11:39:38.787380 [DEBUG] switch_core_state_machine.c:585 (sofia/default/1000@141826.yoursipcloud.com) Running State Change CS_NEW (Cur 27 Tot 7227)
2022-12-16 11:39:38.787380 [DEBUG] sofia.c:10280 sofia/default/1000@141826.yoursipcloud.com receiving invite from 10.0.192.5:57036 version: 1.10.5 -release-17-25569c1631 64bit
2022-12-16 11:39:38.787380 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 10.0.192.5:0.
2022-12-16 11:39:38.787380 [WARNING] sofia_reg.c:1794 SIP auth challenge (INVITE) on sofia profile 'default' for [319000980@141826.yoursipcloud.com] from ip 10.0.192.5
2022-12-16 11:39:38.787380 [DEBUG] switch_core_state_machine.c:604 (sofia/default/1000@141826.yoursipcloud.com) State NEW
2022-12-16 11:39:38.787380 [DEBUG] sofia.c:2434 detaching session d03b5e8f-d15c-4cc9-910e-3bcca4ed1d57
2022-12-16 11:39:39.207467 [DEBUG] sofia.c:2544 Re-attaching to session d03b5e8f-d15c-4cc9-910e-3bcca4ed1d57
2022-12-16 11:39:39.207467 [DEBUG] sofia.c:10280 sofia/default/1000@141826.yoursipcloud.com receiving invite from 10.0.192.5:57036 version: 1.10.5 -release-17-25569c1631 64bit
2022-12-16 11:39:39.207467 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 10.0.192.5:0.
2022-12-16 11:39:39.207467 [DEBUG] sofia.c:11477 Setting NAT mode based on via port
2022-12-16 11:39:39.207467 [DEBUG] sofia.c:7326 Channel sofia/default/1000@141826.yoursipcloud.com entering state [received][100]
2022-12-16 11:39:39.207467 [DEBUG] sofia.c:7336 Remote SDP:
v=0
o=Z 0 414235312 IN IP4 10.0.192.5
s=Z
c=IN IP4 10.0.192.5
t=0 0
m=audio 61033 RTP/AVP 106 9 98 101 0 8 3
a=rtpmap:106 opus/48000/2
a=fmtp:106 sprop-maxcapturerate=16000; minptime=20; useinbandfec=1
a=rtpmap:98 telephone-event/48000
a=fmtp:98 0-16
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16

2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [opus:106:48000:20:0:2]/[PCMA:8:8000:20:64000:1]
2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [opus:106:48000:20:0:2]/[PCMU:0:8000:20:64000:1]
2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 98@48000
2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/1000@141826.yoursipcloud.com PCMU/8000 20 ms 160 samples 64000 bits 1 channels
2022-12-16 11:39:39.207467 [DEBUG] switch_core_codec.c:111 sofia/default/1000@141826.yoursipcloud.com Original read codec set to PCMU:0
2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5911 sofia/default/1000@141826.yoursipcloud.com Set 2833 dtmf send payload to 101 recv payload to 101
2022-12-16 11:39:39.207467 [DEBUG] sofia.c:7760 (sofia/default/1000@141826.yoursipcloud.com) State Change CS_NEW -> CS_INIT
2022-12-16 11:39:39.207467 [DEBUG] switch_core_state_machine.c:585 (sofia/default/1000@141826.yoursipcloud.com) Running State Change CS_INIT (Cur 27 Tot 7227)
2022-12-16 11:39:39.207467 [DEBUG] switch_core_state_machine.c:628 (sofia/default/1000@141826.yoursipcloud.com) State INIT
2022-12-16 11:39:39.207467 [DEBUG] mod_sofia.c:93 sofia/default/1000@141826.yoursipcloud.com SOFIA INIT
2022-12-16 11:39:39.207467 [DEBUG] switch_core_state_machine.c:40 sofia/default/1000@141826.yoursipcloud.com Standard INIT
2022-12-16 11:39:39.207467 [DEBUG] switch_core_state_machine.c:48 (sofia/default/1000@141826.yoursipcloud.com) State Change CS_INIT -> CS_ROUTING
2022-12-16 11:39:39.207467 [DEBUG] switch_core_state_machine.c:628 (sofia/default/1000@141826.yoursipcloud.com) State INIT going to sleep
2022-12-16 11:39:39.207467 [DEBUG] switch_core_state_machine.c:585 (sofia/default/1000@141826.yoursipcloud.com) Running State Change CS_ROUTING (Cur 27 Tot 7227)
2022-12-16 11:39:39.207467 [DEBUG] switch_channel.c:2332 (sofia/default/1000@141826.yoursipcloud.com) Callstate Change DOWN -> RINGING
2022-12-16 11:39:39.207467 [DEBUG] switch_core_state_machine.c:644 (sofia/default/1000@141826.yoursipcloud.com) State ROUTING
2022-12-16 11:39:39.207467 [DEBUG] mod_sofia.c:154 sofia/default/1000@141826.yoursipcloud.com SOFIA ROUTING
2022-12-16 11:39:39.207467 [DEBUG] switch_core_state_machine.c:236 sofia/default/1000@141826.yoursipcloud.com Standard ROUTING
2022-12-16 11:39:39.207467 [INFO] mod_dialplan_xml.c:637 Processing 1000 <1000>->319000980 in context default
2022-12-16 11:39:39.247381 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
2022-12-16 11:39:39.247381 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
2022-12-16 11:39:39.247381 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [xml_handler] Params:
Event-Name: REQUEST_PARAMS
Core-UUID: c687ffac-2ae7-42f3-9408-8ea92987c97f
FreeSWITCH-Hostname: multitenant-1
FreeSWITCH-Switchname: multitenant-1
FreeSWITCH-IPv4: 185.249.40.236
FreeSWITCH-IPv6: %3A%3A1
Event-Date-Local: 2022-12-16%2011%3A39%3A39
Event-Date-GMT: Fri,%2016%20Dec%202022%2010%3A39%3A39%20GMT
Event-Date-Timestamp: 1671187179207467
Event-Calling-File: mod_dialplan_xml.c
Event-Calling-Function: dialplan_xml_locate
Event-Calling-Line-Number: 608
Event-Sequence: 639996
Channel-State: CS_ROUTING
Channel-Call-State: RINGING
Channel-State-Number: 2
Channel-Name: sofia/default/1000%40141826.yoursipcloud.com
Unique-ID: d03b5e8f-d15c-4cc9-910e-3bcca4ed1d57
Call-Direction: inbound
Presence-Call-Direction: inbound
Channel-HIT-Dialplan: true
Channel-Presence-ID: 1000%40141826.yoursipcloud.com
Channel-Call-UUID: d03b5e8f-d15c-4cc9-910e-3bcca4ed1d57
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: 1000
Caller-Dialplan: XML
Caller-Caller-ID-Name: 1000
Caller-Caller-ID-Number: 1000
Caller-Orig-Caller-ID-Name: 1000
Caller-Orig-Caller-ID-Number: 1000
Caller-Network-Addr: 10.0.192.5
Caller-ANI: 1000
Caller-Destination-Number: 319000980
Caller-Unique-ID: d03b5e8f-d15c-4cc9-910e-3bcca4ed1d57
Caller-Source: mod_sofia
Caller-Context: default
Caller-Channel-Name: sofia/default/1000%40141826.yoursipcloud.com
Caller-Profile-Index: 1
Caller-Profile-Created-Time: 1671187179207467
Caller-Channel-Created-Time: 1671187179207467
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: d03b5e8f-d15c-4cc9-910e-3bcca4ed1d57
variable_session_id: 7227
variable_sip_from_params: transport%3DTCP
variable_sip_from_user: 1000
variable_sip_from_uri: 1000%40141826.yoursipcloud.com
variable_sip_from_host: 141826.yoursipcloud.com
variable_video_media_flow: disabled
variable_text_media_flow: disabled
variable_channel_name: sofia/default/1000%40141826.yoursipcloud.com
variable_sip_call_id: sy9uprcDM_cz0vTQk5WNrQ..
variable_sip_local_network_addr: 185.249.40.236
variable_sip_network_ip: 10.0.192.5
variable_sip_network_port: 57036
variable_sip_invite_stamp: 1671187179207467
variable_sip_received_ip: 10.0.192.5
variable_sip_received_port: 57036
variable_sip_via_protocol: tcp
variable_sip_authorized: true
variable_Event-Name: REQUEST_PARAMS
variable_Core-UUID: c687ffac-2ae7-42f3-9408-8ea92987c97f
variable_FreeSWITCH-Hostname: multitenant-1
variable_FreeSWITCH-Switchname: multitenant-1
variable_FreeSWITCH-IPv4: 185.249.40.236
variable_FreeSWITCH-IPv6: %3A%3A1
variable_Event-Date-Local: 2022-12-16%2011%3A39%3A39
variable_Event-Date-GMT: Fri,%2016%20Dec%202022%2010%3A39%3A39%20GMT
variable_Event-Date-Timestamp: 1671187179207467
variable_Event-Calling-File: sofia.c
variable_Event-Calling-Function: sofia_handle_sip_i_invite
variable_Event-Calling-Line-Number: 10542
variable_Event-Sequence: 639987
variable_sip_number_alias: 1000
variable_sip_auth_username: 1000
variable_sip_auth_realm: 141826.yoursipcloud.com
variable_number_alias: 1000
variable_requested_user_name: 1000
variable_requested_domain_name: 141826.yoursipcloud.com
variable_user_context: default
variable_sipcall: true
variable_sip_user: 1000
variable_accountcode: 141826
variable_user_name: 1000
variable_domain_name: 141826.yoursipcloud.com
variable_sip_from_user_stripped: 1000
variable_sip_from_tag: ec253225
variable_sofia_profile_name: default
variable_sofia_profile_url: sip%3Amod_sofia%40185.249.40.236%3A5060
variable_recovery_profile_name: default
variable_sip_full_via: SIP/2.0/TCP%2010.0.192.5%3A59984%3Bbranch%3Dz9hG4bK-524287-1---eb12fc7a2defd632%3Brport%3D57036
variable_sip_recover_via: SIP/2.0/TCP%2010.0.192.5%3A59984%3Bbranch%3Dz9hG4bK-524287-1---eb12fc7a2defd632%3Brport%3D57036
variable_sip_full_from: %3Csip%3A1000%40141826.yoursipcloud.com%3Btransport%3DTCP%3E%3Btag%3Dec253225
variable_sip_full_to: %3Csip%3A319000980%40141826.yoursipcloud.com%3E
variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20BYE,%20NOTIFY,%20REFER,%20MESSAGE,%20OPTIONS,%20INFO,%20SUBSCRIBE
variable_sip_req_params: transport%3DTCP
variable_sip_req_user: 319000980
variable_sip_req_uri: 319000980%40141826.yoursipcloud.com
variable_sip_req_host: 141826.yoursipcloud.com
variable_sip_to_user: 319000980
variable_sip_to_uri: 319000980%40141826.yoursipcloud.com
variable_sip_to_host: 141826.yoursipcloud.com
variable_sip_contact_params: transport%3Dtcp
variable_sip_contact_user: 1000
variable_sip_contact_port: 59984
variable_sip_contact_uri: 1000%4010.0.192.5%3A59984
variable_sip_contact_host: 10.0.192.5
variable_sip_user_agent: Z%205.5.13%20v2.10.18.3
variable_sip_via_host: 10.0.192.5
variable_sip_via_port: 59984
variable_sip_via_rport: 57036
variable_max_forwards: 70
variable_presence_id: 1000%40141826.yoursipcloud.com
variable_sip_nat_detected: true
variable_switch_r_sdp: v%3D0%0D%0Ao%3DZ%200%20414235312%20IN%20IP4%2010.0.192.5%0D%0As%3DZ%0D%0Ac%3DIN%20IP4%2010.0.192.5%0D%0At%3D0%200%0D%0Am%3Daudio%2061033%20RTP/AVP%20106%209%2098%20101%200%208%203%0D%0Aa%3Drtpmap%3A106%20opus/48000/2%0D%0Aa%3Dfmtp%3A106%20sprop-maxcapturerate%3D16000%3B%20minptime%3D20%3B%20useinbandfec%3D1%0D%0Aa%3Drtpmap%3A98%20telephone-event/48000%0D%0Aa%3Dfmtp%3A98%200-16%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0A
variable_ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%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: 10.0.192.5
variable_remote_media_port: 61033
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: d03b5e8f-d15c-4cc9-910e-3bcca4ed1d57
Hunt-Direction: inbound
Hunt-Logical-Direction: inbound
Hunt-Username: 1000
Hunt-Dialplan: XML
Hunt-Caller-ID-Name: 1000
Hunt-Caller-ID-Number: 1000
Hunt-Orig-Caller-ID-Name: 1000
Hunt-Orig-Caller-ID-Number: 1000
Hunt-Network-Addr: 10.0.192.5
Hunt-ANI: 1000
Hunt-Destination-Number: 319000980
Hunt-Unique-ID: d03b5e8f-d15c-4cc9-910e-3bcca4ed1d57
Hunt-Source: mod_sofia
Hunt-Context: default
Hunt-Channel-Name: sofia/default/1000%40141826.yoursipcloud.com
Hunt-Profile-Index: 1
Hunt-Profile-Created-Time: 1671187179207467
Hunt-Channel-Created-Time: 1671187179207467
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


2022-12-16 11:39:39.247381 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] key_value: 
2022-12-16 11:39:39.247381 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] section: dialplan
2022-12-16 11:39:39.247381 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] tag_name: 
2022-12-16 11:39:39.247381 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] key_name: 
2022-12-16 11:39:39.247381 [INFO] switch_cpp.cpp:1447 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
2022-12-16 11:39:39.247381 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Dialed number : 319000980
2022-12-16 11:39:39.247381 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '319000980' limit 1
2022-12-16 11:39:39.247381 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '319000980' AND status=0 limit 1
2022-12-16 11:39:39.247381 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '319000980' and status=0 LIMIT 1
2022-12-16 11:39:39.247381 [WARNING] switch_cpp.cpp:1447 [ASTPP] [FAXER] Custom Caller ID 
2022-12-16 11:39:39.247381 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Caller Id name / number  : 1000 / 1000
2022-12-16 11:39:39.247381 [DEBUG] switch_cpp.cpp:1447 [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 ="319000980" LIMIT 1
2022-12-16 11:39:39.247381 [DEBUG] switch_cpp.cpp:1447 [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="319000980"  AND domains.domain="141826.yoursipcloud.com" limit 1
2022-12-16 11:39:39.247381 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Call direction : outbound
2022-12-16 11:39:39.247381 [INFO] switch_cpp.cpp:1447 [ASTPP] [Accountcode : 141826]
2022-12-16 11:39:39.247381 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "141826" AND deleted = 0 limit 1
2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^319000980.*' OR blocked_patterns = '^31900098.*' OR blocked_patterns = '^3190009.*' OR blocked_patterns = '^319000.*' OR blocked_patterns = '^31900.*' OR blocked_patterns = '^3190.*' OR blocked_patterns = '^319.*' OR blocked_patterns = '^31.*' OR blocked_patterns = '^3.*' OR blocked_patterns ='--') AND accountid = 1178 limit 1 
2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="319000980" AND (accountid = 0 OR status = 1) LIMIT 1
2022-12-16 11:39:39.267383 [NOTICE] switch_cpp.cpp:1447 [ASTPP] Localization Id: 1
2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [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 
2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_LOCALIZATION] Query :"0/31,+/".....319000980
2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_LOCALIZATION] Query :"0/31,+/".....319000980
2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [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 = '^319000980.*' OR patterns = '^31900098.*' OR patterns = '^3190009.*' OR patterns = '^319000.*' OR patterns = '^31900.*' OR patterns = '^3190.*' OR patterns = '^319.*' OR patterns = '^31.*' OR patterns = '^3.*' OR patterns ='--') AND accountid = 1178 ORDER BY LENGTH(PKGPTR.patterns) DESC
2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [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 ="319000980" AND A.accountid = '1178' limit 1
2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 319000980
2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [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 ="319000980" LIMIT 1
2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [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="319000980"  AND domains.domain="141826.yoursipcloud.com" limit 1
2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] New Call Direction : outbound
2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '319000980' OR number = '31900098' OR number = '3190009' OR number = '319000' OR number = '31900' OR number = '3190' OR number = '319' OR number = '31' OR number = '3' OR number ='--') OR (number = '319000980*' OR number = '31900098*' OR number = '3190009*' OR number = '319000*' OR number = '31900*' OR number = '3190*' OR number = '319*' OR number = '31*' OR number = '3*' OR number ='--') OR (number = '1000' OR number = '100' OR number = '10' OR number = '1' OR number ='--') OR (number = '1000*' OR number = '100*' OR number = '10*' OR number = '1*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Call Barring Status : -1
2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '319000980' OR routing_prefix = '31900098' OR routing_prefix = '3190009' OR routing_prefix = '319000' OR routing_prefix = '31900' OR routing_prefix = '3190' OR routing_prefix = '319' OR routing_prefix = '31' OR routing_prefix = '3' OR routing_prefix ='--') AND status = 0 and reseller_id = 12 limit 1
2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Account Information ===================
2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] User id : 1178
2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] Account code : 141826
2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] Balance : 95.05
2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] Ratecard id : 6
2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] CID Pool : 0
2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] ========================================================
2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 6 AND status = 0
2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] call_direction :outbound
2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 6 AND status = 0
2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^319000980.*' OR pattern = '^31900098.*' OR pattern = '^3190009.*' OR pattern = '^319000.*' OR pattern = '^31900.*' OR pattern = '^3190.*' OR pattern = '^319.*' OR pattern = '^31.*' OR pattern = '^3.*' OR pattern ='--') AND status = 0 AND effective_date <= UTC_TIMESTAMP() AND (pricelist_id = 6 OR accountid=1178)  ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC,effective_date DESC LIMIT 1
2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP]  Call Direction : outbound
2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Rates Information ===================
2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] ID : 3742429
2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] Connectcost : 0.07200
2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] Includedseconds : 1
2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] Cost : 0.05400
2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] Comment : Netherland Special
2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] Country Id : 136
2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] Accid : 1178
2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] Trunk ID: 
2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] Routing type: 
2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] ================================================================
2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Your95.05 balance Accountid 1178 !!!
2022-12-16 11:39:39.267383 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Limiting Call To Config Max Length 100 mins!
2022-12-16 11:39:39.267383 [NOTICE] switch_cpp.cpp:1447 [ASTPP] FINDING LIMIT FOR RESELLER: 12
2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "12" AND deleted = 0 limit 1
2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^319000980.*' OR blocked_patterns = '^31900098.*' OR blocked_patterns = '^3190009.*' OR blocked_patterns = '^319000.*' OR blocked_patterns = '^31900.*' OR blocked_patterns = '^3190.*' OR blocked_patterns = '^319.*' OR blocked_patterns = '^31.*' OR blocked_patterns = '^3.*' OR blocked_patterns ='--') AND accountid = 12 limit 1 
2022-12-16 11:39:39.287387 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Reseller Information ===================
2022-12-16 11:39:39.287387 [INFO] switch_cpp.cpp:1447 [ASTPP] User id : 12
2022-12-16 11:39:39.287387 [INFO] switch_cpp.cpp:1447 [ASTPP] Account code : 3847299300
2022-12-16 11:39:39.287387 [INFO] switch_cpp.cpp:1447 [ASTPP] Balance : 98925.58531
2022-12-16 11:39:39.287387 [INFO] switch_cpp.cpp:1447 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
2022-12-16 11:39:39.287387 [INFO] switch_cpp.cpp:1447 [ASTPP] Ratecard id : 44
2022-12-16 11:39:39.287387 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 44 AND status = 0
2022-12-16 11:39:39.287387 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] call_direction :outbound
2022-12-16 11:39:39.287387 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 44 AND status = 0
2022-12-16 11:39:39.287387 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^319000980.*' OR pattern = '^31900098.*' OR pattern = '^3190009.*' OR pattern = '^319000.*' OR pattern = '^31900.*' OR pattern = '^3190.*' OR pattern = '^319.*' OR pattern = '^31.*' OR pattern = '^3.*' OR pattern ='--') AND status = 0 AND effective_date <= UTC_TIMESTAMP() AND (pricelist_id = 44 OR accountid=12)  ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC,effective_date DESC LIMIT 1
2022-12-16 11:39:39.287387 [INFO] switch_cpp.cpp:1447 [ASTPP]  Call Direction : outbound
2022-12-16 11:39:39.287387 [WARNING] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Rates Not Found : 
2022-12-16 11:39:39.287387 [DEBUG] switch_cpp.cpp:1447 [ASTPP]  Post Cdrs : 0
2022-12-16 11:39:39.287387 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET RESELLERID] Query :SELECT reseller_id FROM accounts WHERE id = 1178
2022-12-16 11:39:39.287387 [DEBUG] switch_cpp.cpp:1447 [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="319000980">
<condition field="destination_number" expression="319000980">
<action application="log" data="WARNING Accountcode 141826. Dialed number (319000980)  origination rates not found!!"/>
<action application="set" data="original_caller_id_name=1000"/>
<action application="set" data="original_caller_id_number=1000"/>
<action application="set" data="error_cdr=1"/>
<action application="set" data="callstart=2022-12-16 10:39:39"/>
<action application="set" data="account_id=1178"/>
<action application="set" data="parent_id=12"/>
<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=319000980"/>
<action application="set" data="last_bridge_hangup_cause=ORIGINATION_RATE_NOT_FOUND"/>
<action application="hangup" data="ORIGINATION_RATE_NOT_FOUND"/>
</condition>
</extension>
</context>
</section>
</document>
Dialplan: sofia/default/1000@141826.yoursipcloud.com parsing [default->319000980] continue=false
Dialplan: sofia/default/1000@141826.yoursipcloud.com Regex (PASS) [319000980] destination_number(319000980) =~ /319000980/ break=on-false
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action log(WARNING Accountcode 141826. Dialed number (319000980)  origination rates not found!!) 
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(original_caller_id_name=1000) 
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(original_caller_id_number=1000) 
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(error_cdr=1) 
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(callstart=2022-12-16 10:39:39) 
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(account_id=1178) 
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(parent_id=12) 
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(call_direction=outbound) 
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(sip_ignore_remote_cause=true) 
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(call_processed=internal) 
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(effective_destination_number=319000980) 
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(last_bridge_hangup_cause=ORIGINATION_RATE_NOT_FOUND) 
Dialplan: sofia/default/1000@141826.yoursipcloud.com Action hangup(ORIGINATION_RATE_NOT_FOUND) 
2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:287 (sofia/default/1000@141826.yoursipcloud.com) State Change CS_ROUTING -> CS_EXECUTE
2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:644 (sofia/default/1000@141826.yoursipcloud.com) State ROUTING going to sleep
2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:585 (sofia/default/1000@141826.yoursipcloud.com) Running State Change CS_EXECUTE (Cur 27 Tot 7227)
2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:651 (sofia/default/1000@141826.yoursipcloud.com) State EXECUTE
2022-12-16 11:39:39.287387 [DEBUG] mod_sofia.c:209 sofia/default/1000@141826.yoursipcloud.com SOFIA EXECUTE
2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:329 sofia/default/1000@141826.yoursipcloud.com Standard EXECUTE
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com log(WARNING Accountcode 141826. Dialed number (319000980)  origination rates not found!!)
2022-12-16 11:39:39.287387 [WARNING] mod_dptools.c:1866 Accountcode 141826. Dialed number (319000980)  origination rates not found!!
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(original_caller_id_name=1000)
2022-12-16 11:39:39.287387 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [original_caller_id_name]=[1000]
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(original_caller_id_number=1000)
2022-12-16 11:39:39.287387 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [original_caller_id_number]=[1000]
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(error_cdr=1)
2022-12-16 11:39:39.287387 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [error_cdr]=[1]
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(callstart=2022-12-16 10:39:39)
2022-12-16 11:39:39.287387 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [callstart]=[2022-12-16 10:39:39]
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(account_id=1178)
2022-12-16 11:39:39.287387 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [account_id]=[1178]
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(parent_id=12)
2022-12-16 11:39:39.287387 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [parent_id]=[12]
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(call_direction=outbound)
2022-12-16 11:39:39.287387 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [call_direction]=[outbound]
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(sip_ignore_remote_cause=true)
2022-12-16 11:39:39.287387 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [sip_ignore_remote_cause]=[true]
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(call_processed=internal)
2022-12-16 11:39:39.287387 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [call_processed]=[internal]
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(effective_destination_number=319000980)
2022-12-16 11:39:39.287387 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [effective_destination_number]=[319000980]
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(last_bridge_hangup_cause=ORIGINATION_RATE_NOT_FOUND)
2022-12-16 11:39:39.287387 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [last_bridge_hangup_cause]=[ORIGINATION_RATE_NOT_FOUND]
EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com hangup(ORIGINATION_RATE_NOT_FOUND)
2022-12-16 11:39:39.287387 [NOTICE] mod_dptools.c:1380 Hangup sofia/default/1000@141826.yoursipcloud.com [CS_EXECUTE] [NORMAL_CLEARING]
2022-12-16 11:39:39.287387 [DEBUG] switch_core_session.c:2905 sofia/default/1000@141826.yoursipcloud.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:651 (sofia/default/1000@141826.yoursipcloud.com) State EXECUTE going to sleep
2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:585 (sofia/default/1000@141826.yoursipcloud.com) Running State Change CS_HANGUP (Cur 27 Tot 7227)
2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:848 (sofia/default/1000@141826.yoursipcloud.com) Callstate Change RINGING -> HANGUP
2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:850 (sofia/default/1000@141826.yoursipcloud.com) State HANGUP
2022-12-16 11:39:39.287387 [DEBUG] mod_sofia.c:453 Channel sofia/default/1000@141826.yoursipcloud.com hanging up, cause: NORMAL_CLEARING
2022-12-16 11:39:39.287387 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 480
2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:60 sofia/default/1000@141826.yoursipcloud.com Standard HANGUP, cause: NORMAL_CLEARING
2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:850 (sofia/default/1000@141826.yoursipcloud.com) State HANGUP going to sleep
2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:620 (sofia/default/1000@141826.yoursipcloud.com) State Change CS_HANGUP -> CS_REPORTING
2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:585 (sofia/default/1000@141826.yoursipcloud.com) Running State Change CS_REPORTING (Cur 27 Tot 7227)
2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:936 (sofia/default/1000@141826.yoursipcloud.com) State REPORTING
2022-12-16 11:39:39.287387 [INFO] mod_json_cdr.c:271 Process [d03b5e8f-d15c-4cc9-910e-3bcca4ed1d57.cdr.json]
2022-12-16 11:39:39.307380 [DEBUG] switch_core_state_machine.c:174 sofia/default/1000@141826.yoursipcloud.com Standard REPORTING, cause: NORMAL_CLEARING
2022-12-16 11:39:39.307380 [DEBUG] switch_core_state_machine.c:936 (sofia/default/1000@141826.yoursipcloud.com) State REPORTING going to sleep
2022-12-16 11:39:39.307380 [DEBUG] switch_core_state_machine.c:611 (sofia/default/1000@141826.yoursipcloud.com) State Change CS_REPORTING -> CS_DESTROY
2022-12-16 11:39:39.307380 [DEBUG] switch_core_session.c:1726 Session 7227 (sofia/default/1000@141826.yoursipcloud.com) Locked, Waiting on external entities
2022-12-16 11:39:39.307380 [NOTICE] switch_core_session.c:1744 Session 7227 (sofia/default/1000@141826.yoursipcloud.com) Ended
2022-12-16 11:39:39.307380 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/1000@141826.yoursipcloud.com [CS_DESTROY]
2022-12-16 11:39:39.307380 [DEBUG] switch_core_state_machine.c:739 (sofia/default/1000@141826.yoursipcloud.com) Running State Change CS_DESTROY (Cur 26 Tot 7227)
2022-12-16 11:39:39.307380 [DEBUG] switch_core_state_machine.c:749 (sofia/default/1000@141826.yoursipcloud.com) State DESTROY
2022-12-16 11:39:39.307380 [DEBUG] mod_sofia.c:364 sofia/default/1000@141826.yoursipcloud.com SOFIA DESTROY
2022-12-16 11:39:39.307380 [DEBUG] switch_core_state_machine.c:181 sofia/default/1000@141826.yoursipcloud.com Standard DESTROY
2022-12-16 11:39:39.307380 [DEBUG] switch_core_state_machine.c:749 (sofia/default/1000@141826.yoursipcloud.com) State DESTROY going to sleep
2022-12-16 11:39:42.107379 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [202@00187517.yoursipcloud.com] from ip 89.255.40.6
2022-12-16 11:39:42.327386 [NOTICE] sofia.c:1089 Hangup sofia/default/205@192.168.178.20:5060 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2022-12-16 11:39:42.327386 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/205@192.168.178.20:5060]
2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:654 (sofia/default/205@192.168.178.20:5060) State EXCHANGE_MEDIA going to sleep
2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:585 (sofia/default/205@192.168.178.20:5060) Running State Change CS_HANGUP (Cur 26 Tot 7227)
2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:848 (sofia/default/205@192.168.178.20:5060) Callstate Change ACTIVE -> HANGUP
2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:850 (sofia/default/205@192.168.178.20:5060) State HANGUP
2022-12-16 11:39:42.327386 [DEBUG] mod_sofia.c:453 Channel sofia/default/205@192.168.178.20:5060 hanging up, cause: NORMAL_CLEARING
2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:60 sofia/default/205@192.168.178.20:5060 Standard HANGUP, cause: NORMAL_CLEARING
2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:850 (sofia/default/205@192.168.178.20:5060) State HANGUP going to sleep
2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:620 (sofia/default/205@192.168.178.20:5060) State Change CS_HANGUP -> CS_REPORTING
2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:585 (sofia/default/205@192.168.178.20:5060) Running State Change CS_REPORTING (Cur 26 Tot 7227)
2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:936 (sofia/default/205@192.168.178.20:5060) State REPORTING
2022-12-16 11:39:42.327386 [INFO] mod_json_cdr.c:271 Process [e6084f90-64c3-4a47-9731-977030d49c3d.cdr.json]
2022-12-16 11:39:42.327386 [DEBUG] switch_ivr_bridge.c:820 sofia/default/205@192.168.178.20:5060 ending bridge by request from write function
2022-12-16 11:39:42.327386 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/31628471807@185.249.40.253]
2022-12-16 11:39:42.327386 [DEBUG] switch_ivr_bridge.c:1892 sofia/default/205@192.168.178.20:5060 skip receive message [UNBRIDGE] (channel is hungup already)
2022-12-16 11:39:42.327386 [NOTICE] switch_ivr_bridge.c:1944 Hangup sofia/default/31628471807@185.249.40.253 [CS_EXECUTE] [NORMAL_CLEARING]
2022-12-16 11:39:42.327386 [DEBUG] switch_core_session.c:2905 sofia/default/31628471807@185.249.40.253 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:651 (sofia/default/31628471807@185.249.40.253) State EXECUTE going to sleep
2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31628471807@185.249.40.253) Running State Change CS_HANGUP (Cur 26 Tot 7227)
2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:848 (sofia/default/31628471807@185.249.40.253) Callstate Change ACTIVE -> HANGUP
2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:850 (sofia/default/31628471807@185.249.40.253) State HANGUP
2022-12-16 11:39:42.327386 [DEBUG] mod_sofia.c:447 sofia/default/31628471807@185.249.40.253 Overriding SIP cause 480 with 200 from the other leg
2022-12-16 11:39:42.327386 [DEBUG] mod_sofia.c:453 Channel sofia/default/31628471807@185.249.40.253 hanging up, cause: NORMAL_CLEARING
2022-12-16 11:39:42.327386 [DEBUG] mod_sofia.c:507 Sending BYE to sofia/default/31628471807@185.249.40.253
2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:60 sofia/default/31628471807@185.249.40.253 Standard HANGUP, cause: NORMAL_CLEARING
2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:850 (sofia/default/31628471807@185.249.40.253) State HANGUP going to sleep
2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:620 (sofia/default/31628471807@185.249.40.253) State Change CS_HANGUP -> CS_REPORTING
2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31628471807@185.249.40.253) Running State Change CS_REPORTING (Cur 26 Tot 7227)
2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:936 (sofia/default/31628471807@185.249.40.253) State REPORTING
2022-12-16 11:39:42.327386 [INFO] mod_json_cdr.c:271 Process [09f1ec01-7fab-4d4d-b46e-ee100039709d.cdr.json]
2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:174 sofia/default/205@192.168.178.20:5060 Standard REPORTING, cause: NORMAL_CLEARING
2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:936 (sofia/default/205@192.168.178.20:5060) State REPORTING going to sleep
2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:611 (sofia/default/205@192.168.178.20:5060) State Change CS_REPORTING -> CS_DESTROY
2022-12-16 11:39:42.347383 [DEBUG] switch_core_session.c:1726 Session 7079 (sofia/default/205@192.168.178.20:5060) Locked, Waiting on external entities
2022-12-16 11:39:42.347383 [NOTICE] switch_core_session.c:1744 Session 7079 (sofia/default/205@192.168.178.20:5060) Ended
2022-12-16 11:39:42.347383 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/205@192.168.178.20:5060 [CS_DESTROY]
2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:739 (sofia/default/205@192.168.178.20:5060) Running State Change CS_DESTROY (Cur 25 Tot 7227)
2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:749 (sofia/default/205@192.168.178.20:5060) State DESTROY
2022-12-16 11:39:42.347383 [DEBUG] mod_sofia.c:364 sofia/default/205@192.168.178.20:5060 SOFIA DESTROY
2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:181 sofia/default/205@192.168.178.20:5060 Standard DESTROY
2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:749 (sofia/default/205@192.168.178.20:5060) State DESTROY going to sleep
2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:174 sofia/default/31628471807@185.249.40.253 Standard REPORTING, cause: NORMAL_CLEARING
2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:936 (sofia/default/31628471807@185.249.40.253) State REPORTING going to sleep
2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:611 (sofia/default/31628471807@185.249.40.253) State Change CS_REPORTING -> CS_DESTROY
2022-12-16 11:39:42.347383 [DEBUG] switch_core_session.c:1726 Session 7078 (sofia/default/31628471807@185.249.40.253) Locked, Waiting on external entities
2022-12-16 11:39:42.347383 [NOTICE] switch_core_session.c:1744 Session 7078 (sofia/default/31628471807@185.249.40.253) Ended
2022-12-16 11:39:42.347383 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/31628471807@185.249.40.253 [CS_DESTROY]
2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:739 (sofia/default/31628471807@185.249.40.253) Running State Change CS_DESTROY (Cur 24 Tot 7227)
2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:749 (sofia/default/31628471807@185.249.40.253) State DESTROY
2022-12-16 11:39:42.347383 [DEBUG] mod_sofia.c:364 sofia/default/31628471807@185.249.40.253 SOFIA DESTROY
2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:181 sofia/default/31628471807@185.249.40.253 Standard DESTROY
2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:749 (sofia/default/31628471807@185.249.40.253) State DESTROY going to sleep
2022-12-16 11:39:42.687383 [DEBUG] switch_scheduler.c:147 Deleting task 1332 switch_ivr_schedule_hangup (e6084f90-64c3-4a47-9731-977030d49c3d)
2022-12-16 11:39:45.207441 [NOTICE] switch_channel.c:1118 New Channel sofia/default/183284@213.144.239.110:9933 [ff79c902-8124-4495-bf0a-c7efe83e3e6a]
2022-12-16 11:39:45.207441 [DEBUG] switch_core_state_machine.c:585 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_NEW (Cur 25 Tot 7228)
2022-12-16 11:39:45.207441 [DEBUG] sofia.c:10280 sofia/default/183284@213.144.239.110:9933 receiving invite from 213.144.239.110:9933 version: 1.10.5 -release-17-25569c1631 64bit
2022-12-16 11:39:45.207441 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 213.144.239.110:0.
2022-12-16 11:39:45.207441 [DEBUG] sofia.c:10403 IP 213.144.239.110 Approved by acl "default[]". Access Granted.
2022-12-16 11:39:45.207441 [DEBUG] sofia.c:7326 Channel sofia/default/183284@213.144.239.110:9933 entering state [received][100]
2022-12-16 11:39:45.207441 [DEBUG] sofia.c:7336 Remote SDP:
v=0
o=root 1803226258 1803226258 IN IP4 213.144.239.110
s=Asterisk PBX 11.4.0
c=IN IP4 213.144.239.110
t=0 0
m=audio 12472 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

2022-12-16 11:39:45.207441 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2022-12-16 11:39:45.207441 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2022-12-16 11:39:45.207441 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2022-12-16 11:39:45.207441 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2022-12-16 11:39:45.207441 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2022-12-16 11:39:45.207441 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2022-12-16 11:39:45.207441 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
2022-12-16 11:39:45.207441 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/183284@213.144.239.110:9933 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
2022-12-16 11:39:45.207441 [DEBUG] switch_core_codec.c:111 sofia/default/183284@213.144.239.110:9933 Original read codec set to PCMU:0
2022-12-16 11:39:45.207441 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
2022-12-16 11:39:45.207441 [DEBUG] switch_core_media.c:5911 sofia/default/183284@213.144.239.110:9933 Set 2833 dtmf send payload to 101 recv payload to 101
2022-12-16 11:39:45.207441 [DEBUG] sofia.c:7760 (sofia/default/183284@213.144.239.110:9933) State Change CS_NEW -> CS_INIT
2022-12-16 11:39:45.207441 [DEBUG] switch_core_state_machine.c:604 (sofia/default/183284@213.144.239.110:9933) State NEW
2022-12-16 11:39:45.207441 [DEBUG] switch_core_state_machine.c:585 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_INIT (Cur 25 Tot 7228)
2022-12-16 11:39:45.207441 [DEBUG] switch_core_state_machine.c:628 (sofia/default/183284@213.144.239.110:9933) State INIT
2022-12-16 11:39:45.207441 [DEBUG] mod_sofia.c:93 sofia/default/183284@213.144.239.110:9933 SOFIA INIT
2022-12-16 11:39:45.207441 [DEBUG] switch_core_state_machine.c:40 sofia/default/183284@213.144.239.110:9933 Standard INIT
2022-12-16 11:39:45.207441 [DEBUG] switch_core_state_machine.c:48 (sofia/default/183284@213.144.239.110:9933) State Change CS_INIT -> CS_ROUTING
2022-12-16 11:39:45.207441 [DEBUG] switch_core_state_machine.c:628 (sofia/default/183284@213.144.239.110:9933) State INIT going to sleep
2022-12-16 11:39:45.207441 [DEBUG] switch_core_state_machine.c:585 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_ROUTING (Cur 25 Tot 7228)
2022-12-16 11:39:45.207441 [DEBUG] switch_channel.c:2332 (sofia/default/183284@213.144.239.110:9933) Callstate Change DOWN -> RINGING
2022-12-16 11:39:45.207441 [DEBUG] switch_core_state_machine.c:644 (sofia/default/183284@213.144.239.110:9933) State ROUTING
2022-12-16 11:39:45.207441 [DEBUG] mod_sofia.c:154 sofia/default/183284@213.144.239.110:9933 SOFIA ROUTING
2022-12-16 11:39:45.207441 [DEBUG] switch_core_state_machine.c:236 sofia/default/183284@213.144.239.110:9933 Standard ROUTING
2022-12-16 11:39:45.207441 [INFO] mod_dialplan_xml.c:637 Processing 31702007090 <31702007090>->31613388584 in context default
2022-12-16 11:39:45.207441 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
2022-12-16 11:39:45.207441 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
2022-12-16 11:39:45.207441 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [xml_handler] Params:
Event-Name: REQUEST_PARAMS
Core-UUID: c687ffac-2ae7-42f3-9408-8ea92987c97f
FreeSWITCH-Hostname: multitenant-1
FreeSWITCH-Switchname: multitenant-1
FreeSWITCH-IPv4: 185.249.40.236
FreeSWITCH-IPv6: %3A%3A1
Event-Date-Local: 2022-12-16%2011%3A39%3A45
Event-Date-GMT: Fri,%2016%20Dec%202022%2010%3A39%3A45%20GMT
Event-Date-Timestamp: 1671187185207441
Event-Calling-File: mod_dialplan_xml.c
Event-Calling-Function: dialplan_xml_locate
Event-Calling-Line-Number: 608
Event-Sequence: 640072
Channel-State: CS_ROUTING
Channel-Call-State: RINGING
Channel-State-Number: 2
Channel-Name: sofia/default/183284%40213.144.239.110%3A9933
Unique-ID: ff79c902-8124-4495-bf0a-c7efe83e3e6a
Call-Direction: inbound
Presence-Call-Direction: inbound
Channel-HIT-Dialplan: true
Channel-Presence-ID: 183284%40213.144.239.110
Channel-Call-UUID: ff79c902-8124-4495-bf0a-c7efe83e3e6a
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: 31702007090
Caller-Dialplan: XML
Caller-Caller-ID-Name: 31702007090
Caller-Caller-ID-Number: 31702007090
Caller-Orig-Caller-ID-Name: 31702007090
Caller-Orig-Caller-ID-Number: 31702007090
Caller-Network-Addr: 213.144.239.110
Caller-ANI: 31702007090
Caller-Destination-Number: 31613388584
Caller-Unique-ID: ff79c902-8124-4495-bf0a-c7efe83e3e6a
Caller-Source: mod_sofia
Caller-Context: default
Caller-Channel-Name: sofia/default/183284%40213.144.239.110%3A9933
Caller-Profile-Index: 1
Caller-Profile-Created-Time: 1671187185207441
Caller-Channel-Created-Time: 1671187185207441
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: false
Caller-Privacy-Hide-Name: false
Caller-Privacy-Hide-Number: false
variable_direction: inbound
variable_uuid: ff79c902-8124-4495-bf0a-c7efe83e3e6a
variable_session_id: 7228
variable_sip_from_user: 183284
variable_sip_from_port: 9933
variable_sip_from_uri: 183284%40213.144.239.110%3A9933
variable_sip_from_host: 213.144.239.110
variable_video_media_flow: disabled
variable_text_media_flow: disabled
variable_channel_name: sofia/default/183284%40213.144.239.110%3A9933
variable_sip_call_id: 2e5842a2623b5d6b023c90d913bd0ae7%40213.144.239.110%3A9933
variable_sip_local_network_addr: 185.249.40.236
variable_sip_network_ip: 213.144.239.110
variable_sip_network_port: 9933
variable_sip_invite_stamp: 1671187185207441
variable_sip_received_ip: 213.144.239.110
variable_sip_received_port: 9933
variable_sip_via_protocol: udp
variable_sip_authorized: true
variable_sip_acl_authed_by: default
variable_sip_from_user_stripped: 183284
variable_sip_from_tag: as46cc564e
variable_sofia_profile_name: default
variable_sofia_profile_url: sip%3Amod_sofia%40185.249.40.236%3A5060
variable_recovery_profile_name: default
variable_sip_Remote-Party-ID: %2231702007090%22%20%3Csip%3A31702007090%40213.144.239.110%3E%3Bparty%3Dcalling%3Bprivacy%3Doff%3Bscreen%3Dno
variable_sip_cid_type: rpid
variable_sip_full_via: SIP/2.0/UDP%20213.144.239.110%3A9933%3Bbranch%3Dz9hG4bK57ac30e7%3Brport%3D9933
variable_sip_full_from: %3Csip%3A183284%40213.144.239.110%3A9933%3E%3Btag%3Das46cc564e
variable_sip_full_to: %3Csip%3A31613388584%40emolife138.yoursipcloud.com%3E
variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20OPTIONS,%20BYE,%20REFER,%20SUBSCRIBE,%20NOTIFY,%20INFO,%20PUBLISH
variable_sip_req_user: 31613388584
variable_sip_req_uri: 31613388584%40emolife138.yoursipcloud.com
variable_sip_req_host: emolife138.yoursipcloud.com
variable_sip_to_user: 31613388584
variable_sip_to_uri: 31613388584%40emolife138.yoursipcloud.com
variable_sip_to_host: emolife138.yoursipcloud.com
variable_sip_contact_user: 183284
variable_sip_contact_port: 9933
variable_sip_contact_uri: 183284%40213.144.239.110%3A9933
variable_sip_contact_host: 213.144.239.110
variable_sip_user_agent: FPBX-2.11.0(11.4.0)
variable_sip_via_host: 213.144.239.110
variable_sip_via_port: 9933
variable_sip_via_rport: 9933
variable_max_forwards: 70
variable_presence_id: 183284%40213.144.239.110
variable_switch_r_sdp: v%3D0%0D%0Ao%3Droot%201803226258%201803226258%20IN%20IP4%20213.144.239.110%0D%0As%3DAsterisk%20PBX%2011.4.0%0D%0Ac%3DIN%20IP4%20213.144.239.110%0D%0At%3D0%200%0D%0Am%3Daudio%2012472%20RTP/AVP%200%208%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0Aa%3Dptime%3A20%0D%0A
variable_ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%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: 213.144.239.110
variable_remote_media_port: 12472
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: ff79c902-8124-4495-bf0a-c7efe83e3e6a
Hunt-Direction: inbound
Hunt-Logical-Direction: inbound
Hunt-Username: 31702007090
Hunt-Dialplan: XML
Hunt-Caller-ID-Name: 31702007090
Hunt-Caller-ID-Number: 31702007090
Hunt-Orig-Caller-ID-Name: 31702007090
Hunt-Orig-Caller-ID-Number: 31702007090
Hunt-Network-Addr: 213.144.239.110