2024-03-06 12:39:48.866897 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE domain = '212.150.158.100' limit 1
2024-03-06 12:39:48.926888 98.67% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] in custom Caller ID
2024-03-06 12:39:48.926888 98.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number : 0506834683 / 0506834683
2024-03-06 12:39:48.926888 98.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
2024-03-06 12:39:48.926888 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 13 AND status=0 limit 1
2024-03-06 12:39:49.006881 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....039707000
2024-03-06 12:39:49.006881 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....039707000
2024-03-06 12:39:49.006881 98.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 039707000
2024-03-06 12:39:49.006881 98.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 97239707000
2024-03-06 12:39:49.006881 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:39:49.006881 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:39:49.006881 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:39:49.006881 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:39:49.006881 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:39:49.006881 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.buy_minutes,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,A.call_type_callerid_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="97239707000" LIMIT 1
2024-03-06 12:39:49.066881 98.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : inbound
2024-03-06 12:39:49.066881 98.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 170019686118]
2024-03-06 12:39:49.066881 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "170019686118" AND deleted = 0 limit 1
2024-03-06 12:39:49.146864 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="039707000" AND A.accountid = '709' limit 1
2024-03-06 12:39:49.226873 98.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 039707000
2024-03-06 12:39:49.226873 98.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
2024-03-06 12:39:49.226873 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 13 AND status=0 limit 1
2024-03-06 12:39:49.286883 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....039707000
2024-03-06 12:39:49.286883 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....039707000
2024-03-06 12:39:49.286883 98.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 039707000
2024-03-06 12:39:49.286883 98.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 97239707000
2024-03-06 12:39:49.286883 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:39:49.286883 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:39:49.286883 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:39:49.286883 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:39:49.286883 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:39:49.286883 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.buy_minutes,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,A.call_type_callerid_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="97239707000" LIMIT 1
2024-03-06 12:39:49.366887 98.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call direction : inbound
2024-03-06 12:39:49.366887 98.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
2024-03-06 12:39:49.366887 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 13 AND status=0 limit 1
2024-03-06 12:39:49.426889 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....039707000
2024-03-06 12:39:49.426889 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....039707000
2024-03-06 12:39:49.426889 98.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 039707000
2024-03-06 12:39:49.426889 98.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 97239707000
2024-03-06 12:39:49.426889 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:39:49.426889 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:39:49.426889 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:39:49.426889 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:39:49.426889 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:39:49.426889 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="97239707000" AND (accountid = 0 OR status = 1) LIMIT 1
2024-03-06 12:39:49.506879 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] call_direction :inbound
2024-03-06 12:39:49.506879 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] DID_ACCOUNTID :1573
2024-03-06 12:39:49.506879 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE (patterns = '^039707000.*' OR patterns = '^03970700.*' OR patterns = '^0397070.*' OR patterns = '^039707.*' OR patterns = '^03970.*' OR patterns = '^0397.*' OR patterns = '^039.*' OR patterns = '^03.*' OR patterns = '^0.*' OR patterns ='--') AND accountid = 1573 ORDER BY LENGTH(PKGPTR.patterns) DESC
2024-03-06 12:39:49.566886 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '039707000' OR number = '03970700' OR number = '0397070' OR number = '039707' OR number = '03970' OR number = '0397' OR number = '039' OR number = '03' OR number = '0' OR number ='--') OR (number = '039707000*' OR number = '03970700*' OR number = '0397070*' OR number = '039707*' OR number = '03970*' OR number = '0397*' OR number = '039*' OR number = '03*' OR number = '0*' OR number ='--') OR (number = '0506834683' OR number = '050683468' OR number = '05068346' OR number = '0506834' OR number = '050683' OR number = '05068' OR number = '0506' OR number = '050' OR number = '05' OR number = '0' OR number ='--') OR (number = '0506834683*' OR number = '050683468*' OR number = '05068346*' OR number = '0506834*' OR number = '050683*' OR number = '05068*' OR number = '0506*' OR number = '050*' OR number = '05*' OR number = '0*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
2024-03-06 12:39:49.646886 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1
2024-03-06 12:39:49.646886 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '039707000' OR routing_prefix = '03970700' OR routing_prefix = '0397070' OR routing_prefix = '039707' OR routing_prefix = '03970' OR routing_prefix = '0397' OR routing_prefix = '039' OR routing_prefix = '03' OR routing_prefix = '0' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
2024-03-06 12:39:49.726870 98.67% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
2024-03-06 12:39:49.726870 98.67% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 709
2024-03-06 12:39:49.726870 98.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 170019686118
2024-03-06 12:39:49.726870 98.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 36.04458
2024-03-06 12:39:49.726870 98.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
2024-03-06 12:39:49.726870 98.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 1
2024-03-06 12:39:49.726870 98.67% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
2024-03-06 12:39:49.726870 98.67% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
2024-03-06 12:39:49.726870 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 1 AND status = 0
2024-03-06 12:39:49.786883 98.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :inbound
2024-03-06 12:39:49.786883 98.63% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
2024-03-06 12:39:49.786883 98.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 13 AND status=0 limit 1
2024-03-06 12:39:49.866919 98.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:39:49.866919 98.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:39:49.866919 98.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:39:49.866919 98.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:39:49.866919 98.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:39:49.866919 98.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:39:49.866919 98.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:39:49.866919 98.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.buy_minutes,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,A.call_type_callerid_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="97239707000" LIMIT 1
2024-03-06 12:39:49.926871 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] call_direction:::::: inbound
2024-03-06 12:39:49.926871 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
2024-03-06 12:39:49.926871 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 15049
2024-03-06 12:39:49.926871 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
2024-03-06 12:39:49.926871 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
2024-03-06 12:39:49.926871 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.00000
2024-03-06 12:39:49.926871 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] comment : National Landline
2024-03-06 12:39:49.926871 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 93
2024-03-06 12:39:49.926871 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 709
2024-03-06 12:39:49.926871 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
2024-03-06 12:39:49.926871 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your36.04458 balance Accountid 709 !!!
2024-03-06 12:39:49.926871 98.63% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Call is free - assigning max length!!! :: 180
2024-03-06 12:39:49.926871 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] NIBBLE ID 709
2024-03-06 12:39:49.926871 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] NIBBLE RATE 0.00000
2024-03-06 12:39:49.926871 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] NIBBLE PROVIDER 0
2024-03-06 12:39:49.926871 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] NIBBLE CONNECT COST 0.00000
2024-03-06 12:39:49.926871 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] NIBBLE INITIAL INC 60
2024-03-06 12:39:49.926871 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] NIBBLE INC 60
2024-03-06 12:39:49.926871 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max length duration : 180 minutes
2024-03-06 12:39:49.926871 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] INB_FREE:TRUE
2024-03-06 12:39:49.926871 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] free_inbound:1
2024-03-06 12:39:49.926871 98.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 709 AND status= '0' LIMIT 1
2024-03-06 12:39:50.006880 98.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "1573" AND deleted = 0 limit 1
2024-03-06 12:39:50.086886 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML:709
2024-03-06 12:39:50.086886 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Userinfo XML:709
2024-03-06 12:39:50.086886 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML : 709
2024-03-06 12:39:50.086886 98.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE accountid = 1573 limit 1
2024-03-06 12:39:50.146881 98.63% [WARNING] switch_cpp.cpp:1465 [ASTPP] [user_domain] user_domainITCIVR1394.newsip.israelnumber.com
2024-03-06 12:39:50.146881 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] maxlength::::::::: 180
2024-03-06 12:39:50.146881 98.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"000/00".....0506834683
2024-03-06 12:39:50.146881 98.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"000/00".....0506834683
2024-03-06 12:39:50.146881 98.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE accountid = 1573 limit 1
2024-03-06 12:39:50.226888 98.63% [WARNING] switch_cpp.cpp:1465 [ASTPP] [user_domain] user_domainITCIVR1394.newsip.israelnumber.com
2024-03-06 12:39:50.226888 98.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] custom_function_name:::::::::::::::::::::::::custom_inbound_10
2024-03-06 12:39:50.226888 98.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] custom_function_name::::::::::1343:::::::::::::::15049
2024-03-06 12:39:50.226888 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] call_type : 10
2024-03-06 12:39:50.226888 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] call_type : IVR
2024-03-06 12:39:50.226888 98.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET_IVR_INFO] Query :SELECT * from pbx_ivr_specification WHERE id = 15 and status=0 LIMIT 1
2024-03-06 12:39:50.286884 98.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET_RING_GROUP_INFO] Query :SELECT file_name from pbx_recording WHERE id = '${il-ring}' LIMIT 1
2024-03-06 12:39:50.366881 98.63% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== calleridnumber=calleridnumber calleridnumber==================444=========
2024-03-06 12:39:50.366881 98.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Generated XML:
Dialplan: sofia/default/0506834683@212.150.158.100 parsing [default->97239707000] continue=false
Dialplan: sofia/default/0506834683@212.150.158.100 Regex (PASS) [97239707000] destination_number(039707000) =~ /039707000/ break=on-false
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(effective_destination_number=039707000)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(execute_on_answer=sched_hangup +10800 normal_clearing)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(nibble_account=709)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(nibble_rate=0.00000)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(nibble_increment=60)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(nibble_minimum=0.00000)
Dialplan: sofia/default/0506834683@212.150.158.100 Action nibblebill(heartbeat 60)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(callstart=2024-03-06 12:39:51)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(hangup_after_bridge=true)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(continue_on_fail=TRUE)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(account_id=709)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(parent_id=0)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(entity_id=0)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(call_processed=internal)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(call_direction=inbound)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(accountname=default)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(filename1=astpp.pbx.lua)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(origination_rates_did=ID:15049|CODE:^97239707000.*|DESTINATION:National Landline|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:10|COST:0.00000|INC:60|INITIALBLOCK:60|RATEGROUP:0|MARKUP:0|CI:93|ACCID:1573)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(origination_rates=0)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(original_caller_id_name=0506834683)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(original_caller_id_number=0506834683)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(effective_caller_id_name=0506834683)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(effective_caller_id_number=0506834683)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(receiver_accid=1573)
Dialplan: sofia/default/0506834683@212.150.158.100 Action export(domain_name=ITCIVR1394.newsip.israelnumber.com)
Dialplan: sofia/default/0506834683@212.150.158.100 Action export(sip_to_host=ITCIVR1394.newsip.israelnumber.com)
Dialplan: sofia/default/0506834683@212.150.158.100 Action export(presence_data=x|||prodtwo(170019686118)|||^97239707000.* // National Landline // 0.00000||||||DID|||212.150.158.100)
Dialplan: sofia/default/0506834683@212.150.158.100 Action export(call_type=10)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(did_calltype=10)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(did_extensions=15)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(ringback=${il-ring})
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(effective_caller_id_number=444#0506834683)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(module_name=pbx)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(sip_h_P-call_type=custom_forward)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(sip_h_P-Accountcode=1573)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(domain_name=ITCIVR1394.newsip.israelnumber.com)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(original_did_number=97239707000)
Dialplan: sofia/default/0506834683@212.150.158.100 Action answer()
Dialplan: sofia/default/0506834683@212.150.158.100 Action ivr(ITC IVR0)
Dialplan: sofia/default/0506834683@212.150.158.100 Action lua(astpp/lib/pbx/astpp-failover_ivr_destination.lua)
2024-03-06 12:39:50.366881 98.63% [DEBUG] switch_core_state_machine.c:281 (sofia/default/0506834683@212.150.158.100) State Change CS_ROUTING -> CS_EXECUTE
2024-03-06 12:39:50.366881 98.63% [DEBUG] switch_core_state_machine.c:640 (sofia/default/0506834683@212.150.158.100) State ROUTING going to sleep
2024-03-06 12:39:50.366881 98.63% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0506834683@212.150.158.100) Running State Change CS_EXECUTE (Cur 36 Tot 3432180)
2024-03-06 12:39:50.366881 98.63% [DEBUG] switch_core_state_machine.c:647 (sofia/default/0506834683@212.150.158.100) State EXECUTE
2024-03-06 12:39:50.366881 98.63% [DEBUG] mod_sofia.c:213 sofia/default/0506834683@212.150.158.100 SOFIA EXECUTE
2024-03-06 12:39:50.366881 98.63% [DEBUG] switch_core_state_machine.c:323 sofia/default/0506834683@212.150.158.100 Standard EXECUTE
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(effective_destination_number=039707000)
2024-03-06 12:39:50.366881 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [effective_destination_number]=[039707000]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(execute_on_answer=sched_hangup +10800 normal_clearing)
2024-03-06 12:39:50.366881 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [execute_on_answer]=[sched_hangup +10800 normal_clearing]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(nibble_account=709)
2024-03-06 12:39:50.366881 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [nibble_account]=[709]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(nibble_rate=0.00000)
2024-03-06 12:39:50.366881 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [nibble_rate]=[0.00000]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(nibble_increment=60)
2024-03-06 12:39:50.366881 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [nibble_increment]=[60]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(nibble_minimum=0.00000)
2024-03-06 12:39:50.366881 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [nibble_minimum]=[0.00000]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 nibblebill(heartbeat 60)
2024-03-06 12:39:50.366881 98.63% [WARNING] switch_core_session.c:1693 sofia/default/0506834683@212.150.158.100 using scheduler due to bypass media or media is not established.
2024-03-06 12:39:50.366881 98.63% [DEBUG] switch_scheduler.c:263 Added task 1643031 switch_core_session_sched_heartbeat (e6880004-03e8-42ec-9dd7-cf40f0804e6b) to run at 1709728850
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(callstart=2024-03-06 12:39:51)
2024-03-06 12:39:50.366881 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [callstart]=[2024-03-06 12:39:51]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(hangup_after_bridge=true)
2024-03-06 12:39:50.366881 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [hangup_after_bridge]=[true]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(continue_on_fail=TRUE)
2024-03-06 12:39:50.366881 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [continue_on_fail]=[TRUE]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(account_id=709)
2024-03-06 12:39:50.366881 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [account_id]=[709]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(parent_id=0)
2024-03-06 12:39:50.366881 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [parent_id]=[0]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(entity_id=0)
2024-03-06 12:39:50.366881 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [entity_id]=[0]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(call_processed=internal)
2024-03-06 12:39:50.366881 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [call_processed]=[internal]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(call_direction=inbound)
2024-03-06 12:39:50.366881 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [call_direction]=[inbound]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(accountname=default)
2024-03-06 12:39:50.366881 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [accountname]=[default]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(filename1=astpp.pbx.lua)
2024-03-06 12:39:50.366881 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [filename1]=[astpp.pbx.lua]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(origination_rates_did=ID:15049|CODE:^97239707000.*|DESTINATION:National Landline|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:10|COST:0.00000|INC:60|INITIALBLOCK:60|RATEGROUP:0|MARKUP:0|CI:93|ACCID:1573)
2024-03-06 12:39:50.366881 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [origination_rates_did]=[ID:15049|CODE:^97239707000.*|DESTINATION:National Landline|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:10|COST:0.00000|INC:60|INITIALBLOCK:60|RATEGROUP:0|MARKUP:0|CI:93|ACCID:1573]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(origination_rates=0)
2024-03-06 12:39:50.366881 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [origination_rates]=[0]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(original_caller_id_name=0506834683)
2024-03-06 12:39:50.366881 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [original_caller_id_name]=[0506834683]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(original_caller_id_number=0506834683)
2024-03-06 12:39:50.366881 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [original_caller_id_number]=[0506834683]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(effective_caller_id_name=0506834683)
2024-03-06 12:39:50.366881 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [effective_caller_id_name]=[0506834683]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(effective_caller_id_number=0506834683)
2024-03-06 12:39:50.366881 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [effective_caller_id_number]=[0506834683]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(receiver_accid=1573)
2024-03-06 12:39:50.366881 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [receiver_accid]=[1573]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 export(domain_name=ITCIVR1394.newsip.israelnumber.com)
2024-03-06 12:39:50.366881 98.63% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [domain_name]=[ITCIVR1394.newsip.israelnumber.com]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 export(sip_to_host=ITCIVR1394.newsip.israelnumber.com)
2024-03-06 12:39:50.366881 98.63% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [sip_to_host]=[ITCIVR1394.newsip.israelnumber.com]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 export(presence_data=x|||prodtwo(170019686118)|||^97239707000.* // National Landline // 0.00000||||||DID|||212.150.158.100)
2024-03-06 12:39:50.366881 98.63% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[x|||prodtwo(170019686118)|||^97239707000.* // National Landline // 0.00000||||||DID|||212.150.158.100]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 export(call_type=10)
2024-03-06 12:39:50.366881 98.63% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [call_type]=[10]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(did_calltype=10)
2024-03-06 12:39:50.386884 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [did_calltype]=[10]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(did_extensions=15)
2024-03-06 12:39:50.386884 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [did_extensions]=[15]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(ringback=%(1000,3000,400))
2024-03-06 12:39:50.386884 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [ringback]=[%(1000,3000,400)]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(effective_caller_id_number=444#0506834683)
2024-03-06 12:39:50.386884 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [effective_caller_id_number]=[444#0506834683]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(module_name=pbx)
2024-03-06 12:39:50.386884 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [module_name]=[pbx]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(sip_h_P-call_type=custom_forward)
2024-03-06 12:39:50.386884 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [sip_h_P-call_type]=[custom_forward]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(sip_h_P-Accountcode=1573)
2024-03-06 12:39:50.386884 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [sip_h_P-Accountcode]=[1573]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(domain_name=ITCIVR1394.newsip.israelnumber.com)
2024-03-06 12:39:50.386884 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [domain_name]=[ITCIVR1394.newsip.israelnumber.com]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(original_did_number=97239707000)
2024-03-06 12:39:50.386884 98.63% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [original_did_number]=[97239707000]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 answer()
2024-03-06 12:39:50.386884 98.63% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/0506834683@212.150.158.100] 95.217.164.101 port 23100 -> 212.150.158.100 port 11958 codec: 8 ms: 20
2024-03-06 12:39:50.386884 98.63% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms
2024-03-06 12:39:50.386884 98.63% [DEBUG] switch_core_media.c:9089 sofia/default/0506834683@212.150.158.100 Set 2833 dtmf send payload to 98
2024-03-06 12:39:50.386884 98.63% [DEBUG] switch_core_media.c:9096 sofia/default/0506834683@212.150.158.100 Set 2833 dtmf receive payload to 98
2024-03-06 12:39:50.386884 98.63% [DEBUG] switch_core_media.c:9119 sofia/default/0506834683@212.150.158.100 Set rtp dtmf delay to 40
2024-03-06 12:39:50.386884 98.63% [DEBUG] mod_sofia.c:913 Local SDP sofia/default/0506834683@212.150.158.100:
v=0
o=FreeSWITCH 1709705690 1709705691 IN IP4 95.217.164.101
s=FreeSWITCH
c=IN IP4 95.217.164.101
t=0 0
m=audio 23100 RTP/AVP 8 98
a=rtpmap:8 PCMA/8000
a=rtpmap:98 telephone-event/8000
a=fmtp:98 0-15
a=ptime:20
a=sendrecv
m=video 0 RTP/AVP 19
2024-03-06 12:39:50.386884 98.63% [NOTICE] mod_dptools.c:1419 Channel [sofia/default/0506834683@212.150.158.100] has been answered
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 sched_hangup(+10800 normal_clearing)
2024-03-06 12:39:50.386884 98.63% [DEBUG] sofia.c:7499 Channel sofia/default/0506834683@212.150.158.100 entering state [completed][200]
2024-03-06 12:39:50.386884 98.63% [DEBUG] switch_scheduler.c:263 Added task 1643032 switch_ivr_schedule_hangup (e6880004-03e8-42ec-9dd7-cf40f0804e6b) to run at 1709739590
2024-03-06 12:39:50.386884 98.63% [DEBUG] switch_channel.c:3950 (sofia/default/0506834683@212.150.158.100) Callstate Change RINGING -> ACTIVE
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 ivr(ITC IVR0)
2024-03-06 12:39:51.386869 98.53% [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2024-03-06 12:39:51.386869 98.53% [DEBUG] mod_nibblebill.c:506 Attempting to bill at $0.00000 per minute to account 971
2024-03-06 12:39:51.386869 98.53% [DEBUG] mod_nibblebill.c:573 19 seconds passed since last bill time of 2024-03-06 12:39:31
2024-03-06 12:39:51.386869 98.53% [DEBUG] mod_nibblebill.c:590 Billing $0.000000 to 971 (Call: 3d858c84-eeeb-4557-82a9-c4c4105022e6 / 0.000000 so far)
2024-03-06 12:39:51.386869 98.53% [DEBUG] mod_nibblebill.c:395 Doing update query
[Update accounts SET balance=IF(posttoexternal=1,balance+0.000000,balance-0.000000) WHERE id='971']
2024-03-06 12:39:51.446876 98.53% [DEBUG] mod_nibblebill.c:422 Doing lookup query
[SELECT if(posttoexternal=1,credit_limit+(balance*-1),balance) AS nibble_balance FROM accounts WHERE id='971']
2024-03-06 12:39:51.526880 98.53% [DEBUG] mod_nibblebill.c:430 Retrieved current balance for account 971 (balance = 1093.300410)
2024-03-06 12:39:51.606891 98.53% [DEBUG] switch_ivr_menu.c:746 switch_ivr_menu_stack_xml_add binding 'menu-exit'
2024-03-06 12:39:51.606891 98.53% [DEBUG] switch_ivr_menu.c:746 switch_ivr_menu_stack_xml_add binding 'menu-sub'
2024-03-06 12:39:51.606891 98.53% [DEBUG] switch_ivr_menu.c:746 switch_ivr_menu_stack_xml_add binding 'menu-exec-app'
2024-03-06 12:39:51.606891 98.53% [DEBUG] switch_ivr_menu.c:746 switch_ivr_menu_stack_xml_add binding 'menu-play-sound'
2024-03-06 12:39:51.606891 98.53% [DEBUG] switch_ivr_menu.c:746 switch_ivr_menu_stack_xml_add binding 'menu-back'
2024-03-06 12:39:51.606891 98.53% [DEBUG] switch_ivr_menu.c:746 switch_ivr_menu_stack_xml_add binding 'menu-top'
2024-03-06 12:39:51.606891 98.53% [DEBUG] switch_ivr_menu.c:883 building menu 'ITC IVR0'
2024-03-06 12:39:51.606891 98.53% [DEBUG] switch_ivr_menu.c:968 binding menu action 'menu-exec-app' to '1'
2024-03-06 12:39:51.606891 98.53% [DEBUG] switch_ivr_menu.c:968 binding menu action 'menu-exec-app' to '2'
2024-03-06 12:39:51.606891 98.53% [DEBUG] switch_ivr_menu.c:968 binding menu action 'menu-exec-app' to '3'
2024-03-06 12:39:51.606891 98.53% [DEBUG] switch_ivr_menu.c:968 binding menu action 'menu-exec-app' to '4'
2024-03-06 12:39:51.606891 98.53% [DEBUG] switch_ivr_menu.c:968 binding menu action 'menu-exec-app' to '/^(\d{2,20})$/'
2024-03-06 12:39:51.606891 98.53% [DEBUG] switch_ivr_menu.c:486 Executing IVR menu ITC IVR0
2024-03-06 12:39:51.606891 98.53% [DEBUG] sofia.c:7499 Channel sofia/default/0506834683@212.150.158.100 entering state [ready][200]
2024-03-06 12:39:51.606891 98.53% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
2024-03-06 12:39:51.606891 98.53% [DEBUG] sofia.c:7499 Channel sofia/default/0506834683@212.150.158.100 entering state [received][100]
2024-03-06 12:39:51.606891 98.53% [DEBUG] sofia.c:7509 Remote SDP:
v=0
o=root 7883 7884 IN IP4 82.166.66.145
s=session
c=IN IP4 82.166.66.145
t=0 0
m=audio 16566 RTP/AVP 8 98
a=rtpmap:8 PCMA/8000
a=rtpmap:98 telephone-event/8000
a=fmtp:98 0-16
a=silenceSupp:off - - - -
a=ptime:20
2024-03-06 12:39:51.606891 98.53% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2024-03-06 12:39:51.606891 98.53% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2024-03-06 12:39:51.606891 98.53% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2024-03-06 12:39:51.606891 98.53% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 98@8000
2024-03-06 12:39:51.606891 98.53% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 98@8000
2024-03-06 12:39:51.606891 98.53% [DEBUG] switch_core_media.c:5973 sofia/default/0506834683@212.150.158.100 Set 2833 dtmf send payload to 98 recv payload to 98
2024-03-06 12:39:51.606891 98.53% [DEBUG] switch_core_media.c:8765 Audio params changed for sofia/default/0506834683@212.150.158.100 from 212.150.158.100:11958 to 82.166.66.145:16566
2024-03-06 12:39:51.606891 98.53% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/0506834683@212.150.158.100] 95.217.164.101 port 23100 -> 82.166.66.145 port 16566 codec: 8 ms: 20
2024-03-06 12:39:51.606891 98.53% [DEBUG] switch_core_media.c:8806 AUDIO RTP CHANGING DEST TO: [82.166.66.145:16566]
2024-03-06 12:39:51.606891 98.53% [DEBUG] sofia.c:8454 Processing updated SDP
2024-03-06 12:39:51.626892 98.53% [DEBUG] sofia.c:7499 Channel sofia/default/0506834683@212.150.158.100 entering state [completed][200]
2024-03-06 12:39:51.646863 98.53% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed.
2024-03-06 12:39:51.706873 98.53% [DEBUG] sofia.c:7499 Channel sofia/default/0506834683@212.150.158.100 entering state [ready][200]
2024-03-06 12:39:54.526871 98.47% [NOTICE] sofia.c:1065 Hangup sofia/default/420602517455@46.19.210.14 [CS_EXECUTE] [NORMAL_CLEARING]
2024-03-06 12:39:54.526871 98.47% [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/420602517455@46.19.210.14]
2024-03-06 12:39:54.546871 98.47% [DEBUG] switch_ivr_bridge.c:820 sofia/default/420602517455@46.19.210.14 ending bridge by request from write function
2024-03-06 12:39:54.546871 98.47% [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/420234043209@64.225.69.95]
2024-03-06 12:39:54.546871 98.47% [NOTICE] switch_ivr_bridge.c:1029 Hangup sofia/default/420234043209@64.225.69.95 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2024-03-06 12:39:54.546871 98.47% [DEBUG] switch_core_state_machine.c:650 (sofia/default/420234043209@64.225.69.95) State EXCHANGE_MEDIA going to sleep
2024-03-06 12:39:54.546871 98.47% [DEBUG] switch_core_state_machine.c:581 (sofia/default/420234043209@64.225.69.95) Running State Change CS_HANGUP (Cur 36 Tot 3432180)
2024-03-06 12:39:54.546871 98.47% [DEBUG] switch_core_state_machine.c:844 (sofia/default/420234043209@64.225.69.95) Callstate Change ACTIVE -> HANGUP
2024-03-06 12:39:54.546871 98.47% [DEBUG] switch_core_state_machine.c:846 (sofia/default/420234043209@64.225.69.95) State HANGUP
2024-03-06 12:39:54.546871 98.47% [DEBUG] mod_sofia.c:462 sofia/default/420234043209@64.225.69.95 Overriding SIP cause 480 with 200 from the other leg
2024-03-06 12:39:54.546871 98.47% [DEBUG] mod_sofia.c:468 Channel sofia/default/420234043209@64.225.69.95 hanging up, cause: NORMAL_CLEARING
2024-03-06 12:39:54.546871 98.47% [DEBUG] mod_sofia.c:522 Sending BYE to sofia/default/420234043209@64.225.69.95
2024-03-06 12:39:54.546871 98.47% [DEBUG] switch_core_state_machine.c:59 sofia/default/420234043209@64.225.69.95 Standard HANGUP, cause: NORMAL_CLEARING
2024-03-06 12:39:54.546871 98.47% [DEBUG] switch_core_state_machine.c:846 (sofia/default/420234043209@64.225.69.95) State HANGUP going to sleep
2024-03-06 12:39:54.546871 98.47% [DEBUG] switch_core_state_machine.c:616 (sofia/default/420234043209@64.225.69.95) State Change CS_HANGUP -> CS_REPORTING
2024-03-06 12:39:54.546871 98.47% [DEBUG] switch_core_state_machine.c:581 (sofia/default/420234043209@64.225.69.95) Running State Change CS_REPORTING (Cur 36 Tot 3432180)
2024-03-06 12:39:54.546871 98.47% [DEBUG] switch_core_state_machine.c:932 (sofia/default/420234043209@64.225.69.95) State REPORTING
2024-03-06 12:39:54.546871 98.47% [INFO] mod_json_cdr.c:271 Process [df4330c3-2e31-4f03-8464-fed868a8b99f.cdr.json]
2024-03-06 12:39:54.546871 98.47% [DEBUG] switch_ivr_bridge.c:1892 sofia/default/420234043209@64.225.69.95 skip receive message [UNBRIDGE] (channel is hungup already)
2024-03-06 12:39:54.546871 98.47% [DEBUG] switch_ivr_bridge.c:1895 sofia/default/420602517455@46.19.210.14 skip receive message [UNBRIDGE] (channel is hungup already)
2024-03-06 12:39:54.546871 98.47% [DEBUG] switch_core_session.c:2983 sofia/default/420602517455@46.19.210.14 skip receive message [PHONE_EVENT] (channel is hungup already)
2024-03-06 12:39:54.546871 98.47% [DEBUG] switch_core_state_machine.c:647 (sofia/default/420602517455@46.19.210.14) State EXECUTE going to sleep
2024-03-06 12:39:54.546871 98.47% [DEBUG] switch_core_state_machine.c:581 (sofia/default/420602517455@46.19.210.14) Running State Change CS_HANGUP (Cur 36 Tot 3432180)
2024-03-06 12:39:54.546871 98.47% [DEBUG] switch_core_state_machine.c:844 (sofia/default/420602517455@46.19.210.14) Callstate Change ACTIVE -> HANGUP
2024-03-06 12:39:54.546871 98.47% [DEBUG] switch_core_state_machine.c:846 (sofia/default/420602517455@46.19.210.14) State HANGUP
2024-03-06 12:39:54.546871 98.47% [DEBUG] mod_sofia.c:468 Channel sofia/default/420602517455@46.19.210.14 hanging up, cause: NORMAL_CLEARING
2024-03-06 12:39:54.546871 98.47% [DEBUG] mod_nibblebill.c:506 Attempting to bill at $0.00000 per minute to account 971
2024-03-06 12:39:54.546871 98.47% [DEBUG] mod_nibblebill.c:573 23 seconds passed since last bill time of 2024-03-06 12:39:31
2024-03-06 12:39:54.546871 98.47% [DEBUG] mod_nibblebill.c:590 Billing $0.000000 to 971 (Call: 3d858c84-eeeb-4557-82a9-c4c4105022e6 / 0.000000 so far)
2024-03-06 12:39:54.546871 98.47% [DEBUG] mod_nibblebill.c:395 Doing update query
[Update accounts SET balance=IF(posttoexternal=1,balance+0.000000,balance-0.000000) WHERE id='971']
2024-03-06 12:39:54.626874 98.47% [DEBUG] mod_nibblebill.c:395 Doing update query
[Update accounts SET balance=IF(posttoexternal=1,balance+0.000000,balance-0.000000) WHERE id='971']
2024-03-06 12:39:54.626874 98.47% [DEBUG] switch_core_state_machine.c:168 sofia/default/420234043209@64.225.69.95 Standard REPORTING, cause: NORMAL_CLEARING
2024-03-06 12:39:54.626874 98.47% [DEBUG] switch_core_state_machine.c:932 (sofia/default/420234043209@64.225.69.95) State REPORTING going to sleep
2024-03-06 12:39:54.626874 98.47% [DEBUG] switch_core_state_machine.c:607 (sofia/default/420234043209@64.225.69.95) State Change CS_REPORTING -> CS_DESTROY
2024-03-06 12:39:54.626874 98.47% [DEBUG] switch_core_session.c:1753 Session 3432179 (sofia/default/420234043209@64.225.69.95) Locked, Waiting on external entities
2024-03-06 12:39:54.626874 98.47% [NOTICE] switch_core_session.c:1771 Session 3432179 (sofia/default/420234043209@64.225.69.95) Ended
2024-03-06 12:39:54.626874 98.47% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/420234043209@64.225.69.95 [CS_DESTROY]
2024-03-06 12:39:54.626874 98.47% [DEBUG] switch_core_state_machine.c:735 (sofia/default/420234043209@64.225.69.95) Running State Change CS_DESTROY (Cur 35 Tot 3432180)
2024-03-06 12:39:54.626874 98.47% [DEBUG] switch_core_state_machine.c:745 (sofia/default/420234043209@64.225.69.95) State DESTROY
2024-03-06 12:39:54.626874 98.47% [DEBUG] mod_sofia.c:379 sofia/default/420234043209@64.225.69.95 SOFIA DESTROY
2024-03-06 12:39:54.626874 98.47% [DEBUG] switch_core_state_machine.c:175 sofia/default/420234043209@64.225.69.95 Standard DESTROY
2024-03-06 12:39:54.626874 98.47% [DEBUG] switch_core_state_machine.c:745 (sofia/default/420234043209@64.225.69.95) State DESTROY going to sleep
2024-03-06 12:39:54.686873 98.47% [DEBUG] mod_nibblebill.c:422 Doing lookup query
[SELECT if(posttoexternal=1,credit_limit+(balance*-1),balance) AS nibble_balance FROM accounts WHERE id='971']
2024-03-06 12:39:54.766876 98.47% [DEBUG] mod_nibblebill.c:430 Retrieved current balance for account 971 (balance = 1093.300410)
2024-03-06 12:39:54.766876 98.47% [DEBUG] switch_core_state_machine.c:59 sofia/default/420602517455@46.19.210.14 Standard HANGUP, cause: NORMAL_CLEARING
2024-03-06 12:39:54.766876 98.47% [DEBUG] switch_core_state_machine.c:846 (sofia/default/420602517455@46.19.210.14) State HANGUP going to sleep
2024-03-06 12:39:54.766876 98.47% [DEBUG] switch_core_state_machine.c:616 (sofia/default/420602517455@46.19.210.14) State Change CS_HANGUP -> CS_REPORTING
2024-03-06 12:39:54.766876 98.47% [DEBUG] switch_core_state_machine.c:581 (sofia/default/420602517455@46.19.210.14) Running State Change CS_REPORTING (Cur 35 Tot 3432180)
2024-03-06 12:39:54.766876 98.47% [DEBUG] switch_core_state_machine.c:932 (sofia/default/420602517455@46.19.210.14) State REPORTING
2024-03-06 12:39:54.766876 98.47% [INFO] mod_json_cdr.c:271 Process [3d858c84-eeeb-4557-82a9-c4c4105022e6.cdr.json]
2024-03-06 12:39:54.986869 98.43% [DEBUG] switch_core_state_machine.c:168 sofia/default/420602517455@46.19.210.14 Standard REPORTING, cause: NORMAL_CLEARING
2024-03-06 12:39:54.986869 98.43% [DEBUG] switch_core_state_machine.c:932 (sofia/default/420602517455@46.19.210.14) State REPORTING going to sleep
2024-03-06 12:39:54.986869 98.43% [DEBUG] switch_core_state_machine.c:607 (sofia/default/420602517455@46.19.210.14) State Change CS_REPORTING -> CS_DESTROY
2024-03-06 12:39:54.986869 98.43% [DEBUG] switch_core_session.c:1753 Session 3432178 (sofia/default/420602517455@46.19.210.14) Locked, Waiting on external entities
2024-03-06 12:39:54.986869 98.43% [NOTICE] switch_core_session.c:1771 Session 3432178 (sofia/default/420602517455@46.19.210.14) Ended
2024-03-06 12:39:54.986869 98.43% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/420602517455@46.19.210.14 [CS_DESTROY]
2024-03-06 12:39:54.986869 98.43% [DEBUG] switch_core_state_machine.c:735 (sofia/default/420602517455@46.19.210.14) Running State Change CS_DESTROY (Cur 34 Tot 3432180)
2024-03-06 12:39:54.986869 98.43% [DEBUG] switch_core_state_machine.c:745 (sofia/default/420602517455@46.19.210.14) State DESTROY
2024-03-06 12:39:54.986869 98.43% [DEBUG] mod_sofia.c:379 sofia/default/420602517455@46.19.210.14 SOFIA DESTROY
2024-03-06 12:39:54.986869 98.43% [DEBUG] switch_core_state_machine.c:175 sofia/default/420602517455@46.19.210.14 Standard DESTROY
2024-03-06 12:39:54.986869 98.43% [DEBUG] switch_core_state_machine.c:745 (sofia/default/420602517455@46.19.210.14) State DESTROY going to sleep
2024-03-06 12:39:55.386874 98.43% [DEBUG] switch_scheduler.c:147 Deleting task 1643029 switch_core_session_sched_heartbeat (3d858c84-eeeb-4557-82a9-c4c4105022e6)
2024-03-06 12:39:55.386874 98.43% [DEBUG] switch_scheduler.c:147 Deleting task 1643030 switch_ivr_schedule_hangup (3d858c84-eeeb-4557-82a9-c4c4105022e6)
2024-03-06 12:40:08.166871 98.50% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 2:960
2024-03-06 12:40:08.166871 98.50% [INFO] switch_channel.c:527 RECV DTMF 2:960
2024-03-06 12:40:08.166871 98.50% [DEBUG] switch_ivr_play_say.c:2001 done playing file /var/www/html/astpp/upload/pbx/ITC_IVR_V3_120122094140.wav
2024-03-06 12:40:08.166871 98.50% [DEBUG] switch_ivr_menu.c:378 waiting for 19/20 digits t/o 2000
2024-03-06 12:40:08.386873 98.50% [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
2024-03-06 12:40:08.386873 98.50% [DEBUG] mod_nibblebill.c:506 Attempting to bill at $0.00000 per minute to account 9405
2024-03-06 12:40:08.386873 98.50% [DEBUG] mod_nibblebill.c:573 53 seconds passed since last bill time of 2024-03-06 12:39:14
2024-03-06 12:40:08.386873 98.50% [DEBUG] mod_nibblebill.c:590 Billing $0.000000 to 9405 (Call: b1384f36-2c41-40fe-a4dd-1c12026ab570 / 0.000000 so far)
2024-03-06 12:40:08.386873 98.50% [DEBUG] mod_nibblebill.c:395 Doing update query
[Update accounts SET balance=IF(posttoexternal=1,balance+0.000000,balance-0.000000) WHERE id='9405']
2024-03-06 12:40:08.466874 98.50% [DEBUG] mod_nibblebill.c:422 Doing lookup query
[SELECT if(posttoexternal=1,credit_limit+(balance*-1),balance) AS nibble_balance FROM accounts WHERE id='9405']
2024-03-06 12:40:08.526867 98.50% [DEBUG] mod_nibblebill.c:430 Retrieved current balance for account 9405 (balance = 0.500000)
2024-03-06 12:40:10.166878 98.53% [DEBUG] switch_ivr_menu.c:425 digits '2'
2024-03-06 12:40:10.166878 98.53% [DEBUG] switch_ivr_menu.c:580 IVR action on menu 'ITC IVR0' matched '2' param 'transfer ${original_did_number}#PBX#11#23 XML ${domain_name}'
2024-03-06 12:40:10.166878 98.53% [DEBUG] switch_ivr_menu.c:584 switch_ivr_menu_execute todo=[2]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 transfer(97239707000#PBX#11#23 XML ITCIVR1394.newsip.israelnumber.com)
2024-03-06 12:40:10.166878 98.53% [DEBUG] switch_ivr.c:2289 (sofia/default/0506834683@212.150.158.100) State Change CS_EXECUTE -> CS_ROUTING
2024-03-06 12:40:10.166878 98.53% [NOTICE] switch_ivr.c:2296 Transfer sofia/default/0506834683@212.150.158.100 to XML[97239707000#PBX#11#23@ITCIVR1394.newsip.israelnumber.com]
2024-03-06 12:40:10.166878 98.53% [DEBUG] switch_ivr_menu.c:562 action regex [2] [/^(\d{2,20})$/] [0]
2024-03-06 12:40:10.166878 98.53% [DEBUG] switch_ivr_menu.c:676 exit-sound '(null)'
2024-03-06 12:40:10.166878 98.53% [DEBUG] switch_core_state_machine.c:647 (sofia/default/0506834683@212.150.158.100) State EXECUTE going to sleep
2024-03-06 12:40:10.166878 98.53% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0506834683@212.150.158.100) Running State Change CS_ROUTING (Cur 34 Tot 3432180)
2024-03-06 12:40:10.166878 98.53% [DEBUG] switch_channel.c:2380 (sofia/default/0506834683@212.150.158.100) Callstate Change ACTIVE -> RINGING
2024-03-06 12:40:10.166878 98.53% [DEBUG] switch_core_state_machine.c:640 (sofia/default/0506834683@212.150.158.100) State ROUTING
2024-03-06 12:40:10.166878 98.53% [DEBUG] mod_sofia.c:158 sofia/default/0506834683@212.150.158.100 SOFIA ROUTING
2024-03-06 12:40:10.166878 98.53% [DEBUG] mod_nibblebill.c:506 Attempting to bill at $0.00000 per minute to account 709
2024-03-06 12:40:10.166878 98.53% [INFO] mod_nibblebill.c:565 Beginning new billing on e6880004-03e8-42ec-9dd7-cf40f0804e6b
2024-03-06 12:40:10.166878 98.53% [DEBUG] mod_nibblebill.c:573 19 seconds passed since last bill time of 2024-03-06 12:39:50
2024-03-06 12:40:10.166878 98.53% [DEBUG] mod_nibblebill.c:590 Billing $0.000000 to 709 (Call: e6880004-03e8-42ec-9dd7-cf40f0804e6b / 0.000000 so far)
2024-03-06 12:40:10.166878 98.53% [DEBUG] mod_nibblebill.c:395 Doing update query
[Update accounts SET balance=IF(posttoexternal=1,balance+0.000000,balance-0.000000) WHERE id='709']
2024-03-06 12:40:10.226871 98.53% [DEBUG] mod_nibblebill.c:422 Doing lookup query
[SELECT if(posttoexternal=1,credit_limit+(balance*-1),balance) AS nibble_balance FROM accounts WHERE id='709']
2024-03-06 12:40:10.306871 98.53% [DEBUG] mod_nibblebill.c:430 Retrieved current balance for account 709 (balance = 36.044580)
2024-03-06 12:40:10.306871 98.53% [DEBUG] mod_nibblebill.c:422 Doing lookup query
[SELECT if(posttoexternal=1,credit_limit+(balance*-1),balance) AS nibble_balance FROM accounts WHERE id='709']
2024-03-06 12:40:10.386876 98.53% [DEBUG] mod_nibblebill.c:430 Retrieved current balance for account 709 (balance = 36.044580)
2024-03-06 12:40:10.386876 98.53% [DEBUG] switch_core_state_machine.c:230 sofia/default/0506834683@212.150.158.100 Standard ROUTING
2024-03-06 12:40:10.386876 98.53% [INFO] mod_dialplan_xml.c:639 Processing 0506834683 <0506834683>->97239707000#PBX#11#23 in context ITCIVR1394.newsip.israelnumber.com
2024-03-06 12:40:10.386876 98.53% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
2024-03-06 12:40:10.446877 98.53% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
2024-03-06 12:40:10.526868 98.53% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [xml_handler] Params:
Event-Name: REQUEST_PARAMS
Core-UUID: 5bc8c5c5-4af3-43ec-bda5-1cb9a93e59f7
FreeSWITCH-Hostname: fs-slave
FreeSWITCH-Switchname: fs-slave
FreeSWITCH-IPv4: 95.217.164.101
FreeSWITCH-IPv6: 2a01%3A4f9%3Ac011%3A94fe%3A%3A1
Event-Date-Local: 2024-03-06%2012%3A40%3A10
Event-Date-GMT: Wed,%2006%20Mar%202024%2012%3A40%3A10%20GMT
Event-Date-Timestamp: 1709728810386876
Event-Calling-File: mod_dialplan_xml.c
Event-Calling-Function: dialplan_xml_locate
Event-Calling-Line-Number: 610
Event-Sequence: 211775733
Channel-State: CS_ROUTING
Channel-Call-State: RINGING
Channel-State-Number: 2
Channel-Name: sofia/default/0506834683%40212.150.158.100
Unique-ID: e6880004-03e8-42ec-9dd7-cf40f0804e6b
Call-Direction: inbound
Presence-Call-Direction: inbound
Channel-HIT-Dialplan: true
Channel-Presence-ID: 0506834683%40212.150.158.100
Channel-Presence-Data: x%7C%7C%7Cprodtwo(170019686118)%7C%7C%7C%5E97239707000.*%20//%20National%20Landline%20//%200.00000%7C%7C%7C%7C%7C%7CDID%7C%7C%7C212.150.158.100
Channel-Call-UUID: e6880004-03e8-42ec-9dd7-cf40f0804e6b
Answer-State: answered
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: 0506834683
Caller-Dialplan: XML
Caller-Caller-ID-Name: 0506834683
Caller-Caller-ID-Number: 0506834683
Caller-Orig-Caller-ID-Name: 0506834683
Caller-Orig-Caller-ID-Number: 0506834683
Caller-Network-Addr: 195.201.136.94
Caller-ANI: 0506834683
Caller-Destination-Number: 97239707000%23PBX%2311%2323
Caller-Unique-ID: e6880004-03e8-42ec-9dd7-cf40f0804e6b
Caller-Source: mod_sofia
Caller-Transfer-Source: 1709728810%3A0ce7c037-5314-4182-8279-2e55a6d31bf3%3Abl_xfer%3A97239707000%23PBX%2311%2323/ITCIVR1394.newsip.israelnumber.com/XML
Caller-Context: ITCIVR1394.newsip.israelnumber.com
Caller-RDNIS: 039707000
Caller-Channel-Name: sofia/default/0506834683%40212.150.158.100
Caller-Profile-Index: 2
Caller-Profile-Created-Time: 1709728810166878
Caller-Channel-Created-Time: 1709728788566873
Caller-Channel-Answered-Time: 1709728790386884
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: e6880004-03e8-42ec-9dd7-cf40f0804e6b
variable_session_id: 3432180
variable_sip_from_user: 0506834683
variable_sip_from_uri: 0506834683%40212.150.158.100
variable_sip_from_host: 212.150.158.100
variable_text_media_flow: disabled
variable_channel_name: sofia/default/0506834683%40212.150.158.100
variable_sip_local_network_addr: 95.217.164.101
variable_sip_network_ip: 195.201.136.94
variable_sip_network_port: 5060
variable_sip_invite_stamp: 1709728788566873
variable_sip_received_ip: 195.201.136.94
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: 0506834683
variable_sofia_profile_name: default
variable_sofia_profile_url: sip%3Amod_sofia%4095.217.164.101%3A5060
variable_recovery_profile_name: default
variable_sip_invite_route_uri: %3Csip%3A195.201.136.94%3Blr%3Bdid%3D0be.2f856e3%3E
variable_sip_invite_record_route: %3Csip%3A195.201.136.94%3Blr%3Bdid%3D0be.2f856e3%3E
variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20OPTIONS,%20BYE,%20REFER,%20SUBSCRIBE,%20NOTIFY,%20INFO
variable_sip_req_user: 039707000
variable_sip_req_port: 5060
variable_sip_req_uri: 039707000%40newsip.israelnumber.com%3A5060
variable_sip_req_host: newsip.israelnumber.com
variable_sip_to_user: 039707000
variable_sip_to_port: 5060
variable_sip_to_uri: 039707000%40newsip.israelnumber.com%3A5060
variable_sip_contact_user: 0506834683
variable_sip_contact_uri: 0506834683%40212.150.158.100
variable_sip_contact_host: 212.150.158.100
variable_sip_user_agent: FPBX-2.8.1(1.4.42)
variable_sip_via_host: 195.201.136.94
variable_sip_via_port: 5060
variable_presence_id: 0506834683%40212.150.158.100
variable_sip_date_epoch_time: 1709641354
variable_sip_h_X-AUTH-IP: 212.150.158.100
variable_sip_h_P-effective_caller_id_name: 0506834683
variable_sip_h_P-effective_caller_id_number: 0506834683
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_video_media_flow: sendrecv
variable_video_possible: true
variable_effective_destination_number: 039707000
variable_execute_on_answer: sched_hangup%20%2B10800%20normal_clearing
variable_nibble_account: 709
variable_nibble_rate: 0.00000
variable_nibble_increment: 60
variable_nibble_minimum: 0.00000
variable_callstart: 2024-03-06%2012%3A39%3A51
variable_hangup_after_bridge: true
variable_continue_on_fail: TRUE
variable_account_id: 709
variable_parent_id: 0
variable_entity_id: 0
variable_call_processed: internal
variable_call_direction: inbound
variable_accountname: default
variable_filename1: astpp.pbx.lua
variable_origination_rates_did: ID%3A15049%7CCODE%3A%5E97239707000.*%7CDESTINATION%3ANational%20Landline%7CCONNECTIONCOST%3A0.00000%7CINCLUDEDSECONDS%3A0%7CCT%3A10%7CCOST%3A0.00000%7CINC%3A60%7CINITIALBLOCK%3A60%7CRATEGROUP%3A0%7CMARKUP%3A0%7CCI%3A93%7CACCID%3A1573
variable_origination_rates: 0
variable_original_caller_id_name: 0506834683
variable_original_caller_id_number: 0506834683
variable_effective_caller_id_name: 0506834683
variable_receiver_accid: 1573
variable_sip_to_host: ITCIVR1394.newsip.israelnumber.com
variable_presence_data: x%7C%7C%7Cprodtwo(170019686118)%7C%7C%7C%5E97239707000.*%20//%20National%20Landline%20//%200.00000%7C%7C%7C%7C%7C%7CDID%7C%7C%7C212.150.158.100
variable_call_type: 10
variable_export_vars: domain_name,sip_to_host,presence_data,call_type
variable_did_calltype: 10
variable_did_extensions: 15
variable_ringback: %25(1000,3000,400)
variable_effective_caller_id_number: 444%230506834683
variable_module_name: pbx
variable_sip_h_P-call_type: custom_forward
variable_sip_h_P-Accountcode: 1573
variable_domain_name: ITCIVR1394.newsip.israelnumber.com
variable_original_did_number: 97239707000
variable_rtp_use_timer_name: soft
variable_rtp_use_pt: 8
variable_rtp_use_ssrc: 1588037421
variable_endpoint_disposition: ANSWER
variable_ivr_menu_status: success
variable_RECORD_SOFTWARE: Lavf59.10.100
variable_switch_r_sdp: v%3D0%0D%0Ao%3Droot%207883%207884%20IN%20IP4%2082.166.66.145%0D%0As%3Dsession%0D%0Ac%3DIN%20IP4%2082.166.66.145%0D%0At%3D0%200%0D%0Am%3Daudio%2016566%20RTP/AVP%208%2098%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A98%20telephone-event/8000%0D%0Aa%3Dfmtp%3A98%200-16%0D%0Aa%3DsilenceSupp%3Aoff%20-%20-%20-%20-%0D%0Aa%3Dptime%3A20%0D%0A
variable_ep_codec_string: CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b
variable_rtp_use_codec_string: PCMA,PCMU
variable_remote_video_media_flow: inactive
variable_remote_text_media_flow: inactive
variable_remote_audio_media_flow: sendrecv
variable_audio_media_flow: sendrecv
variable_rtp_audio_recv_pt: 8
variable_dtmf_type: rfc2833
variable_rtp_2833_send_payload: 98
variable_rtp_2833_recv_payload: 98
variable_rtp_local_sdp_str: v%3D0%0D%0Ao%3DFreeSWITCH%201709705690%201709705692%20IN%20IP4%2095.217.164.101%0D%0As%3DFreeSWITCH%0D%0Ac%3DIN%20IP4%2095.217.164.101%0D%0At%3D0%200%0D%0Am%3Daudio%2023100%20RTP/AVP%208%2098%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A98%20telephone-event/8000%0D%0Aa%3Dfmtp%3A98%200-15%0D%0Aa%3Dptime%3A20%0D%0Aa%3Dsendrecv%0D%0A
variable_remote_media_ip: 82.166.66.145
variable_remote_media_port: 16566
variable_local_media_ip: 95.217.164.101
variable_local_media_port: 23100
variable_advertised_media_ip: 95.217.164.101
variable_sip_to_tag: XUQXej5ypBDXe
variable_sip_from_tag: as15defd92
variable_sip_cseq: 103
variable_sip_call_id: 229d14e634fdcdfe4e28030342d0cb3c%40212.150.158.100
variable_sip_full_via: SIP/2.0/UDP%20195.201.136.94%3A5060%3Bbranch%3Dz9hG4bK28da.a38fedb5.2,SIP/2.0/UDP%20212.150.158.100%3A5060%3Breceived%3D212.150.158.100%3Bbranch%3Dz9hG4bK6da7bc25%3Brport%3D5060
variable_sip_from_display: 0506834683
variable_sip_full_from: %220506834683%22%20%3Csip%3A0506834683%40212.150.158.100%3E%3Btag%3Das15defd92
variable_sip_full_to: %3Csip%3A039707000%40newsip.israelnumber.com%3A5060%3E%3Btag%3DXUQXej5ypBDXe
variable_playback_last_offset_pos: 132640
variable_playback_seconds: 18
variable_playback_ms: 18432
variable_playback_samples: 147456
variable_current_application_data: 97239707000%23PBX%2311%2323%20XML%20ITCIVR1394.newsip.israelnumber.com
variable_current_application: transfer
variable_max_forwards: 29
variable_transfer_history: 1709728810%3A0ce7c037-5314-4182-8279-2e55a6d31bf3%3Abl_xfer%3A97239707000%23PBX%2311%2323/ITCIVR1394.newsip.israelnumber.com/XML
variable_transfer_source: 1709728810%3A0ce7c037-5314-4182-8279-2e55a6d31bf3%3Abl_xfer%3A97239707000%23PBX%2311%2323/ITCIVR1394.newsip.israelnumber.com/XML
variable_nibble_bill: 0.000000
variable_nibble_total_billed: 0.000000
variable_nibble_current_balance: 36.044580
variable_call_uuid: e6880004-03e8-42ec-9dd7-cf40f0804e6b
Hunt-Direction: inbound
Hunt-Logical-Direction: inbound
Hunt-Username: 0506834683
Hunt-Dialplan: XML
Hunt-Caller-ID-Name: 0506834683
Hunt-Caller-ID-Number: 0506834683
Hunt-Orig-Caller-ID-Name: 0506834683
Hunt-Orig-Caller-ID-Number: 0506834683
Hunt-Network-Addr: 195.201.136.94
Hunt-ANI: 0506834683
Hunt-Destination-Number: 97239707000%23PBX%2311%2323
Hunt-Unique-ID: e6880004-03e8-42ec-9dd7-cf40f0804e6b
Hunt-Source: mod_sofia
Hunt-Transfer-Source: 1709728810%3A0ce7c037-5314-4182-8279-2e55a6d31bf3%3Abl_xfer%3A97239707000%23PBX%2311%2323/ITCIVR1394.newsip.israelnumber.com/XML
Hunt-Context: ITCIVR1394.newsip.israelnumber.com
Hunt-RDNIS: 039707000
Hunt-Channel-Name: sofia/default/0506834683%40212.150.158.100
Hunt-Profile-Index: 2
Hunt-Profile-Created-Time: 1709728810166878
Hunt-Channel-Created-Time: 1709728788566873
Hunt-Channel-Answered-Time: 1709728790386884
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
2024-03-06 12:40:10.526868 98.53% [INFO] switch_cpp.cpp:1465 [ASTPP] [xml_REQUEST] tag_name:
2024-03-06 12:40:10.526868 98.53% [INFO] switch_cpp.cpp:1465 [ASTPP] [xml_REQUEST] key_name:
2024-03-06 12:40:10.526868 98.53% [INFO] switch_cpp.cpp:1465 [ASTPP] [xml_REQUEST] key_value:
2024-03-06 12:40:10.526868 98.53% [INFO] switch_cpp.cpp:1465 [ASTPP] [xml_REQUEST] section: dialplan
2024-03-06 12:40:10.526868 98.53% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] blind_flag: 0
2024-03-06 12:40:10.526868 98.53% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 97239707000#PBX#11#23
2024-03-06 12:40:10.526868 98.53% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '97239707000#PBX#11#23' limit 1
2024-03-06 12:40:10.606874 98.53% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '97239707000#PBX#11#23' AND status=0 limit 1
2024-03-06 12:40:10.666876 98.53% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE domain = '212.150.158.100' limit 1
2024-03-06 12:40:10.746870 98.53% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] in custom Caller ID
2024-03-06 12:40:10.746870 98.53% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number : 0506834683 / 444#0506834683
2024-03-06 12:40:10.746870 98.53% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE accountid = 1573 limit 1
2024-03-06 12:40:10.826876 98.50% [WARNING] switch_cpp.cpp:1465 [ASTPP] [user_domain] user_domainITCIVR1394.newsip.israelnumber.com
2024-03-06 12:40:10.826876 98.50% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
2024-03-06 12:40:10.826876 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 13 AND status=0 limit 1
2024-03-06 12:40:10.886870 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:40:10.886870 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:40:10.886870 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:40:10.886870 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:40:10.886870 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:40:10.886870 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:40:10.886870 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:40:10.886870 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.buy_minutes,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,A.call_type_callerid_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="97239707000" LIMIT 1
2024-03-06 12:40:10.966875 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : inbound
2024-03-06 12:40:10.966875 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 1573]
2024-03-06 12:40:10.966875 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "1573" AND deleted = 0 limit 1
2024-03-06 12:40:11.026870 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="97239707000#PBX#11#23" AND A.accountid = '1573' limit 1
2024-03-06 12:40:11.106870 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 97239707000#PBX#11#23
2024-03-06 12:40:11.106870 98.50% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
2024-03-06 12:40:11.106870 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 13 AND status=0 limit 1
2024-03-06 12:40:11.186882 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:40:11.186882 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:40:11.186882 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:40:11.186882 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:40:11.186882 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:40:11.186882 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:40:11.186882 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:40:11.186882 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.buy_minutes,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,A.call_type_callerid_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="97239707000" LIMIT 1
2024-03-06 12:40:11.246873 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call direction : inbound
2024-03-06 12:40:11.246873 98.50% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
2024-03-06 12:40:11.246873 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 13 AND status=0 limit 1
2024-03-06 12:40:11.326865 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000#PBX#11#23
2024-03-06 12:40:11.326865 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000#PBX#11#23
2024-03-06 12:40:11.326865 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000#PBX#11#23
2024-03-06 12:40:11.326865 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000#PBX#11#23
2024-03-06 12:40:11.326865 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000#PBX#11#23
2024-03-06 12:40:11.326865 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000#PBX#11#23
2024-03-06 12:40:11.326865 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000#PBX#11#23
2024-03-06 12:40:11.326865 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="97239707000#PBX#11#23" AND (accountid = 0 OR status = 1) LIMIT 1
2024-03-06 12:40:11.386862 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] call_direction :inbound
2024-03-06 12:40:11.386862 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] DID_ACCOUNTID :1573
2024-03-06 12:40:11.386862 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE (patterns = '^97239707000#PBX#11#23.*' OR patterns = '^97239707000#PBX#11#2.*' OR patterns = '^97239707000#PBX#11#.*' OR patterns = '^97239707000#PBX#11.*' OR patterns = '^97239707000#PBX#1.*' OR patterns = '^97239707000#PBX#.*' OR patterns = '^97239707000#PBX.*' OR patterns = '^97239707000#PB.*' OR patterns = '^97239707000#P.*' OR patterns = '^97239707000#.*' OR patterns = '^97239707000.*' OR patterns = '^9723970700.*' OR patterns = '^972397070.*' OR patterns = '^97239707.*' OR patterns = '^9723970.*' OR patterns = '^972397.*' OR patterns = '^97239.*' OR patterns = '^9723.*' OR patterns = '^972.*' OR patterns = '^97.*' OR patterns = '^9.*' OR patterns ='--') AND accountid = 1573 ORDER BY LENGTH(PKGPTR.patterns) DESC
2024-03-06 12:40:11.466870 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '97239707000#PBX#11#23' OR number = '97239707000#PBX#11#2' OR number = '97239707000#PBX#11#' OR number = '97239707000#PBX#11' OR number = '97239707000#PBX#1' OR number = '97239707000#PBX#' OR number = '97239707000#PBX' OR number = '97239707000#PB' OR number = '97239707000#P' OR number = '97239707000#' OR number = '97239707000' OR number = '9723970700' OR number = '972397070' OR number = '97239707' OR number = '9723970' OR number = '972397' OR number = '97239' OR number = '9723' OR number = '972' OR number = '97' OR number = '9' OR number ='--') OR (number = '97239707000#PBX#11#23*' OR number = '97239707000#PBX#11#2*' OR number = '97239707000#PBX#11#*' OR number = '97239707000#PBX#11*' OR number = '97239707000#PBX#1*' OR number = '97239707000#PBX#*' OR number = '97239707000#PBX*' OR number = '97239707000#PB*' OR number = '97239707000#P*' OR number = '97239707000#*' OR number = '97239707000*' OR number = '9723970700*' OR number = '972397070*' OR number = '97239707*' OR number = '9723970*' OR number = '972397*' OR number = '97239*' OR number = '9723*' OR number = '972*' OR number = '97*' OR number = '9*' OR number ='--') OR (number = '444#0506834683' OR number = '444#050683468' OR number = '444#05068346' OR number = '444#0506834' OR number = '444#050683' OR number = '444#05068' OR number = '444#0506' OR number = '444#050' OR number = '444#05' OR number = '444#0' OR number = '444#' OR number = '444' OR number = '44' OR number = '4' OR number ='--') OR (number = '444#0506834683*' OR number = '444#050683468*' OR number = '444#05068346*' OR number = '444#0506834*' OR number = '444#050683*' OR number = '444#05068*' OR number = '444#0506*' OR number = '444#050*' OR number = '444#05*' OR number = '444#0*' OR number = '444#*' OR number = '444*' OR number = '44*' OR number = '4*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
2024-03-06 12:40:11.546869 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1
2024-03-06 12:40:11.546869 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '97239707000#PBX#11#23' OR routing_prefix = '97239707000#PBX#11#2' OR routing_prefix = '97239707000#PBX#11#' OR routing_prefix = '97239707000#PBX#11' OR routing_prefix = '97239707000#PBX#1' OR routing_prefix = '97239707000#PBX#' OR routing_prefix = '97239707000#PBX' OR routing_prefix = '97239707000#PB' OR routing_prefix = '97239707000#P' OR routing_prefix = '97239707000#' OR routing_prefix = '97239707000' OR routing_prefix = '9723970700' OR routing_prefix = '972397070' OR routing_prefix = '97239707' OR routing_prefix = '9723970' OR routing_prefix = '972397' OR routing_prefix = '97239' OR routing_prefix = '9723' OR routing_prefix = '972' OR routing_prefix = '97' OR routing_prefix = '9' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
2024-03-06 12:40:11.606873 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
2024-03-06 12:40:11.606873 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 1573
2024-03-06 12:40:11.606873 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 675242683994
2024-03-06 12:40:11.606873 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 15.67407
2024-03-06 12:40:11.606873 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
2024-03-06 12:40:11.606873 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 75
2024-03-06 12:40:11.606873 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
2024-03-06 12:40:11.606873 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
2024-03-06 12:40:11.606873 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 75 AND status = 0
2024-03-06 12:40:11.686872 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :inbound
2024-03-06 12:40:11.686872 98.50% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
2024-03-06 12:40:11.686872 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 13 AND status=0 limit 1
2024-03-06 12:40:11.746870 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:40:11.746870 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:40:11.746870 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:40:11.746870 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:40:11.746870 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:40:11.746870 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:40:11.746870 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....97239707000
2024-03-06 12:40:11.746870 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.buy_minutes,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,A.call_type_callerid_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="97239707000" LIMIT 1
2024-03-06 12:40:11.826866 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] call_direction:::::: inbound
2024-03-06 12:40:11.826866 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
2024-03-06 12:40:11.826866 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 15049
2024-03-06 12:40:11.826866 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
2024-03-06 12:40:11.826866 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
2024-03-06 12:40:11.826866 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.00000
2024-03-06 12:40:11.826866 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] comment : National Landline
2024-03-06 12:40:11.826866 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 93
2024-03-06 12:40:11.826866 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 1573
2024-03-06 12:40:11.826866 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
2024-03-06 12:40:11.826866 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your15.67407 balance Accountid 1573 !!!
2024-03-06 12:40:11.826866 98.50% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Call is free - assigning max length!!! :: 180
2024-03-06 12:40:11.826866 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] NIBBLE ID 1573
2024-03-06 12:40:11.826866 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] NIBBLE RATE 0.00000
2024-03-06 12:40:11.826866 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] NIBBLE PROVIDER 0
2024-03-06 12:40:11.826866 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] NIBBLE CONNECT COST 0.00000
2024-03-06 12:40:11.826866 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] NIBBLE INITIAL INC 60
2024-03-06 12:40:11.826866 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] NIBBLE INC 60
2024-03-06 12:40:11.826866 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max length duration : 180 minutes
2024-03-06 12:40:11.826866 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] INB_FREE:TRUE
2024-03-06 12:40:11.826866 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] free_inbound:1
2024-03-06 12:40:11.826866 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 1573 AND status= '0' LIMIT 1
2024-03-06 12:40:11.906867 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "1573" AND deleted = 0 limit 1
2024-03-06 12:40:11.966881 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML:1573
2024-03-06 12:40:11.966881 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Userinfo XML:1573
2024-03-06 12:40:11.966881 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML : 1573
2024-03-06 12:40:11.966881 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE accountid = 1573 limit 1
2024-03-06 12:40:12.046880 98.50% [WARNING] switch_cpp.cpp:1465 [ASTPP] [user_domain] user_domainITCIVR1394.newsip.israelnumber.com
2024-03-06 12:40:12.046880 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] timecondition_id : 23
2024-03-06 12:40:12.046880 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET_TIME_CONDITION_QUERY] Query :SELECT * from time_condition WHERE id = '23' and status =0 LIMIT 1
2024-03-06 12:40:12.106876 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] failover_timecondition : [{"extensions":"0523609233#","no_answer_call_type":"12"}]
2024-03-06 12:40:12.106876 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] timecondition : [{"hour":"09-18","minute":"33-30","wday":"1-5","mday":"1-31","mon":"1-12","year":"2023-2032","extensions":"0506834683#","no_answer_call_type":"12","priority":"1"}]
2024-03-06 12:40:12.106876 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] failover_timecondition_str : 0523609233#
2024-03-06 12:40:12.106876 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] priority : 1
2024-03-06 12:40:12.106876 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] condition_str : time-of-day= "09:33-18:30" wday= "1-5" mday= "1-31" mon= "1-12" year= "2023-2032"
2024-03-06 12:40:12.106876 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET_TIME_CONDITION_QUERY] Query :SELECT gmttime from timezone WHERE id = '38' LIMIT 1
2024-03-06 12:40:12.186870 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET_TIME_CONDITION_QUERY] Query :SELECT timezone_name from timezone_custom WHERE gmttime = 'GMT+03:00' LIMIT 1
2024-03-06 12:40:12.266867 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Condition Str : time-of-day= "09:33-18:30" wday= "1-5" mday= "1-31" mon= "1-12" year= "2023-2032"
2024-03-06 12:40:12.266867 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] maxlength::::::::: 180
2024-03-06 12:40:12.266867 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"000/00".....0506834683
2024-03-06 12:40:12.266867 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"000/00".....444#0506834683
2024-03-06 12:40:12.266867 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE accountid = 1573 limit 1
2024-03-06 12:40:12.326875 98.50% [WARNING] switch_cpp.cpp:1465 [ASTPP] [user_domain] user_domainITCIVR1394.newsip.israelnumber.com
2024-03-06 12:40:12.326875 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] custom_function_name:::::::::::::::::::::::::custom_inbound_11
2024-03-06 12:40:12.326875 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] timecondition_id : 23
2024-03-06 12:40:12.326875 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET_TIME_CONDITION_QUERY] Query :SELECT * from time_condition WHERE id = '23' and status =0 LIMIT 1
2024-03-06 12:40:12.406868 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] time_condition::::::::::::::::::::::::15049
2024-03-06 12:40:12.406868 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] custom_function_name::::::::::::::::::::::::15049
2024-03-06 12:40:12.406868 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Generated XML:
Dialplan: sofia/default/0506834683@212.150.158.100 parsing [ITCIVR1394.newsip.israelnumber.com->Support TC 3] continue=true
Dialplan: sofia/default/0506834683@212.150.158.100 Date/Time Match (PASS) [Support TC 3] break=never
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(office_status=open) INLINE
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(office_status=open)
2024-03-06 12:40:12.406868 98.50% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [office_status]=[open]
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(extensions=0523609233#) INLINE
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(extensions=0523609233#)
2024-03-06 12:40:12.406868 98.50% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [extensions]=[0523609233#]
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(no_answer_call_type=12) INLINE
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(no_answer_call_type=12)
2024-03-06 12:40:12.406868 98.50% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [no_answer_call_type]=[12]
Dialplan: sofia/default/0506834683@212.150.158.100 parsing [ITCIVR1394.newsip.israelnumber.com->Support TC 3] continue=true
Dialplan: sofia/default/0506834683@212.150.158.100 Absolute Condition [Support TC 3]
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(timezone=Antarctica/Syowa) INLINE
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(timezone=Antarctica/Syowa)
2024-03-06 12:40:12.406868 98.50% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [timezone]=[Antarctica/Syowa]
Dialplan: sofia/default/0506834683@212.150.158.100 Date/Time Match (PASS) [Support TC 3] break=never
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(office_status=open) INLINE
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(office_status=open)
2024-03-06 12:40:12.406868 98.50% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [office_status]=[open]
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(extensions=0506834683#) INLINE
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(extensions=0506834683#)
2024-03-06 12:40:12.406868 98.50% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [extensions]=[0506834683#]
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(no_answer_call_type=12) INLINE
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(no_answer_call_type=12)
2024-03-06 12:40:12.406868 98.50% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [no_answer_call_type]=[12]
Dialplan: sofia/default/0506834683@212.150.158.100 parsing [ITCIVR1394.newsip.israelnumber.com->tod route, x97239707000] continue=false
Dialplan: sofia/default/0506834683@212.150.158.100 Regex (FAIL) [tod route, x97239707000] destination_number(97239707000#PBX#11#23) =~ /^(\+?)?(97239707000)$/ break=on-false
Dialplan: sofia/default/0506834683@212.150.158.100 parsing [ITCIVR1394.newsip.israelnumber.com->office is open] continue=false
Dialplan: sofia/default/0506834683@212.150.158.100 Regex (PASS) [office is open] destination_number(97239707000#PBX#11#23) =~ /^(\+?)?(97239707000#PBX#11#23)$/ break=on-false
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(domain_name=ITCIVR1394.newsip.israelnumber.com)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(set_extensions=${extensions})
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(no_answer_flag=true)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(no_answer_call_type=${no_answer_call_type})
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(no_answer_number=${extensions})
Dialplan: sofia/default/0506834683@212.150.158.100 Action transfer(1573 XML default)
2024-03-06 12:40:12.406868 98.50% [DEBUG] switch_core_state_machine.c:281 (sofia/default/0506834683@212.150.158.100) State Change CS_ROUTING -> CS_EXECUTE
2024-03-06 12:40:12.406868 98.50% [DEBUG] switch_core_state_machine.c:640 (sofia/default/0506834683@212.150.158.100) State ROUTING going to sleep
2024-03-06 12:40:12.406868 98.50% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0506834683@212.150.158.100) Running State Change CS_EXECUTE (Cur 34 Tot 3432180)
2024-03-06 12:40:12.406868 98.50% [DEBUG] switch_channel.c:2382 (sofia/default/0506834683@212.150.158.100) Callstate Change RINGING -> ACTIVE
2024-03-06 12:40:12.406868 98.50% [DEBUG] switch_core_state_machine.c:647 (sofia/default/0506834683@212.150.158.100) State EXECUTE
2024-03-06 12:40:12.406868 98.50% [DEBUG] mod_sofia.c:213 sofia/default/0506834683@212.150.158.100 SOFIA EXECUTE
2024-03-06 12:40:12.406868 98.50% [INFO] switch_core_session.c:1707 sofia/default/0506834683@212.150.158.100 setting session heartbeat to 60 second(s).
2024-03-06 12:40:12.406868 98.50% [DEBUG] switch_core_state_machine.c:323 sofia/default/0506834683@212.150.158.100 Standard EXECUTE
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(domain_name=ITCIVR1394.newsip.israelnumber.com)
2024-03-06 12:40:12.406868 98.50% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [domain_name]=[ITCIVR1394.newsip.israelnumber.com]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(set_extensions=0506834683#)
2024-03-06 12:40:12.406868 98.50% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [set_extensions]=[0506834683#]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(no_answer_flag=true)
2024-03-06 12:40:12.406868 98.50% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [no_answer_flag]=[true]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(no_answer_call_type=12)
2024-03-06 12:40:12.406868 98.50% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [no_answer_call_type]=[12]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(no_answer_number=0506834683#)
2024-03-06 12:40:12.406868 98.50% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [no_answer_number]=[0506834683#]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 transfer(1573 XML default)
2024-03-06 12:40:12.406868 98.50% [DEBUG] switch_ivr.c:2289 (sofia/default/0506834683@212.150.158.100) State Change CS_EXECUTE -> CS_ROUTING
2024-03-06 12:40:12.406868 98.50% [NOTICE] switch_ivr.c:2296 Transfer sofia/default/0506834683@212.150.158.100 to XML[1573@default]
2024-03-06 12:40:12.406868 98.50% [DEBUG] switch_core_state_machine.c:647 (sofia/default/0506834683@212.150.158.100) State EXECUTE going to sleep
2024-03-06 12:40:12.406868 98.50% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0506834683@212.150.158.100) Running State Change CS_ROUTING (Cur 34 Tot 3432180)
2024-03-06 12:40:12.406868 98.50% [DEBUG] switch_channel.c:2380 (sofia/default/0506834683@212.150.158.100) Callstate Change ACTIVE -> RINGING
2024-03-06 12:40:12.406868 98.50% [DEBUG] switch_core_state_machine.c:640 (sofia/default/0506834683@212.150.158.100) State ROUTING
2024-03-06 12:40:12.406868 98.50% [DEBUG] mod_sofia.c:158 sofia/default/0506834683@212.150.158.100 SOFIA ROUTING
2024-03-06 12:40:12.406868 98.50% [DEBUG] mod_nibblebill.c:506 Attempting to bill at $0.00000 per minute to account 709
2024-03-06 12:40:12.406868 98.50% [DEBUG] mod_nibblebill.c:573 -37 seconds passed since last bill time of 2024-03-06 12:40:50
2024-03-06 12:40:12.406868 98.50% [DEBUG] mod_nibblebill.c:422 Doing lookup query
[SELECT if(posttoexternal=1,credit_limit+(balance*-1),balance) AS nibble_balance FROM accounts WHERE id='709']
2024-03-06 12:40:12.466867 98.50% [DEBUG] mod_nibblebill.c:430 Retrieved current balance for account 709 (balance = 36.044580)
2024-03-06 12:40:12.486873 98.50% [DEBUG] mod_nibblebill.c:422 Doing lookup query
[SELECT if(posttoexternal=1,credit_limit+(balance*-1),balance) AS nibble_balance FROM accounts WHERE id='709']
2024-03-06 12:40:12.546868 98.50% [DEBUG] mod_nibblebill.c:430 Retrieved current balance for account 709 (balance = 36.044580)
2024-03-06 12:40:12.546868 98.50% [DEBUG] switch_core_state_machine.c:230 sofia/default/0506834683@212.150.158.100 Standard ROUTING
2024-03-06 12:40:12.546868 98.50% [INFO] mod_dialplan_xml.c:639 Processing 0506834683 <0506834683>->1573 in context default
2024-03-06 12:40:12.546868 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
2024-03-06 12:40:12.626873 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
2024-03-06 12:40:12.706858 98.50% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [xml_handler] Params:
Event-Name: REQUEST_PARAMS
Core-UUID: 5bc8c5c5-4af3-43ec-bda5-1cb9a93e59f7
FreeSWITCH-Hostname: fs-slave
FreeSWITCH-Switchname: fs-slave
FreeSWITCH-IPv4: 95.217.164.101
FreeSWITCH-IPv6: 2a01%3A4f9%3Ac011%3A94fe%3A%3A1
Event-Date-Local: 2024-03-06%2012%3A40%3A12
Event-Date-GMT: Wed,%2006%20Mar%202024%2012%3A40%3A12%20GMT
Event-Date-Timestamp: 1709728812546868
Event-Calling-File: mod_dialplan_xml.c
Event-Calling-Function: dialplan_xml_locate
Event-Calling-Line-Number: 610
Event-Sequence: 211775768
Channel-State: CS_ROUTING
Channel-Call-State: RINGING
Channel-State-Number: 2
Channel-Name: sofia/default/0506834683%40212.150.158.100
Unique-ID: e6880004-03e8-42ec-9dd7-cf40f0804e6b
Call-Direction: inbound
Presence-Call-Direction: inbound
Channel-HIT-Dialplan: true
Channel-Presence-ID: 0506834683%40212.150.158.100
Channel-Presence-Data: x%7C%7C%7Cprodtwo(170019686118)%7C%7C%7C%5E97239707000.*%20//%20National%20Landline%20//%200.00000%7C%7C%7C%7C%7C%7CDID%7C%7C%7C212.150.158.100
Channel-Call-UUID: e6880004-03e8-42ec-9dd7-cf40f0804e6b
Answer-State: answered
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: 0506834683
Caller-Dialplan: XML
Caller-Caller-ID-Name: 0506834683
Caller-Caller-ID-Number: 0506834683
Caller-Orig-Caller-ID-Name: 0506834683
Caller-Orig-Caller-ID-Number: 0506834683
Caller-Network-Addr: 195.201.136.94
Caller-ANI: 0506834683
Caller-Destination-Number: 1573
Caller-Unique-ID: e6880004-03e8-42ec-9dd7-cf40f0804e6b
Caller-Source: mod_sofia
Caller-Transfer-Source: 1709728812%3A26a0cdba-5bb5-4425-a4a4-82655b4ff287%3Abl_xfer%3A1573/default/XML
Caller-Context: default
Caller-RDNIS: 97239707000%23PBX%2311%2323
Caller-Channel-Name: sofia/default/0506834683%40212.150.158.100
Caller-Profile-Index: 3
Caller-Profile-Created-Time: 1709728812406868
Caller-Channel-Created-Time: 1709728788566873
Caller-Channel-Answered-Time: 1709728790386884
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: e6880004-03e8-42ec-9dd7-cf40f0804e6b
variable_session_id: 3432180
variable_sip_from_user: 0506834683
variable_sip_from_uri: 0506834683%40212.150.158.100
variable_sip_from_host: 212.150.158.100
variable_text_media_flow: disabled
variable_channel_name: sofia/default/0506834683%40212.150.158.100
variable_sip_local_network_addr: 95.217.164.101
variable_sip_network_ip: 195.201.136.94
variable_sip_network_port: 5060
variable_sip_invite_stamp: 1709728788566873
variable_sip_received_ip: 195.201.136.94
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: 0506834683
variable_sofia_profile_name: default
variable_sofia_profile_url: sip%3Amod_sofia%4095.217.164.101%3A5060
variable_recovery_profile_name: default
variable_sip_invite_route_uri: %3Csip%3A195.201.136.94%3Blr%3Bdid%3D0be.2f856e3%3E
variable_sip_invite_record_route: %3Csip%3A195.201.136.94%3Blr%3Bdid%3D0be.2f856e3%3E
variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20OPTIONS,%20BYE,%20REFER,%20SUBSCRIBE,%20NOTIFY,%20INFO
variable_sip_req_user: 039707000
variable_sip_req_port: 5060
variable_sip_req_uri: 039707000%40newsip.israelnumber.com%3A5060
variable_sip_req_host: newsip.israelnumber.com
variable_sip_to_user: 039707000
variable_sip_to_port: 5060
variable_sip_to_uri: 039707000%40newsip.israelnumber.com%3A5060
variable_sip_contact_user: 0506834683
variable_sip_contact_uri: 0506834683%40212.150.158.100
variable_sip_contact_host: 212.150.158.100
variable_sip_user_agent: FPBX-2.8.1(1.4.42)
variable_sip_via_host: 195.201.136.94
variable_sip_via_port: 5060
variable_presence_id: 0506834683%40212.150.158.100
variable_sip_date_epoch_time: 1709641354
variable_sip_h_X-AUTH-IP: 212.150.158.100
variable_sip_h_P-effective_caller_id_name: 0506834683
variable_sip_h_P-effective_caller_id_number: 0506834683
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_video_media_flow: sendrecv
variable_video_possible: true
variable_effective_destination_number: 039707000
variable_execute_on_answer: sched_hangup%20%2B10800%20normal_clearing
variable_nibble_account: 709
variable_nibble_rate: 0.00000
variable_nibble_increment: 60
variable_nibble_minimum: 0.00000
variable_callstart: 2024-03-06%2012%3A39%3A51
variable_hangup_after_bridge: true
variable_continue_on_fail: TRUE
variable_account_id: 709
variable_parent_id: 0
variable_entity_id: 0
variable_call_processed: internal
variable_call_direction: inbound
variable_accountname: default
variable_filename1: astpp.pbx.lua
variable_origination_rates_did: ID%3A15049%7CCODE%3A%5E97239707000.*%7CDESTINATION%3ANational%20Landline%7CCONNECTIONCOST%3A0.00000%7CINCLUDEDSECONDS%3A0%7CCT%3A10%7CCOST%3A0.00000%7CINC%3A60%7CINITIALBLOCK%3A60%7CRATEGROUP%3A0%7CMARKUP%3A0%7CCI%3A93%7CACCID%3A1573
variable_origination_rates: 0
variable_original_caller_id_name: 0506834683
variable_original_caller_id_number: 0506834683
variable_effective_caller_id_name: 0506834683
variable_receiver_accid: 1573
variable_sip_to_host: ITCIVR1394.newsip.israelnumber.com
variable_presence_data: x%7C%7C%7Cprodtwo(170019686118)%7C%7C%7C%5E97239707000.*%20//%20National%20Landline%20//%200.00000%7C%7C%7C%7C%7C%7CDID%7C%7C%7C212.150.158.100
variable_call_type: 10
variable_export_vars: domain_name,sip_to_host,presence_data,call_type
variable_did_calltype: 10
variable_did_extensions: 15
variable_ringback: %25(1000,3000,400)
variable_effective_caller_id_number: 444%230506834683
variable_module_name: pbx
variable_sip_h_P-call_type: custom_forward
variable_sip_h_P-Accountcode: 1573
variable_original_did_number: 97239707000
variable_rtp_use_timer_name: soft
variable_rtp_use_pt: 8
variable_rtp_use_ssrc: 1588037421
variable_endpoint_disposition: ANSWER
variable_ivr_menu_status: success
variable_RECORD_SOFTWARE: Lavf59.10.100
variable_switch_r_sdp: v%3D0%0D%0Ao%3Droot%207883%207884%20IN%20IP4%2082.166.66.145%0D%0As%3Dsession%0D%0Ac%3DIN%20IP4%2082.166.66.145%0D%0At%3D0%200%0D%0Am%3Daudio%2016566%20RTP/AVP%208%2098%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A98%20telephone-event/8000%0D%0Aa%3Dfmtp%3A98%200-16%0D%0Aa%3DsilenceSupp%3Aoff%20-%20-%20-%20-%0D%0Aa%3Dptime%3A20%0D%0A
variable_ep_codec_string: CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b
variable_rtp_use_codec_string: PCMA,PCMU
variable_remote_video_media_flow: inactive
variable_remote_text_media_flow: inactive
variable_remote_audio_media_flow: sendrecv
variable_audio_media_flow: sendrecv
variable_rtp_audio_recv_pt: 8
variable_dtmf_type: rfc2833
variable_rtp_2833_send_payload: 98
variable_rtp_2833_recv_payload: 98
variable_rtp_local_sdp_str: v%3D0%0D%0Ao%3DFreeSWITCH%201709705690%201709705692%20IN%20IP4%2095.217.164.101%0D%0As%3DFreeSWITCH%0D%0Ac%3DIN%20IP4%2095.217.164.101%0D%0At%3D0%200%0D%0Am%3Daudio%2023100%20RTP/AVP%208%2098%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A98%20telephone-event/8000%0D%0Aa%3Dfmtp%3A98%200-15%0D%0Aa%3Dptime%3A20%0D%0Aa%3Dsendrecv%0D%0A
variable_remote_media_ip: 82.166.66.145
variable_remote_media_port: 16566
variable_local_media_ip: 95.217.164.101
variable_local_media_port: 23100
variable_advertised_media_ip: 95.217.164.101
variable_sip_to_tag: XUQXej5ypBDXe
variable_sip_from_tag: as15defd92
variable_sip_cseq: 103
variable_sip_call_id: 229d14e634fdcdfe4e28030342d0cb3c%40212.150.158.100
variable_sip_full_via: SIP/2.0/UDP%20195.201.136.94%3A5060%3Bbranch%3Dz9hG4bK28da.a38fedb5.2,SIP/2.0/UDP%20212.150.158.100%3A5060%3Breceived%3D212.150.158.100%3Bbranch%3Dz9hG4bK6da7bc25%3Brport%3D5060
variable_sip_from_display: 0506834683
variable_sip_full_from: %220506834683%22%20%3Csip%3A0506834683%40212.150.158.100%3E%3Btag%3Das15defd92
variable_sip_full_to: %3Csip%3A039707000%40newsip.israelnumber.com%3A5060%3E%3Btag%3DXUQXej5ypBDXe
variable_playback_last_offset_pos: 132640
variable_playback_seconds: 18
variable_playback_ms: 18432
variable_playback_samples: 147456
variable_transfer_history: ARRAY%3A%3A1709728810%3A0ce7c037-5314-4182-8279-2e55a6d31bf3%3Abl_xfer%3A97239707000%23PBX%2311%2323/ITCIVR1394.newsip.israelnumber.com/XML%7C%3A1709728812%3A26a0cdba-5bb5-4425-a4a4-82655b4ff287%3Abl_xfer%3A1573/default/XML
variable_nibble_bill: 0.000000
variable_nibble_total_billed: 0.000000
variable_timezone: Antarctica/Syowa
variable_office_status: open
variable_extensions: 0506834683%23
variable_DP_MATCH: 97239707000%23PBX%2311%2323
variable_domain_name: ITCIVR1394.newsip.israelnumber.com
variable_set_extensions: 0506834683%23
variable_no_answer_flag: true
variable_no_answer_call_type: 12
variable_no_answer_number: 0506834683%23
variable_current_application_data: 1573%20XML%20default
variable_current_application: transfer
variable_max_forwards: 28
variable_transfer_source: 1709728812%3A26a0cdba-5bb5-4425-a4a4-82655b4ff287%3Abl_xfer%3A1573/default/XML
variable_nibble_current_balance: 36.044580
variable_call_uuid: e6880004-03e8-42ec-9dd7-cf40f0804e6b
Hunt-Direction: inbound
Hunt-Logical-Direction: inbound
Hunt-Username: 0506834683
Hunt-Dialplan: XML
Hunt-Caller-ID-Name: 0506834683
Hunt-Caller-ID-Number: 0506834683
Hunt-Orig-Caller-ID-Name: 0506834683
Hunt-Orig-Caller-ID-Number: 0506834683
Hunt-Network-Addr: 195.201.136.94
Hunt-ANI: 0506834683
Hunt-Destination-Number: 1573
Hunt-Unique-ID: e6880004-03e8-42ec-9dd7-cf40f0804e6b
Hunt-Source: mod_sofia
Hunt-Transfer-Source: 1709728812%3A26a0cdba-5bb5-4425-a4a4-82655b4ff287%3Abl_xfer%3A1573/default/XML
Hunt-Context: default
Hunt-RDNIS: 97239707000%23PBX%2311%2323
Hunt-Channel-Name: sofia/default/0506834683%40212.150.158.100
Hunt-Profile-Index: 3
Hunt-Profile-Created-Time: 1709728812406868
Hunt-Channel-Created-Time: 1709728788566873
Hunt-Channel-Answered-Time: 1709728790386884
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
2024-03-06 12:40:12.706858 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [xml_REQUEST] key_value:
2024-03-06 12:40:12.706858 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [xml_REQUEST] tag_name:
2024-03-06 12:40:12.706858 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [xml_REQUEST] key_name:
2024-03-06 12:40:12.706858 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [xml_REQUEST] section: dialplan
2024-03-06 12:40:12.706858 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] blind_flag: 0
2024-03-06 12:40:12.706858 98.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 1573
2024-03-06 12:40:12.706858 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '1573' limit 1
2024-03-06 12:40:12.766862 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '1573' AND status=0 limit 1
2024-03-06 12:40:12.846863 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE domain = '212.150.158.100' limit 1
2024-03-06 12:40:12.886870 98.43% [DEBUG] switch_scheduler.c:147 Deleting task 1643031 switch_core_session_sched_heartbeat (e6880004-03e8-42ec-9dd7-cf40f0804e6b)
2024-03-06 12:40:12.906874 98.43% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] in custom Caller ID
2024-03-06 12:40:12.906874 98.43% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number : 0506834683 / 444#0506834683
2024-03-06 12:40:12.906874 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE accountid = 1573 limit 1
2024-03-06 12:40:12.986861 98.43% [WARNING] switch_cpp.cpp:1465 [ASTPP] [user_domain] user_domainITCIVR1394.newsip.israelnumber.com
2024-03-06 12:40:12.986861 98.43% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
2024-03-06 12:40:12.986861 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 13 AND status=0 limit 1
2024-03-06 12:40:13.046862 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....1573
2024-03-06 12:40:13.046862 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....1573
2024-03-06 12:40:13.046862 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....1573
2024-03-06 12:40:13.046862 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....1573
2024-03-06 12:40:13.046862 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....1573
2024-03-06 12:40:13.046862 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....1573
2024-03-06 12:40:13.046862 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....1573
2024-03-06 12:40:13.046862 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.buy_minutes,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,A.call_type_callerid_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="1573" LIMIT 1
2024-03-06 12:40:13.126862 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as accounts ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="1573" AND domains.domain="ITCIVR1394.newsip.israelnumber.com" limit 1
2024-03-06 12:40:13.206870 98.43% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
2024-03-06 12:40:13.206870 98.43% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 1573]
2024-03-06 12:40:13.206870 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "1573" AND deleted = 0 limit 1
2024-03-06 12:40:13.266864 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^1573.*' OR blocked_patterns = '^157.*' OR blocked_patterns = '^15.*' OR blocked_patterns = '^1.*' OR blocked_patterns ='--') AND accountid = 1573 limit 1
2024-03-06 12:40:13.346864 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="1573" AND A.accountid = '1573' limit 1
2024-03-06 12:40:13.406866 98.43% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 1573
2024-03-06 12:40:13.406866 98.43% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
2024-03-06 12:40:13.406866 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 13 AND status=0 limit 1
2024-03-06 12:40:13.486865 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....1573
2024-03-06 12:40:13.486865 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....1573
2024-03-06 12:40:13.486865 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....1573
2024-03-06 12:40:13.486865 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....1573
2024-03-06 12:40:13.486865 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....1573
2024-03-06 12:40:13.486865 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....1573
2024-03-06 12:40:13.486865 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....1573
2024-03-06 12:40:13.486865 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.buy_minutes,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,A.call_type_callerid_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="1573" LIMIT 1
2024-03-06 12:40:13.566873 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as accounts ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="1573" AND domains.domain="ITCIVR1394.newsip.israelnumber.com" limit 1
2024-03-06 12:40:13.626874 98.43% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call direction : outbound
2024-03-06 12:40:13.626874 98.43% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
2024-03-06 12:40:13.626874 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 13 AND status=0 limit 1
2024-03-06 12:40:13.706877 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....1573
2024-03-06 12:40:13.706877 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....1573
2024-03-06 12:40:13.706877 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....1573
2024-03-06 12:40:13.706877 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....1573
2024-03-06 12:40:13.706877 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....1573
2024-03-06 12:40:13.706877 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....1573
2024-03-06 12:40:13.706877 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....1573
2024-03-06 12:40:13.706877 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="1573" AND (accountid = 0 OR status = 1) LIMIT 1
2024-03-06 12:40:13.766870 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "1573" AND deleted = 0 limit 1
2024-03-06 12:40:13.846872 98.40% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^1573.*' OR blocked_patterns = '^157.*' OR blocked_patterns = '^15.*' OR blocked_patterns = '^1.*' OR blocked_patterns ='--') AND accountid = 1573 limit 1
2024-03-06 12:40:13.926887 98.40% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Generated ringgroup_no_answer_forward:1573
2024-03-06 12:40:13.926887 98.40% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE accountid = 1573 limit 1
2024-03-06 12:40:13.986876 98.40% [WARNING] switch_cpp.cpp:1465 [ASTPP] [user_domain] user_domainITCIVR1394.newsip.israelnumber.com
2024-03-06 12:40:13.986876 98.40% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] call_type : 12
2024-03-06 12:40:13.986876 98.40% [WARNING] switch_cpp.cpp:1465 [ASTPP] Time-Codtion Pstn Call
2024-03-06 12:40:13.986876 98.40% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_accounts_info] Query :SELECT * FROM accounts WHERE id = 1573 and status = 0 limit 1
2024-03-06 12:40:14.066864 98.40% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Generated XML:
Dialplan: sofia/default/0506834683@212.150.158.100 parsing [default->1573] continue=false
Dialplan: sofia/default/0506834683@212.150.158.100 Regex (PASS) [1573] destination_number(1573) =~ /1573/ break=on-false
Dialplan: sofia/default/0506834683@212.150.158.100 Action export(did_user_domain=ITCIVR1394.newsip.israelnumber.com)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(continue_on_fail=TRUE)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(ignore_early_media=true)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(no_answer_flag=false)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(did_calltype=12)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(did_extensions=0506834683#)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(accountcode=675242683994)
Dialplan: sofia/default/0506834683@212.150.158.100 Action transfer(0506834683 XML default)
2024-03-06 12:40:14.066864 98.40% [DEBUG] switch_core_state_machine.c:281 (sofia/default/0506834683@212.150.158.100) State Change CS_ROUTING -> CS_EXECUTE
2024-03-06 12:40:14.066864 98.40% [DEBUG] switch_core_state_machine.c:640 (sofia/default/0506834683@212.150.158.100) State ROUTING going to sleep
2024-03-06 12:40:14.066864 98.40% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0506834683@212.150.158.100) Running State Change CS_EXECUTE (Cur 34 Tot 3432180)
2024-03-06 12:40:14.066864 98.40% [DEBUG] switch_channel.c:2382 (sofia/default/0506834683@212.150.158.100) Callstate Change RINGING -> ACTIVE
2024-03-06 12:40:14.066864 98.40% [DEBUG] switch_core_state_machine.c:647 (sofia/default/0506834683@212.150.158.100) State EXECUTE
2024-03-06 12:40:14.066864 98.40% [DEBUG] mod_sofia.c:213 sofia/default/0506834683@212.150.158.100 SOFIA EXECUTE
2024-03-06 12:40:14.066864 98.40% [INFO] switch_core_session.c:1707 sofia/default/0506834683@212.150.158.100 setting session heartbeat to 60 second(s).
2024-03-06 12:40:14.066864 98.40% [DEBUG] switch_core_state_machine.c:323 sofia/default/0506834683@212.150.158.100 Standard EXECUTE
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 export(did_user_domain=ITCIVR1394.newsip.israelnumber.com)
2024-03-06 12:40:14.066864 98.40% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [did_user_domain]=[ITCIVR1394.newsip.israelnumber.com]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(continue_on_fail=TRUE)
2024-03-06 12:40:14.066864 98.40% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [continue_on_fail]=[TRUE]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(ignore_early_media=true)
2024-03-06 12:40:14.066864 98.40% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [ignore_early_media]=[true]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(no_answer_flag=false)
2024-03-06 12:40:14.066864 98.40% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [no_answer_flag]=[false]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(did_calltype=12)
2024-03-06 12:40:14.066864 98.40% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [did_calltype]=[12]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(did_extensions=0506834683#)
2024-03-06 12:40:14.066864 98.40% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [did_extensions]=[0506834683#]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(accountcode=675242683994)
2024-03-06 12:40:14.066864 98.40% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [accountcode]=[675242683994]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 transfer(0506834683 XML default)
2024-03-06 12:40:14.066864 98.40% [DEBUG] switch_ivr.c:2289 (sofia/default/0506834683@212.150.158.100) State Change CS_EXECUTE -> CS_ROUTING
2024-03-06 12:40:14.066864 98.40% [NOTICE] switch_ivr.c:2296 Transfer sofia/default/0506834683@212.150.158.100 to XML[0506834683@default]
2024-03-06 12:40:14.066864 98.40% [DEBUG] switch_core_state_machine.c:647 (sofia/default/0506834683@212.150.158.100) State EXECUTE going to sleep
2024-03-06 12:40:14.066864 98.40% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0506834683@212.150.158.100) Running State Change CS_ROUTING (Cur 34 Tot 3432180)
2024-03-06 12:40:14.066864 98.40% [DEBUG] switch_channel.c:2380 (sofia/default/0506834683@212.150.158.100) Callstate Change ACTIVE -> RINGING
2024-03-06 12:40:14.066864 98.40% [DEBUG] switch_core_state_machine.c:640 (sofia/default/0506834683@212.150.158.100) State ROUTING
2024-03-06 12:40:14.066864 98.40% [DEBUG] mod_sofia.c:158 sofia/default/0506834683@212.150.158.100 SOFIA ROUTING
2024-03-06 12:40:14.066864 98.40% [DEBUG] mod_nibblebill.c:506 Attempting to bill at $0.00000 per minute to account 709
2024-03-06 12:40:14.066864 98.40% [DEBUG] mod_nibblebill.c:573 -36 seconds passed since last bill time of 2024-03-06 12:40:50
2024-03-06 12:40:14.066864 98.40% [DEBUG] mod_nibblebill.c:422 Doing lookup query
[SELECT if(posttoexternal=1,credit_limit+(balance*-1),balance) AS nibble_balance FROM accounts WHERE id='709']
2024-03-06 12:40:14.146873 98.40% [DEBUG] mod_nibblebill.c:430 Retrieved current balance for account 709 (balance = 36.044580)
2024-03-06 12:40:14.146873 98.40% [DEBUG] mod_nibblebill.c:422 Doing lookup query
[SELECT if(posttoexternal=1,credit_limit+(balance*-1),balance) AS nibble_balance FROM accounts WHERE id='709']
2024-03-06 12:40:14.206867 98.40% [DEBUG] mod_nibblebill.c:430 Retrieved current balance for account 709 (balance = 36.044580)
2024-03-06 12:40:14.206867 98.40% [DEBUG] switch_core_state_machine.c:230 sofia/default/0506834683@212.150.158.100 Standard ROUTING
2024-03-06 12:40:14.206867 98.40% [INFO] mod_dialplan_xml.c:639 Processing 0506834683 <0506834683>->0506834683 in context default
2024-03-06 12:40:14.206867 98.40% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
2024-03-06 12:40:14.286875 98.40% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
2024-03-06 12:40:14.366875 98.40% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [xml_handler] Params:
Event-Name: REQUEST_PARAMS
Core-UUID: 5bc8c5c5-4af3-43ec-bda5-1cb9a93e59f7
FreeSWITCH-Hostname: fs-slave
FreeSWITCH-Switchname: fs-slave
FreeSWITCH-IPv4: 95.217.164.101
FreeSWITCH-IPv6: 2a01%3A4f9%3Ac011%3A94fe%3A%3A1
Event-Date-Local: 2024-03-06%2012%3A40%3A14
Event-Date-GMT: Wed,%2006%20Mar%202024%2012%3A40%3A14%20GMT
Event-Date-Timestamp: 1709728814206867
Event-Calling-File: mod_dialplan_xml.c
Event-Calling-Function: dialplan_xml_locate
Event-Calling-Line-Number: 610
Event-Sequence: 211775791
Channel-State: CS_ROUTING
Channel-Call-State: RINGING
Channel-State-Number: 2
Channel-Name: sofia/default/0506834683%40212.150.158.100
Unique-ID: e6880004-03e8-42ec-9dd7-cf40f0804e6b
Call-Direction: inbound
Presence-Call-Direction: inbound
Channel-HIT-Dialplan: true
Channel-Presence-ID: 0506834683%40212.150.158.100
Channel-Presence-Data: x%7C%7C%7Cprodtwo(170019686118)%7C%7C%7C%5E97239707000.*%20//%20National%20Landline%20//%200.00000%7C%7C%7C%7C%7C%7CDID%7C%7C%7C212.150.158.100
Channel-Call-UUID: e6880004-03e8-42ec-9dd7-cf40f0804e6b
Answer-State: answered
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: 0506834683
Caller-Dialplan: XML
Caller-Caller-ID-Name: 0506834683
Caller-Caller-ID-Number: 0506834683
Caller-Orig-Caller-ID-Name: 0506834683
Caller-Orig-Caller-ID-Number: 0506834683
Caller-Network-Addr: 195.201.136.94
Caller-ANI: 0506834683
Caller-Destination-Number: 0506834683
Caller-Unique-ID: e6880004-03e8-42ec-9dd7-cf40f0804e6b
Caller-Source: mod_sofia
Caller-Transfer-Source: 1709728814%3Ae872fba7-ec12-461a-bb42-44eb801f7e0d%3Abl_xfer%3A0506834683/default/XML
Caller-Context: default
Caller-RDNIS: 1573
Caller-Channel-Name: sofia/default/0506834683%40212.150.158.100
Caller-Profile-Index: 4
Caller-Profile-Created-Time: 1709728814066864
Caller-Channel-Created-Time: 1709728788566873
Caller-Channel-Answered-Time: 1709728790386884
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: e6880004-03e8-42ec-9dd7-cf40f0804e6b
variable_session_id: 3432180
variable_sip_from_user: 0506834683
variable_sip_from_uri: 0506834683%40212.150.158.100
variable_sip_from_host: 212.150.158.100
variable_text_media_flow: disabled
variable_channel_name: sofia/default/0506834683%40212.150.158.100
variable_sip_local_network_addr: 95.217.164.101
variable_sip_network_ip: 195.201.136.94
variable_sip_network_port: 5060
variable_sip_invite_stamp: 1709728788566873
variable_sip_received_ip: 195.201.136.94
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: 0506834683
variable_sofia_profile_name: default
variable_sofia_profile_url: sip%3Amod_sofia%4095.217.164.101%3A5060
variable_recovery_profile_name: default
variable_sip_invite_route_uri: %3Csip%3A195.201.136.94%3Blr%3Bdid%3D0be.2f856e3%3E
variable_sip_invite_record_route: %3Csip%3A195.201.136.94%3Blr%3Bdid%3D0be.2f856e3%3E
variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20OPTIONS,%20BYE,%20REFER,%20SUBSCRIBE,%20NOTIFY,%20INFO
variable_sip_req_user: 039707000
variable_sip_req_port: 5060
variable_sip_req_uri: 039707000%40newsip.israelnumber.com%3A5060
variable_sip_req_host: newsip.israelnumber.com
variable_sip_to_user: 039707000
variable_sip_to_port: 5060
variable_sip_to_uri: 039707000%40newsip.israelnumber.com%3A5060
variable_sip_contact_user: 0506834683
variable_sip_contact_uri: 0506834683%40212.150.158.100
variable_sip_contact_host: 212.150.158.100
variable_sip_user_agent: FPBX-2.8.1(1.4.42)
variable_sip_via_host: 195.201.136.94
variable_sip_via_port: 5060
variable_presence_id: 0506834683%40212.150.158.100
variable_sip_date_epoch_time: 1709641354
variable_sip_h_X-AUTH-IP: 212.150.158.100
variable_sip_h_P-effective_caller_id_name: 0506834683
variable_sip_h_P-effective_caller_id_number: 0506834683
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_video_media_flow: sendrecv
variable_video_possible: true
variable_effective_destination_number: 039707000
variable_execute_on_answer: sched_hangup%20%2B10800%20normal_clearing
variable_nibble_account: 709
variable_nibble_rate: 0.00000
variable_nibble_increment: 60
variable_nibble_minimum: 0.00000
variable_callstart: 2024-03-06%2012%3A39%3A51
variable_hangup_after_bridge: true
variable_account_id: 709
variable_parent_id: 0
variable_entity_id: 0
variable_call_processed: internal
variable_call_direction: inbound
variable_accountname: default
variable_filename1: astpp.pbx.lua
variable_origination_rates_did: ID%3A15049%7CCODE%3A%5E97239707000.*%7CDESTINATION%3ANational%20Landline%7CCONNECTIONCOST%3A0.00000%7CINCLUDEDSECONDS%3A0%7CCT%3A10%7CCOST%3A0.00000%7CINC%3A60%7CINITIALBLOCK%3A60%7CRATEGROUP%3A0%7CMARKUP%3A0%7CCI%3A93%7CACCID%3A1573
variable_origination_rates: 0
variable_original_caller_id_name: 0506834683
variable_original_caller_id_number: 0506834683
variable_effective_caller_id_name: 0506834683
variable_receiver_accid: 1573
variable_sip_to_host: ITCIVR1394.newsip.israelnumber.com
variable_presence_data: x%7C%7C%7Cprodtwo(170019686118)%7C%7C%7C%5E97239707000.*%20//%20National%20Landline%20//%200.00000%7C%7C%7C%7C%7C%7CDID%7C%7C%7C212.150.158.100
variable_call_type: 10
variable_ringback: %25(1000,3000,400)
variable_effective_caller_id_number: 444%230506834683
variable_module_name: pbx
variable_sip_h_P-call_type: custom_forward
variable_sip_h_P-Accountcode: 1573
variable_original_did_number: 97239707000
variable_rtp_use_timer_name: soft
variable_rtp_use_pt: 8
variable_rtp_use_ssrc: 1588037421
variable_endpoint_disposition: ANSWER
variable_ivr_menu_status: success
variable_RECORD_SOFTWARE: Lavf59.10.100
variable_switch_r_sdp: v%3D0%0D%0Ao%3Droot%207883%207884%20IN%20IP4%2082.166.66.145%0D%0As%3Dsession%0D%0Ac%3DIN%20IP4%2082.166.66.145%0D%0At%3D0%200%0D%0Am%3Daudio%2016566%20RTP/AVP%208%2098%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A98%20telephone-event/8000%0D%0Aa%3Dfmtp%3A98%200-16%0D%0Aa%3DsilenceSupp%3Aoff%20-%20-%20-%20-%0D%0Aa%3Dptime%3A20%0D%0A
variable_ep_codec_string: CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b
variable_rtp_use_codec_string: PCMA,PCMU
variable_remote_video_media_flow: inactive
variable_remote_text_media_flow: inactive
variable_remote_audio_media_flow: sendrecv
variable_audio_media_flow: sendrecv
variable_rtp_audio_recv_pt: 8
variable_dtmf_type: rfc2833
variable_rtp_2833_send_payload: 98
variable_rtp_2833_recv_payload: 98
variable_rtp_local_sdp_str: v%3D0%0D%0Ao%3DFreeSWITCH%201709705690%201709705692%20IN%20IP4%2095.217.164.101%0D%0As%3DFreeSWITCH%0D%0Ac%3DIN%20IP4%2095.217.164.101%0D%0At%3D0%200%0D%0Am%3Daudio%2023100%20RTP/AVP%208%2098%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A98%20telephone-event/8000%0D%0Aa%3Dfmtp%3A98%200-15%0D%0Aa%3Dptime%3A20%0D%0Aa%3Dsendrecv%0D%0A
variable_remote_media_ip: 82.166.66.145
variable_remote_media_port: 16566
variable_local_media_ip: 95.217.164.101
variable_local_media_port: 23100
variable_advertised_media_ip: 95.217.164.101
variable_sip_to_tag: XUQXej5ypBDXe
variable_sip_from_tag: as15defd92
variable_sip_cseq: 103
variable_sip_call_id: 229d14e634fdcdfe4e28030342d0cb3c%40212.150.158.100
variable_sip_full_via: SIP/2.0/UDP%20195.201.136.94%3A5060%3Bbranch%3Dz9hG4bK28da.a38fedb5.2,SIP/2.0/UDP%20212.150.158.100%3A5060%3Breceived%3D212.150.158.100%3Bbranch%3Dz9hG4bK6da7bc25%3Brport%3D5060
variable_sip_from_display: 0506834683
variable_sip_full_from: %220506834683%22%20%3Csip%3A0506834683%40212.150.158.100%3E%3Btag%3Das15defd92
variable_sip_full_to: %3Csip%3A039707000%40newsip.israelnumber.com%3A5060%3E%3Btag%3DXUQXej5ypBDXe
variable_playback_last_offset_pos: 132640
variable_playback_seconds: 18
variable_playback_ms: 18432
variable_playback_samples: 147456
variable_transfer_history: ARRAY%3A%3A1709728810%3A0ce7c037-5314-4182-8279-2e55a6d31bf3%3Abl_xfer%3A97239707000%23PBX%2311%2323/ITCIVR1394.newsip.israelnumber.com/XML%7C%3A1709728812%3A26a0cdba-5bb5-4425-a4a4-82655b4ff287%3Abl_xfer%3A1573/default/XML%7C%3A1709728814%3Ae872fba7-ec12-461a-bb42-44eb801f7e0d%3Abl_xfer%3A0506834683/default/XML
variable_nibble_bill: 0.000000
variable_nibble_total_billed: 0.000000
variable_timezone: Antarctica/Syowa
variable_office_status: open
variable_extensions: 0506834683%23
variable_DP_MATCH: 97239707000%23PBX%2311%2323
variable_domain_name: ITCIVR1394.newsip.israelnumber.com
variable_set_extensions: 0506834683%23
variable_no_answer_call_type: 12
variable_no_answer_number: 0506834683%23
variable_did_user_domain: ITCIVR1394.newsip.israelnumber.com
variable_export_vars: domain_name,sip_to_host,presence_data,call_type,did_user_domain
variable_continue_on_fail: TRUE
variable_ignore_early_media: true
variable_no_answer_flag: false
variable_did_calltype: 12
variable_did_extensions: 0506834683%23
variable_accountcode: 675242683994
variable_current_application_data: 0506834683%20XML%20default
variable_current_application: transfer
variable_max_forwards: 27
variable_transfer_source: 1709728814%3Ae872fba7-ec12-461a-bb42-44eb801f7e0d%3Abl_xfer%3A0506834683/default/XML
variable_nibble_current_balance: 36.044580
variable_call_uuid: e6880004-03e8-42ec-9dd7-cf40f0804e6b
Hunt-Direction: inbound
Hunt-Logical-Direction: inbound
Hunt-Username: 0506834683
Hunt-Dialplan: XML
Hunt-Caller-ID-Name: 0506834683
Hunt-Caller-ID-Number: 0506834683
Hunt-Orig-Caller-ID-Name: 0506834683
Hunt-Orig-Caller-ID-Number: 0506834683
Hunt-Network-Addr: 195.201.136.94
Hunt-ANI: 0506834683
Hunt-Destination-Number: 0506834683
Hunt-Unique-ID: e6880004-03e8-42ec-9dd7-cf40f0804e6b
Hunt-Source: mod_sofia
Hunt-Transfer-Source: 1709728814%3Ae872fba7-ec12-461a-bb42-44eb801f7e0d%3Abl_xfer%3A0506834683/default/XML
Hunt-Context: default
Hunt-RDNIS: 1573
Hunt-Channel-Name: sofia/default/0506834683%40212.150.158.100
Hunt-Profile-Index: 4
Hunt-Profile-Created-Time: 1709728814066864
Hunt-Channel-Created-Time: 1709728788566873
Hunt-Channel-Answered-Time: 1709728790386884
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
2024-03-06 12:40:14.366875 98.40% [INFO] switch_cpp.cpp:1465 [ASTPP] [xml_REQUEST] section: dialplan
2024-03-06 12:40:14.366875 98.40% [INFO] switch_cpp.cpp:1465 [ASTPP] [xml_REQUEST] key_name:
2024-03-06 12:40:14.366875 98.40% [INFO] switch_cpp.cpp:1465 [ASTPP] [xml_REQUEST] tag_name:
2024-03-06 12:40:14.366875 98.40% [INFO] switch_cpp.cpp:1465 [ASTPP] [xml_REQUEST] key_value:
2024-03-06 12:40:14.366875 98.40% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] blind_flag: 0
2024-03-06 12:40:14.366875 98.40% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 0506834683
2024-03-06 12:40:14.366875 98.40% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '0506834683' limit 1
2024-03-06 12:40:14.426861 98.40% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '0506834683' AND status=0 limit 1
2024-03-06 12:40:14.506874 98.40% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE domain = '212.150.158.100' limit 1
2024-03-06 12:40:14.586868 98.40% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] in custom Caller ID
2024-03-06 12:40:14.586868 98.40% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number : 0506834683 / 444#0506834683
2024-03-06 12:40:14.586868 98.40% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE accountid = 1573 limit 1
2024-03-06 12:40:14.646869 98.40% [WARNING] switch_cpp.cpp:1465 [ASTPP] [user_domain] user_domainITCIVR1394.newsip.israelnumber.com
2024-03-06 12:40:14.646869 98.40% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
2024-03-06 12:40:14.646869 98.40% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 13 AND status=0 limit 1
2024-03-06 12:40:14.726867 98.40% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0506834683
2024-03-06 12:40:14.726867 98.40% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0506834683
2024-03-06 12:40:14.726867 98.40% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0506834683
2024-03-06 12:40:14.726867 98.40% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0506834683
2024-03-06 12:40:14.726867 98.40% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0506834683
2024-03-06 12:40:14.726867 98.40% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0506834683
2024-03-06 12:40:14.726867 98.40% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0506834683
2024-03-06 12:40:14.726867 98.40% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.buy_minutes,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,A.call_type_callerid_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="0506834683" LIMIT 1
2024-03-06 12:40:14.786874 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as accounts ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="0506834683" AND domains.domain="ITCIVR1394.newsip.israelnumber.com" limit 1
2024-03-06 12:40:14.866870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
2024-03-06 12:40:14.866870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 1573]
2024-03-06 12:40:14.866870 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "1573" AND deleted = 0 limit 1
2024-03-06 12:40:14.926871 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^0506834683.*' OR blocked_patterns = '^050683468.*' OR blocked_patterns = '^05068346.*' OR blocked_patterns = '^0506834.*' OR blocked_patterns = '^050683.*' OR blocked_patterns = '^05068.*' OR blocked_patterns = '^0506.*' OR blocked_patterns = '^050.*' OR blocked_patterns = '^05.*' OR blocked_patterns = '^0.*' OR blocked_patterns ='--') AND accountid = 1573 limit 1
2024-03-06 12:40:15.006872 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="0506834683" AND A.accountid = '1573' limit 1
2024-03-06 12:40:15.086874 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 0506834683
2024-03-06 12:40:15.086874 98.33% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
2024-03-06 12:40:15.086874 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 13 AND status=0 limit 1
2024-03-06 12:40:15.146869 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0506834683
2024-03-06 12:40:15.146869 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0506834683
2024-03-06 12:40:15.146869 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0506834683
2024-03-06 12:40:15.146869 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0506834683
2024-03-06 12:40:15.146869 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0506834683
2024-03-06 12:40:15.146869 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0506834683
2024-03-06 12:40:15.146869 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0506834683
2024-03-06 12:40:15.146869 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.buy_minutes,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,A.call_type_callerid_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="0506834683" LIMIT 1
2024-03-06 12:40:15.226872 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as accounts ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="0506834683" AND domains.domain="ITCIVR1394.newsip.israelnumber.com" limit 1
2024-03-06 12:40:15.286873 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call direction : outbound
2024-03-06 12:40:15.286873 98.33% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
2024-03-06 12:40:15.286873 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 13 AND status=0 limit 1
2024-03-06 12:40:15.366872 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0506834683
2024-03-06 12:40:15.366872 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0506834683
2024-03-06 12:40:15.366872 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0506834683
2024-03-06 12:40:15.366872 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0506834683
2024-03-06 12:40:15.366872 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0506834683
2024-03-06 12:40:15.366872 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0506834683
2024-03-06 12:40:15.366872 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0506834683
2024-03-06 12:40:15.366872 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="0506834683" AND (accountid = 0 OR status = 1) LIMIT 1
2024-03-06 12:40:15.426871 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE (patterns = '^0506834683.*' OR patterns = '^050683468.*' OR patterns = '^05068346.*' OR patterns = '^0506834.*' OR patterns = '^050683.*' OR patterns = '^05068.*' OR patterns = '^0506.*' OR patterns = '^050.*' OR patterns = '^05.*' OR patterns = '^0.*' OR patterns ='--') AND accountid = 1573 ORDER BY LENGTH(PKGPTR.patterns) DESC
2024-03-06 12:40:15.506877 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '0506834683' OR number = '050683468' OR number = '05068346' OR number = '0506834' OR number = '050683' OR number = '05068' OR number = '0506' OR number = '050' OR number = '05' OR number = '0' OR number ='--') OR (number = '0506834683*' OR number = '050683468*' OR number = '05068346*' OR number = '0506834*' OR number = '050683*' OR number = '05068*' OR number = '0506*' OR number = '050*' OR number = '05*' OR number = '0*' OR number ='--') OR (number = '444#0506834683' OR number = '444#050683468' OR number = '444#05068346' OR number = '444#0506834' OR number = '444#050683' OR number = '444#05068' OR number = '444#0506' OR number = '444#050' OR number = '444#05' OR number = '444#0' OR number = '444#' OR number = '444' OR number = '44' OR number = '4' OR number ='--') OR (number = '444#0506834683*' OR number = '444#050683468*' OR number = '444#05068346*' OR number = '444#0506834*' OR number = '444#050683*' OR number = '444#05068*' OR number = '444#0506*' OR number = '444#050*' OR number = '444#05*' OR number = '444#0*' OR number = '444#*' OR number = '444*' OR number = '44*' OR number = '4*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
2024-03-06 12:40:15.586875 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1
2024-03-06 12:40:15.586875 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '0506834683' OR routing_prefix = '050683468' OR routing_prefix = '05068346' OR routing_prefix = '0506834' OR routing_prefix = '050683' OR routing_prefix = '05068' OR routing_prefix = '0506' OR routing_prefix = '050' OR routing_prefix = '05' OR routing_prefix = '0' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
2024-03-06 12:40:15.646874 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
2024-03-06 12:40:15.646874 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 1573
2024-03-06 12:40:15.646874 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 675242683994
2024-03-06 12:40:15.646874 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 15.67407
2024-03-06 12:40:15.646874 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
2024-03-06 12:40:15.646874 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 75
2024-03-06 12:40:15.646874 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
2024-03-06 12:40:15.646874 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
2024-03-06 12:40:15.646874 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 75 AND status = 0
2024-03-06 12:40:15.726877 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :outbound
2024-03-06 12:40:15.726877 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 75 AND status = 0
2024-03-06 12:40:15.786876 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^0506834683.*' OR pattern = '^050683468.*' OR pattern = '^05068346.*' OR pattern = '^0506834.*' OR pattern = '^050683.*' OR pattern = '^05068.*' OR pattern = '^0506.*' OR pattern = '^050.*' OR pattern = '^05.*' OR pattern = '^0.*' OR pattern ='--') AND status = 0 AND (pricelist_id = 75 OR accountid=1573) ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC LIMIT 1
2024-03-06 12:40:15.866870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] call_direction:::::: outbound
2024-03-06 12:40:15.866870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
2024-03-06 12:40:15.866870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 34743845
2024-03-06 12:40:15.866870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
2024-03-06 12:40:15.866870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
2024-03-06 12:40:15.866870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.04000
2024-03-06 12:40:15.866870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] comment : Israel Mobile
2024-03-06 12:40:15.866870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 93
2024-03-06 12:40:15.866870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 1573
2024-03-06 12:40:15.866870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] Trunk ID: 15
2024-03-06 12:40:15.866870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] Routing type:
2024-03-06 12:40:15.866870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
2024-03-06 12:40:15.866870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your15.67407 balance Accountid 1573 !!!
2024-03-06 12:40:15.866870 98.33% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Limiting call to config max length 180 mins!
2024-03-06 12:40:15.866870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] NIBBLE ID 1573
2024-03-06 12:40:15.866870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] NIBBLE RATE 0.04000
2024-03-06 12:40:15.866870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] NIBBLE PROVIDER 0
2024-03-06 12:40:15.866870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] NIBBLE CONNECT COST 0.00000
2024-03-06 12:40:15.866870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] NIBBLE INITIAL INC 30
2024-03-06 12:40:15.866870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] NIBBLE INC 10
2024-03-06 12:40:15.866870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max length duration : 180 minutes
2024-03-06 12:40:15.866870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] [DIALPLAN] User Rate ID : 34743845
2024-03-06 12:40:15.866870 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES]123131 Query rate_carrier_id :15
2024-03-06 12:40:15.866870 98.33% [DEBUG] switch_cpp.cpp:1465 [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 = '^0506834683.*' OR pattern = '^050683468.*' OR pattern = '^05068346.*' OR pattern = '^0506834.*' OR pattern = '^050683.*' OR pattern = '^05068.*' OR pattern = '^0506.*' OR pattern = '^050.*' OR pattern = '^05.*' OR pattern = '^0.*' OR pattern ='--') AND TR.status = 0 AND TR.trunk_id IN (15) ORDER by FIELD(TR.trunk_id,15),LENGTH (pattern) DESC,TR.cost ASC,TR.precedence ASC
2024-03-06 12:40:16.006870 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [HYBRID_ROUTING]LCR with Cost in 2nd stage
2024-03-06 12:40:16.006870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Termination Rates Information ===================
2024-03-06 12:40:16.006870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 9453047
2024-03-06 12:40:16.006870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] Code : ^05.*
2024-03-06 12:40:16.006870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] Destination : Israel Mobile
2024-03-06 12:40:16.006870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
2024-03-06 12:40:16.006870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] Free Seconds : 0
2024-03-06 12:40:16.006870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] Prefix : ^05.*
2024-03-06 12:40:16.006870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] Strip : 05
2024-03-06 12:40:16.006870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] Prepend : 9725
2024-03-06 12:40:16.006870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] Carrier id : 15
2024-03-06 12:40:16.006870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] carrier_name : hallo
2024-03-06 12:40:16.006870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] dialplan_variable :
2024-03-06 12:40:16.006870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] Failover gateway : Cellcom2
2024-03-06 12:40:16.006870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] Vendor id : 901
2024-03-06 12:40:16.006870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] Max channels : 0
2024-03-06 12:40:16.006870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] Trunk Name : hallotrunk
2024-03-06 12:40:16.006870 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================END OF TERMINATION RATES=======================
2024-03-06 12:40:16.006870 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 1573 AND status= '0' LIMIT 1
2024-03-06 12:40:16.086875 98.33% [INFO] switch_cpp.cpp:1465 [ASTPP] maxlength::::::::: 180
2024-03-06 12:40:16.086875 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 75 AND status = 0
2024-03-06 12:40:16.146870 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate FROM localization WHERE id=(SELECT localization_id from accounts where id = 901) AND status=0 limit 1
2024-03-06 12:40:16.226871 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :05/9725.....0506834683
2024-03-06 12:40:16.226871 98.33% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 0506834683
2024-03-06 12:40:16.226871 98.33% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 972506834683
2024-03-06 12:40:16.226871 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Generated XML:
Dialplan: sofia/default/0506834683@212.150.158.100 parsing [default->0506834683] continue=false
Dialplan: sofia/default/0506834683@212.150.158.100 Regex (PASS) [0506834683] destination_number(0506834683) =~ /0506834683/ break=on-false
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(effective_destination_number=0506834683)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(execute_on_answer=sched_hangup +10800 normal_clearing)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(nibble_account=1573)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(nibble_rate=0.04000)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(nibble_increment=10)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(nibble_minimum=0.00000)
Dialplan: sofia/default/0506834683@212.150.158.100 Action nibblebill(heartbeat 10)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(callstart=2024-03-06 12:40:17)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(hangup_after_bridge=true)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(continue_on_fail=TRUE)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(account_id=1573)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(parent_id=0)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(entity_id=0)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(call_processed=internal)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(call_direction=outbound)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(accountname=default)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(filename1=astpp.pbx.lua)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(origination_rates=ID:34743845|CODE:^05.*|DESTINATION:Israel Mobile|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.04000|INC:10|INITIALBLOCK:30|RATEGROUP:75|MARKUP:0|CI:93|ACCID:1573)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(original_caller_id_name=0506834683)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(original_caller_id_number=444#0506834683)
Dialplan: sofia/default/0506834683@212.150.158.100 Action limit(db 1573 user_1573 2 !SWITCH_CONGESTION)
Dialplan: sofia/default/0506834683@212.150.158.100 Action limit(hash CPS_1573 CPS_user_1573 2/1 !SWITCH_CONGESTION)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(effective_caller_id_name=0506834683)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(effective_caller_id_number=444#0506834683)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(calltype=STANDARD)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(termination_rates=ID:9453047|CODE:^05.*|DESTINATION:Israel Mobile|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.01922|INC:1|INITIALBLOCK:1|TRUNK:15|PROVIDER:901)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(trunk_id=15)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(provider_id=901)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(rate_flag=0)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(force_trunk_flag=34743845)
Dialplan: sofia/default/0506834683@212.150.158.100 Action export(presence_data=trunk_id=15)
Dialplan: sofia/default/0506834683@212.150.158.100 Action set(intcall=0)
Dialplan: sofia/default/0506834683@212.150.158.100 Action export(presence_data=x|||ITCIVR(675242683994)|||^05.* // Israel Mobile // 0.04000 // trunk_id=15|||hallotrunk // ^05.* // Israel Mobile // 0.01922 // trunk_id=15|||STD|||212.150.158.100)
Dialplan: sofia/default/0506834683@212.150.158.100 Action bridge({sip_cid_type=pid}[leg_timeout=0,absolute_codec_string=^^:PCMA:PCMU:G729]sofia/gateway/hallo/972506834683;fs_path=sip:195.201.136.94:5060)
Dialplan: sofia/default/0506834683@212.150.158.100 Action bridge({sip_cid_type=pid}[leg_timeout=0,absolute_codec_string=^^:PCMA:PCMU:G729]sofia/gateway/Cellcom2/972506834683;fs_path=sip:195.201.136.94:5060)
2024-03-06 12:40:16.226871 98.33% [DEBUG] switch_core_state_machine.c:281 (sofia/default/0506834683@212.150.158.100) State Change CS_ROUTING -> CS_EXECUTE
2024-03-06 12:40:16.226871 98.33% [DEBUG] switch_core_state_machine.c:640 (sofia/default/0506834683@212.150.158.100) State ROUTING going to sleep
2024-03-06 12:40:16.226871 98.33% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0506834683@212.150.158.100) Running State Change CS_EXECUTE (Cur 34 Tot 3432180)
2024-03-06 12:40:16.226871 98.33% [DEBUG] switch_channel.c:2382 (sofia/default/0506834683@212.150.158.100) Callstate Change RINGING -> ACTIVE
2024-03-06 12:40:16.226871 98.33% [DEBUG] switch_core_state_machine.c:647 (sofia/default/0506834683@212.150.158.100) State EXECUTE
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_sofia.c:213 sofia/default/0506834683@212.150.158.100 SOFIA EXECUTE
2024-03-06 12:40:16.226871 98.33% [INFO] switch_core_session.c:1707 sofia/default/0506834683@212.150.158.100 setting session heartbeat to 60 second(s).
2024-03-06 12:40:16.226871 98.33% [DEBUG] switch_core_state_machine.c:323 sofia/default/0506834683@212.150.158.100 Standard EXECUTE
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(effective_destination_number=0506834683)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [effective_destination_number]=[0506834683]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(execute_on_answer=sched_hangup +10800 normal_clearing)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [execute_on_answer]=[sched_hangup +10800 normal_clearing]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(nibble_account=1573)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [nibble_account]=[1573]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(nibble_rate=0.04000)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [nibble_rate]=[0.04000]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(nibble_increment=10)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [nibble_increment]=[10]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(nibble_minimum=0.00000)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [nibble_minimum]=[0.00000]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 nibblebill(heartbeat 10)
2024-03-06 12:40:16.226871 98.33% [INFO] switch_core_session.c:1707 sofia/default/0506834683@212.150.158.100 setting session heartbeat to 10 second(s).
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(callstart=2024-03-06 12:40:17)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [callstart]=[2024-03-06 12:40:17]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(hangup_after_bridge=true)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [hangup_after_bridge]=[true]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(continue_on_fail=TRUE)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [continue_on_fail]=[TRUE]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(account_id=1573)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [account_id]=[1573]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(parent_id=0)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [parent_id]=[0]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(entity_id=0)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [entity_id]=[0]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(call_processed=internal)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [call_processed]=[internal]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(call_direction=outbound)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [call_direction]=[outbound]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(accountname=default)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [accountname]=[default]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(filename1=astpp.pbx.lua)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [filename1]=[astpp.pbx.lua]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(origination_rates=ID:34743845|CODE:^05.*|DESTINATION:Israel Mobile|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.04000|INC:10|INITIALBLOCK:30|RATEGROUP:75|MARKUP:0|CI:93|ACCID:1573)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [origination_rates]=[ID:34743845|CODE:^05.*|DESTINATION:Israel Mobile|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.04000|INC:10|INITIALBLOCK:30|RATEGROUP:75|MARKUP:0|CI:93|ACCID:1573]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(original_caller_id_name=0506834683)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [original_caller_id_name]=[0506834683]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(original_caller_id_number=444#0506834683)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [original_caller_id_number]=[444#0506834683]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 limit(db 1573 user_1573 2 !SWITCH_CONGESTION)
2024-03-06 12:40:16.226871 98.33% [DEBUG] switch_limit.c:124 incr called: 1573_user_1573 max:2, interval:0
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_db.c:194 Usage for 1573_user_1573 is now 1/2
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 limit(hash CPS_1573 CPS_user_1573 2/1 !SWITCH_CONGESTION)
2024-03-06 12:40:16.226871 98.33% [DEBUG] switch_limit.c:124 incr called: CPS_1573_CPS_user_1573 max:2, interval:1
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_hash.c:195 Usage for CPS_1573_CPS_user_1573 is now 1/2 for the last 1 seconds
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(effective_caller_id_name=0506834683)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [effective_caller_id_name]=[0506834683]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(effective_caller_id_number=444#0506834683)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [effective_caller_id_number]=[444#0506834683]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(calltype=STANDARD)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [calltype]=[STANDARD]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(termination_rates=ID:9453047|CODE:^05.*|DESTINATION:Israel Mobile|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.01922|INC:1|INITIALBLOCK:1|TRUNK:15|PROVIDER:901)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [termination_rates]=[ID:9453047|CODE:^05.*|DESTINATION:Israel Mobile|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.01922|INC:1|INITIALBLOCK:1|TRUNK:15|PROVIDER:901]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(trunk_id=15)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [trunk_id]=[15]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(provider_id=901)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [provider_id]=[901]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(rate_flag=0)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [rate_flag]=[0]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(force_trunk_flag=34743845)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [force_trunk_flag]=[34743845]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 export(presence_data=trunk_id=15)
2024-03-06 12:40:16.226871 98.33% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[trunk_id=15]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 set(intcall=0)
2024-03-06 12:40:16.226871 98.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/0506834683@212.150.158.100 [intcall]=[0]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 export(presence_data=x|||ITCIVR(675242683994)|||^05.* // Israel Mobile // 0.04000 // trunk_id=15|||hallotrunk // ^05.* // Israel Mobile // 0.01922 // trunk_id=15|||STD|||212.150.158.100)
2024-03-06 12:40:16.226871 98.33% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[x|||ITCIVR(675242683994)|||^05.* // Israel Mobile // 0.04000 // trunk_id=15|||hallotrunk // ^05.* // Israel Mobile // 0.01922 // trunk_id=15|||STD|||212.150.158.100]
EXECUTE [depth=0] sofia/default/0506834683@212.150.158.100 bridge({sip_cid_type=pid}[leg_timeout=0,absolute_codec_string=^^:PCMA:PCMU:G729]sofia/gateway/hallo/972506834683;fs_path=sip:195.201.136.94:5060)
2024-03-06 12:40:16.226871 98.33% [DEBUG] switch_channel.c:1954 (sofia/default/0506834683@212.150.158.100) Callstate Change ACTIVE -> RING_WAIT
2024-03-06 12:40:16.246876 98.33% [DEBUG] switch_channel.c:1269 sofia/default/0506834683@212.150.158.100 EXPORTING[export_vars] [domain_name]=[ITCIVR1394.newsip.israelnumber.com] to event
2024-03-06 12:40:16.246876 98.33% [DEBUG] switch_channel.c:1269 sofia/default/0506834683@212.150.158.100 EXPORTING[export_vars] [sip_to_host]=[ITCIVR1394.newsip.israelnumber.com] to event
2024-03-06 12:40:16.246876 98.33% [DEBUG] switch_channel.c:1269 sofia/default/0506834683@212.150.158.100 EXPORTING[export_vars] [presence_data]=[x|||ITCIVR(675242683994)|||^05.* // Israel Mobile // 0.04000 // trunk_id=15|||hallotrunk // ^05.* // Israel Mobile // 0.01922 // trunk_id=15|||STD|||212.150.158.100] to event
2024-03-06 12:40:16.246876 98.33% [DEBUG] switch_channel.c:1269 sofia/default/0506834683@212.150.158.100 EXPORTING[export_vars] [call_type]=[10] to event
2024-03-06 12:40:16.246876 98.33% [DEBUG] switch_channel.c:1269 sofia/default/0506834683@212.150.158.100 EXPORTING[export_vars] [did_user_domain]=[ITCIVR1394.newsip.israelnumber.com] to event
2024-03-06 12:40:16.246876 98.33% [DEBUG] switch_channel.c:1269 sofia/default/0506834683@212.150.158.100 EXPORTING[export_vars] [presence_data]=[x|||ITCIVR(675242683994)|||^05.* // Israel Mobile // 0.04000 // trunk_id=15|||hallotrunk // ^05.* // Israel Mobile // 0.01922 // trunk_id=15|||STD|||212.150.158.100] to event
2024-03-06 12:40:16.246876 98.33% [DEBUG] switch_channel.c:1269 sofia/default/0506834683@212.150.158.100 EXPORTING[export_vars] [presence_data]=[x|||ITCIVR(675242683994)|||^05.* // Israel Mobile // 0.04000 // trunk_id=15|||hallotrunk // ^05.* // Israel Mobile // 0.01922 // trunk_id=15|||STD|||212.150.158.100] to event
2024-03-06 12:40:16.246876 98.33% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
2024-03-06 12:40:16.246876 98.33% [DEBUG] switch_ivr_originate.c:2843 Parsing session specific variables
2024-03-06 12:40:16.246876 98.33% [NOTICE] switch_channel.c:1123 New Channel sofia/default/972506834683 [0cf6ca55-bb92-47ef-ae0b-dca5399e19c8]
2024-03-06 12:40:16.246876 98.33% [DEBUG] mod_sofia.c:5121 (sofia/default/972506834683) State Change CS_NEW -> CS_INIT
2024-03-06 12:40:16.246876 98.33% [DEBUG] switch_core_state_machine.c:581 (sofia/default/972506834683) Running State Change CS_INIT (Cur 35 Tot 3432181)
2024-03-06 12:40:16.246876 98.33% [DEBUG] switch_core_state_machine.c:624 (sofia/default/972506834683) State INIT
2024-03-06 12:40:16.246876 98.33% [DEBUG] mod_sofia.c:97 sofia/default/972506834683 SOFIA INIT
2024-03-06 12:40:16.246876 98.33% [DEBUG] sofia_glue.c:1620 sip:195.201.136.94:5060 Setting proxy route to sofia/default/972506834683
2024-03-06 12:40:16.246876 98.33% [INFO] sofia_glue.c:1651 sofia/default/972506834683 sending invite call-id: (null)
2024-03-06 12:40:16.246876 98.33% [DEBUG] sofia_glue.c:1654 sofia/default/972506834683 sending invite version: 1.10.7 -release-19-883d2cb662 64bit
Local SDP:
v=0
o=FreeSWITCH 1709697306 1709697307 IN IP4 95.217.164.101
s=FreeSWITCH
c=IN IP4 95.217.164.101
t=0 0
m=audio 31510 RTP/AVP 8 0 18 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
a=sendrecv
2024-03-06 12:40:16.246876 98.33% [DEBUG] switch_core_state_machine.c:40 sofia/default/972506834683 Standard INIT
2024-03-06 12:40:16.246876 98.33% [DEBUG] switch_core_state_machine.c:48 (sofia/default/972506834683) State Change CS_INIT -> CS_ROUTING
2024-03-06 12:40:16.246876 98.33% [DEBUG] switch_core_state_machine.c:624 (sofia/default/972506834683) State INIT going to sleep
2024-03-06 12:40:16.246876 98.33% [DEBUG] switch_core_state_machine.c:581 (sofia/default/972506834683) Running State Change CS_ROUTING (Cur 35 Tot 3432181)
2024-03-06 12:40:16.246876 98.33% [DEBUG] switch_core_state_machine.c:640 (sofia/default/972506834683) State ROUTING
2024-03-06 12:40:16.246876 98.33% [DEBUG] mod_sofia.c:158 sofia/default/972506834683 SOFIA ROUTING
2024-03-06 12:40:16.246876 98.33% [DEBUG] switch_ivr_originate.c:67 (sofia/default/972506834683) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2024-03-06 12:40:16.246876 98.33% [DEBUG] switch_core_state_machine.c:640 (sofia/default/972506834683) State ROUTING going to sleep
2024-03-06 12:40:16.246876 98.33% [DEBUG] switch_core_state_machine.c:581 (sofia/default/972506834683) Running State Change CS_CONSUME_MEDIA (Cur 35 Tot 3432181)
2024-03-06 12:40:16.246876 98.33% [DEBUG] switch_core_state_machine.c:659 (sofia/default/972506834683) State CONSUME_MEDIA
2024-03-06 12:40:16.246876 98.33% [DEBUG] switch_core_state_machine.c:659 (sofia/default/972506834683) State CONSUME_MEDIA going to sleep
2024-03-06 12:40:16.246876 98.33% [DEBUG] sofia.c:7499 Channel sofia/default/972506834683 entering state [calling][0]
2024-03-06 12:40:17.126867 98.27% [DEBUG] sofia.c:7499 Channel sofia/default/972506834683 entering state [proceeding][183]
2024-03-06 12:40:17.126867 98.27% [DEBUG] sofia.c:7509 Remote SDP:
v=0
o=- 1709728818 1709728818 IN IP4 185.23.172.32
s=-
c=IN IP4 185.23.172.32
t=0 0
m=audio 43386 RTP/AVP 8 0 18 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
a=silenceSupp:off - - - -
2024-03-06 12:40:17.126867 98.27% [NOTICE] sofia.c:7512 Pre-Answer sofia/default/972506834683!
2024-03-06 12:40:17.126867 98.27% [DEBUG] switch_channel.c:3622 (sofia/default/972506834683) Callstate Change DOWN -> EARLY
2024-03-06 12:40:17.126867 98.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2024-03-06 12:40:17.126867 98.27% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2024-03-06 12:40:17.126867 98.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2024-03-06 12:40:17.126867 98.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
2024-03-06 12:40:17.126867 98.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2024-03-06 12:40:17.126867 98.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2024-03-06 12:40:17.126867 98.27% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2024-03-06 12:40:17.126867 98.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
2024-03-06 12:40:17.126867 98.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
2024-03-06 12:40:17.126867 98.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
2024-03-06 12:40:17.126867 98.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
2024-03-06 12:40:17.126867 98.27% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
2024-03-06 12:40:17.126867 98.27% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
2024-03-06 12:40:17.126867 98.27% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/972506834683 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
2024-03-06 12:40:17.126867 98.27% [DEBUG] switch_core_codec.c:111 sofia/default/972506834683 Original read codec set to PCMA:8
2024-03-06 12:40:17.126867 98.27% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
2024-03-06 12:40:17.126867 98.27% [DEBUG] switch_core_media.c:5973 sofia/default/972506834683 Set 2833 dtmf send payload to 101 recv payload to 101
2024-03-06 12:40:17.126867 98.27% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/972506834683] 95.217.164.101 port 31510 -> 185.23.172.32 port 43386 codec: 8 ms: 20
2024-03-06 12:40:17.126867 98.27% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms
2024-03-06 12:40:17.126867 98.27% [DEBUG] switch_core_media.c:9089 sofia/default/972506834683 Set 2833 dtmf send payload to 101
2024-03-06 12:40:17.126867 98.27% [DEBUG] switch_core_media.c:9096 sofia/default/972506834683 Set 2833 dtmf receive payload to 101
2024-03-06 12:40:17.126867 98.27% [DEBUG] switch_core_media.c:9119 sofia/default/972506834683 Set rtp dtmf delay to 40
2024-03-06 12:40:17.166882 98.27% [DEBUG] switch_ivr_originate.c:1353 Raw Codec Activation Success L16@8000hz 1 channel 20ms
2024-03-06 12:40:17.166882 98.27% [DEBUG] switch_core_codec.c:223 sofia/default/0506834683@212.150.158.100 Push codec L16:100
2024-03-06 12:40:17.166882 98.27% [DEBUG] switch_ivr_originate.c:1427 Play Ringback Tone [%(1000,3000,400)]
2024-03-06 12:40:21.186863 98.47% [NOTICE] switch_channel.c:1123 New Channel sofia/default/+32475866264@pstn.twilio.com:5060 [b8e381f7-569b-4c36-a641-248734710176]
2024-03-06 12:40:21.186863 98.47% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+32475866264@pstn.twilio.com:5060) Running State Change CS_NEW (Cur 36 Tot 3432182)
2024-03-06 12:40:21.186863 98.47% [INFO] sofia.c:10462 sofia/default/+32475866264@pstn.twilio.com:5060 receiving invite from 195.201.136.94:5060 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 2c173d2a2f8225afe34ad797971b165c@0.0.0.0
2024-03-06 12:40:21.186863 98.47% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 195.201.136.94:0.
2024-03-06 12:40:21.186863 98.47% [DEBUG] sofia.c:10585 IP 195.201.136.94 Approved by acl "default[]". Access Granted.
2024-03-06 12:40:21.186863 98.47% [DEBUG] sofia.c:7499 Channel sofia/default/+32475866264@pstn.twilio.com:5060 entering state [received][100]
2024-03-06 12:40:21.186863 98.47% [DEBUG] sofia.c:7509 Remote SDP:
v=0
o=root 1395356424 1395356424 IN IP4 172.21.26.188
s=Twilio Media Gateway
c=IN IP4 168.86.151.239
t=0 0
m=audio 14154 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:20
2024-03-06 12:40:21.186863 98.47% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2024-03-06 12:40:21.186863 98.47% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2024-03-06 12:40:21.186863 98.47% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2024-03-06 12:40:21.186863 98.47% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2024-03-06 12:40:21.186863 98.47% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2024-03-06 12:40:21.186863 98.47% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2024-03-06 12:40:21.186863 98.47% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
2024-03-06 12:40:21.186863 98.47% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/+32475866264@pstn.twilio.com:5060 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
2024-03-06 12:40:21.186863 98.47% [DEBUG] switch_core_codec.c:111 sofia/default/+32475866264@pstn.twilio.com:5060 Original read codec set to PCMU:0
2024-03-06 12:40:21.186863 98.47% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
2024-03-06 12:40:21.186863 98.47% [DEBUG] switch_core_media.c:5973 sofia/default/+32475866264@pstn.twilio.com:5060 Set 2833 dtmf send payload to 101 recv payload to 101
2024-03-06 12:40:21.186863 98.47% [DEBUG] sofia.c:7933 (sofia/default/+32475866264@pstn.twilio.com:5060) State Change CS_NEW -> CS_INIT
2024-03-06 12:40:21.186863 98.47% [DEBUG] switch_core_state_machine.c:600 (sofia/default/+32475866264@pstn.twilio.com:5060) State NEW
2024-03-06 12:40:21.186863 98.47% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+32475866264@pstn.twilio.com:5060) Running State Change CS_INIT (Cur 36 Tot 3432182)
2024-03-06 12:40:21.186863 98.47% [DEBUG] switch_core_state_machine.c:624 (sofia/default/+32475866264@pstn.twilio.com:5060) State INIT
2024-03-06 12:40:21.186863 98.47% [DEBUG] mod_sofia.c:97 sofia/default/+32475866264@pstn.twilio.com:5060 SOFIA INIT
2024-03-06 12:40:21.186863 98.47% [DEBUG] switch_core_state_machine.c:40 sofia/default/+32475866264@pstn.twilio.com:5060 Standard INIT
2024-03-06 12:40:21.186863 98.47% [DEBUG] switch_core_state_machine.c:48 (sofia/default/+32475866264@pstn.twilio.com:5060) State Change CS_INIT -> CS_ROUTING
2024-03-06 12:40:21.186863 98.47% [DEBUG] switch_core_state_machine.c:624 (sofia/default/+32475866264@pstn.twilio.com:5060) State INIT going to sleep
2024-03-06 12:40:21.186863 98.47% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+32475866264@pstn.twilio.com:5060) Running State Change CS_ROUTING (Cur 36 Tot 3432182)
2024-03-06 12:40:21.186863 98.47% [DEBUG] switch_channel.c:2380 (sofia/default/+32475866264@pstn.twilio.com:5060) Callstate Change DOWN -> RINGING
2024-03-06 12:40:21.186863 98.47% [DEBUG] switch_core_state_machine.c:640 (sofia/default/+32475866264@pstn.twilio.com:5060) State ROUTING
2024-03-06 12:40:21.186863 98.47% [DEBUG] mod_sofia.c:158 sofia/default/+32475866264@pstn.twilio.com:5060 SOFIA ROUTING
2024-03-06 12:40:21.186863 98.47% [DEBUG] switch_core_state_machine.c:230 sofia/default/+32475866264@pstn.twilio.com:5060 Standard ROUTING
2024-03-06 12:40:21.186863 98.47% [INFO] mod_dialplan_xml.c:639 Processing +32475866264 <+32475866264>->+32460239235 in context default
2024-03-06 12:40:21.186863 98.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
2024-03-06 12:40:21.246866 98.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
2024-03-06 12:40:21.326883 98.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [xml_handler] Params: