2021-10-21 08:11:02.066665 [INFO] mod_dialplan_xml.c:637 Processing 442031501300 <442031501300>->00443453000101 in context default
2021-10-21 08:11:02.066665 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fd4240c7a70 Connected.
2021-10-21 08:11:02.066665 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
2021-10-21 08:11:02.066665 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
2021-10-21 08:11:02.066665 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [xml_handler] Params:
'Event-Name: REQUEST_PARAMS
Core-UUID: 383771d0-00bf-11ec-97b1-7dfb9f6e00f0
FreeSWITCH-Hostname: astpp1.local
FreeSWITCH-Switchname: astpp1.local
FreeSWITCH-IPv4: 89.46.223.193
FreeSWITCH-IPv6: %3A%3A1
Event-Date-Local: 2021-10-21%2008%3A11%3A02
Event-Date-GMT: Thu,%2021%20Oct%202021%2012%3A11%3A02%20GMT
Event-Date-Timestamp: 1634818262066665
Event-Calling-File: mod_dialplan_xml.c
Event-Calling-Function: dialplan_xml_locate
Event-Calling-Line-Number: 608
Event-Sequence: 20515436
Channel-State: CS_ROUTING
Channel-Call-State: RINGING
Channel-State-Number: 2
Channel-Name: sofia/default/442031501300%40212.199.161.206
Unique-ID: f5563380-3267-11ec-8918-7dfb9f6e00f0
Call-Direction: inbound
Presence-Call-Direction: inbound
Channel-HIT-Dialplan: true
Channel-Presence-ID: 442031501300%40212.199.161.206
Channel-Call-UUID: f5563380-3267-11ec-8918-7dfb9f6e00f0
Answer-State: ringing
Channel-Read-Codec-Name: PCMA
Channel-Read-Codec-Rate: 8000
Channel-Read-Codec-Bit-Rate: 64000
Channel-Write-Codec-Name: PCMA
Channel-Write-Codec-Rate: 8000
Channel-Write-Codec-Bit-Rate: 64000
Caller-Direction: inbound
Caller-Logical-Direction: inbound
Caller-Username: 442031501300
Caller-Dialplan: XML
Caller-Caller-ID-Name: 442031501300
Caller-Caller-ID-Number: 442031501300
Caller-Orig-Caller-ID-Name: 442031501300
Caller-Orig-Caller-ID-Number: 442031501300
Caller-Network-Addr: 136.243.196.198
Caller-ANI: 442031501300
Caller-Destination-Number: 00443453000101
Caller-Unique-ID: f5563380-3267-11ec-8918-7dfb9f6e00f0
Caller-Source: mod_sofia
Caller-Context: default
Caller-Channel-Name: sofia/default/442031501300%40212.199.161.206
Caller-Profile-Index: 1
Caller-Profile-Created-Time: 1634818262066665
Caller-Channel-Created-Time: 1634818262066665
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: f5563380-3267-11ec-8918-7dfb9f6e00f0
variable_session_id: 727135
variable_sip_from_user: 442031501300
variable_sip_from_uri: 442031501300%40212.199.161.206
variable_sip_from_host: 212.199.161.206
variable_video_media_flow: sendrecv
variable_channel_name: sofia/default/442031501300%40212.199.161.206
variable_sip_call_id: 3d2dc31b1eb18d8c7d9567db0a103cc5%40212.199.161.206%3A5060
variable_ep_codec_string: CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,mod_g729.G729%408000h%4020i%408000b
variable_sip_local_network_addr: 89.46.223.193
variable_sip_network_ip: 136.243.196.198
variable_sip_network_port: 5060
variable_sip_invite_stamp: 1634818262066665
variable_sip_received_ip: 136.243.196.198
variable_sip_received_port: 5060
variable_sip_via_protocol: udp
variable_sip_authorized: true
variable_sip_acl_authed_by: default
variable_sip_from_user_stripped: 442031501300
variable_sip_from_tag: as7d0e3ac6
variable_sofia_profile_name: default
variable_recovery_profile_name: default
variable_sip_invite_route_uri: %3Csip%3A136.243.196.198%3Blr%3Bdid%3De22.b2579746%3E
variable_sip_invite_record_route: %3Csip%3A136.243.196.198%3Blr%3Bdid%3De22.b2579746%3E
variable_sip_full_via: SIP/2.0/UDP%20136.243.196.198%3A5060%3Bbranch%3Dz9hG4bKdf0c.252d9292.0,SIP/2.0/UDP%20212.199.161.206%3A5060%3Breceived%3D212.199.161.206%3Bbranch%3Dz9hG4bK51c41089%3Brport%3D5060
variable_sip_from_display: 442031501300
variable_sip_full_from: %22442031501300%22%20%3Csip%3A442031501300%40212.199.161.206%3E%3Btag%3Das7d0e3ac6
variable_sip_full_to: %3Csip%3A00443453000101%40136.243.196.198%3E
variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20OPTIONS,%20BYE,%20REFER,%20SUBSCRIBE,%20NOTIFY,%20INFO,%20PUBLISH
variable_sip_req_user: 00443453000101
variable_sip_req_uri: 00443453000101%40136.243.196.198
variable_sip_req_host: 136.243.196.198
variable_sip_to_user: 00443453000101
variable_sip_to_uri: 00443453000101%40136.243.196.198
variable_sip_to_host: 136.243.196.198
variable_sip_contact_user: 442031501300
variable_sip_contact_port: 5060
variable_sip_contact_uri: 442031501300%40212.199.161.206%3A5060
variable_sip_contact_host: 212.199.161.206
variable_sip_user_agent: FPBX-2.9.0(1.8.15.0)
variable_sip_via_host: 136.243.196.198
variable_sip_via_port: 5060
variable_max_forwards: 30
variable_presence_id: 442031501300%40212.199.161.206
variable_sip_h_X-AUTH-IP: 212.199.161.206
variable_sip_h_P-Accountcode: 97237449191
variable_sip_h_P-effective_caller_id_name: 442031501300
variable_sip_h_P-effective_caller_id_number: 442031501300
variable_switch_r_sdp: v%3D0%0D%0Ao%3Droot%20690547655%20690547655%20IN%20IP4%20212.199.161.206%0D%0As%3DAsterisk%20PBX%201.8.15.0%0D%0Ac%3DIN%20IP4%20212.199.161.206%0D%0At%3D0%200%0D%0Am%3Daudio%2010030%20RTP/AVP%208%200%2018%203%20112%205%2010%207%20110%2097%20111%209%20118%20117%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A18%20G729/8000%0D%0Aa%3Dfmtp%3A18%20annexb%3Dno%0D%0Aa%3Drtpmap%3A3%20GSM/8000%0D%0Aa%3Drtpmap%3A112%20AAL2-G726-32/8000%0D%0Aa%3Drtpmap%3A5%20DVI4/8000%0D%0Aa%3Drtpmap%3A10%20L16/8000%0D%0Aa%3Drtpmap%3A7%20LPC/8000%0D%0Aa%3Drtpmap%3A110%20speex/8000%0D%0Aa%3Drtpmap%3A97%20iLBC/8000%0D%0Aa%3Dfmtp%3A97%20mode%3D30%0D%0Aa%3Drtpmap%3A111%20G726-32/8000%0D%0Aa%3Drtpmap%3A9%20G722/8000%0D%0Aa%3Drtpmap%3A118%20L16/16000%0D%0Aa%3Drtpmap%3A117%20speex/16000%0D%0Aa%3Dptime%3A20%0D%0A
variable_rtp_use_codec_string: PCMA,PCMU,G729
variable_audio_media_flow: sendrecv
variable_remote_media_ip: 212.199.161.206
variable_remote_media_port: 10030
variable_rtp_audio_recv_pt: 8
variable_rtp_use_codec_name: PCMA
variable_rtp_use_codec_rate: 8000
variable_rtp_use_codec_ptime: 20
variable_rtp_use_codec_channels: 1
variable_rtp_last_audio_codec_string: PCMA%408000h%4020i%401c
variable_read_codec: PCMA
variable_original_read_codec: PCMA
variable_read_rate: 8000
variable_original_read_rate: 8000
variable_write_codec: PCMA
variable_write_rate: 8000
variable_dtmf_type: rfc2833
variable_endpoint_disposition: RECEIVED
variable_call_uuid: f5563380-3267-11ec-8918-7dfb9f6e00f0
Hunt-Direction: inbound
Hunt-Logical-Direction: inbound
Hunt-Username: 442031501300
Hunt-Dialplan: XML
Hunt-Caller-ID-Name: 442031501300
Hunt-Caller-ID-Number: 442031501300
Hunt-Orig-Caller-ID-Name: 442031501300
Hunt-Orig-Caller-ID-Number: 442031501300
Hunt-Network-Addr: 136.243.196.198
Hunt-ANI: 442031501300
Hunt-Destination-Number: 00443453000101
Hunt-Unique-ID: f5563380-3267-11ec-8918-7dfb9f6e00f0
Hunt-Source: mod_sofia
Hunt-Context: default
Hunt-Channel-Name: sofia/default/442031501300%40212.199.161.206
Hunt-Profile-Index: 1
Hunt-Profile-Created-Time: 1634818262066665
Hunt-Channel-Created-Time: 1634818262066665
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

'
2021-10-21 08:11:02.066665 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] key_name:
2021-10-21 08:11:02.066665 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] key_value:
2021-10-21 08:11:02.066665 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] tag_name:
2021-10-21 08:11:02.066665 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] section: dialplan
2021-10-21 08:11:02.066665 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] blind_flag: 0
2021-10-21 08:11:02.066665 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Dialed number : 00443453000101
2021-10-21 08:11:02.066665 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '00443453000101' limit 1
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number as number FROM accessnumber WHERE access_number = '00443453000101' AND status=0 limit 1
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE domain = '212.199.161.206' limit 1
2021-10-21 08:11:02.086664 [WARNING] switch_cpp.cpp:1365 [ASTPP] [FAXER] in custom Caller ID
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Caller Id name / number  : 442031501300 / 442031501300
2021-10-21 08:11:02.086664 [NOTICE] switch_cpp.cpp:1365 [ASTPP] DID global translation id: 13
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 13 AND status=0 limit 1
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :"+/*".....00443453000101
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [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 ="00443453000101" LIMIT 1
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [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="00443453000101"  AND domains.domain="136.243.196.198" limit 1
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Call direction : outbound
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] [Accountcode1 : 97237449191]
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE (number = "97237449191" OR number = "97237449191") AND deleted = 0 limit 1
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^00443453000101.*' OR blocked_patterns = '^0044345300010.*' OR blocked_patterns = '^004434530001.*' OR blocked_patterns = '^00443453000.*' OR blocked_patterns = '^0044345300.*' OR blocked_patterns = '^004434530.*' OR blocked_patterns = '^00443453.*' OR blocked_patterns = '^0044345.*' OR blocked_patterns = '^004434.*' OR blocked_patterns = '^00443.*' OR blocked_patterns = '^0044.*' OR blocked_patterns = '^004.*' OR blocked_patterns = '^00.*' OR blocked_patterns = '^0.*' OR blocked_patterns ='--') AND accountid = 1179 limit 1
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="00443453000101" AND A.accountid = '1179' limit 1
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 00443453000101
2021-10-21 08:11:02.086664 [NOTICE] switch_cpp.cpp:1365 [ASTPP] DID global translation id: 13
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 13 AND status=0 limit 1
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :"+/*".....00443453000101
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [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 ="00443453000101" LIMIT 1
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [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="00443453000101"  AND domains.domain="136.243.196.198" limit 1
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] New Call direction : outbound
2021-10-21 08:11:02.086664 [NOTICE] switch_cpp.cpp:1365 [ASTPP] DID global translation id: 13
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 13 AND status=0 limit 1
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :"+/*".....00443453000101
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="00443453000101" AND (accountid = 0 OR status = 1) LIMIT 1
2021-10-21 08:11:02.086664 [NOTICE] switch_cpp.cpp:1365 [ASTPP] Localization Id: 3
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 3 AND status=0 limit 1
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :"00/*,+/*".....00443453000101
2021-10-21 08:11:02.086664 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 00443453000101
2021-10-21 08:11:02.086664 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 443453000101
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :"00/*,+/*".....443453000101
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [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 (patterns = '^443453000101.*' OR patterns = '^44345300010.*' OR patterns = '^4434530001.*' OR patterns = '^443453000.*' OR patterns = '^44345300.*' OR patterns = '^4434530.*' OR patterns = '^443453.*' OR patterns = '^44345.*' OR patterns = '^4434.*' OR patterns = '^443.*' OR patterns = '^44.*' OR patterns = '^4.*' OR patterns ='--') AND accountid = 1179 ORDER BY LENGTH(PKGPTR.patterns) DESC
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '443453000101' OR number = '44345300010' OR number = '4434530001' OR number = '443453000' OR number = '44345300' OR number = '4434530' OR number = '443453' OR number = '44345' OR number = '4434' OR number = '443' OR number = '44' OR number = '4' OR number ='--') OR (number = '443453000101*' OR number = '44345300010*' OR number = '4434530001*' OR number = '443453000*' OR number = '44345300*' OR number = '4434530*' OR number = '443453*' OR number = '44345*' OR number = '4434*' OR number = '443*' OR number = '44*' OR number = '4*' OR number ='--') OR (number = '442031501300' OR number = '44203150130' OR number = '4420315013' OR number = '442031501' OR number = '44203150' OR number = '4420315' OR number = '442031' OR number = '44203' OR number = '4420' OR number = '442' OR number = '44' OR number = '4' OR number ='--') OR (number = '442031501300*' OR number = '44203150130*' OR number = '4420315013*' OR number = '442031501*' OR number = '44203150*' OR number = '4420315*' OR number = '442031*' OR number = '44203*' OR number = '4420*' OR number = '442*' OR number = '44*' OR number = '4*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] Call Barring Status : -1
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '443453000101' OR routing_prefix = '44345300010' OR routing_prefix = '4434530001' OR routing_prefix = '443453000' OR routing_prefix = '44345300' OR routing_prefix = '4434530' OR routing_prefix = '443453' OR routing_prefix = '44345' OR routing_prefix = '4434' OR routing_prefix = '443' OR routing_prefix = '44' OR routing_prefix = '4' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] =============== Account Information ===================
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] User id : 1179
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Account code : 97237449191
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Balance : 110.07787
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Ratecard id : 75
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] CID Pool : 0
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] ========================================================
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 75 AND status = 0
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_RATES] call_direction :outbound
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 75 AND status = 0
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^443453000101.*' OR pattern = '^44345300010.*' OR pattern = '^4434530001.*' OR pattern = '^443453000.*' OR pattern = '^44345300.*' OR pattern = '^4434530.*' OR pattern = '^443453.*' OR pattern = '^44345.*' OR pattern = '^4434.*' OR pattern = '^443.*' OR pattern = '^44.*' OR pattern = '^4.*' OR pattern ='--') AND status = 0 AND (pricelist_id = 75 OR accountid=1179)  ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC LIMIT 1
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] call_direction:::::: outbound
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] =============== Rates Information ===================
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] ID : 1598243
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Connectcost : 0.00000
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Includedseconds : 0
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Cost : 0.02500
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] comment : United Kingdom Wide Calls
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Country Id : 0
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Accid : 1179
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Trunk ID: 0
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Routing type:
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] ================================================================
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] [FIND_MAXLENGTH] Your110.07787 balance Accountid 1179 !!!
2021-10-21 08:11:02.086664 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [FIND_MAXLENGTH] Limiting call to config max length 180 mins!
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Call Max length duration : 180 minutes
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] [DIALPLAN] User Rate ID : 1598243
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_CARRIER_RATES]123131 Query rate_carrier_id  :0
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_CARRIER_RATES_TRUNKS] Query :SELECT GROUP_CONCAT(trunk_id) as ids FROM routing WHERE pricelist_id=75 ORDER by id asc
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_CARRIER_RATES] Query :SELECT TK.id as trunk_id,TK.name as trunk_name,TK.codec,GW.name as path,GW.dialplan_variable,TK.provider_id,TR.init_inc,TK.status,TK.maxchannels,TK.cps,TK.leg_timeout,TR.pattern,TR.id as outbound_route_id,TR.connectcost,TR.comment,TR.includedseconds,TR.cost,TR.inc,TR.prepend,TR.strip,(select name from gateways where status=0 AND id = TK.failover_gateway_id) as path1,(select name from gateways where status=0 AND id = TK.failover_gateway_id1) as path2 FROM outbound_routes as TR,trunks as TK,gateways as GW WHERE GW.status=0 AND GW.id= TK.gateway_id AND TK.status=0 AND TK.id= TR.trunk_id AND (pattern = '^443453000101.*' OR pattern = '^44345300010.*' OR pattern = '^4434530001.*' OR pattern = '^443453000.*' OR pattern = '^44345300.*' OR pattern = '^4434530.*' OR pattern = '^443453.*' OR pattern = '^44345.*' OR pattern = '^4434.*' OR pattern = '^443.*' OR pattern = '^44.*' OR pattern = '^4.*' OR pattern ='--') AND TR.status = 0  AND TR.trunk_id IN (43) ORDER by LENGTH (pattern) DESC,TR.cost ASC,TR.precedence ASC, TK.precedence
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [HYBRID_ROUTING]LCR with Cost in 2nd stage
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] =============== Termination Rates Information ===================
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] ID : 1527843
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Code : ^443.*
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Destination : United Kingdom Wide Calls
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Connectcost : 0.00000
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Free Seconds : 0
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Prefix : ^443.*
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Strip : 0
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Prepend : 0
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Carrier id : 43
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] carrier_name : BezeqINT2
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] dialplan_variable :
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Failover gateway : BezeqINT1
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Vendor id : 1403
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Max channels : 0
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Trunk Name : BezeqINT
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] ========================END OF TERMINATION RATES=======================
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 1179 AND status=0 LIMIT 1
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] maxlength::::::::: 180
2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] [PBX] call_direction : outbound
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 75 AND status = 0
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from accounts where id = 1403) AND status=0 limit 1
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :0/0.....443453000101
2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Generated XML:
<?xml version="1.0" encoding="UTF-8" standalone="no"?>
<document type="freeswitch/xml">
<section name="dialplan" description="ASTPP Dialplan">
<context name="default">
<extension name="443453000101">
<condition field="destination_number" expression="00443453000101">
<action application="set" data="effective_destination_number=443453000101"/>
<action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
<action application="set" data="bridge_pre_execute_bleg_data=+10800 normal_clearing"/>
<action application="set" data="callstart=2021-10-21 12:11:02"/>
<action application="set" data="hangup_after_bridge=true"/>
<action application="set" data="continue_on_fail=1,19,20,21,27,34,38,41,42,44,52,58,69,79,102,602"/>
<action application="set" data="account_id=1179"/>
<action application="set" data="parent_id=0"/>
<action application="set" data="entity_id=0"/>
<action application="set" data="call_processed=internal"/>
<action application="set" data="call_direction=outbound"/>
<action application="set" data="accountname=default"/>
<action application="set" data="filename1=astpp.pbx.lua"/>
<action application="set" data="origination_rates=ID:1598243|CODE:^443.*|DESTINATION:United Kingdom Wide Calls|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.02500|INC:10|INITIALBLOCK:30|RATEGROUP:75|MARKUP:0|CI:0|ACCID:1179"/>
<action application="set" data="original_caller_id_name=442031501300"/>
<action application="set" data="original_caller_id_number=442031501300"/>

<action application="export" data="is_recording=1"/>
<action application="export" data="media_bug_answer_req=true"/>
<action application="export" data="RECORD_STEREO=true"/>
<action application="export" data="record_sample_rate=8000"/>
<action application="export" data="execute_on_answer=record_session $${recordings_dir}/${uuid}.wav"/>
<action application="set" data="effective_caller_id_name=442031501300"/>
<action application="set" data="effective_caller_id_number=442031501300"/>
<action application="set" data="calltype=STANDARD"/>
<action application="set" data="termination_rates=ID:1527843|CODE:^443.*|DESTINATION:United Kingdom Wide Calls|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.01000|INC:1|INITIALBLOCK:1|TRUNK:43|PROVIDER:1403"/>
<action application="set" data="trunk_id=43"/>
<action application="set" data="provider_id=1403"/>
<action application="set" data="rate_flag=0"/>
<action application="set" data="force_trunk_flag=1598243"/>
<action application="export" data="presence_data=trunk_id=43"/>
<action application="set" data="intcall=0"/>
<action application="export" data="presence_data=x|||Asserson(97237449191)|||^443.* // United Kingdom Wide Calls // 0.02500 // trunk_id=0|||BezeqINT // ^443.* // United Kingdom Wide Calls // 0.01000 // trunk_id=43|||STD|||212.199.161.206"/>
<action application="bridge" data="[leg_timeout=0]sofia/gateway/BezeqINT2/443453000101;fs_path=sip:136.243.196.198:5060"/>
<action application="bridge" data="[leg_timeout=0]sofia/gateway/BezeqINT1/443453000101;fs_path=sip:136.243.196.198:5060"/>
</condition>
</extension>
</context>
</section>
</document>
2021-10-21 08:11:02.086664 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fd4240c7a70 released.
Dialplan: sofia/default/442031501300@212.199.161.206 parsing [default->443453000101] continue=false
Dialplan: sofia/default/442031501300@212.199.161.206 Regex (PASS) [443453000101] destination_number(00443453000101) =~ /00443453000101/ break=on-false
Dialplan: sofia/default/442031501300@212.199.161.206 Action set(effective_destination_number=443453000101)
Dialplan: sofia/default/442031501300@212.199.161.206 Action set(bridge_pre_execute_bleg_app=sched_hangup)
Dialplan: sofia/default/442031501300@212.199.161.206 Action set(bridge_pre_execute_bleg_data=+10800 normal_clearing)
Dialplan: sofia/default/442031501300@212.199.161.206 Action set(callstart=2021-10-21 12:11:02)
Dialplan: sofia/default/442031501300@212.199.161.206 Action set(hangup_after_bridge=true)
Dialplan: sofia/default/442031501300@212.199.161.206 Action set(continue_on_fail=1,19,20,21,27,34,38,41,42,44,52,58,69,79,102,602)
Dialplan: sofia/default/442031501300@212.199.161.206 Action set(account_id=1179)
Dialplan: sofia/default/442031501300@212.199.161.206 Action set(parent_id=0)
Dialplan: sofia/default/442031501300@212.199.161.206 Action set(entity_id=0)
Dialplan: sofia/default/442031501300@212.199.161.206 Action set(call_processed=internal)
Dialplan: sofia/default/442031501300@212.199.161.206 Action set(call_direction=outbound)
Dialplan: sofia/default/442031501300@212.199.161.206 Action set(accountname=default)
Dialplan: sofia/default/442031501300@212.199.161.206 Action set(filename1=astpp.pbx.lua)
Dialplan: sofia/default/442031501300@212.199.161.206 Action set(origination_rates=ID:1598243|CODE:^443.*|DESTINATION:United Kingdom Wide Calls|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.02500|INC:10|INITIALBLOCK:30|RATEGROUP:75|MARKUP:0|CI:0|ACCID:1179)
Dialplan: sofia/default/442031501300@212.199.161.206 Action set(original_caller_id_name=442031501300)
Dialplan: sofia/default/442031501300@212.199.161.206 Action set(original_caller_id_number=442031501300)
Dialplan: sofia/default/442031501300@212.199.161.206 Action export(is_recording=1)
Dialplan: sofia/default/442031501300@212.199.161.206 Action export(media_bug_answer_req=true)
Dialplan: sofia/default/442031501300@212.199.161.206 Action export(RECORD_STEREO=true)
Dialplan: sofia/default/442031501300@212.199.161.206 Action export(record_sample_rate=8000)
Dialplan: sofia/default/442031501300@212.199.161.206 Action export(execute_on_answer=record_session $${recordings_dir}/${uuid}.wav)
Dialplan: sofia/default/442031501300@212.199.161.206 Action set(effective_caller_id_name=442031501300)
Dialplan: sofia/default/442031501300@212.199.161.206 Action set(effective_caller_id_number=442031501300)
Dialplan: sofia/default/442031501300@212.199.161.206 Action set(calltype=STANDARD)
Dialplan: sofia/default/442031501300@212.199.161.206 Action set(termination_rates=ID:1527843|CODE:^443.*|DESTINATION:United Kingdom Wide Calls|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.01000|INC:1|INITIALBLOCK:1|TRUNK:43|PROVIDER:1403)
Dialplan: sofia/default/442031501300@212.199.161.206 Action set(trunk_id=43)
Dialplan: sofia/default/442031501300@212.199.161.206 Action set(provider_id=1403)
Dialplan: sofia/default/442031501300@212.199.161.206 Action set(rate_flag=0)
Dialplan: sofia/default/442031501300@212.199.161.206 Action set(force_trunk_flag=1598243)
Dialplan: sofia/default/442031501300@212.199.161.206 Action export(presence_data=trunk_id=43)
Dialplan: sofia/default/442031501300@212.199.161.206 Action set(intcall=0)
Dialplan: sofia/default/442031501300@212.199.161.206 Action export(presence_data=x|||Asserson(97237449191)|||^443.* // United Kingdom Wide Calls // 0.02500 // trunk_id=0|||BezeqINT // ^443.* // United Kingdom Wide Calls // 0.01000 // trunk_id=43|||STD|||212.199.161.206)
Dialplan: sofia/default/442031501300@212.199.161.206 Action bridge([leg_timeout=0]sofia/gateway/BezeqINT2/443453000101;fs_path=sip:136.243.196.198:5060)
Dialplan: sofia/default/442031501300@212.199.161.206 Action bridge([leg_timeout=0]sofia/gateway/BezeqINT1/443453000101;fs_path=sip:136.243.196.198:5060)
2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:286 (sofia/default/442031501300@212.199.161.206) State Change CS_ROUTING -> CS_EXECUTE
2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:643 (sofia/default/442031501300@212.199.161.206) State ROUTING going to sleep
2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:584 (sofia/default/442031501300@212.199.161.206) Running State Change CS_EXECUTE (Cur 3 Tot 727135)
2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:650 (sofia/default/442031501300@212.199.161.206) State EXECUTE
2021-10-21 08:11:02.106679 [DEBUG] mod_sofia.c:198 sofia/default/442031501300@212.199.161.206 SOFIA EXECUTE
2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:328 sofia/default/442031501300@212.199.161.206 Standard EXECUTE
EXECUTE sofia/default/442031501300@212.199.161.206 set(effective_destination_number=443453000101)
2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [effective_destination_number]=[443453000101]
EXECUTE sofia/default/442031501300@212.199.161.206 set(bridge_pre_execute_bleg_app=sched_hangup)
2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [bridge_pre_execute_bleg_app]=[sched_hangup]
EXECUTE sofia/default/442031501300@212.199.161.206 set(bridge_pre_execute_bleg_data=+10800 normal_clearing)
2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [bridge_pre_execute_bleg_data]=[+10800 normal_clearing]
EXECUTE sofia/default/442031501300@212.199.161.206 set(callstart=2021-10-21 12:11:02)
2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [callstart]=[2021-10-21 12:11:02]
EXECUTE sofia/default/442031501300@212.199.161.206 set(hangup_after_bridge=true)
2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [hangup_after_bridge]=[true]
EXECUTE sofia/default/442031501300@212.199.161.206 set(continue_on_fail=1,19,20,21,27,34,38,41,42,44,52,58,69,79,102,602)
2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [continue_on_fail]=[1,19,20,21,27,34,38,41,42,44,52,58,69,79,102,602]
EXECUTE sofia/default/442031501300@212.199.161.206 set(account_id=1179)
2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [account_id]=[1179]
EXECUTE sofia/default/442031501300@212.199.161.206 set(parent_id=0)
2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [parent_id]=[0]
EXECUTE sofia/default/442031501300@212.199.161.206 set(entity_id=0)
2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [entity_id]=[0]
EXECUTE sofia/default/442031501300@212.199.161.206 set(call_processed=internal)
2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [call_processed]=[internal]
EXECUTE sofia/default/442031501300@212.199.161.206 set(call_direction=outbound)
2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [call_direction]=[outbound]
EXECUTE sofia/default/442031501300@212.199.161.206 set(accountname=default)
2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [accountname]=[default]
EXECUTE sofia/default/442031501300@212.199.161.206 set(filename1=astpp.pbx.lua)
2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [filename1]=[astpp.pbx.lua]
EXECUTE sofia/default/442031501300@212.199.161.206 set(origination_rates=ID:1598243|CODE:^443.*|DESTINATION:United Kingdom Wide Calls|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.02500|INC:10|INITIALBLOCK:30|RATEGROUP:75|MARKUP:0|CI:0|ACCID:1179)
2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [origination_rates]=[ID:1598243|CODE:^443.*|DESTINATION:United Kingdom Wide Calls|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.02500|INC:10|INITIALBLOCK:30|RATEGROUP:75|MARKUP:0|CI:0|ACCID:1179]
EXECUTE sofia/default/442031501300@212.199.161.206 set(original_caller_id_name=442031501300)
2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [original_caller_id_name]=[442031501300]
EXECUTE sofia/default/442031501300@212.199.161.206 set(original_caller_id_number=442031501300)
2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [original_caller_id_number]=[442031501300]
EXECUTE sofia/default/442031501300@212.199.161.206 export(is_recording=1)
2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [is_recording]=[1]
EXECUTE sofia/default/442031501300@212.199.161.206 export(media_bug_answer_req=true)
2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [media_bug_answer_req]=[true]
EXECUTE sofia/default/442031501300@212.199.161.206 export(RECORD_STEREO=true)
2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RECORD_STEREO]=[true]
EXECUTE sofia/default/442031501300@212.199.161.206 export(record_sample_rate=8000)
2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [record_sample_rate]=[8000]
EXECUTE sofia/default/442031501300@212.199.161.206 export(execute_on_answer=record_session /var/lib/freeswitch/recordings/f5563380-3267-11ec-8918-7dfb9f6e00f0.wav)
2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [execute_on_answer]=[record_session /var/lib/freeswitch/recordings/f5563380-3267-11ec-8918-7dfb9f6e00f0.wav]
EXECUTE sofia/default/442031501300@212.199.161.206 set(effective_caller_id_name=442031501300)
2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [effective_caller_id_name]=[442031501300]
EXECUTE sofia/default/442031501300@212.199.161.206 set(effective_caller_id_number=442031501300)
2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [effective_caller_id_number]=[442031501300]
EXECUTE sofia/default/442031501300@212.199.161.206 set(calltype=STANDARD)
2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [calltype]=[STANDARD]
EXECUTE sofia/default/442031501300@212.199.161.206 set(termination_rates=ID:1527843|CODE:^443.*|DESTINATION:United Kingdom Wide Calls|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.01000|INC:1|INITIALBLOCK:1|TRUNK:43|PROVIDER:1403)
2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [termination_rates]=[ID:1527843|CODE:^443.*|DESTINATION:United Kingdom Wide Calls|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.01000|INC:1|INITIALBLOCK:1|TRUNK:43|PROVIDER:1403]
EXECUTE sofia/default/442031501300@212.199.161.206 set(trunk_id=43)
2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [trunk_id]=[43]
EXECUTE sofia/default/442031501300@212.199.161.206 set(provider_id=1403)
2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [provider_id]=[1403]
EXECUTE sofia/default/442031501300@212.199.161.206 set(rate_flag=0)
2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [rate_flag]=[0]
EXECUTE sofia/default/442031501300@212.199.161.206 set(force_trunk_flag=1598243)
2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [force_trunk_flag]=[1598243]
EXECUTE sofia/default/442031501300@212.199.161.206 export(presence_data=trunk_id=43)
2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [presence_data]=[trunk_id=43]
EXECUTE sofia/default/442031501300@212.199.161.206 set(intcall=0)
2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [intcall]=[0]
EXECUTE sofia/default/442031501300@212.199.161.206 export(presence_data=x|||Asserson(97237449191)|||^443.* // United Kingdom Wide Calls // 0.02500 // trunk_id=0|||BezeqINT // ^443.* // United Kingdom Wide Calls // 0.01000 // trunk_id=43|||STD|||212.199.161.206)
2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [presence_data]=[x|||Asserson(97237449191)|||^443.* // United Kingdom Wide Calls // 0.02500 // trunk_id=0|||BezeqINT // ^443.* // United Kingdom Wide Calls // 0.01000 // trunk_id=43|||STD|||212.199.161.206]
EXECUTE sofia/default/442031501300@212.199.161.206 bridge([leg_timeout=0]sofia/gateway/BezeqINT2/443453000101;fs_path=sip:136.243.196.198:5060)
2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1250 sofia/default/442031501300@212.199.161.206 EXPORTING[export_vars] [is_recording]=[1] to event
2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1250 sofia/default/442031501300@212.199.161.206 EXPORTING[export_vars] [media_bug_answer_req]=[true] to event
2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1250 sofia/default/442031501300@212.199.161.206 EXPORTING[export_vars] [RECORD_STEREO]=[true] to event
2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1250 sofia/default/442031501300@212.199.161.206 EXPORTING[export_vars] [record_sample_rate]=[8000] to event
2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1250 sofia/default/442031501300@212.199.161.206 EXPORTING[export_vars] [execute_on_answer]=[record_session /var/lib/freeswitch/recordings/f5563380-3267-11ec-8918-7dfb9f6e00f0.wav] to event
2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1250 sofia/default/442031501300@212.199.161.206 EXPORTING[export_vars] [presence_data]=[x|||Asserson(97237449191)|||^443.* // United Kingdom Wide Calls // 0.02500 // trunk_id=0|||BezeqINT // ^443.* // United Kingdom Wide Calls // 0.01000 // trunk_id=43|||STD|||212.199.161.206] to event
2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1250 sofia/default/442031501300@212.199.161.206 EXPORTING[export_vars] [presence_data]=[x|||Asserson(97237449191)|||^443.* // United Kingdom Wide Calls // 0.02500 // trunk_id=0|||BezeqINT // ^443.* // United Kingdom Wide Calls // 0.01000 // trunk_id=43|||STD|||212.199.161.206] to event
2021-10-21 08:11:02.106679 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
2021-10-21 08:11:02.106679 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
2021-10-21 08:11:02.106679 [NOTICE] switch_channel.c:1104 New Channel sofia/default/443453000101 [f55c452c-3267-11ec-893e-7dfb9f6e00f0]
2021-10-21 08:11:02.106679 [DEBUG] mod_sofia.c:4819 (sofia/default/443453000101) State Change CS_NEW -> CS_INIT
2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:584 (sofia/default/443453000101) Running State Change CS_INIT (Cur 4 Tot 727136)
2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:627 (sofia/default/443453000101) State INIT
2021-10-21 08:11:02.106679 [DEBUG] mod_sofia.c:90 sofia/default/443453000101 SOFIA INIT
2021-10-21 08:11:02.106679 [DEBUG] sofia_glue.c:1264 sip:136.243.196.198:5060 Setting proxy route to sofia/default/443453000101
2021-10-21 08:11:02.106679 [DEBUG] sofia_glue.c:1295 sofia/default/443453000101 sending invite version: 1.6.20  64bit
Local SDP:
v=0
o=FreeSWITCH 1634787550 1634787551 IN IP4 89.46.223.193
s=FreeSWITCH
c=IN IP4 89.46.223.193
t=0 0
m=audio 30712 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:40 sofia/default/443453000101 Standard INIT
2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:48 (sofia/default/443453000101) State Change CS_INIT -> CS_ROUTING
2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:627 (sofia/default/443453000101) State INIT going to sleep
2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:584 (sofia/default/443453000101) Running State Change CS_ROUTING (Cur 4 Tot 727136)
2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:643 (sofia/default/443453000101) State ROUTING
2021-10-21 08:11:02.106679 [DEBUG] mod_sofia.c:143 sofia/default/443453000101 SOFIA ROUTING
2021-10-21 08:11:02.106679 [DEBUG] switch_ivr_originate.c:67 (sofia/default/443453000101) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:643 (sofia/default/443453000101) State ROUTING going to sleep
2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:584 (sofia/default/443453000101) Running State Change CS_CONSUME_MEDIA (Cur 4 Tot 727136)
2021-10-21 08:11:02.106679 [DEBUG] sofia.c:7084 Channel sofia/default/443453000101 entering state [calling][0]
2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:662 (sofia/default/443453000101) State CONSUME_MEDIA
2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:662 (sofia/default/443453000101) State CONSUME_MEDIA going to sleep
2021-10-21 08:11:06.546664 [DEBUG] sofia.c:7084 Channel sofia/default/443453000101 entering state [proceeding][180]
2021-10-21 08:11:06.546664 [DEBUG] sofia.c:7094 Remote SDP:
v=0
o=sbc-uk-bm-th14a 312340480 1634818263 IN IP4 212.179.237.23
s=sip call
c=IN IP4 212.179.237.23
t=0 0
m=audio 60138 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

2021-10-21 08:11:06.546664 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2021-10-21 08:11:06.546664 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2021-10-21 08:11:06.546664 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
2021-10-21 08:11:06.546664 [DEBUG] switch_core_media.c:3061 Set Codec sofia/default/443453000101 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
2021-10-21 08:11:06.546664 [DEBUG] switch_core_codec.c:111 sofia/default/443453000101 Original read codec set to PCMA:8
2021-10-21 08:11:06.546664 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
2021-10-21 08:11:06.546664 [DEBUG] switch_core_media.c:4767 sofia/default/443453000101 Set 2833 dtmf send payload to 101 recv payload to 101
2021-10-21 08:11:06.546664 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/default/443453000101] 89.46.223.193 port 30712 -> 212.179.237.23 port 60138 codec: 8 ms: 20
2021-10-21 08:11:06.546664 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms
2021-10-21 08:11:06.546664 [DEBUG] switch_core_media.c:7180 sofia/default/443453000101 Set 2833 dtmf send payload to 101
2021-10-21 08:11:06.546664 [DEBUG] switch_core_media.c:7187 sofia/default/443453000101 Set 2833 dtmf receive payload to 101
2021-10-21 08:11:06.546664 [DEBUG] switch_core_media.c:7210 sofia/default/443453000101 Set rtp dtmf delay to 40
2021-10-21 08:11:06.546664 [NOTICE] sofia_media.c:92 Pre-Answer sofia/default/443453000101!
2021-10-21 08:11:06.546664 [DEBUG] switch_channel.c:3474 (sofia/default/443453000101) Callstate Change DOWN -> EARLY
2021-10-21 08:11:06.566661 [INFO] switch_ivr_originate.c:3654 Sending early media
2021-10-21 08:11:06.566661 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/default/442031501300@212.199.161.206] 89.46.223.193 port 26784 -> 212.199.161.206 port 10030 codec: 8 ms: 20
2021-10-21 08:11:06.566661 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms
2021-10-21 08:11:06.566661 [DEBUG] switch_core_media.c:7180 sofia/default/442031501300@212.199.161.206 Set 2833 dtmf send payload to 101
2021-10-21 08:11:06.566661 [DEBUG] switch_core_media.c:7187 sofia/default/442031501300@212.199.161.206 Set 2833 dtmf receive payload to 101
2021-10-21 08:11:06.566661 [DEBUG] switch_core_media.c:7210 sofia/default/442031501300@212.199.161.206 Set rtp dtmf delay to 40
2021-10-21 08:11:06.566661 [DEBUG] mod_sofia.c:2364 Ring SDP:
v=0
o=FreeSWITCH 1634791482 1634791483 IN IP4 89.46.223.193
s=FreeSWITCH
c=IN IP4 89.46.223.193
t=0 0
m=audio 26784 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

2021-10-21 08:11:06.566661 [NOTICE] mod_sofia.c:2367 Pre-Answer sofia/default/442031501300@212.199.161.206!
2021-10-21 08:11:06.566661 [DEBUG] switch_channel.c:3474 (sofia/default/442031501300@212.199.161.206) Callstate Change RINGING -> EARLY
2021-10-21 08:11:06.566661 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/default/443453000101]
2021-10-21 08:11:06.566661 [DEBUG] sofia.c:7084 Channel sofia/default/442031501300@212.199.161.206 entering state [early][183]
2021-10-21 08:11:06.566661 [DEBUG] switch_ivr_bridge.c:1614 (sofia/default/443453000101) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2021-10-21 08:11:06.566661 [DEBUG] switch_core_state_machine.c:584 (sofia/default/443453000101) Running State Change CS_EXCHANGE_MEDIA (Cur 4 Tot 727136)
2021-10-21 08:11:06.566661 [DEBUG] switch_core_state_machine.c:653 (sofia/default/443453000101) State EXCHANGE_MEDIA
2021-10-21 08:11:06.566661 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
2021-10-21 08:11:06.606664 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed.
2021-10-21 08:11:06.666668 [DEBUG] sofia.c:7084 Channel sofia/default/443453000101 entering state [completing][200]
2021-10-21 08:11:06.666668 [DEBUG] sofia.c:7091 Duplicate SDP
v=0
o=sbc-uk-bm-th14a 312340480 1634818263 IN IP4 212.179.237.23
s=sip call
c=IN IP4 212.179.237.23
t=0 0
m=audio 60138 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

2021-10-21 08:11:06.686664 [DEBUG] sofia.c:7084 Channel sofia/default/443453000101 entering state [ready][200]
2021-10-21 08:11:06.686664 [NOTICE] sofia.c:8159 Channel [sofia/default/443453000101] has been answered
EXECUTE sofia/default/443453000101 record_session(/var/lib/freeswitch/recordings/f5563380-3267-11ec-8918-7dfb9f6e00f0.wav)
2021-10-21 08:11:06.686664 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/default/442031501300@212.199.161.206.
2021-10-21 08:11:06.686664 [DEBUG] mod_sofia.c:850 Local SDP sofia/default/442031501300@212.199.161.206:
v=0
o=FreeSWITCH 1634791482 1634791484 IN IP4 89.46.223.193
s=FreeSWITCH
c=IN IP4 89.46.223.193
t=0 0
m=audio 26784 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

2021-10-21 08:11:06.686664 [NOTICE] switch_ivr_bridge.c:623 Channel [sofia/default/442031501300@212.199.161.206] has been answered
2021-10-21 08:11:06.686664 [DEBUG] sofia.c:7084 Channel sofia/default/442031501300@212.199.161.206 entering state [completed][200]
EXECUTE sofia/default/442031501300@212.199.161.206 record_session(/var/lib/freeswitch/recordings/f5563380-3267-11ec-8918-7dfb9f6e00f0.wav)
2021-10-21 08:11:06.686664 [DEBUG] switch_core_media_bug.c:945 Attaching BUG to sofia/default/443453000101
2021-10-21 08:11:06.686664 [DEBUG] switch_channel.c:3773 (sofia/default/443453000101) Callstate Change EARLY -> ACTIVE
2021-10-21 08:11:06.686664 [DEBUG] switch_core_media_bug.c:945 Attaching BUG to sofia/default/442031501300@212.199.161.206
2021-10-21 08:11:06.686664 [DEBUG] switch_channel.c:3773 (sofia/default/442031501300@212.199.161.206) Callstate Change EARLY -> ACTIVE
2021-10-21 08:11:06.706940 [DEBUG] switch_ivr_async.c:1500 No silence detection configured; assuming start of speech
2021-10-21 08:11:06.706940 [DEBUG] switch_ivr_async.c:1500 No silence detection configured; assuming start of speech
2021-10-21 08:11:06.706940 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed.
2021-10-21 08:11:06.706940 [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMA:8
2021-10-21 08:11:06.746667 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed.
2021-10-21 08:11:06.746667 [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMA:8
2021-10-21 08:11:06.746667 [DEBUG] switch_ivr_bridge.c:546 sofia/default/443453000101 Bridge execute app sched_hangup(+10800 normal_clearing)
2021-10-21 08:11:06.766668 [DEBUG] switch_ivr.c:623 sofia/default/443453000101 Command Execute sched_hangup(+10800 normal_clearing)
EXECUTE sofia/default/443453000101 sched_hangup(+10800 normal_clearing)
2021-10-21 08:11:06.766668 [DEBUG] switch_scheduler.c:249 Added task 82642 switch_ivr_schedule_hangup (f55c452c-3267-11ec-893e-7dfb9f6e00f0) to run at 1634829066
2021-10-21 08:11:06.786727 [DEBUG] sofia.c:7084 Channel sofia/default/442031501300@212.199.161.206 entering state [ready][200]
2021-10-21 08:11:12.246662 [WARNING] sofia_reg.c:1792 SIP auth challenge (REGISTER) on sofia profile 'default' for [6000@89.46.223.193] from ip 137.135.14.148
2021-10-21 08:11:12.466664 [WARNING] sofia_reg.c:2906 Can't find user [6000@89.46.223.193] from 137.135.14.148
You must define a domain called '89.46.223.193' in your directory and add a user with the id="6000" attribute
and you must configure your device to use the proper domain in it's authentication credentials.
2021-10-21 08:11:12.466664 [WARNING] sofia_reg.c:1737 SIP auth failure (REGISTER) on sofia profile 'default' for [6000@89.46.223.193] from ip 137.135.14.148
2021-10-21 08:11:22.686664 [WARNING] sofia_reg.c:1792 SIP auth challenge (REGISTER) on sofia profile 'default' for [363363@89.46.223.193] from ip 137.135.14.148
2021-10-21 08:11:22.846665 [WARNING] sofia_reg.c:2906 Can't find user [363363@89.46.223.193] from 137.135.14.148
You must define a domain called '89.46.223.193' in your directory and add a user with the id="363363" attribute
and you must configure your device to use the proper domain in it's authentication credentials.
2021-10-21 08:11:22.846665 [WARNING] sofia_reg.c:1737 SIP auth failure (REGISTER) on sofia profile 'default' for [363363@89.46.223.193] from ip 137.135.14.148
2021-10-21 08:11:24.086671 [NOTICE] sofia.c:1012 Hangup sofia/default/41445083106@92.249.69.77 [CS_EXECUTE] [NORMAL_CLEARING]
2021-10-21 08:11:24.086671 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/default/41445083106@92.249.69.77]
2021-10-21 08:11:24.086671 [DEBUG] switch_ivr_bridge.c:706 sofia/default/41445083106@92.249.69.77 ending bridge by request from write function
2021-10-21 08:11:24.086671 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/default/41796404058]
2021-10-21 08:11:24.086671 [NOTICE] switch_ivr_bridge.c:904 Hangup sofia/default/41796404058 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2021-10-21 08:11:24.086671 [DEBUG] switch_core_state_machine.c:653 (sofia/default/41796404058) State EXCHANGE_MEDIA going to sleep
2021-10-21 08:11:24.086671 [DEBUG] switch_core_state_machine.c:584 (sofia/default/41796404058) Running State Change CS_HANGUP (Cur 4 Tot 727136)
2021-10-21 08:11:24.086671 [DEBUG] switch_ivr_async.c:1316 Stop recording file /var/lib/freeswitch/recordings/cba2b5b8-3267-11ec-8826-7dfb9f6e00f0.wav
2021-10-21 08:11:24.086671 [DEBUG] switch_ivr_bridge.c:1712 sofia/default/41796404058 skip receive message [UNBRIDGE] (channel is hungup already)
2021-10-21 08:11:24.086671 [DEBUG] switch_ivr_bridge.c:1715 sofia/default/41445083106@92.249.69.77 skip receive message [UNBRIDGE] (channel is hungup already)
2021-10-21 08:11:24.106754 [DEBUG] switch_core_session.c:2815 sofia/default/41445083106@92.249.69.77 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:650 (sofia/default/41445083106@92.249.69.77) State EXECUTE going to sleep
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:584 (sofia/default/41445083106@92.249.69.77) Running State Change CS_HANGUP (Cur 4 Tot 727136)
2021-10-21 08:11:24.106754 [DEBUG] switch_ivr_async.c:1316 Stop recording file /var/lib/freeswitch/recordings/cba2b5b8-3267-11ec-8826-7dfb9f6e00f0.wav
2021-10-21 08:11:24.106754 [DEBUG] switch_ivr_async.c:1380 Channel is hung up
2021-10-21 08:11:24.106754 [DEBUG] switch_ivr_async.c:1380 Channel is hung up
2021-10-21 08:11:24.106754 [DEBUG] switch_core_media_bug.c:1298 Removing BUG from sofia/default/41796404058
2021-10-21 08:11:24.106754 [DEBUG] switch_core_media_bug.c:1298 Removing BUG from sofia/default/41445083106@92.249.69.77
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:850 (sofia/default/41796404058) Callstate Change ACTIVE -> HANGUP
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:852 (sofia/default/41796404058) State HANGUP
2021-10-21 08:11:24.106754 [DEBUG] mod_sofia.c:432 sofia/default/41796404058 Overriding SIP cause 480 with 200 from the other leg
2021-10-21 08:11:24.106754 [DEBUG] mod_sofia.c:438 Channel sofia/default/41796404058 hanging up, cause: NORMAL_CLEARING
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:850 (sofia/default/41445083106@92.249.69.77) Callstate Change ACTIVE -> HANGUP
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:852 (sofia/default/41445083106@92.249.69.77) State HANGUP
2021-10-21 08:11:24.106754 [DEBUG] mod_sofia.c:438 Channel sofia/default/41445083106@92.249.69.77 hanging up, cause: NORMAL_CLEARING
2021-10-21 08:11:24.106754 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/default/41796404058
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:60 sofia/default/41796404058 Standard HANGUP, cause: NORMAL_CLEARING
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:852 (sofia/default/41796404058) State HANGUP going to sleep
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:619 (sofia/default/41796404058) State Change CS_HANGUP -> CS_REPORTING
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:584 (sofia/default/41796404058) Running State Change CS_REPORTING (Cur 4 Tot 727136)
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:938 (sofia/default/41796404058) State REPORTING
2021-10-21 08:11:24.106754 [INFO] mod_json_cdr.c:271 Process [cba849ba-3267-11ec-884c-7dfb9f6e00f0.cdr.json]
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:60 sofia/default/41445083106@92.249.69.77 Standard HANGUP, cause: NORMAL_CLEARING
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:852 (sofia/default/41445083106@92.249.69.77) State HANGUP going to sleep
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:619 (sofia/default/41445083106@92.249.69.77) State Change CS_HANGUP -> CS_REPORTING
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:584 (sofia/default/41445083106@92.249.69.77) Running State Change CS_REPORTING (Cur 4 Tot 727136)
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:938 (sofia/default/41445083106@92.249.69.77) State REPORTING
2021-10-21 08:11:24.106754 [INFO] mod_json_cdr.c:271 Process [cba2b5b8-3267-11ec-8826-7dfb9f6e00f0.cdr.json]
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:174 sofia/default/41796404058 Standard REPORTING, cause: NORMAL_CLEARING
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:938 (sofia/default/41796404058) State REPORTING going to sleep
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:610 (sofia/default/41796404058) State Change CS_REPORTING -> CS_DESTROY
2021-10-21 08:11:24.106754 [DEBUG] switch_core_session.c:1665 Session 727124 (sofia/default/41796404058) Locked, Waiting on external entities
2021-10-21 08:11:24.106754 [NOTICE] switch_core_session.c:1683 Session 727124 (sofia/default/41796404058) Ended
2021-10-21 08:11:24.106754 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/41796404058 [CS_DESTROY]
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:741 (sofia/default/41796404058) Running State Change CS_DESTROY (Cur 3 Tot 727136)
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:751 (sofia/default/41796404058) State DESTROY
2021-10-21 08:11:24.106754 [DEBUG] mod_sofia.c:343 sofia/default/41796404058 SOFIA DESTROY
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:181 sofia/default/41796404058 Standard DESTROY
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:751 (sofia/default/41796404058) State DESTROY going to sleep
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:174 sofia/default/41445083106@92.249.69.77 Standard REPORTING, cause: NORMAL_CLEARING
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:938 (sofia/default/41445083106@92.249.69.77) State REPORTING going to sleep
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:610 (sofia/default/41445083106@92.249.69.77) State Change CS_REPORTING -> CS_DESTROY
2021-10-21 08:11:24.106754 [DEBUG] switch_core_session.c:1665 Session 727123 (sofia/default/41445083106@92.249.69.77) Locked, Waiting on external entities
2021-10-21 08:11:24.106754 [NOTICE] switch_core_session.c:1683 Session 727123 (sofia/default/41445083106@92.249.69.77) Ended
2021-10-21 08:11:24.106754 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/41445083106@92.249.69.77 [CS_DESTROY]
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:741 (sofia/default/41445083106@92.249.69.77) Running State Change CS_DESTROY (Cur 2 Tot 727136)
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:751 (sofia/default/41445083106@92.249.69.77) State DESTROY
2021-10-21 08:11:24.106754 [DEBUG] mod_sofia.c:343 sofia/default/41445083106@92.249.69.77 SOFIA DESTROY
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:181 sofia/default/41445083106@92.249.69.77 Standard DESTROY
2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:751 (sofia/default/41445083106@92.249.69.77) State DESTROY going to sleep
2021-10-21 08:11:24.266668 [DEBUG] switch_scheduler.c:144 Deleting task 82639 switch_ivr_schedule_hangup (cba849ba-3267-11ec-884c-7dfb9f6e00f0)
2021-10-21 08:11:48.226666 [NOTICE] switch_channel.c:1104 New Channel sofia/default/+447578992323@199.244.96.39 [10d93058-3268-11ec-894a-7dfb9f6e00f0]
2021-10-21 08:11:48.226666 [DEBUG] switch_core_state_machine.c:584 (sofia/default/+447578992323@199.244.96.39) Running State Change CS_NEW (Cur 3 Tot 727137)
2021-10-21 08:11:48.226666 [DEBUG] sofia.c:9873 sofia/default/+447578992323@199.244.96.39 receiving invite from 136.243.196.198:5060 version: 1.6.20  64bit
2021-10-21 08:11:48.226666 [DEBUG] sofia.c:9989 IP 136.243.196.198 Approved by acl "default[]". Access Granted.
2021-10-21 08:11:48.226666 [DEBUG] sofia.c:7084 Channel sofia/default/+447578992323@199.244.96.39 entering state [received][100]
2021-10-21 08:11:48.226666 [DEBUG] sofia.c:7094 Remote SDP:
v=0
o=PortaSIP 396105275207201534 1 IN IP4 199.244.96.46
s=sip call
t=0 0
m=audio 45912 RTP/AVP 8 18 0 101
c=IN IP4 199.244.96.46
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=silenceSupp:off - - - -
a=ptime:20

2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:3061 Set Codec sofia/default/+447578992323@199.244.96.39 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
2021-10-21 08:11:48.226666 [DEBUG] switch_core_codec.c:111 sofia/default/+447578992323@199.244.96.39 Original read codec set to PCMA:8
2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4767 sofia/default/+447578992323@199.244.96.39 Set 2833 dtmf send payload to 101 recv payload to 101
2021-10-21 08:11:48.226666 [DEBUG] sofia.c:7507 (sofia/default/+447578992323@199.244.96.39) State Change CS_NEW -> CS_INIT
2021-10-21 08:11:48.226666 [DEBUG] switch_core_state_machine.c:603 (sofia/default/+447578992323@199.244.96.39) State NEW
2021-10-21 08:11:48.226666 [DEBUG] switch_core_state_machine.c:584 (sofia/default/+447578992323@199.244.96.39) Running State Change CS_INIT (Cur 3 Tot 727137)
2021-10-21 08:11:48.226666 [DEBUG] switch_core_state_machine.c:627 (sofia/default/+447578992323@199.244.96.39) State INIT
2021-10-21 08:11:48.226666 [DEBUG] mod_sofia.c:90 sofia/default/+447578992323@199.244.96.39 SOFIA INIT
2021-10-21 08:11:48.226666 [DEBUG] switch_core_state_machine.c:40 sofia/default/+447578992323@199.244.96.39 Standard INIT
2021-10-21 08:11:48.226666 [DEBUG] switch_core_state_machine.c:48 (sofia/default/+447578992323@199.244.96.39) State Change CS_INIT -> CS_ROUTING
2021-10-21 08:11:48.226666 [DEBUG] switch_core_state_machine.c:627 (sofia/default/+447578992323@199.244.96.39) State INIT going to sleep
2021-10-21 08:11:48.226666 [DEBUG] switch_core_state_machine.c:584 (sofia/default/+447578992323@199.244.96.39) Running State Change CS_ROUTING (Cur 3 Tot 727137)
2021-10-21 08:11:48.226666 [DEBUG] switch_channel.c:2249 (sofia/default/+447578992323@199.244.96.39) Callstate Change DOWN -> RINGING
2021-10-21 08:11:48.226666 [DEBUG] switch_core_state_machine.c:643 (sofia/default/+447578992323@199.244.96.39) State ROUTING
2021-10-21 08:11:48.226666 [DEBUG] mod_sofia.c:143 sofia/default/+447578992323@199.244.96.39 SOFIA ROUTING
2021-10-21 08:11:48.226666 [DEBUG] switch_core_state_machine.c:236 sofia/default/+447578992323@199.244.96.39 Standard ROUTING
2021-10-21 08:11:48.226666 [INFO] mod_dialplan_xml.c:637 Processing +447578992323 <+447578992323>->442039123706 in context default
2021-10-21 08:11:48.226666 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fd4240c7a70 Connected.
2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
2021-10-21 08:11:48.226666 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [xml_handler] Params:
'Event-Name: REQUEST_PARAMS
Core-UUID: 383771d0-00bf-11ec-97b1-7dfb9f6e00f0
FreeSWITCH-Hostname: astpp1.local
FreeSWITCH-Switchname: astpp1.local
FreeSWITCH-IPv4: 89.46.223.193
FreeSWITCH-IPv6: %3A%3A1
Event-Date-Local: 2021-10-21%2008%3A11%3A48
Event-Date-GMT: Thu,%2021%20Oct%202021%2012%3A11%3A48%20GMT
Event-Date-Timestamp: 1634818308226666
Event-Calling-File: mod_dialplan_xml.c
Event-Calling-Function: dialplan_xml_locate
Event-Calling-Line-Number: 608
Event-Sequence: 20515580
Channel-State: CS_ROUTING
Channel-Call-State: RINGING
Channel-State-Number: 2
Channel-Name: sofia/default/%2B447578992323%40199.244.96.39
Unique-ID: 10d93058-3268-11ec-894a-7dfb9f6e00f0
Call-Direction: inbound
Presence-Call-Direction: inbound
Channel-HIT-Dialplan: true
Channel-Presence-ID: %2B447578992323%40199.244.96.39
Channel-Call-UUID: 10d93058-3268-11ec-894a-7dfb9f6e00f0
Answer-State: ringing
Channel-Read-Codec-Name: PCMA
Channel-Read-Codec-Rate: 8000
Channel-Read-Codec-Bit-Rate: 64000
Channel-Write-Codec-Name: PCMA
Channel-Write-Codec-Rate: 8000
Channel-Write-Codec-Bit-Rate: 64000
Caller-Direction: inbound
Caller-Logical-Direction: inbound
Caller-Username: %2B447578992323
Caller-Dialplan: XML
Caller-Caller-ID-Name: %2B447578992323
Caller-Caller-ID-Number: %2B447578992323
Caller-Orig-Caller-ID-Name: %2B447578992323
Caller-Orig-Caller-ID-Number: %2B447578992323
Caller-Network-Addr: 136.243.196.198
Caller-ANI: %2B447578992323
Caller-Destination-Number: 442039123706
Caller-Unique-ID: 10d93058-3268-11ec-894a-7dfb9f6e00f0
Caller-Source: mod_sofia
Caller-Context: default
Caller-Channel-Name: sofia/default/%2B447578992323%40199.244.96.39
Caller-Profile-Index: 1
Caller-Profile-Created-Time: 1634818308226666
Caller-Channel-Created-Time: 1634818308226666
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: 10d93058-3268-11ec-894a-7dfb9f6e00f0
variable_session_id: 727137
variable_sip_from_user: %2B447578992323
variable_sip_from_uri: %2B447578992323%40199.244.96.39
variable_sip_from_host: 199.244.96.39
variable_video_media_flow: sendrecv
variable_channel_name: sofia/default/%2B447578992323%40199.244.96.39
variable_sip_call_id: 24548822-3843807107-1184227746%40MSX17.gammatelecom.com~2o
variable_ep_codec_string: CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b,mod_g729.G729%408000h%4020i%408000b,CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b
variable_sip_local_network_addr: 89.46.223.193
variable_sip_network_ip: 136.243.196.198
variable_sip_network_port: 5060
variable_sip_invite_stamp: 1634818308226666
variable_sip_received_ip: 136.243.196.198
variable_sip_received_port: 5060
variable_sip_via_protocol: udp
variable_sip_authorized: true
variable_sip_acl_authed_by: default
variable_sip_from_user_stripped: 447578992323
variable_sip_from_tag: yjp5nyxvdrst26nt.o
variable_sofia_profile_name: default
variable_recovery_profile_name: default
variable_sip_Remote-Party-ID: %3Csip%3A%2B447578992323%40199.244.96.39%3E%3Bparty%3Dcalling
variable_sip_P-Asserted-Identity: %2B447578992323
variable_sip_cid_type: pid
variable_sip_invite_route_uri: %3Csip%3A136.243.196.198%3Blr%3Bdid%3D428.ac4ae483%3E,%3Csip%3A199.244.96.39%3Blr%3Bep%3E
variable_sip_invite_record_route: %3Csip%3A199.244.96.39%3Blr%3Bep%3E,%3Csip%3A136.243.196.198%3Blr%3Bdid%3D428.ac4ae483%3E
variable_sip_full_via: SIP/2.0/UDP%20136.243.196.198%3A5060%3Bbranch%3Dz9hG4bK8d7f.dc289622.0,SIP/2.0/UDP%20199.244.96.39%3A5060%3Breceived%3D199.244.96.39%3Bbranch%3Dz9hG4bK-524287-1---fd6c70cccdef37b3f58cd7e403893cc0%3Brport%3D5060,SIP/2.0/UDP%20199.244.96.46%3A5071%3Brport%3D5071%3Bbranch%3Dz9hG4bK-sixdqbr24druxrju
variable_sip_full_from: %3Csip%3A%2B447578992323%40199.244.96.39%3E%3Btag%3Dyjp5nyxvdrst26nt.o
variable_sip_full_to: %3Csip%3A442039123706%40newsip.israelnumber.com%3E
variable_sip_allow: INVITE,%20ACK,%20BYE,%20CANCEL,%20INFO,%20SUBSCRIBE,%20NOTIFY,%20REFER,%20MESSAGE,%20OPTIONS,%20UPDATE
variable_sip_req_user: 442039123706
variable_sip_req_uri: 442039123706%40newsip.israelnumber.com
variable_sip_req_host: newsip.israelnumber.com
variable_sip_to_user: 442039123706
variable_sip_to_uri: 442039123706%40newsip.israelnumber.com
variable_sip_to_host: newsip.israelnumber.com
variable_sip_contact_user: %2B447578992323
variable_sip_contact_port: 5071
variable_sip_contact_uri: %2B447578992323%40199.244.96.46%3A5071
variable_sip_contact_host: 199.244.96.46
variable_sip_user_agent: PortaSIP
variable_sip_via_host: 136.243.196.198
variable_sip_via_port: 5060
variable_max_forwards: 30
variable_presence_id: %2B447578992323%40199.244.96.39
variable_sip_h_X-AUTH-IP: 199.244.96.39
variable_sip_h_P-Accountcode: 594905904039
variable_sip_h_P-effective_caller_id_name: %2B447578992323
variable_sip_h_P-effective_caller_id_number: %2B447578992323
variable_switch_r_sdp: v%3D0%0D%0Ao%3DPortaSIP%20396105275207201534%201%20IN%20IP4%20199.244.96.46%0D%0As%3Dsip%20call%0D%0At%3D0%200%0D%0Am%3Daudio%2045912%20RTP/AVP%208%2018%200%20101%0D%0Ac%3DIN%20IP4%20199.244.96.46%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A18%20G729/8000%0D%0Aa%3Dfmtp%3A18%20annexb%3Dno%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-15%0D%0Aa%3DsilenceSupp%3Aoff%20-%20-%20-%20-%0D%0Aa%3Dptime%3A20%0D%0A
variable_rtp_use_codec_string: PCMA,PCMU,G729
variable_audio_media_flow: sendrecv
variable_remote_media_ip: 199.244.96.46
variable_remote_media_port: 45912
variable_rtp_audio_recv_pt: 8
variable_rtp_use_codec_name: PCMA
variable_rtp_use_codec_rate: 8000
variable_rtp_use_codec_ptime: 20
variable_rtp_use_codec_channels: 1
variable_rtp_last_audio_codec_string: PCMA%408000h%4020i%401c
variable_read_codec: PCMA
variable_original_read_codec: PCMA
variable_read_rate: 8000
variable_original_read_rate: 8000
variable_write_codec: PCMA
variable_write_rate: 8000
variable_dtmf_type: rfc2833
variable_endpoint_disposition: RECEIVED
variable_call_uuid: 10d93058-3268-11ec-894a-7dfb9f6e00f0
Hunt-Direction: inbound
Hunt-Logical-Direction: inbound
Hunt-Username: %2B447578992323
Hunt-Dialplan: XML
Hunt-Caller-ID-Name: %2B447578992323
Hunt-Caller-ID-Number: %2B447578992323
Hunt-Orig-Caller-ID-Name: %2B447578992323
Hunt-Orig-Caller-ID-Number: %2B447578992323
Hunt-Network-Addr: 136.243.196.198
Hunt-ANI: %2B447578992323
Hunt-Destination-Number: 442039123706
Hunt-Unique-ID: 10d93058-3268-11ec-894a-7dfb9f6e00f0
Hunt-Source: mod_sofia
Hunt-Context: default
Hunt-Channel-Name: sofia/default/%2B447578992323%40199.244.96.39
Hunt-Profile-Index: 1
Hunt-Profile-Created-Time: 1634818308226666
Hunt-Channel-Created-Time: 1634818308226666
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

'
2021-10-21 08:11:48.226666 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] key_name:
2021-10-21 08:11:48.226666 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] key_value:
2021-10-21 08:11:48.226666 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] tag_name:
2021-10-21 08:11:48.226666 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] section: dialplan
2021-10-21 08:11:48.226666 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] blind_flag: 0
2021-10-21 08:11:48.226666 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Dialed number : 442039123706
2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '442039123706' limit 1
2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number as number FROM accessnumber WHERE access_number = '442039123706' AND status=0 limit 1
2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE domain = '199.244.96.39' limit 1
2021-10-21 08:11:48.226666 [WARNING] switch_cpp.cpp:1365 [ASTPP] [FAXER] in custom Caller ID
2021-10-21 08:11:48.226666 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Caller Id name / number  : +447578992323 / +447578992323
2021-10-21 08:11:48.226666 [NOTICE] switch_cpp.cpp:1365 [ASTPP] DID global translation id: 13
2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 13 AND status=0 limit 1
2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :"+/*".....442039123706
2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [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 ="442039123706" LIMIT 1
2021-10-21 08:11:48.226666 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Call direction : inbound
2021-10-21 08:11:48.226666 [INFO] switch_cpp.cpp:1365 [ASTPP] [Accountcode1 : 594905904039]
2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE (number = "594905904039" OR number = "594905904039") AND deleted = 0 limit 1
2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="442039123706" AND A.accountid = '855' limit 1
2021-10-21 08:11:48.226666 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 442039123706
2021-10-21 08:11:48.226666 [NOTICE] switch_cpp.cpp:1365 [ASTPP] DID global translation id: 13
2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 13 AND status=0 limit 1
2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :"+/*".....442039123706
2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [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 ="442039123706" LIMIT 1
2021-10-21 08:11:48.226666 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] New Call direction : inbound
2021-10-21 08:11:48.226666 [NOTICE] switch_cpp.cpp:1365 [ASTPP] DID global translation id: 13
2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 13 AND status=0 limit 1
2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :"+/*".....442039123706
2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="442039123706" AND (accountid = 0 OR status = 1) LIMIT 1
2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PACKAGE_INFO] call_direction :inbound
2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PACKAGE_INFO] DID_ACCOUNTID :851
2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [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 (patterns = '^442039123706.*' OR patterns = '^44203912370.*' OR patterns = '^4420391237.*' OR patterns = '^442039123.*' OR patterns = '^44203912.*' OR patterns = '^4420391.*' OR patterns = '^442039.*' OR patterns = '^44203.*' OR patterns = '^4420.*' OR patterns = '^442.*' OR patterns = '^44.*' OR patterns = '^4.*' OR patterns ='--') AND accountid = 851 ORDER BY LENGTH(PKGPTR.patterns) DESC
2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '442039123706' OR number = '44203912370' OR number = '4420391237' OR number = '442039123' OR number = '44203912' OR number = '4420391' OR number = '442039' OR number = '44203' OR number = '4420' OR number = '442' OR number = '44' OR number = '4' OR number ='--') OR (number = '442039123706*' OR number = '44203912370*' OR number = '4420391237*' OR number = '442039123*' OR number = '44203912*' OR number = '4420391*' OR number = '442039*' OR number = '44203*' OR number = '4420*' OR number = '442*' OR number = '44*' OR number = '4*' OR number ='--') OR (number = '+447578992323' OR number = '+44757899232' OR number = '+4475789923' OR number = '+447578992' OR number = '+44757899' OR number = '+4475789' OR number = '+447578' OR number = '+44757' OR number = '+4475' OR number = '+447' OR number = '+44' OR number = '+4' OR number = '+' OR number ='--') OR (number = '+447578992323*' OR number = '+44757899232*' OR number = '+4475789923*' OR number = '+447578992*' OR number = '+44757899*' OR number = '+4475789*' OR number = '+447578*' OR number = '+44757*' OR number = '+4475*' OR number = '+447*' OR number = '+44*' OR number = '+4*' OR number = '+*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] Call Barring Status : -1
2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '442039123706' OR routing_prefix = '44203912370' OR routing_prefix = '4420391237' OR routing_prefix = '442039123' OR routing_prefix = '44203912' OR routing_prefix = '4420391' OR routing_prefix = '442039' OR routing_prefix = '44203' OR routing_prefix = '4420' OR routing_prefix = '442' OR routing_prefix = '44' OR routing_prefix = '4' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] =============== Account Information ===================
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] User id : 855
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] Account code : 594905904039
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] Balance : 10000
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] Ratecard id : 1
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] CID Pool : 0
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] ========================================================
2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 1 AND status = 0
2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_RATES] call_direction :inbound
2021-10-21 08:11:48.246675 [NOTICE] switch_cpp.cpp:1365 [ASTPP] DID global translation id: 13
2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 13 AND status=0 limit 1
2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :"+/*".....442039123706
2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [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 ="442039123706" LIMIT 1
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] call_direction:::::: inbound
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] =============== Rates Information ===================
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] ID : 12355
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] Connectcost : 0.00000
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] Includedseconds : 0
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] Cost : 0.01300
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] comment : London Landline
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] Country Id : 200
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] Accid : 855
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] ================================================================
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] [FIND_MAXLENGTH] Your10000 balance Accountid 855 !!!
2021-10-21 08:11:48.246675 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [FIND_MAXLENGTH] Limiting call to config max length 180 mins!
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] Call Max length duration : 180 minutes
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] [userinfo] INB_FREE:TRUE
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] [userinfo] free_inbound:1
2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 855 AND status=0 LIMIT 1
2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE (id = "851" OR number = "851") AND deleted = 0 limit 1
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] [userinfo] Actual CustomerInfo XML:855
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] [userinfo] Userinfo XML:855
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] [userinfo] Actual CustomerInfo XML : 855
2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE accountid = 851 limit 1
2021-10-21 08:11:48.246675 [WARNING] switch_cpp.cpp:1365 [ASTPP] [user_domain] user_domainVoicespin802.newsip.israelnumber.com
2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] maxlength::::::::: 180
2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE accountid = 851 limit 1
2021-10-21 08:11:48.246675 [WARNING] switch_cpp.cpp:1365 [ASTPP] [user_domain] user_domainVoicespin802.newsip.israelnumber.com
2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] custom_function_name:::::::::::::::::::::::::custom_inbound_2
2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Generated XML:<?xml version="1.0" encoding="UTF-8" standalone="no"?>
<document type="freeswitch/xml">
<section name="dialplan" description="ASTPP Dialplan">
<context name="default">
<extension name="442039123706">
<condition field="destination_number" expression="442039123706">
<action application="set" data="effective_destination_number=442039123706"/>
<action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
<action application="set" data="bridge_pre_execute_bleg_data=+10800 normal_clearing"/>
<action application="set" data="callstart=2021-10-21 12:11:48"/>
<action application="set" data="hangup_after_bridge=true"/>
<action application="set" data="continue_on_fail=1,19,20,21,27,34,38,41,42,44,52,58,69,79,102,602"/>
<action application="set" data="account_id=855"/>
<action application="set" data="parent_id=0"/>
<action application="set" data="entity_id=3"/>
<action application="set" data="call_processed=internal"/>
<action application="set" data="call_direction=inbound"/>
<action application="set" data="accountname=default"/>
<action application="set" data="filename1=astpp.pbx.lua"/>
<action application="set" data="origination_rates_did=ID:12355|CODE:^442039123706.*|DESTINATION:London Landline|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:2|COST:0.01300|INC:60|INITIALBLOCK:60|RATEGROUP:0|MARKUP:0|CI:200|ACCID:851"/>
<action application="set" data="origination_rates=0"/>
<action application="set" data="original_caller_id_name=+447578992323"/>
<action application="set" data="original_caller_id_number=+447578992323"/>
<action application="set" data="effective_caller_id_name=+447578992323"/>
<action application="set" data="effective_caller_id_number=+447578992323"/>
<action application="set" data="receiver_accid=851"/>
<action application="export" data="domain_name=Voicespin802.newsip.israelnumber.com"/>
<action application="export" data="sip_to_host=Voicespin802.newsip.israelnumber.com"/>
<action application="limit" data="db 442039123706 did_442039123706 2 !SWITCH_CONGESTION"/>
<action application="export" data="presence_data=x|||AvoxiIn1(594905904039)|||^442039123706.* // London Landline // 0.01300||||||DID|||199.244.96.39"/>
<action application="export" data="call_type=2"/>
<action application="set" data="calltype=DIRECT-IP"/>
<action application="bridge" data="{sip_invite_params=user=DIRECT-IP}[leg_timeout=30]sofia/${sofia_profile_name}/442039123706@35.205.116.118"/>
</condition>
</extension>
</context>
</section>
</document>
2021-10-21 08:11:48.246675 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fd4240c7a70 released.
Dialplan: sofia/default/+447578992323@199.244.96.39 parsing [default->442039123706] continue=false
Dialplan: sofia/default/+447578992323@199.244.96.39 Regex (PASS) [442039123706] destination_number(442039123706) =~ /442039123706/ break=on-false
Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(effective_destination_number=442039123706)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(bridge_pre_execute_bleg_app=sched_hangup)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(bridge_pre_execute_bleg_data=+10800 normal_clearing)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(callstart=2021-10-21 12:11:48)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(hangup_after_bridge=true)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(continue_on_fail=1,19,20,21,27,34,38,41,42,44,52,58,69,79,102,602)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(account_id=855)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(parent_id=0)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(entity_id=3)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(call_processed=internal)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(call_direction=inbound)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(accountname=default)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(filename1=astpp.pbx.lua)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(origination_rates_did=ID:12355|CODE:^442039123706.*|DESTINATION:London Landline|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:2|COST:0.01300|INC:60|INITIALBLOCK:60|RATEGROUP:0|MARKUP:0|CI:200|ACCID:851)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(origination_rates=0)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(original_caller_id_name=+447578992323)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(original_caller_id_number=+447578992323)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(effective_caller_id_name=+447578992323)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(effective_caller_id_number=+447578992323)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(receiver_accid=851)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action export(domain_name=Voicespin802.newsip.israelnumber.com)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action export(sip_to_host=Voicespin802.newsip.israelnumber.com)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action limit(db 442039123706 did_442039123706 2 !SWITCH_CONGESTION)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action export(presence_data=x|||AvoxiIn1(594905904039)|||^442039123706.* // London Landline // 0.01300||||||DID|||199.244.96.39)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action export(call_type=2)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(calltype=DIRECT-IP)
Dialplan: sofia/default/+447578992323@199.244.96.39 Action bridge({sip_invite_params=user=DIRECT-IP}[leg_timeout=30]sofia/${sofia_profile_name}/442039123706@35.205.116.118)
2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:286 (sofia/default/+447578992323@199.244.96.39) State Change CS_ROUTING -> CS_EXECUTE
2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:643 (sofia/default/+447578992323@199.244.96.39) State ROUTING going to sleep
2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:584 (sofia/default/+447578992323@199.244.96.39) Running State Change CS_EXECUTE (Cur 3 Tot 727137)
2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:650 (sofia/default/+447578992323@199.244.96.39) State EXECUTE
2021-10-21 08:11:48.246675 [DEBUG] mod_sofia.c:198 sofia/default/+447578992323@199.244.96.39 SOFIA EXECUTE
2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:328 sofia/default/+447578992323@199.244.96.39 Standard EXECUTE
EXECUTE sofia/default/+447578992323@199.244.96.39 set(effective_destination_number=442039123706)
2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [effective_destination_number]=[442039123706]
EXECUTE sofia/default/+447578992323@199.244.96.39 set(bridge_pre_execute_bleg_app=sched_hangup)
2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [bridge_pre_execute_bleg_app]=[sched_hangup]
EXECUTE sofia/default/+447578992323@199.244.96.39 set(bridge_pre_execute_bleg_data=+10800 normal_clearing)
2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [bridge_pre_execute_bleg_data]=[+10800 normal_clearing]
EXECUTE sofia/default/+447578992323@199.244.96.39 set(callstart=2021-10-21 12:11:48)
2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [callstart]=[2021-10-21 12:11:48]
EXECUTE sofia/default/+447578992323@199.244.96.39 set(hangup_after_bridge=true)
2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [hangup_after_bridge]=[true]
EXECUTE sofia/default/+447578992323@199.244.96.39 set(continue_on_fail=1,19,20,21,27,34,38,41,42,44,52,58,69,79,102,602)
2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [continue_on_fail]=[1,19,20,21,27,34,38,41,42,44,52,58,69,79,102,602]
EXECUTE sofia/default/+447578992323@199.244.96.39 set(account_id=855)
2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [account_id]=[855]
EXECUTE sofia/default/+447578992323@199.244.96.39 set(parent_id=0)
2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [parent_id]=[0]
EXECUTE sofia/default/+447578992323@199.244.96.39 set(entity_id=3)
2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [entity_id]=[3]
EXECUTE sofia/default/+447578992323@199.244.96.39 set(call_processed=internal)
2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [call_processed]=[internal]
EXECUTE sofia/default/+447578992323@199.244.96.39 set(call_direction=inbound)
2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [call_direction]=[inbound]
EXECUTE sofia/default/+447578992323@199.244.96.39 set(accountname=default)
2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [accountname]=[default]
EXECUTE sofia/default/+447578992323@199.244.96.39 set(filename1=astpp.pbx.lua)
2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [filename1]=[astpp.pbx.lua]
EXECUTE sofia/default/+447578992323@199.244.96.39 set(origination_rates_did=ID:12355|CODE:^442039123706.*|DESTINATION:London Landline|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:2|COST:0.01300|INC:60|INITIALBLOCK:60|RATEGROUP:0|MARKUP:0|CI:200|ACCID:851)
2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [origination_rates_did]=[ID:12355|CODE:^442039123706.*|DESTINATION:London Landline|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:2|COST:0.01300|INC:60|INITIALBLOCK:60|RATEGROUP:0|MARKUP:0|CI:200|ACCID:851]
EXECUTE sofia/default/+447578992323@199.244.96.39 set(origination_rates=0)
2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [origination_rates]=[0]
EXECUTE sofia/default/+447578992323@199.244.96.39 set(original_caller_id_name=+447578992323)
2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [original_caller_id_name]=[+447578992323]
EXECUTE sofia/default/+447578992323@199.244.96.39 set(original_caller_id_number=+447578992323)
2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [original_caller_id_number]=[+447578992323]
EXECUTE sofia/default/+447578992323@199.244.96.39 set(effective_caller_id_name=+447578992323)
2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [effective_caller_id_name]=[+447578992323]
EXECUTE sofia/default/+447578992323@199.244.96.39 set(effective_caller_id_number=+447578992323)
2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [effective_caller_id_number]=[+447578992323]
EXECUTE sofia/default/+447578992323@199.244.96.39 set(receiver_accid=851)
2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [receiver_accid]=[851]
EXECUTE sofia/default/+447578992323@199.244.96.39 export(domain_name=Voicespin802.newsip.israelnumber.com)
2021-10-21 08:11:48.246675 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [domain_name]=[Voicespin802.newsip.israelnumber.com]
EXECUTE sofia/default/+447578992323@199.244.96.39 export(sip_to_host=Voicespin802.newsip.israelnumber.com)
2021-10-21 08:11:48.246675 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [sip_to_host]=[Voicespin802.newsip.israelnumber.com]
EXECUTE sofia/default/+447578992323@199.244.96.39 limit(db 442039123706 did_442039123706 2 !SWITCH_CONGESTION)
2021-10-21 08:11:48.246675 [DEBUG] switch_limit.c:126 incr called: 442039123706_did_442039123706 max:2, interval:0
2021-10-21 08:11:48.246675 [DEBUG] mod_db.c:194 Usage for 442039123706_did_442039123706 is now 1/2
EXECUTE sofia/default/+447578992323@199.244.96.39 export(presence_data=x|||AvoxiIn1(594905904039)|||^442039123706.* // London Landline // 0.01300||||||DID|||199.244.96.39)
2021-10-21 08:11:48.246675 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [presence_data]=[x|||AvoxiIn1(594905904039)|||^442039123706.* // London Landline // 0.01300||||||DID|||199.244.96.39]
EXECUTE sofia/default/+447578992323@199.244.96.39 export(call_type=2)
2021-10-21 08:11:48.246675 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [call_type]=[2]
EXECUTE sofia/default/+447578992323@199.244.96.39 set(calltype=DIRECT-IP)
2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [calltype]=[DIRECT-IP]
EXECUTE sofia/default/+447578992323@199.244.96.39 bridge({sip_invite_params=user=DIRECT-IP}[leg_timeout=30]sofia/default/442039123706@35.205.116.118)
2021-10-21 08:11:48.246675 [DEBUG] switch_channel.c:1250 sofia/default/+447578992323@199.244.96.39 EXPORTING[export_vars] [domain_name]=[Voicespin802.newsip.israelnumber.com] to event
2021-10-21 08:11:48.246675 [DEBUG] switch_channel.c:1250 sofia/default/+447578992323@199.244.96.39 EXPORTING[export_vars] [sip_to_host]=[Voicespin802.newsip.israelnumber.com] to event
2021-10-21 08:11:48.246675 [DEBUG] switch_channel.c:1250 sofia/default/+447578992323@199.244.96.39 EXPORTING[export_vars] [presence_data]=[x|||AvoxiIn1(594905904039)|||^442039123706.* // London Landline // 0.01300||||||DID|||199.244.96.39] to event
2021-10-21 08:11:48.246675 [DEBUG] switch_channel.c:1250 sofia/default/+447578992323@199.244.96.39 EXPORTING[export_vars] [call_type]=[2] to event
2021-10-21 08:11:48.246675 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
2021-10-21 08:11:48.246675 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
2021-10-21 08:11:48.246675 [NOTICE] switch_channel.c:1104 New Channel sofia/default/442039123706@35.205.116.118 [10de8f26-3268-11ec-896a-7dfb9f6e00f0]
2021-10-21 08:11:48.246675 [DEBUG] mod_sofia.c:4819 (sofia/default/442039123706@35.205.116.118) State Change CS_NEW -> CS_INIT
2021-10-21 08:11:48.246675 [DEBUG] switch_ivr_originate.c:2963 sofia/default/442039123706@35.205.116.118 Setting leg timeout to 30
2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:584 (sofia/default/442039123706@35.205.116.118) Running State Change CS_INIT (Cur 4 Tot 727138)
2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:627 (sofia/default/442039123706@35.205.116.118) State INIT
2021-10-21 08:11:48.246675 [DEBUG] mod_sofia.c:90 sofia/default/442039123706@35.205.116.118 SOFIA INIT
2021-10-21 08:11:48.246675 [DEBUG] sofia_glue.c:1295 sofia/default/442039123706@35.205.116.118 sending invite version: 1.6.20  64bit
Local SDP:
v=0
o=FreeSWITCH 1634791738 1634791739 IN IP4 89.46.223.193
s=FreeSWITCH
c=IN IP4 89.46.223.193
t=0 0
m=audio 26570 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:40 sofia/default/442039123706@35.205.116.118 Standard INIT
2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:48 (sofia/default/442039123706@35.205.116.118) State Change CS_INIT -> CS_ROUTING
2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:627 (sofia/default/442039123706@35.205.116.118) State INIT going to sleep
2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:584 (sofia/default/442039123706@35.205.116.118) Running State Change CS_ROUTING (Cur 4 Tot 727138)
2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:643 (sofia/default/442039123706@35.205.116.118) State ROUTING
2021-10-21 08:11:48.246675 [DEBUG] mod_sofia.c:143 sofia/default/442039123706@35.205.116.118 SOFIA ROUTING
2021-10-21 08:11:48.246675 [DEBUG] switch_ivr_originate.c:67 (sofia/default/442039123706@35.205.116.118) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:643 (sofia/default/442039123706@35.205.116.118) State ROUTING going to sleep
2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:584 (sofia/default/442039123706@35.205.116.118) Running State Change CS_CONSUME_MEDIA (Cur 4 Tot 727138)
2021-10-21 08:11:48.246675 [DEBUG] sofia.c:7084 Channel sofia/default/442039123706@35.205.116.118 entering state [calling][0]
2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:662 (sofia/default/442039123706@35.205.116.118) State CONSUME_MEDIA
2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:662 (sofia/default/442039123706@35.205.116.118) State CONSUME_MEDIA going to sleep
2021-10-21 08:11:48.526661 [DEBUG] sofia.c:7084 Channel sofia/default/442039123706@35.205.116.118 entering state [completing][200]
2021-10-21 08:11:48.526661 [DEBUG] sofia.c:7094 Remote SDP:
v=0
o=root 894262298 894262298 IN IP4 104.155.54.20
s=Asterisk PBX 13.29.2
c=IN IP4 104.155.54.20
t=0 0
m=audio 15608 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150

2021-10-21 08:11:48.526661 [DEBUG] sofia.c:7084 Channel sofia/default/442039123706@35.205.116.118 entering state [ready][200]
2021-10-21 08:11:48.526661 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2021-10-21 08:11:48.526661 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2021-10-21 08:11:48.526661 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
2021-10-21 08:11:48.526661 [DEBUG] switch_core_media.c:3061 Set Codec sofia/default/442039123706@35.205.116.118 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
2021-10-21 08:11:48.526661 [DEBUG] switch_core_codec.c:111 sofia/default/442039123706@35.205.116.118 Original read codec set to PCMA:8
2021-10-21 08:11:48.526661 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
2021-10-21 08:11:48.526661 [DEBUG] switch_core_media.c:4767 sofia/default/442039123706@35.205.116.118 Set 2833 dtmf send payload to 101 recv payload to 101
2021-10-21 08:11:48.526661 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/default/442039123706@35.205.116.118] 89.46.223.193 port 26570 -> 104.155.54.20 port 15608 codec: 8 ms: 20
2021-10-21 08:11:48.526661 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms
2021-10-21 08:11:48.526661 [DEBUG] switch_core_media.c:7180 sofia/default/442039123706@35.205.116.118 Set 2833 dtmf send payload to 101
2021-10-21 08:11:48.526661 [DEBUG] switch_core_media.c:7187 sofia/default/442039123706@35.205.116.118 Set 2833 dtmf receive payload to 101
2021-10-21 08:11:48.526661 [DEBUG] switch_core_media.c:7210 sofia/default/442039123706@35.205.116.118 Set rtp dtmf delay to 40
2021-10-21 08:11:48.526661 [NOTICE] sofia.c:8218 Channel [sofia/default/442039123706@35.205.116.118] has been answered
2021-10-21 08:11:48.526661 [DEBUG] switch_channel.c:3773 (sofia/default/442039123706@35.205.116.118) Callstate Change DOWN -> ACTIVE
2021-10-21 08:11:48.546661 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/default/+447578992323@199.244.96.39] 89.46.223.193 port 21052 -> 199.244.96.46 port 45912 codec: 8 ms: 20
2021-10-21 08:11:48.546661 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms
2021-10-21 08:11:48.546661 [DEBUG] switch_core_media.c:7180 sofia/default/+447578992323@199.244.96.39 Set 2833 dtmf send payload to 101
2021-10-21 08:11:48.546661 [DEBUG] switch_core_media.c:7187 sofia/default/+447578992323@199.244.96.39 Set 2833 dtmf receive payload to 101
2021-10-21 08:11:48.546661 [DEBUG] switch_core_media.c:7210 sofia/default/+447578992323@199.244.96.39 Set rtp dtmf delay to 40
2021-10-21 08:11:48.546661 [DEBUG] mod_sofia.c:850 Local SDP sofia/default/+447578992323@199.244.96.39:
v=0
o=FreeSWITCH 1634797256 1634797257 IN IP4 89.46.223.193
s=FreeSWITCH
c=IN IP4 89.46.223.193
t=0 0
m=audio 21052 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

2021-10-21 08:11:48.546661 [NOTICE] switch_ivr_originate.c:3647 Channel [sofia/default/+447578992323@199.244.96.39] has been answered
2021-10-21 08:11:48.546661 [DEBUG] switch_channel.c:3773 (sofia/default/+447578992323@199.244.96.39) Callstate Change RINGING -> ACTIVE
2021-10-21 08:11:48.546661 [DEBUG] sofia.c:7084 Channel sofia/default/+447578992323@199.244.96.39 entering state [completed][200]
2021-10-21 08:11:48.546661 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/default/442039123706@35.205.116.118]
2021-10-21 08:11:48.546661 [DEBUG] switch_ivr_bridge.c:1614 (sofia/default/442039123706@35.205.116.118) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2021-10-21 08:11:48.546661 [DEBUG] switch_core_state_machine.c:584 (sofia/default/442039123706@35.205.116.118) Running State Change CS_EXCHANGE_MEDIA (Cur 4 Tot 727138)
2021-10-21 08:11:48.546661 [DEBUG] switch_core_state_machine.c:653 (sofia/default/442039123706@35.205.116.118) State EXCHANGE_MEDIA
2021-10-21 08:11:48.546661 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
2021-10-21 08:11:48.706665 [DEBUG] sofia.c:7084 Channel sofia/default/+447578992323@199.244.96.39 entering state [ready][200]
2021-10-21 08:11:48.726735 [DEBUG] switch_ivr_bridge.c:546 sofia/default/442039123706@35.205.116.118 Bridge execute app sched_hangup(+10800 normal_clearing)
2021-10-21 08:11:48.746667 [DEBUG] switch_ivr.c:623 sofia/default/442039123706@35.205.116.118 Command Execute sched_hangup(+10800 normal_clearing)
EXECUTE sofia/default/442039123706@35.205.116.118 sched_hangup(+10800 normal_clearing)
2021-10-21 08:11:48.746667 [DEBUG] switch_scheduler.c:249 Added task 82643 switch_ivr_schedule_hangup (10de8f26-3268-11ec-896a-7dfb9f6e00f0) to run at 1634829108
2021-10-21 08:11:48.906674 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed.
2021-10-21 08:11:50.986672 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed.
2021-10-21 08:11:56.346678 [WARNING] sofia_reg.c:1792 SIP auth challenge (REGISTER) on sofia profile 'default' for [775@89.46.223.193] from ip 185.166.84.32
2021-10-21 08:11:56.406708 [WARNING] sofia_reg.c:2906 Can't find user [775@89.46.223.193] from 185.166.84.32
You must define a domain called '89.46.223.193' in your directory and add a user with the id="775" attribute
and you must configure your device to use the proper domain in it's authentication credentials.
2021-10-21 08:11:56.406708 [WARNING] sofia_reg.c:1737 SIP auth failure (REGISTER) on sofia profile 'default' for [775@89.46.223.193] from ip 185.166.84.32
2021-10-21 08:12:00.946725 [NOTICE] sofia.c:1012 Hangup sofia/default/442031501300@212.199.161.206 [CS_EXECUTE] [NORMAL_CLEARING]
2021-10-21 08:12:00.946725 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/default/442031501300@212.199.161.206]
2021-10-21 08:12:00.966668 [DEBUG] switch_ivr_bridge.c:706 sofia/default/442031501300@212.199.161.206 ending bridge by request from write function
2021-10-21 08:12:00.966668 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/default/443453000101]
2021-10-21 08:12:00.966668 [NOTICE] switch_ivr_bridge.c:904 Hangup sofia/default/443453000101 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2021-10-21 08:12:00.966668 [DEBUG] switch_core_state_machine.c:653 (sofia/default/443453000101) State EXCHANGE_MEDIA going to sleep
2021-10-21 08:12:00.966668 [DEBUG] switch_core_state_machine.c:584 (sofia/default/443453000101) Running State Change CS_HANGUP (Cur 4 Tot 727138)
2021-10-21 08:12:00.966668 [DEBUG] switch_ivr_async.c:1316 Stop recording file /var/lib/freeswitch/recordings/f5563380-3267-11ec-8918-7dfb9f6e00f0.wav
2021-10-21 08:12:00.966668 [DEBUG] switch_ivr_bridge.c:1712 sofia/default/443453000101 skip receive message [UNBRIDGE] (channel is hungup already)
2021-10-21 08:12:00.966668 [DEBUG] switch_ivr_bridge.c:1715 sofia/default/442031501300@212.199.161.206 skip receive message [UNBRIDGE] (channel is hungup already)
2021-10-21 08:12:00.966668 [DEBUG] switch_core_session.c:2815 sofia/default/442031501300@212.199.161.206 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2021-10-21 08:12:00.966668 [DEBUG] switch_core_state_machine.c:650 (sofia/default/442031501300@212.199.161.206) State EXECUTE going to sleep
2021-10-21 08:12:00.966668 [DEBUG] switch_core_state_machine.c:584 (sofia/default/442031501300@212.199.161.206) Running State Change CS_HANGUP (Cur 4 Tot 727138)
2021-10-21 08:12:00.966668 [DEBUG] switch_ivr_async.c:1316 Stop recording file /var/lib/freeswitch/recordings/f5563380-3267-11ec-8918-7dfb9f6e00f0.wav
2021-10-21 08:12:00.966668 [DEBUG] switch_ivr_async.c:1380 Channel is hung up
2021-10-21 08:12:00.966668 [DEBUG] switch_core_media_bug.c:1298 Removing BUG from sofia/default/442031501300@212.199.161.206
2021-10-21 08:12:00.966668 [DEBUG] switch_core_state_machine.c:850 (sofia/default/442031501300@212.199.161.206) Callstate Change ACTIVE -> HANGUP
2021-10-21 08:12:00.966668 [DEBUG] switch_core_state_machine.c:852 (sofia/default/442031501300@212.199.161.206) State HANGUP
2021-10-21 08:12:00.966668 [DEBUG] mod_sofia.c:438 Channel sofia/default/442031501300@212.199.161.206 hanging up, cause: NORMAL_CLEARING
2021-10-21 08:12:00.966668 [DEBUG] switch_core_state_machine.c:60 sofia/default/442031501300@212.199.161.206 Standard HANGUP, cause: NORMAL_CLEARING
2021-10-21 08:12:00.966668 [DEBUG] switch_core_state_machine.c:852 (sofia/default/442031501300@212.199.161.206) State HANGUP going to sleep
2021-10-21 08:12:00.966668 [DEBUG] switch_core_state_machine.c:619 (sofia/default/442031501300@212.199.161.206) State Change CS_HANGUP -> CS_REPORTING
2021-10-21 08:12:00.966668 [DEBUG] switch_core_state_machine.c:584 (sofia/default/442031501300@212.199.161.206) Running State Change CS_REPORTING (Cur 4 Tot 727138)
2021-10-21 08:12:00.966668 [DEBUG] switch_core_state_machine.c:938 (sofia/default/442031501300@212.199.161.206) State REPORTING
2021-10-21 08:12:00.966668 [INFO] mod_json_cdr.c:271 Process [f5563380-3267-11ec-8918-7dfb9f6e00f0.cdr.json]
2021-10-21 08:12:00.986672 [DEBUG] switch_ivr_async.c:1380 Channel is hung up
2021-10-21 08:12:00.986672 [DEBUG] switch_core_media_bug.c:1298 Removing BUG from sofia/default/443453000101
2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:850 (sofia/default/443453000101) Callstate Change ACTIVE -> HANGUP
2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:852 (sofia/default/443453000101) State HANGUP
2021-10-21 08:12:00.986672 [DEBUG] mod_sofia.c:432 sofia/default/443453000101 Overriding SIP cause 480 with 200 from the other leg
2021-10-21 08:12:00.986672 [DEBUG] mod_sofia.c:438 Channel sofia/default/443453000101 hanging up, cause: NORMAL_CLEARING
2021-10-21 08:12:00.986672 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/default/443453000101
2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:60 sofia/default/443453000101 Standard HANGUP, cause: NORMAL_CLEARING
2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:852 (sofia/default/443453000101) State HANGUP going to sleep
2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:619 (sofia/default/443453000101) State Change CS_HANGUP -> CS_REPORTING
2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:584 (sofia/default/443453000101) Running State Change CS_REPORTING (Cur 4 Tot 727138)
2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:938 (sofia/default/443453000101) State REPORTING
2021-10-21 08:12:00.986672 [INFO] mod_json_cdr.c:271 Process [f55c452c-3267-11ec-893e-7dfb9f6e00f0.cdr.json]
2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:174 sofia/default/443453000101 Standard REPORTING, cause: NORMAL_CLEARING
2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:938 (sofia/default/443453000101) State REPORTING going to sleep
2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:610 (sofia/default/443453000101) State Change CS_REPORTING -> CS_DESTROY
2021-10-21 08:12:00.986672 [DEBUG] switch_core_session.c:1665 Session 727136 (sofia/default/443453000101) Locked, Waiting on external entities
2021-10-21 08:12:00.986672 [NOTICE] switch_core_session.c:1683 Session 727136 (sofia/default/443453000101) Ended
2021-10-21 08:12:00.986672 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/443453000101 [CS_DESTROY]
2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:741 (sofia/default/443453000101) Running State Change CS_DESTROY (Cur 3 Tot 727138)
2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:751 (sofia/default/443453000101) State DESTROY
2021-10-21 08:12:00.986672 [DEBUG] mod_sofia.c:343 sofia/default/443453000101 SOFIA DESTROY
2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:181 sofia/default/443453000101 Standard DESTROY
2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:751 (sofia/default/443453000101) State DESTROY going to sleep
2021-10-21 08:12:01.046729 [DEBUG] switch_core_state_machine.c:174 sofia/default/442031501300@212.199.161.206 Standard REPORTING, cause: NORMAL_CLEARING
2021-10-21 08:12:01.046729 [DEBUG] switch_core_state_machine.c:938 (sofia/default/442031501300@212.199.161.206) State REPORTING going to sleep
2021-10-21 08:12:01.046729 [DEBUG] switch_core_state_machine.c:610 (sofia/default/442031501300@212.199.161.206) State Change CS_REPORTING -> CS_DESTROY
2021-10-21 08:12:01.046729 [DEBUG] switch_core_session.c:1665 Session 727135 (sofia/default/442031501300@212.199.161.206) Locked, Waiting on external entities
2021-10-21 08:12:01.046729 [NOTICE] switch_core_session.c:1683 Session 727135 (sofia/default/442031501300@212.199.161.206) Ended
2021-10-21 08:12:01.046729 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/442031501300@212.199.161.206 [CS_DESTROY]
2021-10-21 08:12:01.046729 [DEBUG] switch_core_state_machine.c:741 (sofia/default/442031501300@212.199.161.206) Running State Change CS_DESTROY (Cur 2 Tot 727138)
2021-10-21 08:12:01.046729 [DEBUG] switch_core_state_machine.c:751 (sofia/default/442031501300@212.199.161.206) State DESTROY
2021-10-21 08:12:01.046729 [DEBUG] mod_sofia.c:343 sofia/default/442031501300@212.199.161.206 SOFIA DESTROY
2021-10-21 08:12:01.046729 [DEBUG] switch_core_state_machine.c:181 sofia/default/442031501300@212.199.161.206 Standard DESTROY
2021-10-21 08:12:01.046729 [DEBUG] switch_core_state_machine.c:751 (sofia/default/442031501300@212.199.161.206) State DESTROY going to sleep
2021-10-21 08:12:01.246672 [DEBUG] switch_scheduler.c:144 Deleting task 82642 switch_ivr_schedule_hangup (f55c452c-3267-11ec-893e-7dfb9f6e00f0)
2021-10-21 08:12:03.766665 [NOTICE] sofia.c:1012 Hangup sofia/default/+447578992323@199.244.96.39 [CS_EXECUTE] [NORMAL_CLEARING]
2021-10-21 08:12:03.766665 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/default/+447578992323@199.244.96.39]
2021-10-21 08:12:03.766665 [DEBUG] switch_ivr_bridge.c:706 sofia/default/+447578992323@199.244.96.39 ending bridge by request from write function
2021-10-21 08:12:03.766665 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/default/442039123706@35.205.116.118]
2021-10-21 08:12:03.766665 [NOTICE] switch_ivr_bridge.c:904 Hangup sofia/default/442039123706@35.205.116.118 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:653 (sofia/default/442039123706@35.205.116.118) State EXCHANGE_MEDIA going to sleep
2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:584 (sofia/default/442039123706@35.205.116.118) Running State Change CS_HANGUP (Cur 2 Tot 727138)
2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:850 (sofia/default/442039123706@35.205.116.118) Callstate Change ACTIVE -> HANGUP
2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:852 (sofia/default/442039123706@35.205.116.118) State HANGUP
2021-10-21 08:12:03.766665 [DEBUG] mod_sofia.c:432 sofia/default/442039123706@35.205.116.118 Overriding SIP cause 480 with 200 from the other leg
2021-10-21 08:12:03.766665 [DEBUG] mod_sofia.c:438 Channel sofia/default/442039123706@35.205.116.118 hanging up, cause: NORMAL_CLEARING
2021-10-21 08:12:03.766665 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/default/442039123706@35.205.116.118
2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:60 sofia/default/442039123706@35.205.116.118 Standard HANGUP, cause: NORMAL_CLEARING
2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:852 (sofia/default/442039123706@35.205.116.118) State HANGUP going to sleep
2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:619 (sofia/default/442039123706@35.205.116.118) State Change CS_HANGUP -> CS_REPORTING
2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:584 (sofia/default/442039123706@35.205.116.118) Running State Change CS_REPORTING (Cur 2 Tot 727138)
2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:938 (sofia/default/442039123706@35.205.116.118) State REPORTING
2021-10-21 08:12:03.766665 [DEBUG] switch_ivr_bridge.c:1712 sofia/default/442039123706@35.205.116.118 skip receive message [UNBRIDGE] (channel is hungup already)
2021-10-21 08:12:03.766665 [DEBUG] switch_ivr_bridge.c:1715 sofia/default/+447578992323@199.244.96.39 skip receive message [UNBRIDGE] (channel is hungup already)
2021-10-21 08:12:03.766665 [INFO] mod_json_cdr.c:271 Process [10de8f26-3268-11ec-896a-7dfb9f6e00f0.cdr.json]
2021-10-21 08:12:03.766665 [DEBUG] switch_core_session.c:2815 sofia/default/+447578992323@199.244.96.39 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:650 (sofia/default/+447578992323@199.244.96.39) State EXECUTE going to sleep
2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:584 (sofia/default/+447578992323@199.244.96.39) Running State Change CS_HANGUP (Cur 2 Tot 727138)
2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:850 (sofia/default/+447578992323@199.244.96.39) Callstate Change ACTIVE -> HANGUP
2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:852 (sofia/default/+447578992323@199.244.96.39) State HANGUP
2021-10-21 08:12:03.766665 [DEBUG] mod_sofia.c:438 Channel sofia/default/+447578992323@199.244.96.39 hanging up, cause: NORMAL_CLEARING
2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:60 sofia/default/+447578992323@199.244.96.39 Standard HANGUP, cause: NORMAL_CLEARING
2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:852 (sofia/default/+447578992323@199.244.96.39) State HANGUP going to sleep
2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:619 (sofia/default/+447578992323@199.244.96.39) State Change CS_HANGUP -> CS_REPORTING
2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:584 (sofia/default/+447578992323@199.244.96.39) Running State Change CS_REPORTING (Cur 2 Tot 727138)
2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:938 (sofia/default/+447578992323@199.244.96.39) State REPORTING
2021-10-21 08:12:03.766665 [INFO] mod_json_cdr.c:271 Process [10d93058-3268-11ec-894a-7dfb9f6e00f0.cdr.json]
2021-10-21 08:12:03.786666 [DEBUG] switch_core_state_machine.c:174 sofia/default/442039123706@35.205.116.118 Standard REPORTING, cause: NORMAL_CLEARING
2021-10-21 08:12:03.786666 [DEBUG] switch_core_state_machine.c:938 (sofia/default/442039123706@35.205.116.118) State REPORTING going to sleep
2021-10-21 08:12:03.786666 [DEBUG] switch_core_state_machine.c:610 (sofia/default/442039123706@35.205.116.118) State Change CS_REPORTING -> CS_DESTROY
2021-10-21 08:12:03.786666 [DEBUG] switch_core_session.c:1665 Session 727138 (sofia/default/442039123706@35.205.116.118) Locked, Waiting on external entities
2021-10-21 08:12:03.786666 [NOTICE] switch_core_session.c:1683 Session 727138 (sofia/default/442039123706@35.205.116.118) Ended
2021-10-21 08:12:03.786666 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/442039123706@35.205.116.118 [CS_DESTROY]
2021-10-21 08:12:03.786666 [DEBUG] switch_core_state_machine.c:741 (sofia/default/442039123706@35.205.116.118) Running State Change CS_DESTROY (Cur 1 Tot 727138)
2021-10-21 08:12:03.786666 [DEBUG] switch_core_state_machine.c:751 (sofia/default/442039123706@35.205.116.118) State DESTROY
2021-10-21 08:12:03.786666 [DEBUG] mod_sofia.c:343 sofia/default/442039123706@35.205.116.118 SOFIA DESTROY
2021-10-21 08:12:03.786666 [DEBUG] switch_core_state_machine.c:181 sofia/default/442039123706@35.205.116.118 Standard DESTROY
2021-10-21 08:12:03.786666 [DEBUG] switch_core_state_machine.c:751 (sofia/default/442039123706@35.205.116.118) State DESTROY going to sleep
2021-10-21 08:12:03.826669 [DEBUG] switch_core_state_machine.c:174 sofia/default/+447578992323@199.244.96.39 Standard REPORTING, cause: NORMAL_CLEARING
2021-10-21 08:12:03.826669 [DEBUG] switch_core_state_machine.c:938 (sofia/default/+447578992323@199.244.96.39) State REPORTING going to sleep
2021-10-21 08:12:03.826669 [DEBUG] switch_core_state_machine.c:610 (sofia/default/+447578992323@199.244.96.39) State Change CS_REPORTING -> CS_DESTROY
2021-10-21 08:12:03.826669 [DEBUG] switch_core_session.c:1665 Session 727137 (sofia/default/+447578992323@199.244.96.39) Locked, Waiting on external entities
2021-10-21 08:12:03.826669 [NOTICE] switch_core_session.c:1683 Session 727137 (sofia/default/+447578992323@199.244.96.39) Ended
2021-10-21 08:12:03.826669 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/+447578992323@199.244.96.39 [CS_DESTROY]
2021-10-21 08:12:03.826669 [DEBUG] switch_core_state_machine.c:741 (sofia/default/+447578992323@199.244.96.39) Running State Change CS_DESTROY (Cur 0 Tot 727138)
2021-10-21 08:12:03.826669 [DEBUG] switch_core_state_machine.c:751 (sofia/default/+447578992323@199.244.96.39) State DESTROY
2021-10-21 08:12:03.826669 [DEBUG] mod_sofia.c:343 sofia/default/+447578992323@199.244.96.39 SOFIA DESTROY
2021-10-21 08:12:03.826669 [DEBUG] switch_core_state_machine.c:181 sofia/default/+447578992323@199.244.96.39 Standard DESTROY
2021-10-21 08:12:03.826669 [DEBUG] switch_core_state_machine.c:751 (sofia/default/+447578992323@199.244.96.39) State DESTROY going to sleep
freeswitch@astpp1.local> /exit

[root@astpp1 inextrix1]#