FreeSWITCH Pastebin

Israel access card

From Israel access card, 1 Year ago, written in FreeSWITCH.
- view diff
URL https://pastebin.freeswitch.org/view/17f7bf26
Embed
Download Paste or View Raw
  1. span style="color: cornflowerblue; font-weight: bold;"> 2023-02-07 13:27:02.173785 98.10% [NOTICE] switch_channel.c:1123 New Channel sofia/default/436766007464@46.19.210.14 [27d40098-c915-4cc9-812b-97a120a401e9]
  2. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/436766007464@46.19.210.14) Running State Change CS_NEW (Cur 5 Tot 1858)
  3. 2023-02-07 13:27:02.173785 98.10% [INFO] sofia.c:10462 sofia/default/436766007464@46.19.210.14 receiving invite from 195.201.136.94:5060 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 11-11-413020C1-63E251A6000276C9-0E22D700
  4. 2023-02-07 13:27:02.173785 98.10% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 195.201.136.94:0.
  5. 2023-02-07 13:27:02.173785 98.10% [DEBUG] sofia.c:10585 IP 195.201.136.94 Approved by acl "default[]". Access Granted.
  6. 2023-02-07 13:27:02.173785 98.10% [DEBUG] sofia.c:7499 Channel sofia/default/436766007464@46.19.210.14 entering state [received][100]
  7. 2023-02-07 13:27:02.173785 98.10% [DEBUG] sofia.c:7509 Remote SDP:
  8. v=0
  9. o=didww-sbc 2862399366 2862399367 IN IP4 46.19.210.39
  10. s=didww-sbc
  11. t=0 0
  12. m=audio 47488 RTP/AVP 0 8 18 4 101
  13. c=IN IP4 46.19.210.39
  14. a=rtpmap:0 PCMU/8000
  15. a=rtpmap:8 PCMA/8000
  16. a=rtpmap:18 G729/8000
  17. a=fmtp:18 annexb=no
  18. a=rtpmap:4 G723/8000
  19. a=rtpmap:101 telephone-event/8000
  20. a=ptime:20
  21.  
  22. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  23. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  24. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  25. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  26. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  27. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  28. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  29. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  30. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G723:4:8000:20:6300:1]/[PCMA:8:8000:20:64000:1]
  31. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G723:4:8000:20:6300:1]/[PCMU:0:8000:20:64000:1]
  32. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  33. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/436766007464@46.19.210.14 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  34. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_core_codec.c:111 sofia/default/436766007464@46.19.210.14 Original read codec set to PCMU:0
  35. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  36. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_core_media.c:5973 sofia/default/436766007464@46.19.210.14 Set 2833 dtmf send payload to 101 recv payload to 101
  37. 2023-02-07 13:27:02.173785 98.10% [DEBUG] sofia.c:7933 (sofia/default/436766007464@46.19.210.14) State Change CS_NEW -> CS_INIT
  38. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_core_state_machine.c:600 (sofia/default/436766007464@46.19.210.14) State NEW
  39. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/436766007464@46.19.210.14) Running State Change CS_INIT (Cur 5 Tot 1858)
  40. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_core_state_machine.c:624 (sofia/default/436766007464@46.19.210.14) State INIT
  41. 2023-02-07 13:27:02.173785 98.10% [DEBUG] mod_sofia.c:97 sofia/default/436766007464@46.19.210.14 SOFIA INIT
  42. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_core_state_machine.c:40 sofia/default/436766007464@46.19.210.14 Standard INIT
  43. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_core_state_machine.c:48 (sofia/default/436766007464@46.19.210.14) State Change CS_INIT -> CS_ROUTING
  44. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_core_state_machine.c:624 (sofia/default/436766007464@46.19.210.14) State INIT going to sleep
  45. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/436766007464@46.19.210.14) Running State Change CS_ROUTING (Cur 5 Tot 1858)
  46. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_channel.c:2380 (sofia/default/436766007464@46.19.210.14) Callstate Change DOWN -> RINGING
  47. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_core_state_machine.c:640 (sofia/default/436766007464@46.19.210.14) State ROUTING
  48. 2023-02-07 13:27:02.173785 98.10% [DEBUG] mod_sofia.c:158 sofia/default/436766007464@46.19.210.14 SOFIA ROUTING
  49. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_core_state_machine.c:230 sofia/default/436766007464@46.19.210.14 Standard ROUTING
  50. 2023-02-07 13:27:02.173785 98.10% [INFO] mod_dialplan_xml.c:639 Processing 436766007464 <436766007464>->43720145500 in context default
  51. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
  52. 2023-02-07 13:27:02.173785 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  53. 2023-02-07 13:27:02.193781 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] blind_flag: 0
  54. 2023-02-07 13:27:02.193781 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 43720145500
  55. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '43720145500' limit 1
  56. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '43720145500' AND status=0 limit 1
  57. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE domain = '46.19.210.14' limit 1
  58. 2023-02-07 13:27:02.193781 98.10% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] in custom Caller ID
  59. 2023-02-07 13:27:02.193781 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 436766007464 / 436766007464
  60. 2023-02-07 13:27:02.193781 98.10% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
  61. 2023-02-07 13:27:02.193781 98.10% [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
  62. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  63. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  64. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  65. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  66. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  67. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  68. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  69. 2023-02-07 13:27:02.193781 98.10% [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 FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="43720145500" LIMIT 1
  70. 2023-02-07 13:27:02.193781 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : inbound
  71. 2023-02-07 13:27:02.193781 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 714761424818]
  72. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "714761424818" AND deleted = 0 limit 1
  73. 2023-02-07 13:27:02.193781 98.10% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
  74. 2023-02-07 13:27:02.193781 98.10% [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
  75. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  76. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  77. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  78. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  79. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  80. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  81. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  82. 2023-02-07 13:27:02.193781 98.10% [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 FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="43720145500" LIMIT 1
  83. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "862027868347" AND deleted = 0 limit 1
  84. 2023-02-07 13:27:02.193781 98.10% [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 ="43720145500" AND A.accountid = '889' limit 1
  85. 2023-02-07 13:27:02.193781 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 43720145500
  86. 2023-02-07 13:27:02.193781 98.10% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
  87. 2023-02-07 13:27:02.193781 98.10% [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
  88. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  89. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  90. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  91. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  92. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  93. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  94. 2023-02-07 13:27:02.193781 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  95. 2023-02-07 13:27:02.193781 98.10% [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 FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="43720145500" LIMIT 1
  96. 2023-02-07 13:27:02.213777 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call direction : inbound
  97. 2023-02-07 13:27:02.213777 98.10% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
  98. 2023-02-07 13:27:02.213777 98.10% [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
  99. 2023-02-07 13:27:02.213777 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  100. 2023-02-07 13:27:02.213777 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  101. 2023-02-07 13:27:02.213777 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  102. 2023-02-07 13:27:02.213777 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  103. 2023-02-07 13:27:02.213777 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  104. 2023-02-07 13:27:02.213777 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  105. 2023-02-07 13:27:02.213777 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  106. 2023-02-07 13:27:02.213777 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="43720145500" AND (accountid = 0 OR status = 1) LIMIT 1
  107. 2023-02-07 13:27:02.213777 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] call_direction :inbound
  108. 2023-02-07 13:27:02.213777 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] DID_ACCOUNTID :889
  109. 2023-02-07 13:27:02.213777 98.10% [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 = '^43720145500.*' OR patterns = '^4372014550.*' OR patterns = '^437201455.*' OR patterns = '^43720145.*' OR patterns = '^4372014.*' OR patterns = '^437201.*' OR patterns = '^43720.*' OR patterns = '^4372.*' OR patterns = '^437.*' OR patterns = '^43.*' OR patterns = '^4.*' OR patterns ='--') AND accountid = 889 ORDER BY LENGTH(PKGPTR.patterns) DESC
  110. 2023-02-07 13:27:02.213777 98.10% [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 = '43720145500' OR number = '4372014550' OR number = '437201455' OR number = '43720145' OR number = '4372014' OR number = '437201' OR number = '43720' OR number = '4372' OR number = '437' OR number = '43' OR number = '4' OR number ='--') OR (number = '43720145500*' OR number = '4372014550*' OR number = '437201455*' OR number = '43720145*' OR number = '4372014*' OR number = '437201*' OR number = '43720*' OR number = '4372*' OR number = '437*' OR number = '43*' OR number = '4*' OR number ='--') OR (number = '436766007464' OR number = '43676600746' OR number = '4367660074' OR number = '436766007' OR number = '43676600' OR number = '4367660' OR number = '436766' OR number = '43676' OR number = '4367' OR number = '436' OR number = '43' OR number = '4' OR number ='--') OR (number = '436766007464*' OR number = '43676600746*' OR number = '4367660074*' OR number = '436766007*' OR number = '43676600*' OR number = '4367660*' OR number = '436766*' OR number = '43676*' OR number = '4367*' OR number = '436*' OR number = '43*' OR number = '4*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
  111. 2023-02-07 13:27:02.213777 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1
  112. 2023-02-07 13:27:02.213777 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '43720145500' OR routing_prefix = '4372014550' OR routing_prefix = '437201455' OR routing_prefix = '43720145' OR routing_prefix = '4372014' OR routing_prefix = '437201' OR routing_prefix = '43720' OR routing_prefix = '4372' OR routing_prefix = '437' OR routing_prefix = '43' OR routing_prefix = '4' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
  113. 2023-02-07 13:27:02.213777 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
  114. 2023-02-07 13:27:02.213777 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 889
  115. 2023-02-07 13:27:02.213777 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 862027868347
  116. 2023-02-07 13:27:02.213777 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 3992.04532
  117. 2023-02-07 13:27:02.213777 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
  118. 2023-02-07 13:27:02.213777 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 51
  119. 2023-02-07 13:27:02.213777 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
  120. 2023-02-07 13:27:02.213777 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
  121. 2023-02-07 13:27:02.213777 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 51 AND status = 0
  122. 2023-02-07 13:27:02.213777 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :inbound
  123. 2023-02-07 13:27:02.213777 98.10% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
  124. 2023-02-07 13:27:02.213777 98.10% [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
  125. 2023-02-07 13:27:02.213777 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  126. 2023-02-07 13:27:02.213777 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  127. 2023-02-07 13:27:02.213777 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  128. 2023-02-07 13:27:02.213777 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  129. 2023-02-07 13:27:02.213777 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  130. 2023-02-07 13:27:02.213777 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  131. 2023-02-07 13:27:02.213777 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....43720145500
  132. 2023-02-07 13:27:02.213777 98.10% [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 FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="43720145500" LIMIT 1
  133. 2023-02-07 13:27:02.213777 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] call_direction:::::: inbound
  134. 2023-02-07 13:27:02.213777 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
  135. 2023-02-07 13:27:02.213777 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 1973
  136. 2023-02-07 13:27:02.213777 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
  137. 2023-02-07 13:27:02.213777 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
  138. 2023-02-07 13:27:02.213777 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.00000
  139. 2023-02-07 13:27:02.213777 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] comment : National Landline
  140. 2023-02-07 13:27:02.213777 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 13
  141. 2023-02-07 13:27:02.213777 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 889
  142. 2023-02-07 13:27:02.213777 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
  143. 2023-02-07 13:27:02.213777 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your3992.04532 balance Accountid 889 !!!
  144. 2023-02-07 13:27:02.213777 98.10% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Call is free - assigning max length!!! :: 180
  145. 2023-02-07 13:27:02.213777 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max length duration : 180 minutes
  146. 2023-02-07 13:27:02.213777 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] INB_FREE:TRUE
  147. 2023-02-07 13:27:02.213777 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] free_inbound:1
  148. 2023-02-07 13:27:02.213777 98.10% [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 = 889 AND status= '0' LIMIT 1
  149. 2023-02-07 13:27:02.213777 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "889" AND deleted = 0 limit 1
  150. 2023-02-07 13:27:02.213777 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML:889
  151. 2023-02-07 13:27:02.233778 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Userinfo XML:889
  152. 2023-02-07 13:27:02.233778 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML : 889
  153. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE accountid = 889 limit 1
  154. 2023-02-07 13:27:02.233778 98.10% [WARNING] switch_cpp.cpp:1465 [ASTPP] [user_domain] user_domainReshetcall812.newsip.israelnumber.com
  155. 2023-02-07 13:27:02.233778 98.10% [INFO] switch_cpp.cpp:1465 [ASTPP] maxlength::::::::: 180
  156. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"000/00".....436766007464
  157. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"000/00".....436766007464
  158. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE accountid = 889 limit 1
  159. 2023-02-07 13:27:02.233778 98.10% [WARNING] switch_cpp.cpp:1465 [ASTPP] [user_domain] user_domainReshetcall812.newsip.israelnumber.com
  160. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] custom_function_name:::::::::::::::::::::::::custom_inbound_2
  161. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Generated XML:<?xml version="1.0" encoding="UTF-8" standalone="no"?>
  162. <document type="freeswitch/xml">
  163. <section name="dialplan" description="ASTPP Dialplan">
  164. <context name="default">
  165. <extension name="43720145500">
  166. <condition field="destination_number" expression="43720145500">
  167. <action application="set" data="effective_destination_number=43720145500"/>
  168. <action application="set" data="execute_on_answer=sched_hangup +10800 normal_clearing"/>
  169. <action application="set" data="callstart=2023-02-07 13:27:02"/>
  170. <action application="set" data="hangup_after_bridge=true"/>
  171. <action application="set" data="continue_on_fail=TRUE"/>
  172. <action application="set" data="account_id=889"/>
  173. <action application="set" data="parent_id=0"/>
  174. <action application="set" data="entity_id=0"/>
  175. <action application="set" data="call_processed=internal"/>
  176. <action application="set" data="call_direction=inbound"/>
  177. <action application="set" data="accountname=default"/>
  178. <action application="set" data="filename1=astpp.pbx.lua"/>
  179. <action application="set" data="origination_rates_did=ID:1973|CODE:^43720145500.*|DESTINATION:National Landline|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:2|COST:0.00000|INC:60|INITIALBLOCK:60|RATEGROUP:0|MARKUP:0|CI:13|ACCID:889"/>
  180. <action application="set" data="origination_rates=0"/>
  181. <action application="set" data="original_caller_id_name=436766007464"/>
  182. <action application="set" data="original_caller_id_number=436766007464"/>
  183. <action application="set" data="effective_caller_id_name=436766007464"/>
  184. <action application="set" data="effective_caller_id_number=436766007464"/>
  185. <action application="set" data="receiver_accid=889"/>
  186. <action application="export" data="domain_name=Reshetcall812.newsip.israelnumber.com"/>
  187. <action application="export" data="sip_to_host=Reshetcall812.newsip.israelnumber.com"/>
  188. <action application="export" data="presence_data=x|||Reshetcall(862027868347)|||^43720145500.* // National Landline // 0.00000||||||DID|||46.19.210.14"/>
  189. <action application="export" data="call_type=2"/>
  190. <action application="set" data="calltype=DIRECT-IP"/>
  191. <action application="bridge" data="[leg_timeout=30]sofia/${sofia_profile_name}/43720145500@88.99.5.200;fs_path=sip:195.201.136.94:5060"/>
  192. </condition>
  193. </extension>
  194. </context>
  195. </section>
  196. </document>
  197. Dialplan: sofia/default/436766007464@46.19.210.14 parsing [default->43720145500] continue=false
  198. Dialplan: sofia/default/436766007464@46.19.210.14 Regex (PASS) [43720145500] destination_number(43720145500) =~ /43720145500/ break=on-false
  199. Dialplan: sofia/default/436766007464@46.19.210.14 Action set(effective_destination_number=43720145500)
  200. Dialplan: sofia/default/436766007464@46.19.210.14 Action set(execute_on_answer=sched_hangup +10800 normal_clearing)
  201. Dialplan: sofia/default/436766007464@46.19.210.14 Action set(callstart=2023-02-07 13:27:02)
  202. Dialplan: sofia/default/436766007464@46.19.210.14 Action set(hangup_after_bridge=true)
  203. Dialplan: sofia/default/436766007464@46.19.210.14 Action set(continue_on_fail=TRUE)
  204. Dialplan: sofia/default/436766007464@46.19.210.14 Action set(account_id=889)
  205. Dialplan: sofia/default/436766007464@46.19.210.14 Action set(parent_id=0)
  206. Dialplan: sofia/default/436766007464@46.19.210.14 Action set(entity_id=0)
  207. Dialplan: sofia/default/436766007464@46.19.210.14 Action set(call_processed=internal)
  208. Dialplan: sofia/default/436766007464@46.19.210.14 Action set(call_direction=inbound)
  209. Dialplan: sofia/default/436766007464@46.19.210.14 Action set(accountname=default)
  210. Dialplan: sofia/default/436766007464@46.19.210.14 Action set(filename1=astpp.pbx.lua)
  211. Dialplan: sofia/default/436766007464@46.19.210.14 Action set(origination_rates_did=ID:1973|CODE:^43720145500.*|DESTINATION:National Landline|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:2|COST:0.00000|INC:60|INITIALBLOCK:60|RATEGROUP:0|MARKUP:0|CI:13|ACCID:889)
  212. Dialplan: sofia/default/436766007464@46.19.210.14 Action set(origination_rates=0)
  213. Dialplan: sofia/default/436766007464@46.19.210.14 Action set(original_caller_id_name=436766007464)
  214. Dialplan: sofia/default/436766007464@46.19.210.14 Action set(original_caller_id_number=436766007464)
  215. Dialplan: sofia/default/436766007464@46.19.210.14 Action set(effective_caller_id_name=436766007464)
  216. Dialplan: sofia/default/436766007464@46.19.210.14 Action set(effective_caller_id_number=436766007464)
  217. Dialplan: sofia/default/436766007464@46.19.210.14 Action set(receiver_accid=889)
  218. Dialplan: sofia/default/436766007464@46.19.210.14 Action export(domain_name=Reshetcall812.newsip.israelnumber.com)
  219. Dialplan: sofia/default/436766007464@46.19.210.14 Action export(sip_to_host=Reshetcall812.newsip.israelnumber.com)
  220. Dialplan: sofia/default/436766007464@46.19.210.14 Action export(presence_data=x|||Reshetcall(862027868347)|||^43720145500.* // National Landline // 0.00000||||||DID|||46.19.210.14)
  221. Dialplan: sofia/default/436766007464@46.19.210.14 Action export(call_type=2)
  222. Dialplan: sofia/default/436766007464@46.19.210.14 Action set(calltype=DIRECT-IP)
  223. Dialplan: sofia/default/436766007464@46.19.210.14 Action bridge([leg_timeout=30]sofia/${sofia_profile_name}/43720145500@88.99.5.200;fs_path=sip:195.201.136.94:5060)
  224. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_core_state_machine.c:281 (sofia/default/436766007464@46.19.210.14) State Change CS_ROUTING -> CS_EXECUTE
  225. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_core_state_machine.c:640 (sofia/default/436766007464@46.19.210.14) State ROUTING going to sleep
  226. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/436766007464@46.19.210.14) Running State Change CS_EXECUTE (Cur 5 Tot 1858)
  227. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_core_state_machine.c:647 (sofia/default/436766007464@46.19.210.14) State EXECUTE
  228. 2023-02-07 13:27:02.233778 98.10% [DEBUG] mod_sofia.c:213 sofia/default/436766007464@46.19.210.14 SOFIA EXECUTE
  229. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_core_state_machine.c:323 sofia/default/436766007464@46.19.210.14 Standard EXECUTE
  230. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 set(effective_destination_number=43720145500)
  231. 2023-02-07 13:27:02.233778 98.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/436766007464@46.19.210.14 [effective_destination_number]=[43720145500]
  232. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 set(execute_on_answer=sched_hangup +10800 normal_clearing)
  233. 2023-02-07 13:27:02.233778 98.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/436766007464@46.19.210.14 [execute_on_answer]=[sched_hangup +10800 normal_clearing]
  234. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 set(callstart=2023-02-07 13:27:02)
  235. 2023-02-07 13:27:02.233778 98.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/436766007464@46.19.210.14 [callstart]=[2023-02-07 13:27:02]
  236. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 set(hangup_after_bridge=true)
  237. 2023-02-07 13:27:02.233778 98.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/436766007464@46.19.210.14 [hangup_after_bridge]=[true]
  238. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 set(continue_on_fail=TRUE)
  239. 2023-02-07 13:27:02.233778 98.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/436766007464@46.19.210.14 [continue_on_fail]=[TRUE]
  240. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 set(account_id=889)
  241. 2023-02-07 13:27:02.233778 98.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/436766007464@46.19.210.14 [account_id]=[889]
  242. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 set(parent_id=0)
  243. 2023-02-07 13:27:02.233778 98.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/436766007464@46.19.210.14 [parent_id]=[0]
  244. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 set(entity_id=0)
  245. 2023-02-07 13:27:02.233778 98.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/436766007464@46.19.210.14 [entity_id]=[0]
  246. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 set(call_processed=internal)
  247. 2023-02-07 13:27:02.233778 98.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/436766007464@46.19.210.14 [call_processed]=[internal]
  248. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 set(call_direction=inbound)
  249. 2023-02-07 13:27:02.233778 98.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/436766007464@46.19.210.14 [call_direction]=[inbound]
  250. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 set(accountname=default)
  251. 2023-02-07 13:27:02.233778 98.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/436766007464@46.19.210.14 [accountname]=[default]
  252. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 set(filename1=astpp.pbx.lua)
  253. 2023-02-07 13:27:02.233778 98.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/436766007464@46.19.210.14 [filename1]=[astpp.pbx.lua]
  254. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 set(origination_rates_did=ID:1973|CODE:^43720145500.*|DESTINATION:National Landline|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:2|COST:0.00000|INC:60|INITIALBLOCK:60|RATEGROUP:0|MARKUP:0|CI:13|ACCID:889)
  255. 2023-02-07 13:27:02.233778 98.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/436766007464@46.19.210.14 [origination_rates_did]=[ID:1973|CODE:^43720145500.*|DESTINATION:National Landline|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:2|COST:0.00000|INC:60|INITIALBLOCK:60|RATEGROUP:0|MARKUP:0|CI:13|ACCID:889]
  256. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 set(origination_rates=0)
  257. 2023-02-07 13:27:02.233778 98.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/436766007464@46.19.210.14 [origination_rates]=[0]
  258. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 set(original_caller_id_name=436766007464)
  259. 2023-02-07 13:27:02.233778 98.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/436766007464@46.19.210.14 [original_caller_id_name]=[436766007464]
  260. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 set(original_caller_id_number=436766007464)
  261. 2023-02-07 13:27:02.233778 98.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/436766007464@46.19.210.14 [original_caller_id_number]=[436766007464]
  262. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 set(effective_caller_id_name=436766007464)
  263. 2023-02-07 13:27:02.233778 98.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/436766007464@46.19.210.14 [effective_caller_id_name]=[436766007464]
  264. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 set(effective_caller_id_number=436766007464)
  265. 2023-02-07 13:27:02.233778 98.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/436766007464@46.19.210.14 [effective_caller_id_number]=[436766007464]
  266. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 set(receiver_accid=889)
  267. 2023-02-07 13:27:02.233778 98.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/436766007464@46.19.210.14 [receiver_accid]=[889]
  268. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 export(domain_name=Reshetcall812.newsip.israelnumber.com)
  269. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [domain_name]=[Reshetcall812.newsip.israelnumber.com]
  270. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 export(sip_to_host=Reshetcall812.newsip.israelnumber.com)
  271. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [sip_to_host]=[Reshetcall812.newsip.israelnumber.com]
  272. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 export(presence_data=x|||Reshetcall(862027868347)|||^43720145500.* // National Landline // 0.00000||||||DID|||46.19.210.14)
  273. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[x|||Reshetcall(862027868347)|||^43720145500.* // National Landline // 0.00000||||||DID|||46.19.210.14]
  274. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 export(call_type=2)
  275. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [call_type]=[2]
  276. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 set(calltype=DIRECT-IP)
  277. 2023-02-07 13:27:02.233778 98.10% [DEBUG] mod_dptools.c:1685 SET sofia/default/436766007464@46.19.210.14 [calltype]=[DIRECT-IP]
  278. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 bridge([leg_timeout=30]sofia/default/43720145500@88.99.5.200;fs_path=sip:195.201.136.94:5060)
  279. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_channel.c:1269 sofia/default/436766007464@46.19.210.14 EXPORTING[export_vars] [domain_name]=[Reshetcall812.newsip.israelnumber.com] to event
  280. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_channel.c:1269 sofia/default/436766007464@46.19.210.14 EXPORTING[export_vars] [sip_to_host]=[Reshetcall812.newsip.israelnumber.com] to event
  281. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_channel.c:1269 sofia/default/436766007464@46.19.210.14 EXPORTING[export_vars] [presence_data]=[x|||Reshetcall(862027868347)|||^43720145500.* // National Landline // 0.00000||||||DID|||46.19.210.14] to event
  282. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_channel.c:1269 sofia/default/436766007464@46.19.210.14 EXPORTING[export_vars] [call_type]=[2] to event
  283. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  284. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_ivr_originate.c:2843 Parsing session specific variables
  285. 2023-02-07 13:27:02.233778 98.10% [NOTICE] switch_channel.c:1123 New Channel sofia/default/43720145500@88.99.5.200 [85c52195-91fb-4883-863c-4ee9b032a311]
  286. 2023-02-07 13:27:02.233778 98.10% [DEBUG] mod_sofia.c:5121 (sofia/default/43720145500@88.99.5.200) State Change CS_NEW -> CS_INIT
  287. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_ivr_originate.c:3151 sofia/default/43720145500@88.99.5.200 Setting leg timeout to 30
  288. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/43720145500@88.99.5.200) Running State Change CS_INIT (Cur 6 Tot 1859)
  289. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_core_state_machine.c:624 (sofia/default/43720145500@88.99.5.200) State INIT
  290. 2023-02-07 13:27:02.233778 98.10% [DEBUG] mod_sofia.c:97 sofia/default/43720145500@88.99.5.200 SOFIA INIT
  291. 2023-02-07 13:27:02.233778 98.10% [DEBUG] sofia_glue.c:1620 sip:195.201.136.94:5060 Setting proxy route to sofia/default/43720145500@88.99.5.200
  292. 2023-02-07 13:27:02.233778 98.10% [INFO] sofia_glue.c:1651 sofia/default/43720145500@88.99.5.200 sending invite call-id: (null)
  293. 2023-02-07 13:27:02.233778 98.10% [DEBUG] sofia_glue.c:1654 sofia/default/43720145500@88.99.5.200 sending invite version: 1.10.7 -release-19-883d2cb662 64bit
  294. Local SDP:
  295. v=0
  296. o=FreeSWITCH 1675747466 1675747467 IN IP4 195.201.136.88
  297. s=FreeSWITCH
  298. c=IN IP4 195.201.136.88
  299. t=0 0
  300. m=audio 28956 RTP/AVP 0 101
  301. a=rtpmap:0 PCMU/8000
  302. a=rtpmap:101 telephone-event/8000
  303. a=fmtp:101 0-15
  304. a=ptime:20
  305. a=sendrecv
  306.  
  307. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_core_state_machine.c:40 sofia/default/43720145500@88.99.5.200 Standard INIT
  308. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_core_state_machine.c:48 (sofia/default/43720145500@88.99.5.200) State Change CS_INIT -> CS_ROUTING
  309. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_core_state_machine.c:624 (sofia/default/43720145500@88.99.5.200) State INIT going to sleep
  310. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/43720145500@88.99.5.200) Running State Change CS_ROUTING (Cur 6 Tot 1859)
  311. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_core_state_machine.c:640 (sofia/default/43720145500@88.99.5.200) State ROUTING
  312. 2023-02-07 13:27:02.233778 98.10% [DEBUG] mod_sofia.c:158 sofia/default/43720145500@88.99.5.200 SOFIA ROUTING
  313. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_ivr_originate.c:67 (sofia/default/43720145500@88.99.5.200) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  314. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_core_state_machine.c:640 (sofia/default/43720145500@88.99.5.200) State ROUTING going to sleep
  315. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/43720145500@88.99.5.200) Running State Change CS_CONSUME_MEDIA (Cur 6 Tot 1859)
  316. 2023-02-07 13:27:02.233778 98.10% [DEBUG] sofia.c:7499 Channel sofia/default/43720145500@88.99.5.200 entering state [calling][0]
  317. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_core_state_machine.c:659 (sofia/default/43720145500@88.99.5.200) State CONSUME_MEDIA
  318. 2023-02-07 13:27:02.233778 98.10% [DEBUG] switch_core_state_machine.c:659 (sofia/default/43720145500@88.99.5.200) State CONSUME_MEDIA going to sleep
  319. 2023-02-07 13:27:02.833788 98.00% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [101@195.201.136.88] from ip 128.90.116.22
  320. 2023-02-07 13:27:02.893795 98.00% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [201@195.201.136.88] from ip 128.90.104.131
  321. 2023-02-07 13:27:02.913794 98.00% [WARNING] sofia_reg.c:3223 Can't find user [201@195.201.136.88] from 128.90.104.131
  322. You must define a domain called '195.201.136.88' in your directory and add a user with the id="201" attribute
  323. and you must configure your device to use the proper domain in its authentication credentials.
  324. 2023-02-07 13:27:02.913794 98.00% [WARNING] sofia_reg.c:1806 SIP auth failure (REGISTER) on sofia profile 'default' for [201@195.201.136.88] from ip 128.90.104.131
  325. 2023-02-07 13:27:03.013777 98.00% [WARNING] sofia_reg.c:3223 Can't find user [101@195.201.136.88] from 128.90.116.22
  326. You must define a domain called '195.201.136.88' in your directory and add a user with the id="101" attribute
  327. and you must configure your device to use the proper domain in its authentication credentials.
  328. 2023-02-07 13:27:03.013777 98.00% [WARNING] sofia_reg.c:1806 SIP auth failure (REGISTER) on sofia profile 'default' for [101@195.201.136.88] from ip 128.90.116.22
  329. 2023-02-07 13:27:03.373785 98.00% [DEBUG] sofia.c:7499 Channel sofia/default/43720145500@88.99.5.200 entering state [completing][200]
  330. 2023-02-07 13:27:03.373785 98.00% [DEBUG] sofia.c:7509 Remote SDP:
  331. v=0
  332. o=FreeSWITCH 1675758043 1675758044 IN IP4 88.99.5.200
  333. s=FreeSWITCH
  334. c=IN IP4 88.99.5.200
  335. t=0 0
  336. m=audio 18380 RTP/AVP 0 101
  337. a=rtpmap:0 PCMU/8000
  338. a=rtpmap:101 telephone-event/8000
  339. a=fmtp:101 0-16
  340. a=ptime:20
  341.  
  342. 2023-02-07 13:27:03.373785 98.00% [DEBUG] sofia.c:7499 Channel sofia/default/43720145500@88.99.5.200 entering state [ready][200]
  343. 2023-02-07 13:27:03.373785 98.00% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  344. 2023-02-07 13:27:03.373785 98.00% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  345. 2023-02-07 13:27:03.373785 98.00% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  346. 2023-02-07 13:27:03.373785 98.00% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/43720145500@88.99.5.200 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  347. 2023-02-07 13:27:03.373785 98.00% [DEBUG] switch_core_codec.c:111 sofia/default/43720145500@88.99.5.200 Original read codec set to PCMU:0
  348. 2023-02-07 13:27:03.373785 98.00% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  349. 2023-02-07 13:27:03.373785 98.00% [DEBUG] switch_core_media.c:5973 sofia/default/43720145500@88.99.5.200 Set 2833 dtmf send payload to 101 recv payload to 101
  350. 2023-02-07 13:27:03.373785 98.00% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/43720145500@88.99.5.200] 195.201.136.88 port 28956 -> 88.99.5.200 port 18380 codec: 0 ms: 20
  351. 2023-02-07 13:27:03.373785 98.00% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms
  352. 2023-02-07 13:27:03.373785 98.00% [DEBUG] switch_core_media.c:9089 sofia/default/43720145500@88.99.5.200 Set 2833 dtmf send payload to 101
  353. 2023-02-07 13:27:03.373785 98.00% [DEBUG] switch_core_media.c:9096 sofia/default/43720145500@88.99.5.200 Set 2833 dtmf receive payload to 101
  354. 2023-02-07 13:27:03.373785 98.00% [DEBUG] switch_core_media.c:9119 sofia/default/43720145500@88.99.5.200 Set rtp dtmf delay to 40
  355. 2023-02-07 13:27:03.373785 98.00% [NOTICE] sofia.c:8683 Channel [sofia/default/43720145500@88.99.5.200] has been answered
  356. 2023-02-07 13:27:03.373785 98.00% [DEBUG] switch_channel.c:3950 (sofia/default/43720145500@88.99.5.200) Callstate Change DOWN -> ACTIVE
  357. 2023-02-07 13:27:03.393781 98.00% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/436766007464@46.19.210.14] 195.201.136.88 port 31978 -> 46.19.210.39 port 47488 codec: 0 ms: 20
  358. 2023-02-07 13:27:03.393781 98.00% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms
  359. 2023-02-07 13:27:03.393781 98.00% [DEBUG] switch_core_media.c:9089 sofia/default/436766007464@46.19.210.14 Set 2833 dtmf send payload to 101
  360. 2023-02-07 13:27:03.393781 98.00% [DEBUG] switch_core_media.c:9096 sofia/default/436766007464@46.19.210.14 Set 2833 dtmf receive payload to 101
  361. 2023-02-07 13:27:03.393781 98.00% [DEBUG] switch_core_media.c:9119 sofia/default/436766007464@46.19.210.14 Set rtp dtmf delay to 40
  362. 2023-02-07 13:27:03.393781 98.00% [DEBUG] mod_sofia.c:913 Local SDP sofia/default/436766007464@46.19.210.14:
  363. v=0
  364. o=FreeSWITCH 1675744445 1675744446 IN IP4 195.201.136.88
  365. s=FreeSWITCH
  366. c=IN IP4 195.201.136.88
  367. t=0 0
  368. m=audio 31978 RTP/AVP 0 101
  369. a=rtpmap:0 PCMU/8000
  370. a=rtpmap:101 telephone-event/8000
  371. a=fmtp:101 0-15
  372. a=ptime:20
  373. a=sendrecv
  374.  
  375. 2023-02-07 13:27:03.393781 98.00% [NOTICE] switch_ivr_originate.c:3834 Channel [sofia/default/436766007464@46.19.210.14] has been answered
  376. EXECUTE [depth=0] sofia/default/436766007464@46.19.210.14 sched_hangup(+10800 normal_clearing)
  377. 2023-02-07 13:27:03.393781 98.00% [DEBUG] sofia.c:7499 Channel sofia/default/436766007464@46.19.210.14 entering state [completed][200]
  378. 2023-02-07 13:27:03.393781 98.00% [DEBUG] switch_scheduler.c:263 Added task 53 switch_ivr_schedule_hangup (27d40098-c915-4cc9-812b-97a120a401e9) to run at 1675787223
  379. 2023-02-07 13:27:03.393781 98.00% [DEBUG] switch_channel.c:3950 (sofia/default/436766007464@46.19.210.14) Callstate Change RINGING -> ACTIVE
  380. 2023-02-07 13:27:03.393781 98.00% [DEBUG] switch_ivr_originate.c:3892 Originate Resulted in Success: [sofia/default/43720145500@88.99.5.200] Peer UUID: 85c52195-91fb-4883-863c-4ee9b032a311
  381. 2023-02-07 13:27:03.393781 98.00% [DEBUG] switch_ivr_bridge.c:1793 (sofia/default/43720145500@88.99.5.200) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  382. 2023-02-07 13:27:03.393781 98.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/43720145500@88.99.5.200) Running State Change CS_EXCHANGE_MEDIA (Cur 6 Tot 1859)
  383. 2023-02-07 13:27:03.393781 98.00% [DEBUG] switch_core_state_machine.c:650 (sofia/default/43720145500@88.99.5.200) State EXCHANGE_MEDIA
  384. 2023-02-07 13:27:03.393781 98.00% [DEBUG] mod_sofia.c:671 SOFIA EXCHANGE_MEDIA
  385. 2023-02-07 13:27:03.433778 98.00% [DEBUG] sofia.c:7499 Channel sofia/default/436766007464@46.19.210.14 entering state [ready][200]
  386. 2023-02-07 13:27:03.433778 98.00% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed.
  387. 2023-02-07 13:27:03.513784 98.00% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed.
  388. 2023-02-07 13:27:19.873790 97.90% [NOTICE] sofia.c:1065 Hangup sofia/default/436766007464@46.19.210.14 [CS_EXECUTE] [NORMAL_CLEARING]
  389. 2023-02-07 13:27:19.873790 97.90% [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/436766007464@46.19.210.14]
  390. 2023-02-07 13:27:19.873790 97.90% [DEBUG] switch_ivr_bridge.c:820 sofia/default/436766007464@46.19.210.14 ending bridge by request from write function
  391. 2023-02-07 13:27:19.873790 97.90% [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/43720145500@88.99.5.200]
  392. 2023-02-07 13:27:19.873790 97.90% [NOTICE] switch_ivr_bridge.c:1029 Hangup sofia/default/43720145500@88.99.5.200 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  393. 2023-02-07 13:27:19.873790 97.90% [DEBUG] switch_core_state_machine.c:650 (sofia/default/43720145500@88.99.5.200) State EXCHANGE_MEDIA going to sleep
  394. 2023-02-07 13:27:19.873790 97.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/43720145500@88.99.5.200) Running State Change CS_HANGUP (Cur 6 Tot 1859)
  395. 2023-02-07 13:27:19.873790 97.90% [DEBUG] switch_core_state_machine.c:844 (sofia/default/43720145500@88.99.5.200) Callstate Change ACTIVE -> HANGUP
  396. 2023-02-07 13:27:19.873790 97.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/43720145500@88.99.5.200) State HANGUP
  397. 2023-02-07 13:27:19.873790 97.90% [DEBUG] mod_sofia.c:462 sofia/default/43720145500@88.99.5.200 Overriding SIP cause 480 with 200 from the other leg
  398. 2023-02-07 13:27:19.873790 97.90% [DEBUG] mod_sofia.c:468 Channel sofia/default/43720145500@88.99.5.200 hanging up, cause: NORMAL_CLEARING
  399. 2023-02-07 13:27:19.873790 97.90% [DEBUG] mod_sofia.c:522 Sending BYE to sofia/default/43720145500@88.99.5.200
  400. 2023-02-07 13:27:19.873790 97.90% [DEBUG] switch_core_state_machine.c:59 sofia/default/43720145500@88.99.5.200 Standard HANGUP, cause: NORMAL_CLEARING
  401. 2023-02-07 13:27:19.873790 97.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/43720145500@88.99.5.200) State HANGUP going to sleep
  402. 2023-02-07 13:27:19.873790 97.90% [DEBUG] switch_ivr_bridge.c:1892 sofia/default/43720145500@88.99.5.200 skip receive message [UNBRIDGE] (channel is hungup already)
  403. 2023-02-07 13:27:19.873790 97.90% [DEBUG] switch_ivr_bridge.c:1895 sofia/default/436766007464@46.19.210.14 skip receive message [UNBRIDGE] (channel is hungup already)
  404. 2023-02-07 13:27:19.873790 97.90% [DEBUG] switch_core_state_machine.c:616 (sofia/default/43720145500@88.99.5.200) State Change CS_HANGUP -> CS_REPORTING
  405. 2023-02-07 13:27:19.873790 97.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/43720145500@88.99.5.200) Running State Change CS_REPORTING (Cur 6 Tot 1859)
  406. 2023-02-07 13:27:19.873790 97.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/43720145500@88.99.5.200) State REPORTING
  407. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_session.c:2983 sofia/default/436766007464@46.19.210.14 skip receive message [PHONE_EVENT] (channel is hungup already)
  408. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_state_machine.c:647 (sofia/default/436766007464@46.19.210.14) State EXECUTE going to sleep
  409. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/436766007464@46.19.210.14) Running State Change CS_HANGUP (Cur 6 Tot 1859)
  410. 2023-02-07 13:27:19.893789 97.90% [INFO] mod_json_cdr.c:271 Process [85c52195-91fb-4883-863c-4ee9b032a311.cdr.json]
  411. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_state_machine.c:844 (sofia/default/436766007464@46.19.210.14) Callstate Change ACTIVE -> HANGUP
  412. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/436766007464@46.19.210.14) State HANGUP
  413. 2023-02-07 13:27:19.893789 97.90% [DEBUG] mod_sofia.c:468 Channel sofia/default/436766007464@46.19.210.14 hanging up, cause: NORMAL_CLEARING
  414. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_state_machine.c:59 sofia/default/436766007464@46.19.210.14 Standard HANGUP, cause: NORMAL_CLEARING
  415. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/436766007464@46.19.210.14) State HANGUP going to sleep
  416. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_state_machine.c:616 (sofia/default/436766007464@46.19.210.14) State Change CS_HANGUP -> CS_REPORTING
  417. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/436766007464@46.19.210.14) Running State Change CS_REPORTING (Cur 6 Tot 1859)
  418. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/436766007464@46.19.210.14) State REPORTING
  419. 2023-02-07 13:27:19.893789 97.90% [INFO] mod_json_cdr.c:271 Process [27d40098-c915-4cc9-812b-97a120a401e9.cdr.json]
  420. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_state_machine.c:168 sofia/default/43720145500@88.99.5.200 Standard REPORTING, cause: NORMAL_CLEARING
  421. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/43720145500@88.99.5.200) State REPORTING going to sleep
  422. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_state_machine.c:607 (sofia/default/43720145500@88.99.5.200) State Change CS_REPORTING -> CS_DESTROY
  423. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_session.c:1753 Session 1859 (sofia/default/43720145500@88.99.5.200) Locked, Waiting on external entities
  424. 2023-02-07 13:27:19.893789 97.90% [NOTICE] switch_core_session.c:1771 Session 1859 (sofia/default/43720145500@88.99.5.200) Ended
  425. 2023-02-07 13:27:19.893789 97.90% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/43720145500@88.99.5.200 [CS_DESTROY]
  426. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_state_machine.c:735 (sofia/default/43720145500@88.99.5.200) Running State Change CS_DESTROY (Cur 5 Tot 1859)
  427. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/43720145500@88.99.5.200) State DESTROY
  428. 2023-02-07 13:27:19.893789 97.90% [DEBUG] mod_sofia.c:379 sofia/default/43720145500@88.99.5.200 SOFIA DESTROY
  429. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_state_machine.c:175 sofia/default/43720145500@88.99.5.200 Standard DESTROY
  430. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/43720145500@88.99.5.200) State DESTROY going to sleep
  431. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_state_machine.c:168 sofia/default/436766007464@46.19.210.14 Standard REPORTING, cause: NORMAL_CLEARING
  432. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/436766007464@46.19.210.14) State REPORTING going to sleep
  433. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_state_machine.c:607 (sofia/default/436766007464@46.19.210.14) State Change CS_REPORTING -> CS_DESTROY
  434. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_session.c:1753 Session 1858 (sofia/default/436766007464@46.19.210.14) Locked, Waiting on external entities
  435. 2023-02-07 13:27:19.893789 97.90% [NOTICE] switch_core_session.c:1771 Session 1858 (sofia/default/436766007464@46.19.210.14) Ended
  436. 2023-02-07 13:27:19.893789 97.90% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/436766007464@46.19.210.14 [CS_DESTROY]
  437. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_state_machine.c:735 (sofia/default/436766007464@46.19.210.14) Running State Change CS_DESTROY (Cur 4 Tot 1859)
  438. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/436766007464@46.19.210.14) State DESTROY
  439. 2023-02-07 13:27:19.893789 97.90% [DEBUG] mod_sofia.c:379 sofia/default/436766007464@46.19.210.14 SOFIA DESTROY
  440. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_state_machine.c:175 sofia/default/436766007464@46.19.210.14 Standard DESTROY
  441. 2023-02-07 13:27:19.893789 97.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/436766007464@46.19.210.14) State DESTROY going to sleep
  442. 2023-02-07 13:27:19.913778 97.90% [DEBUG] switch_scheduler.c:147 Deleting task 53 switch_ivr_schedule_hangup (27d40098-c915-4cc9-812b-97a120a401e9)
  443. 2023-02-07 13:27:21.873806 97.83% [NOTICE] switch_channel.c:1123 New Channel sofia/default/0546322423@212.150.158.100 [02641f90-b524-448f-8f56-9cbd99102e61]
  444. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0546322423@212.150.158.100) Running State Change CS_NEW (Cur 5 Tot 1860)
  445. 2023-02-07 13:27:21.873806 97.83% [INFO] sofia.c:10462 sofia/default/0546322423@212.150.158.100 receiving invite from 195.201.136.94:5060 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 600cfe8911b3e2f63124df395d8dacdf@212.150.158.100
  446. 2023-02-07 13:27:21.873806 97.83% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 195.201.136.94:0.
  447. 2023-02-07 13:27:21.873806 97.83% [DEBUG] sofia.c:10585 IP 195.201.136.94 Approved by acl "default[]". Access Granted.
  448. 2023-02-07 13:27:21.873806 97.83% [DEBUG] sofia.c:7499 Channel sofia/default/0546322423@212.150.158.100 entering state [received][100]
  449. 2023-02-07 13:27:21.873806 97.83% [DEBUG] sofia.c:7509 Remote SDP:
  450. v=0
  451. o=root 7779 7779 IN IP4 212.150.158.100
  452. s=session
  453. c=IN IP4 212.150.158.100
  454. b=CT:384
  455. t=0 0
  456. m=audio 13310 RTP/AVP 8 0 3 112 5 10 7 18 111 101
  457. a=rtpmap:8 PCMA/8000
  458. a=rtpmap:0 PCMU/8000
  459. a=rtpmap:3 GSM/8000
  460. a=rtpmap:112 AAL2-G726-32/8000
  461. a=rtpmap:5 DVI4/8000
  462. a=rtpmap:10 L16/8000
  463. a=rtpmap:7 LPC/8000
  464. a=rtpmap:18 G729/8000
  465. a=fmtp:18 annexb=no
  466. a=rtpmap:111 G726-32/8000
  467. a=rtpmap:101 telephone-event/8000
  468. a=fmtp:101 0-16
  469. a=silenceSupp:off - - - -
  470. a=ptime:20
  471. m=video 17968 RTP/AVP 26 31 34 103 99
  472. a=rtpmap:26 JPEG/90000
  473. a=rtpmap:31 H261/90000
  474. a=rtpmap:34 H263/90000
  475. a=rtpmap:103 h263-1998/90000
  476. a=rtpmap:99 H264/90000
  477.  
  478. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  479. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  480. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  481. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  482. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  483. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  484. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  485. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  486. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [AAL2-G726-32:112:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  487. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [AAL2-G726-32:112:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  488. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [DVI4:5:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  489. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [DVI4:5:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  490. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [L16:10:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  491. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [L16:10:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  492. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [LPC:7:8000:20:2400:1]/[PCMA:8:8000:20:64000:1]
  493. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [LPC:7:8000:20:2400:1]/[PCMU:0:8000:20:64000:1]
  494. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  495. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  496. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G726-32:111:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  497. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G726-32:111:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  498. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  499. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/0546322423@212.150.158.100 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  500. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_codec.c:111 sofia/default/0546322423@212.150.158.100 Original read codec set to PCMA:8
  501. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  502. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:5973 sofia/default/0546322423@212.150.158.100 Set 2833 dtmf send payload to 101 recv payload to 101
  503. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:6301 No matches with FTMP, fallback to ignoring FMTP
  504. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_media.c:6309 No matches with inherit_codec, fallback to ignoring PT
  505. 2023-02-07 13:27:21.873806 97.83% [DEBUG] sofia.c:7933 (sofia/default/0546322423@212.150.158.100) State Change CS_NEW -> CS_INIT
  506. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_state_machine.c:600 (sofia/default/0546322423@212.150.158.100) State NEW
  507. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0546322423@212.150.158.100) Running State Change CS_INIT (Cur 5 Tot 1860)
  508. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_state_machine.c:624 (sofia/default/0546322423@212.150.158.100) State INIT
  509. 2023-02-07 13:27:21.873806 97.83% [DEBUG] mod_sofia.c:97 sofia/default/0546322423@212.150.158.100 SOFIA INIT
  510. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_state_machine.c:40 sofia/default/0546322423@212.150.158.100 Standard INIT
  511. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_state_machine.c:48 (sofia/default/0546322423@212.150.158.100) State Change CS_INIT -> CS_ROUTING
  512. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_state_machine.c:624 (sofia/default/0546322423@212.150.158.100) State INIT going to sleep
  513. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0546322423@212.150.158.100) Running State Change CS_ROUTING (Cur 5 Tot 1860)
  514. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_channel.c:2380 (sofia/default/0546322423@212.150.158.100) Callstate Change DOWN -> RINGING
  515. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_state_machine.c:640 (sofia/default/0546322423@212.150.158.100) State ROUTING
  516. 2023-02-07 13:27:21.873806 97.83% [DEBUG] mod_sofia.c:158 sofia/default/0546322423@212.150.158.100 SOFIA ROUTING
  517. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_core_state_machine.c:230 sofia/default/0546322423@212.150.158.100 Standard ROUTING
  518. 2023-02-07 13:27:21.873806 97.83% [INFO] mod_dialplan_xml.c:639 Processing 0546322423 <0546322423>->0737004346 in context default
  519. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
  520. 2023-02-07 13:27:21.873806 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  521. 2023-02-07 13:27:21.893778 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] blind_flag: 0
  522. 2023-02-07 13:27:21.893778 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 0737004346
  523. 2023-02-07 13:27:21.893778 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '0737004346' limit 1
  524. 2023-02-07 13:27:21.893778 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '0737004346' AND status=0 limit 1
  525. 2023-02-07 13:27:21.893778 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE domain = '212.150.158.100' limit 1
  526. 2023-02-07 13:27:21.893778 97.83% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] in custom Caller ID
  527. 2023-02-07 13:27:21.893778 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 0546322423 / 0546322423
  528. 2023-02-07 13:27:21.893778 97.83% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
  529. 2023-02-07 13:27:21.893778 97.83% [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
  530. 2023-02-07 13:27:21.893778 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0737004346
  531. 2023-02-07 13:27:21.893778 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0737004346
  532. 2023-02-07 13:27:21.893778 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0737004346
  533. 2023-02-07 13:27:21.893778 97.83% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 0737004346
  534. 2023-02-07 13:27:21.893778 97.83% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 972737004346
  535. 2023-02-07 13:27:21.893778 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....972737004346
  536. 2023-02-07 13:27:21.893778 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....972737004346
  537. 2023-02-07 13:27:21.893778 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....972737004346
  538. 2023-02-07 13:27:21.893778 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....972737004346
  539. 2023-02-07 13:27:21.893778 97.83% [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 FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="972737004346" LIMIT 1
  540. 2023-02-07 13:27:21.893778 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : inbound
  541. 2023-02-07 13:27:21.893778 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 170019686118]
  542. 2023-02-07 13:27:21.893778 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "170019686118" AND deleted = 0 limit 1
  543. 2023-02-07 13:27:21.893778 97.83% [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 ="0737004346" AND A.accountid = '709' limit 1
  544. 2023-02-07 13:27:21.893778 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 0737004346
  545. 2023-02-07 13:27:21.893778 97.83% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
  546. 2023-02-07 13:27:21.893778 97.83% [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
  547. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0737004346
  548. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0737004346
  549. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0737004346
  550. 2023-02-07 13:27:21.913789 97.83% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 0737004346
  551. 2023-02-07 13:27:21.913789 97.83% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 972737004346
  552. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....972737004346
  553. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....972737004346
  554. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....972737004346
  555. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....972737004346
  556. 2023-02-07 13:27:21.913789 97.83% [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 FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="972737004346" LIMIT 1
  557. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call direction : inbound
  558. 2023-02-07 13:27:21.913789 97.83% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
  559. 2023-02-07 13:27:21.913789 97.83% [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
  560. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0737004346
  561. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0737004346
  562. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....0737004346
  563. 2023-02-07 13:27:21.913789 97.83% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 0737004346
  564. 2023-02-07 13:27:21.913789 97.83% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 972737004346
  565. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....972737004346
  566. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....972737004346
  567. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....972737004346
  568. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....972737004346
  569. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="972737004346" AND (accountid = 0 OR status = 1) LIMIT 1
  570. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] call_direction :inbound
  571. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] DID_ACCOUNTID :5203
  572. 2023-02-07 13:27:21.913789 97.83% [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 = '^0737004346.*' OR patterns = '^073700434.*' OR patterns = '^07370043.*' OR patterns = '^0737004.*' OR patterns = '^073700.*' OR patterns = '^07370.*' OR patterns = '^0737.*' OR patterns = '^073.*' OR patterns = '^07.*' OR patterns = '^0.*' OR patterns ='--') AND accountid = 5203 ORDER BY LENGTH(PKGPTR.patterns) DESC
  573. 2023-02-07 13:27:21.913789 97.83% [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 = '0737004346' OR number = '073700434' OR number = '07370043' OR number = '0737004' OR number = '073700' OR number = '07370' OR number = '0737' OR number = '073' OR number = '07' OR number = '0' OR number ='--') OR (number = '0737004346*' OR number = '073700434*' OR number = '07370043*' OR number = '0737004*' OR number = '073700*' OR number = '07370*' OR number = '0737*' OR number = '073*' OR number = '07*' OR number = '0*' OR number ='--') OR (number = '0546322423' OR number = '054632242' OR number = '05463224' OR number = '0546322' OR number = '054632' OR number = '05463' OR number = '0546' OR number = '054' OR number = '05' OR number = '0' OR number ='--') OR (number = '0546322423*' OR number = '054632242*' OR number = '05463224*' OR number = '0546322*' OR number = '054632*' OR number = '05463*' OR number = '0546*' OR number = '054*' OR number = '05*' OR number = '0*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
  574. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1
  575. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '0737004346' OR routing_prefix = '073700434' OR routing_prefix = '07370043' OR routing_prefix = '0737004' OR routing_prefix = '073700' OR routing_prefix = '07370' OR routing_prefix = '0737' OR routing_prefix = '073' OR routing_prefix = '07' OR routing_prefix = '0' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
  576. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
  577. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 709
  578. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 170019686118
  579. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 8.92
  580. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
  581. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 23
  582. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
  583. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
  584. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 23 AND status = 0
  585. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :inbound
  586. 2023-02-07 13:27:21.913789 97.83% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
  587. 2023-02-07 13:27:21.913789 97.83% [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
  588. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....972737004346
  589. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....972737004346
  590. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....972737004346
  591. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....972737004346
  592. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....972737004346
  593. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....972737004346
  594. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....972737004346
  595. 2023-02-07 13:27:21.913789 97.83% [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 FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="972737004346" LIMIT 1
  596. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] call_direction:::::: inbound
  597. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
  598. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 17861
  599. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
  600. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
  601. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.00000
  602. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] comment : 972737004346
  603. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 93
  604. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 709
  605. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
  606. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your8.92 balance Accountid 709 !!!
  607. 2023-02-07 13:27:21.913789 97.83% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Call is free - assigning max length!!! :: 180
  608. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max length duration : 180 minutes
  609. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] INB_FREE:TRUE
  610. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] free_inbound:1
  611. 2023-02-07 13:27:21.913789 97.83% [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
  612. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "5203" AND deleted = 0 limit 1
  613. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML:709
  614. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Userinfo XML:709
  615. 2023-02-07 13:27:21.913789 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML : 709
  616. 2023-02-07 13:27:21.913789 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE accountid = 5203 limit 1
  617. 2023-02-07 13:27:21.933790 97.83% [WARNING] switch_cpp.cpp:1465 [ASTPP] [user_domain] user_domainAmir4660.newsip.israelnumber.com
  618. 2023-02-07 13:27:21.933790 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] maxlength::::::::: 180
  619. 2023-02-07 13:27:21.933790 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"000/00".....0546322423
  620. 2023-02-07 13:27:21.933790 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"000/00".....0546322423
  621. 2023-02-07 13:27:21.933790 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE accountid = 5203 limit 1
  622. 2023-02-07 13:27:21.933790 97.83% [WARNING] switch_cpp.cpp:1465 [ASTPP] [user_domain] user_domainAmir4660.newsip.israelnumber.com
  623. 2023-02-07 13:27:21.933790 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] custom_function_name:::::::::::::::::::::::::custom_inbound_14
  624. 2023-02-07 13:27:21.933790 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] call_type : 14
  625. 2023-02-07 13:27:21.933790 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Generated XML:<?xml version="1.0" encoding="UTF-8" standalone="no"?>
  626. <document type="freeswitch/xml">
  627. <section name="dialplan" description="ASTPP Dialplan">
  628. <context name="default">
  629. <extension name="972737004346">
  630. <condition field="destination_number" expression="0737004346">
  631. <action application="set" data="effective_destination_number=0737004346"/>
  632. <action application="set" data="execute_on_answer=sched_hangup +10800 normal_clearing"/>
  633. <action application="set" data="callstart=2023-02-07 13:27:21"/>
  634. <action application="set" data="hangup_after_bridge=true"/>
  635. <action application="set" data="continue_on_fail=TRUE"/>
  636. <action application="set" data="account_id=709"/>
  637. <action application="set" data="parent_id=0"/>
  638. <action application="set" data="entity_id=0"/>
  639. <action application="set" data="call_processed=internal"/>
  640. <action application="set" data="call_direction=inbound"/>
  641. <action application="set" data="accountname=default"/>
  642. <action application="set" data="filename1=astpp.pbx.lua"/>
  643. <action application="set" data="origination_rates_did=ID:17861|CODE:^972737004346.*|DESTINATION:972737004346|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:14|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:93|ACCID:5203"/>
  644. <action application="set" data="origination_rates=0"/>
  645. <action application="set" data="original_caller_id_name=0546322423"/>
  646. <action application="set" data="original_caller_id_number=0546322423"/>
  647. <action application="limit" data="db 5203 user_5203 2 !SWITCH_CONGESTION"/>
  648. <action application="set" data="effective_caller_id_name=0546322423"/>
  649. <action application="set" data="effective_caller_id_number=0546322423"/>
  650. <action application="set" data="receiver_accid=5203"/>
  651. <action application="export" data="domain_name=Amir4660.newsip.israelnumber.com"/>
  652. <action application="export" data="sip_to_host=Amir4660.newsip.israelnumber.com"/>
  653. <action application="limit" data="db 972737004346 did_972737004346 2 !SWITCH_CONGESTION"/>
  654. <action application="export" data="presence_data=x|||prodtwo(170019686118)|||^972737004346.* // 972737004346 // 0.00000||||||DID|||212.150.158.100"/>
  655. <action application="export" data="call_type=14"/>
  656. <action application="set" data="did_calltype=14"/>
  657. <action application="set" data="did_extensions=972737004346"/>
  658. <action application="transfer" data="972737004346 XML default"/>
  659. </condition>
  660. </extension>
  661. </context>
  662. </section>
  663. </document>
  664. Dialplan: sofia/default/0546322423@212.150.158.100 parsing [default->972737004346] continue=false
  665. Dialplan: sofia/default/0546322423@212.150.158.100 Regex (PASS) [972737004346] destination_number(0737004346) =~ /0737004346/ break=on-false
  666. Dialplan: sofia/default/0546322423@212.150.158.100 Action set(effective_destination_number=0737004346)
  667. Dialplan: sofia/default/0546322423@212.150.158.100 Action set(execute_on_answer=sched_hangup +10800 normal_clearing)
  668. Dialplan: sofia/default/0546322423@212.150.158.100 Action set(callstart=2023-02-07 13:27:21)
  669. Dialplan: sofia/default/0546322423@212.150.158.100 Action set(hangup_after_bridge=true)
  670. Dialplan: sofia/default/0546322423@212.150.158.100 Action set(continue_on_fail=TRUE)
  671. Dialplan: sofia/default/0546322423@212.150.158.100 Action set(account_id=709)
  672. Dialplan: sofia/default/0546322423@212.150.158.100 Action set(parent_id=0)
  673. Dialplan: sofia/default/0546322423@212.150.158.100 Action set(entity_id=0)
  674. Dialplan: sofia/default/0546322423@212.150.158.100 Action set(call_processed=internal)
  675. Dialplan: sofia/default/0546322423@212.150.158.100 Action set(call_direction=inbound)
  676. Dialplan: sofia/default/0546322423@212.150.158.100 Action set(accountname=default)
  677. Dialplan: sofia/default/0546322423@212.150.158.100 Action set(filename1=astpp.pbx.lua)
  678. Dialplan: sofia/default/0546322423@212.150.158.100 Action set(origination_rates_did=ID:17861|CODE:^972737004346.*|DESTINATION:972737004346|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:14|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:93|ACCID:5203)
  679. Dialplan: sofia/default/0546322423@212.150.158.100 Action set(origination_rates=0)
  680. Dialplan: sofia/default/0546322423@212.150.158.100 Action set(original_caller_id_name=0546322423)
  681. Dialplan: sofia/default/0546322423@212.150.158.100 Action set(original_caller_id_number=0546322423)
  682. Dialplan: sofia/default/0546322423@212.150.158.100 Action limit(db 5203 user_5203 2 !SWITCH_CONGESTION)
  683. Dialplan: sofia/default/0546322423@212.150.158.100 Action set(effective_caller_id_name=0546322423)
  684. Dialplan: sofia/default/0546322423@212.150.158.100 Action set(effective_caller_id_number=0546322423)
  685. Dialplan: sofia/default/0546322423@212.150.158.100 Action set(receiver_accid=5203)
  686. Dialplan: sofia/default/0546322423@212.150.158.100 Action export(domain_name=Amir4660.newsip.israelnumber.com)
  687. Dialplan: sofia/default/0546322423@212.150.158.100 Action export(sip_to_host=Amir4660.newsip.israelnumber.com)
  688. Dialplan: sofia/default/0546322423@212.150.158.100 Action limit(db 972737004346 did_972737004346 2 !SWITCH_CONGESTION)
  689. Dialplan: sofia/default/0546322423@212.150.158.100 Action export(presence_data=x|||prodtwo(170019686118)|||^972737004346.* // 972737004346 // 0.00000||||||DID|||212.150.158.100)
  690. Dialplan: sofia/default/0546322423@212.150.158.100 Action export(call_type=14)
  691. Dialplan: sofia/default/0546322423@212.150.158.100 Action set(did_calltype=14)
  692. Dialplan: sofia/default/0546322423@212.150.158.100 Action set(did_extensions=972737004346)
  693. Dialplan: sofia/default/0546322423@212.150.158.100 Action transfer(972737004346 XML default)
  694. 2023-02-07 13:27:21.933790 97.83% [DEBUG] switch_core_state_machine.c:281 (sofia/default/0546322423@212.150.158.100) State Change CS_ROUTING -> CS_EXECUTE
  695. 2023-02-07 13:27:21.933790 97.83% [DEBUG] switch_core_state_machine.c:640 (sofia/default/0546322423@212.150.158.100) State ROUTING going to sleep
  696. 2023-02-07 13:27:21.933790 97.83% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0546322423@212.150.158.100) Running State Change CS_EXECUTE (Cur 5 Tot 1860)
  697. 2023-02-07 13:27:21.933790 97.83% [DEBUG] switch_core_state_machine.c:647 (sofia/default/0546322423@212.150.158.100) State EXECUTE
  698. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_sofia.c:213 sofia/default/0546322423@212.150.158.100 SOFIA EXECUTE
  699. 2023-02-07 13:27:21.933790 97.83% [DEBUG] switch_core_state_machine.c:323 sofia/default/0546322423@212.150.158.100 Standard EXECUTE
  700. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(effective_destination_number=0737004346)
  701. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [effective_destination_number]=[0737004346]
  702. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(execute_on_answer=sched_hangup +10800 normal_clearing)
  703. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [execute_on_answer]=[sched_hangup +10800 normal_clearing]
  704. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(callstart=2023-02-07 13:27:21)
  705. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [callstart]=[2023-02-07 13:27:21]
  706. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(hangup_after_bridge=true)
  707. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [hangup_after_bridge]=[true]
  708. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(continue_on_fail=TRUE)
  709. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [continue_on_fail]=[TRUE]
  710. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(account_id=709)
  711. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [account_id]=[709]
  712. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(parent_id=0)
  713. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [parent_id]=[0]
  714. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(entity_id=0)
  715. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [entity_id]=[0]
  716. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(call_processed=internal)
  717. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [call_processed]=[internal]
  718. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(call_direction=inbound)
  719. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [call_direction]=[inbound]
  720. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(accountname=default)
  721. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [accountname]=[default]
  722. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(filename1=astpp.pbx.lua)
  723. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [filename1]=[astpp.pbx.lua]
  724. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(origination_rates_did=ID:17861|CODE:^972737004346.*|DESTINATION:972737004346|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:14|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:93|ACCID:5203)
  725. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [origination_rates_did]=[ID:17861|CODE:^972737004346.*|DESTINATION:972737004346|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:14|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:93|ACCID:5203]
  726. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(origination_rates=0)
  727. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [origination_rates]=[0]
  728. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(original_caller_id_name=0546322423)
  729. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [original_caller_id_name]=[0546322423]
  730. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(original_caller_id_number=0546322423)
  731. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [original_caller_id_number]=[0546322423]
  732. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 limit(db 5203 user_5203 2 !SWITCH_CONGESTION)
  733. 2023-02-07 13:27:21.933790 97.83% [DEBUG] switch_limit.c:124 incr called: 5203_user_5203 max:2, interval:0
  734. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_db.c:194 Usage for 5203_user_5203 is now 1/2
  735. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(effective_caller_id_name=0546322423)
  736. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [effective_caller_id_name]=[0546322423]
  737. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(effective_caller_id_number=0546322423)
  738. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [effective_caller_id_number]=[0546322423]
  739. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(receiver_accid=5203)
  740. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [receiver_accid]=[5203]
  741. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(domain_name=Amir4660.newsip.israelnumber.com)
  742. 2023-02-07 13:27:21.933790 97.83% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [domain_name]=[Amir4660.newsip.israelnumber.com]
  743. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(sip_to_host=Amir4660.newsip.israelnumber.com)
  744. 2023-02-07 13:27:21.933790 97.83% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [sip_to_host]=[Amir4660.newsip.israelnumber.com]
  745. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 limit(db 972737004346 did_972737004346 2 !SWITCH_CONGESTION)
  746. 2023-02-07 13:27:21.933790 97.83% [DEBUG] switch_limit.c:124 incr called: 972737004346_did_972737004346 max:2, interval:0
  747. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_db.c:194 Usage for 972737004346_did_972737004346 is now 1/2
  748. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(presence_data=x|||prodtwo(170019686118)|||^972737004346.* // 972737004346 // 0.00000||||||DID|||212.150.158.100)
  749. 2023-02-07 13:27:21.933790 97.83% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[x|||prodtwo(170019686118)|||^972737004346.* // 972737004346 // 0.00000||||||DID|||212.150.158.100]
  750. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(call_type=14)
  751. 2023-02-07 13:27:21.933790 97.83% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [call_type]=[14]
  752. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(did_calltype=14)
  753. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [did_calltype]=[14]
  754. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(did_extensions=972737004346)
  755. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [did_extensions]=[972737004346]
  756. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 transfer(972737004346 XML default)
  757. 2023-02-07 13:27:21.933790 97.83% [DEBUG] switch_ivr.c:2289 (sofia/default/0546322423@212.150.158.100) State Change CS_EXECUTE -> CS_ROUTING
  758. 2023-02-07 13:27:21.933790 97.83% [NOTICE] switch_ivr.c:2296 Transfer sofia/default/0546322423@212.150.158.100 to XML[972737004346@default]
  759. 2023-02-07 13:27:21.933790 97.83% [DEBUG] switch_core_state_machine.c:647 (sofia/default/0546322423@212.150.158.100) State EXECUTE going to sleep
  760. 2023-02-07 13:27:21.933790 97.83% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0546322423@212.150.158.100) Running State Change CS_ROUTING (Cur 5 Tot 1860)
  761. 2023-02-07 13:27:21.933790 97.83% [DEBUG] switch_core_state_machine.c:640 (sofia/default/0546322423@212.150.158.100) State ROUTING
  762. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_sofia.c:149 Call appears to be already acknowledged
  763. 2023-02-07 13:27:21.933790 97.83% [DEBUG] mod_sofia.c:158 sofia/default/0546322423@212.150.158.100 SOFIA ROUTING
  764. 2023-02-07 13:27:21.933790 97.83% [DEBUG] switch_core_state_machine.c:230 sofia/default/0546322423@212.150.158.100 Standard ROUTING
  765. 2023-02-07 13:27:21.933790 97.83% [INFO] mod_dialplan_xml.c:639 Processing 0546322423 <0546322423>->972737004346 in context default
  766. 2023-02-07 13:27:21.933790 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
  767. 2023-02-07 13:27:21.933790 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  768. 2023-02-07 13:27:21.953782 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] blind_flag: 0
  769. 2023-02-07 13:27:21.953782 97.83% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 972737004346
  770. 2023-02-07 13:27:21.953782 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '972737004346' limit 1
  771. 2023-02-07 13:27:21.953782 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '972737004346' AND status=0 limit 1
  772. 2023-02-07 13:27:21.953782 97.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Generated XML:
  773. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  774. <document type="freeswitch/xml">
  775. <section name="dialplan" description="ASTPP Dialplan">
  776. <context name="default">
  777. <extension name="972737004346">
  778. <condition field="destination_number" expression="972737004346">
  779. <action application="log" data="INFO ASTPP - Calling Card Call"/>
  780. <action application="answer"/>
  781. <action application="sleep" data="2000"/>
  782. <action application="lua" data="astpp-callingcards.lua"/>
  783. </condition>
  784. </extension>
  785. </context>
  786. </section>
  787. </document>
  788. Dialplan: sofia/default/0546322423@212.150.158.100 parsing [default->972737004346] continue=false
  789. Dialplan: sofia/default/0546322423@212.150.158.100 Regex (PASS) [972737004346] destination_number(972737004346) =~ /972737004346/ break=on-false
  790. Dialplan: sofia/default/0546322423@212.150.158.100 Action log(INFO ASTPP - Calling Card Call)
  791. Dialplan: sofia/default/0546322423@212.150.158.100 Action answer()
  792. Dialplan: sofia/default/0546322423@212.150.158.100 Action sleep(2000)
  793. Dialplan: sofia/default/0546322423@212.150.158.100 Action lua(astpp-callingcards.lua)
  794. 2023-02-07 13:27:21.953782 97.83% [DEBUG] switch_core_state_machine.c:281 (sofia/default/0546322423@212.150.158.100) State Change CS_ROUTING -> CS_EXECUTE
  795. 2023-02-07 13:27:21.953782 97.83% [DEBUG] switch_core_state_machine.c:640 (sofia/default/0546322423@212.150.158.100) State ROUTING going to sleep
  796. 2023-02-07 13:27:21.953782 97.83% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0546322423@212.150.158.100) Running State Change CS_EXECUTE (Cur 5 Tot 1860)
  797. 2023-02-07 13:27:21.953782 97.83% [DEBUG] switch_core_state_machine.c:647 (sofia/default/0546322423@212.150.158.100) State EXECUTE
  798. 2023-02-07 13:27:21.953782 97.83% [DEBUG] mod_sofia.c:213 sofia/default/0546322423@212.150.158.100 SOFIA EXECUTE
  799. 2023-02-07 13:27:21.953782 97.83% [DEBUG] switch_core_state_machine.c:323 sofia/default/0546322423@212.150.158.100 Standard EXECUTE
  800. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 log(INFO ASTPP - Calling Card Call)
  801. 2023-02-07 13:27:21.953782 97.83% [INFO] mod_dptools.c:1879 ASTPP - Calling Card Call
  802. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 answer()
  803. 2023-02-07 13:27:21.953782 97.83% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/0546322423@212.150.158.100] 195.201.136.88 port 22604 -> 212.150.158.100 port 13310 codec: 8 ms: 20
  804. 2023-02-07 13:27:21.953782 97.83% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms
  805. 2023-02-07 13:27:21.953782 97.83% [DEBUG] switch_core_media.c:9089 sofia/default/0546322423@212.150.158.100 Set 2833 dtmf send payload to 101
  806. 2023-02-07 13:27:21.953782 97.83% [DEBUG] switch_core_media.c:9096 sofia/default/0546322423@212.150.158.100 Set 2833 dtmf receive payload to 101
  807. 2023-02-07 13:27:21.953782 97.83% [DEBUG] switch_core_media.c:9119 sofia/default/0546322423@212.150.158.100 Set rtp dtmf delay to 40
  808. 2023-02-07 13:27:21.953782 97.83% [DEBUG] mod_sofia.c:913 Local SDP sofia/default/0546322423@212.150.158.100:
  809. v=0
  810. o=FreeSWITCH 1675753837 1675753838 IN IP4 195.201.136.88
  811. s=FreeSWITCH
  812. c=IN IP4 195.201.136.88
  813. t=0 0
  814. m=audio 22604 RTP/AVP 8 101
  815. a=rtpmap:8 PCMA/8000
  816. a=rtpmap:101 telephone-event/8000
  817. a=fmtp:101 0-15
  818. a=ptime:20
  819. a=sendrecv
  820. m=video 0 RTP/AVP 19
  821.  
  822. 2023-02-07 13:27:21.953782 97.83% [NOTICE] mod_dptools.c:1419 Channel [sofia/default/0546322423@212.150.158.100] has been answered
  823. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 sched_hangup(+10800 normal_clearing)
  824. 2023-02-07 13:27:21.953782 97.83% [DEBUG] sofia.c:7499 Channel sofia/default/0546322423@212.150.158.100 entering state [completed][200]
  825. 2023-02-07 13:27:21.953782 97.83% [DEBUG] switch_scheduler.c:263 Added task 54 switch_ivr_schedule_hangup (02641f90-b524-448f-8f56-9cbd99102e61) to run at 1675787241
  826. 2023-02-07 13:27:21.953782 97.83% [DEBUG] switch_channel.c:3950 (sofia/default/0546322423@212.150.158.100) Callstate Change RINGING -> ACTIVE
  827. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 sleep(2000)
  828. 2023-02-07 13:27:22.013779 97.83% [DEBUG] sofia.c:7499 Channel sofia/default/0546322423@212.150.158.100 entering state [ready][200]
  829. 2023-02-07 13:27:22.033778 97.83% [DEBUG] sofia.c:7499 Channel sofia/default/0546322423@212.150.158.100 entering state [received][100]
  830. 2023-02-07 13:27:22.033778 97.83% [DEBUG] sofia.c:7509 Remote SDP:
  831. v=0
  832. o=root 7779 7780 IN IP4 82.166.67.145
  833. s=session
  834. c=IN IP4 82.166.67.145
  835. t=0 0
  836. m=audio 9804 RTP/AVP 8 101
  837. a=rtpmap:8 PCMA/8000
  838. a=rtpmap:101 telephone-event/8000
  839. a=fmtp:101 0-16
  840. a=silenceSupp:off - - - -
  841. a=ptime:20
  842.  
  843. 2023-02-07 13:27:22.033778 97.83% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  844. 2023-02-07 13:27:22.033778 97.83% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  845. 2023-02-07 13:27:22.033778 97.83% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  846. 2023-02-07 13:27:22.033778 97.83% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  847. 2023-02-07 13:27:22.033778 97.83% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  848. 2023-02-07 13:27:22.033778 97.83% [DEBUG] switch_core_media.c:5973 sofia/default/0546322423@212.150.158.100 Set 2833 dtmf send payload to 101 recv payload to 101
  849. 2023-02-07 13:27:22.033778 97.83% [DEBUG] switch_core_media.c:8765 Audio params changed for sofia/default/0546322423@212.150.158.100 from 212.150.158.100:13310 to 82.166.67.145:9804
  850. 2023-02-07 13:27:22.033778 97.83% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/0546322423@212.150.158.100] 195.201.136.88 port 22604 -> 82.166.67.145 port 9804 codec: 8 ms: 20
  851. 2023-02-07 13:27:22.033778 97.83% [DEBUG] switch_core_media.c:8806 AUDIO RTP CHANGING DEST TO: [82.166.67.145:9804]
  852. 2023-02-07 13:27:22.033778 97.83% [DEBUG] sofia.c:8454 Processing updated SDP
  853. 2023-02-07 13:27:22.053786 97.83% [DEBUG] sofia.c:7499 Channel sofia/default/0546322423@212.150.158.100 entering state [completed][200]
  854. 2023-02-07 13:27:22.093798 97.83% [DEBUG] sofia.c:7499 Channel sofia/default/0546322423@212.150.158.100 entering state [ready][200]
  855. 2023-02-07 13:27:22.213778 97.83% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed.
  856. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 lua(astpp-callingcards.lua)
  857. 2023-02-07 13:27:23.973782 97.73% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
  858. 2023-02-07 13:27:23.973782 97.73% [NOTICE] switch_cpp.cpp:1465 [ASTPP] SECTION
  859. 2023-02-07 13:27:23.973782 97.73% [DEBUG] switch_core_file.c:444 File /usr/share/freeswitch/sounds/en/us/callie/astpp-welcome.wav sample rate 16000 doesn't match requested rate 8000
  860. 2023-02-07 13:27:23.973782 97.73% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
  861. 2023-02-07 13:27:25.773783 97.67% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-welcome.wav
  862. 2023-02-07 13:27:25.773783 97.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_ani] Query :SELECT * FROM ani_map WHERE number = 0546322423
  863. 2023-02-07 13:27:25.773783 97.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT *,(select currencyrate from currency where id=currency_id) as currencyrate FROM accounts WHERE (number = "5203" OR id="5203") AND status=0 AND deleted=0 limit 1
  864. 2023-02-07 13:27:25.773783 97.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [validate_card_usage] Query :SELECT DATE_FORMAT('2033-01-09 13:46:06' , '%Y%m%d%H%i%s') AS expiry
  865. 2023-02-07 13:27:25.773783 97.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [validate_card_usage] Query :SELECT DATE_FORMAT('2023-02-07 13:27:25' , '%Y%m%d%H%i%s') AS expiry
  866. 2023-02-07 13:27:25.773783 97.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 389385276538]
  867. 2023-02-07 13:27:25.773783 97.67% [DEBUG] switch_core_file.c:444 File /usr/share/freeswitch/sounds/en/us/callie/astpp-phonenum.wav sample rate 16000 doesn't match requested rate 8000
  868. 2023-02-07 13:27:25.773783 97.67% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
  869. 2023-02-07 13:27:26.993802 97.67% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [102@195.201.136.88] from ip 45.254.254.147
  870. 2023-02-07 13:27:27.413804 97.67% [WARNING] sofia_reg.c:3223 Can't find user [102@195.201.136.88] from 45.254.254.147
  871. You must define a domain called '195.201.136.88' in your directory and add a user with the id="102" attribute
  872. and you must configure your device to use the proper domain in its authentication credentials.
  873. 2023-02-07 13:27:27.413804 97.67% [WARNING] sofia_reg.c:1806 SIP auth failure (REGISTER) on sofia profile 'default' for [102@195.201.136.88] from ip 45.254.254.147
  874. 2023-02-07 13:27:29.473782 97.60% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-phonenum.wav
  875. 2023-02-07 13:27:29.833796 97.60% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [302@195.201.136.88] from ip 45.120.203.97
  876. 2023-02-07 13:27:30.293822 97.60% [WARNING] sofia_reg.c:3223 Can't find user [302@195.201.136.88] from 45.120.203.97
  877. You must define a domain called '195.201.136.88' in your directory and add a user with the id="302" attribute
  878. and you must configure your device to use the proper domain in its authentication credentials.
  879. 2023-02-07 13:27:30.293822 97.60% [WARNING] sofia_reg.c:1806 SIP auth failure (REGISTER) on sofia profile 'default' for [302@195.201.136.88] from ip 45.120.203.97
  880. 2023-02-07 13:27:30.593779 97.57% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 9:1800
  881. 2023-02-07 13:27:30.593779 97.57% [INFO] switch_channel.c:527 RECV DTMF 9:1800
  882. 2023-02-07 13:27:31.093801 97.57% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 7:1800
  883. 2023-02-07 13:27:31.093801 97.57% [INFO] switch_channel.c:527 RECV DTMF 7:1800
  884. 2023-02-07 13:27:31.613780 97.53% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 2:1760
  885. 2023-02-07 13:27:31.613780 97.53% [INFO] switch_channel.c:527 RECV DTMF 2:1760
  886. 2023-02-07 13:27:33.053785 97.60% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 3:1800
  887. 2023-02-07 13:27:33.053785 97.60% [INFO] switch_channel.c:527 RECV DTMF 3:1800
  888. 2023-02-07 13:27:33.533783 97.63% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 5:1760
  889. 2023-02-07 13:27:33.533783 97.63% [INFO] switch_channel.c:527 RECV DTMF 5:1760
  890. 2023-02-07 13:27:34.033780 97.63% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 5:1920
  891. 2023-02-07 13:27:34.033780 97.63% [INFO] switch_channel.c:527 RECV DTMF 5:1920
  892. 2023-02-07 13:27:34.553783 97.67% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 5:2000
  893. 2023-02-07 13:27:34.553783 97.67% [INFO] switch_channel.c:527 RECV DTMF 5:2000
  894. 2023-02-07 13:27:35.053774 97.67% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 5:1840
  895. 2023-02-07 13:27:35.053774 97.67% [INFO] switch_channel.c:527 RECV DTMF 5:1840
  896. 2023-02-07 13:27:35.553774 97.67% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 5:1800
  897. 2023-02-07 13:27:35.553774 97.67% [INFO] switch_channel.c:527 RECV DTMF 5:1800
  898. 2023-02-07 13:27:35.593776 97.67% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [302@195.201.136.88] from ip 45.254.246.243
  899. 2023-02-07 13:27:36.033787 97.67% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 5:1760
  900. 2023-02-07 13:27:36.033787 97.67% [INFO] switch_channel.c:527 RECV DTMF 5:1760
  901. 2023-02-07 13:27:36.093786 97.67% [WARNING] sofia_reg.c:3223 Can't find user [302@195.201.136.88] from 45.254.246.243
  902. You must define a domain called '195.201.136.88' in your directory and add a user with the id="302" attribute
  903. and you must configure your device to use the proper domain in its authentication credentials.
  904. 2023-02-07 13:27:36.093786 97.67% [WARNING] sofia_reg.c:1806 SIP auth failure (REGISTER) on sofia profile 'default' for [302@195.201.136.88] from ip 45.254.246.243
  905. 2023-02-07 13:27:36.573785 97.67% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 5:2080
  906. 2023-02-07 13:27:36.573785 97.67% [INFO] switch_channel.c:527 RECV DTMF 5:2080
  907. 2023-02-07 13:27:37.053772 97.67% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [101@195.201.136.88] from ip 128.90.102.195
  908. 2023-02-07 13:27:37.073777 97.67% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF #:1760
  909. span style="color: green; font-weight: bold;"> 2023-02-07 13:27:37.073777 97.67% [INFO] switch_channel.c:527 RECV DTMF #:1760
  910. span style="color: green; font-weight: bold;"> 2023-02-07 13:27:37.073777 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [CHECK_destination] Dialed destination number :97235555555
  911. 2023-02-07 13:27:37.073777 97.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 = '97235555555' OR number = '9723555555' OR number = '972355555' OR number = '97235555' OR number = '9723555' OR number = '972355' OR number = '97235' OR number = '9723' OR number = '972' OR number = '97' OR number = '9' OR number ='--') OR (number = '97235555555*' OR number = '9723555555*' OR number = '972355555*' OR number = '97235555*' OR number = '9723555*' OR number = '972355*' OR number = '97235*' OR number = '9723*' OR number = '972*' OR number = '97*' OR number = '9*' OR number ='--') OR (number = '0546322423' OR number = '054632242' OR number = '05463224' OR number = '0546322' OR number = '054632' OR number = '05463' OR number = '0546' OR number = '054' OR number = '05' OR number = '0' OR number ='--') OR (number = '0546322423*' OR number = '054632242*' OR number = '05463224*' OR number = '0546322*' OR number = '054632*' OR number = '05463*' OR number = '0546*' OR number = '054*' OR number = '05*' OR number = '0*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
  912. 2023-02-07 13:27:37.073777 97.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1
  913. 2023-02-07 13:27:37.073777 97.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '97235555555' OR routing_prefix = '9723555555' OR routing_prefix = '972355555' OR routing_prefix = '97235555' OR routing_prefix = '9723555' OR routing_prefix = '972355' OR routing_prefix = '97235' 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
  914. 2023-02-07 13:27:37.073777 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
  915. 2023-02-07 13:27:37.073777 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 5203
  916. 2023-02-07 13:27:37.073777 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 389385276538
  917. 2023-02-07 13:27:37.073777 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 17.78002
  918. 2023-02-07 13:27:37.073777 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
  919. 2023-02-07 13:27:37.073777 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 137
  920. 2023-02-07 13:27:37.073777 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
  921. 2023-02-07 13:27:37.073777 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
  922. 2023-02-07 13:27:37.073777 97.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 = 3 AND status=0 limit 1
  923. 2023-02-07 13:27:37.073777 97.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"00/*,+/*".....97235555555
  924. 2023-02-07 13:27:37.073777 97.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"00/*,+/*".....97235555555
  925. 2023-02-07 13:27:37.073777 97.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^97235555555.*' OR blocked_patterns = '^9723555555.*' OR blocked_patterns = '^972355555.*' OR blocked_patterns = '^97235555.*' OR blocked_patterns = '^9723555.*' OR blocked_patterns = '^972355.*' OR blocked_patterns = '^97235.*' OR blocked_patterns = '^9723.*' OR blocked_patterns = '^972.*' OR blocked_patterns = '^97.*' OR blocked_patterns = '^9.*' OR blocked_patterns ='--') AND accountid = 5203 limit 1
  926. 2023-02-07 13:27:37.073777 97.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 = '^97235555555.*' OR patterns = '^9723555555.*' OR patterns = '^972355555.*' OR patterns = '^97235555.*' OR patterns = '^9723555.*' OR patterns = '^972355.*' OR patterns = '^97235.*' OR patterns = '^9723.*' OR patterns = '^972.*' OR patterns = '^97.*' OR patterns = '^9.*' OR patterns ='--') AND accountid = 5203 ORDER BY LENGTH(PKGPTR.patterns) DESC
  927. 2023-02-07 13:27:37.093773 97.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 137 AND status = 0
  928. 2023-02-07 13:27:37.093773 97.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :
  929. 2023-02-07 13:27:37.093773 97.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 137 AND status = 0
  930. 2023-02-07 13:27:37.093773 97.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^97235555555.*' OR pattern = '^9723555555.*' OR pattern = '^972355555.*' OR pattern = '^97235555.*' OR pattern = '^9723555.*' OR pattern = '^972355.*' OR pattern = '^97235.*' OR pattern = '^9723.*' OR pattern = '^972.*' OR pattern = '^97.*' OR pattern = '^9.*' OR pattern ='--') AND status = 0 AND (pricelist_id = 137 OR accountid=5203)  ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC LIMIT 1
  931. 2023-02-07 13:27:37.093773 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] call_direction::::::
  932. 2023-02-07 13:27:37.093773 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
  933. 2023-02-07 13:27:37.093773 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 46448781
  934. 2023-02-07 13:27:37.093773 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
  935. 2023-02-07 13:27:37.093773 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
  936. 2023-02-07 13:27:37.093773 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.05125
  937. 2023-02-07 13:27:37.093773 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] comment : IsraelOther
  938. 2023-02-07 13:27:37.093773 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 0
  939. 2023-02-07 13:27:37.093773 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 5203
  940. 2023-02-07 13:27:37.093773 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Trunk ID: 0
  941. 2023-02-07 13:27:37.093773 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Routing type:
  942. 2023-02-07 13:27:37.093773 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
  943. 2023-02-07 13:27:37.093773 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your17.78002 balance Accountid 5203 !!!
  944. 2023-02-07 13:27:37.093773 97.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Limiting call to config max length 180 mins!
  945. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 limit(db 389385276538 db_389385276538 2)
  946. 2023-02-07 13:27:37.093773 97.67% [DEBUG] switch_limit.c:124 incr called: 389385276538_db_389385276538 max:2, interval:0
  947. 2023-02-07 13:27:37.093773 97.67% [DEBUG] mod_db.c:194 Usage for 389385276538_db_389385276538 is now 1/2
  948. 2023-02-07 13:27:37.093773 97.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] 180 Minutes
  949. 2023-02-07 13:27:37.093773 97.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [SAY_TIMELIMIT] MINUTES 180
  950. 2023-02-07 13:27:37.093773 97.67% [DEBUG] switch_core_file.c:444 File /usr/share/freeswitch/sounds/en/us/callie/astpp-this-call-will-last.wav sample rate 16000 doesn't match requested rate 8000
  951. 2023-02-07 13:27:37.093773 97.67% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
  952. 2023-02-07 13:27:37.373782 97.67% [WARNING] sofia_reg.c:3223 Can't find user [101@195.201.136.88] from 128.90.102.195
  953. You must define a domain called '195.201.136.88' in your directory and add a user with the id="101" attribute
  954. and you must configure your device to use the proper domain in its authentication credentials.
  955. 2023-02-07 13:27:37.373782 97.67% [WARNING] sofia_reg.c:1806 SIP auth failure (REGISTER) on sofia profile 'default' for [101@195.201.136.88] from ip 128.90.102.195
  956. 2023-02-07 13:27:38.273778 97.67% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-this-call-will-last.wav
  957. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 say(en number pronounced 180)
  958. 2023-02-07 13:27:38.273778 97.67% [WARNING] mod_sndfile.c:281 Error Opening File [/usr/share/freeswitch/sounds/en/us/callie/digits/1.wav] [System error : No such file or directory.]
  959. 2023-02-07 13:27:38.273778 97.67% [ERR] mod_dptools.c:5045 Can't open file /usr/share/freeswitch/sounds/en/us/callie/digits/1.wav
  960. 2023-02-07 13:27:38.273778 97.67% [WARNING] mod_sndfile.c:281 Error Opening File [/usr/share/freeswitch/sounds/en/us/callie/digits/hundred.wav] [System error : No such file or directory.]
  961. 2023-02-07 13:27:38.273778 97.67% [ERR] mod_dptools.c:5045 Can't open file /usr/share/freeswitch/sounds/en/us/callie/digits/hundred.wav
  962. 2023-02-07 13:27:38.273778 97.67% [WARNING] mod_sndfile.c:281 Error Opening File [/usr/share/freeswitch/sounds/en/us/callie/digits/80.wav] [System error : No such file or directory.]
  963. 2023-02-07 13:27:38.273778 97.67% [ERR] mod_dptools.c:5045 Can't open file /usr/share/freeswitch/sounds/en/us/callie/digits/80.wav
  964. 2023-02-07 13:27:38.273778 97.67% [DEBUG] switch_core_file.c:444 File /usr/share/freeswitch/sounds/en/us/callie/astpp-minutes.wav sample rate 16000 doesn't match requested rate 8000
  965. 2023-02-07 13:27:38.273778 97.67% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
  966. 2023-02-07 13:27:38.893787 97.67% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-minutes.wav
  967. 2023-02-07 13:27:38.893787 97.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES]123131 Query rate_carrier_id   :0
  968. 2023-02-07 13:27:38.893787 97.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES_TRUNKS] Query :SELECT GROUP_CONCAT(trunk_id) as ids FROM routing WHERE pricelist_id=137 ORDER by id asc
  969. 2023-02-07 13:27:38.893787 97.67% [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 = '^97235555555.*' OR pattern = '^9723555555.*' OR pattern = '^972355555.*' OR pattern = '^97235555.*' OR pattern = '^9723555.*' OR pattern = '^972355.*' OR pattern = '^97235.*' OR pattern = '^9723.*' OR pattern = '^972.*' OR pattern = '^97.*' OR pattern = '^9.*' OR pattern ='--') AND TR.status = 0  AND TR.trunk_id IN (25,33) ORDER by LENGTH (pattern) DESC,TR.cost ASC,TR.precedence ASC, TK.precedence
  970. 2023-02-07 13:27:38.893787 97.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [HYBRID_ROUTING]LCR with Cost in 2nd stage
  971. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Termination Rates Information ===================
  972. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 28031785
  973. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Code : ^972.*
  974. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Destination : IsraelOther
  975. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
  976. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Free Seconds : 0
  977. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Prefix : ^972.*
  978. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Strip : 0
  979. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Termination rate id : 25
  980. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Gateway name : tata3
  981. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Failover gateway :
  982. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Vendor id : 587
  983. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Max channels : 0
  984. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] trunk_name : tata3
  985. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] =================================================================
  986. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Termination Rates Information ===================
  987. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 28239385
  988. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Code : ^972.*
  989. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Destination : IsraelOther
  990. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
  991. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Free Seconds : 0
  992. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Prefix : ^972.*
  993. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Strip : 0
  994. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Termination rate id : 33
  995. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Gateway name : tata3
  996. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Failover gateway :
  997. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Vendor id : 587
  998. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Max channels : 0
  999. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] trunk_name : tata3
  1000. 2023-02-07 13:27:38.893787 97.67% [INFO] switch_cpp.cpp:1465 [ASTPP] =================================================================
  1001. 2023-02-07 13:27:38.893787 97.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Loss less routing flag ::> 1
  1002. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(call_processed=internal)
  1003. 2023-02-07 13:27:38.893787 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [call_processed]=[internal]
  1004. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(callstart=2023-02-07 13:27:38)
  1005. 2023-02-07 13:27:38.893787 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [callstart]=[2023-02-07 13:27:38]
  1006. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(originated_destination_number=97235555555)
  1007. 2023-02-07 13:27:38.893787 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [originated_destination_number]=[97235555555]
  1008. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(effective_destination_number=97235555555)
  1009. 2023-02-07 13:27:38.893787 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [effective_destination_number]=[97235555555]
  1010. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(continue_on_fail=true)
  1011. 2023-02-07 13:27:38.893787 97.67% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [continue_on_fail]=[true]
  1012. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(hangup_after_bridge=true)
  1013. 2023-02-07 13:27:38.893787 97.67% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [hangup_after_bridge]=[true]
  1014. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(account_id=5203)
  1015. 2023-02-07 13:27:38.893787 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [account_id]=[5203]
  1016. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(account_type=0)
  1017. 2023-02-07 13:27:38.893787 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [account_type]=[0]
  1018. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(resellerid=0)
  1019. 2023-02-07 13:27:38.893787 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [resellerid]=[0]
  1020. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(parent_id=0)
  1021. 2023-02-07 13:27:38.893787 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [parent_id]=[0]
  1022. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(accountcode=389385276538)
  1023. 2023-02-07 13:27:38.893787 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [accountcode]=[389385276538]
  1024. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(call_direction=outbound)
  1025. 2023-02-07 13:27:38.893787 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [call_direction]=[outbound]
  1026. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(calltype=CALLINGCARD)
  1027. 2023-02-07 13:27:38.893787 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [calltype]=[CALLINGCARD]
  1028. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(origination_rates=ID:46448781|CODE:^972.*|DESTINATION:IsraelOther|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.05125|INC:10|INITIALBLOCK:30|RATEGROUP:137|MARKUP:0|CI:0|ACCID:5203)
  1029. 2023-02-07 13:27:38.893787 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [origination_rates]=[ID:46448781|CODE:^972.*|DESTINATION:IsraelOther|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.05125|INC:10|INITIALBLOCK:30|RATEGROUP:137|MARKUP:0|CI:0|ACCID:5203]
  1030. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(execute_on_answer=sched_hangup +10800)
  1031. 2023-02-07 13:27:38.893787 97.67% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [execute_on_answer]=[sched_hangup +10800]
  1032. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(process_cdr=true)
  1033. 2023-02-07 13:27:38.893787 97.67% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [process_cdr]=[true]
  1034. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 sched_hangup(+10800)
  1035. 2023-02-07 13:27:38.893787 97.67% [DEBUG] switch_scheduler.c:263 Added task 55 switch_ivr_schedule_hangup (02641f90-b524-448f-8f56-9cbd99102e61) to run at 1675787258
  1036. 2023-02-07 13:27:38.893787 97.67% [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 = 5203 AND status != '1' LIMIT 1
  1037. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(original_caller_id_name=201501718238)
  1038. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [original_caller_id_name]=[201501718238]
  1039. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(original_caller_id_number=201501718238)
  1040. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [original_caller_id_number]=[201501718238]
  1041. 2023-02-07 13:27:38.913777 97.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 = 3 AND status=0 limit 1
  1042. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(presence_data=x|||Amir(389385276538)|||^972.* // IsraelOther // 0.05125|||tata3 // ^972.* // IsraelOther // 0.02050|||CC)
  1043. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[x|||Amir(389385276538)|||^972.* // IsraelOther // 0.05125|||tata3 // ^972.* // IsraelOther // 0.02050|||CC]
  1044. 2023-02-07 13:27:38.913777 97.67% [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 = 587) AND status=0 limit 1
  1045. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(effective_caller_id_name=201501718238)
  1046. 2023-02-07 13:27:38.913777 97.67% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [effective_caller_id_name]=[201501718238]
  1047. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(effective_caller_id_number=201501718238)
  1048. 2023-02-07 13:27:38.913777 97.67% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [effective_caller_id_number]=[201501718238]
  1049. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(effective_caller_id_name=201501718238)
  1050. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [effective_caller_id_name]=[201501718238]
  1051. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(effective_caller_id_number=201501718238)
  1052. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [effective_caller_id_number]=[201501718238]
  1053. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :0/0.....97235555555
  1054. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(termination_rates=ID:28031785|CODE:^972.*|DESTINATION:IsraelOther|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.02050|INC:1|TRUNK:25|PROVIDER:587)
  1055. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [termination_rates]=[ID:28031785|CODE:^972.*|DESTINATION:IsraelOther|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.02050|INC:1|TRUNK:25|PROVIDER:587]
  1056. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(trunk_id=25)
  1057. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [trunk_id]=[25]
  1058. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(provider_id=587)
  1059. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [provider_id]=[587]
  1060. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 bridge([leg_timeout=0]sofia/gateway/tata3/97235555555)
  1061. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1954 (sofia/default/0546322423@212.150.158.100) Callstate Change ACTIVE -> RING_WAIT
  1062. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [domain_name]=[Amir4660.newsip.israelnumber.com] to event
  1063. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [sip_to_host]=[Amir4660.newsip.israelnumber.com] to event
  1064. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [presence_data]=[x|||Amir(389385276538)|||^972.* // IsraelOther // 0.05125|||tata3 // ^972.* // IsraelOther // 0.02050|||CC] to event
  1065. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [call_type]=[14] to event
  1066. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [call_processed]=[internal] to event
  1067. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [callstart]=[2023-02-07 13:27:38] to event
  1068. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [originated_destination_number]=[97235555555] to event
  1069. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [effective_destination_number]=[97235555555] to event
  1070. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [account_id]=[5203] to event
  1071. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [account_type]=[0] to event
  1072. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [resellerid]=[0] to event
  1073. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [parent_id]=[0] to event
  1074. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [accountcode]=[389385276538] to event
  1075. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [call_direction]=[outbound] to event
  1076. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [calltype]=[CALLINGCARD] to event
  1077. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [origination_rates]=[ID:46448781|CODE:^972.*|DESTINATION:IsraelOther|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.05125|INC:10|INITIALBLOCK:30|RATEGROUP:137|MARKUP:0|CI:0|ACCID:5203] to event
  1078. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [original_caller_id_name]=[201501718238] to event
  1079. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [original_caller_id_number]=[201501718238] to event
  1080. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [presence_data]=[x|||Amir(389385276538)|||^972.* // IsraelOther // 0.05125|||tata3 // ^972.* // IsraelOther // 0.02050|||CC] to event
  1081. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [effective_caller_id_name]=[201501718238] to event
  1082. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [effective_caller_id_number]=[201501718238] to event
  1083. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [termination_rates]=[ID:28031785|CODE:^972.*|DESTINATION:IsraelOther|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.02050|INC:1|TRUNK:25|PROVIDER:587] to event
  1084. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [trunk_id]=[25] to event
  1085. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [provider_id]=[587] to event
  1086. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  1087. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_ivr_originate.c:2843 Parsing session specific variables
  1088. 2023-02-07 13:27:38.913777 97.67% [NOTICE] switch_channel.c:1123 New Channel sofia/default/97235555555 [321b749b-dd3b-480e-bd59-c6e7ca1ddfb7]
  1089. 2023-02-07 13:27:38.913777 97.67% [DEBUG] mod_sofia.c:5121 (sofia/default/97235555555) State Change CS_NEW -> CS_INIT
  1090. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/97235555555) Running State Change CS_INIT (Cur 6 Tot 1861)
  1091. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/97235555555) State INIT
  1092. 2023-02-07 13:27:38.913777 97.67% [DEBUG] mod_sofia.c:97 sofia/default/97235555555 SOFIA INIT
  1093. 2023-02-07 13:27:38.913777 97.67% [INFO] sofia_glue.c:1651 sofia/default/97235555555 sending invite call-id: (null)
  1094. 2023-02-07 13:27:38.913777 97.67% [DEBUG] sofia_glue.c:1654 sofia/default/97235555555 sending invite version: 1.10.7 -release-19-883d2cb662 64bit
  1095. Local SDP:
  1096. v=0
  1097. o=FreeSWITCH 1675744024 1675744025 IN IP4 195.201.136.88
  1098. s=FreeSWITCH
  1099. c=IN IP4 195.201.136.88
  1100. t=0 0
  1101. m=audio 32434 RTP/AVP 8 101
  1102. a=rtpmap:8 PCMA/8000
  1103. a=rtpmap:101 telephone-event/8000
  1104. a=fmtp:101 0-15
  1105. a=ptime:20
  1106. a=sendrecv
  1107.  
  1108. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_core_state_machine.c:40 sofia/default/97235555555 Standard INIT
  1109. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_core_state_machine.c:48 (sofia/default/97235555555) State Change CS_INIT -> CS_ROUTING
  1110. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/97235555555) State INIT going to sleep
  1111. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/97235555555) Running State Change CS_ROUTING (Cur 6 Tot 1861)
  1112. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/97235555555) State ROUTING
  1113. 2023-02-07 13:27:38.913777 97.67% [DEBUG] mod_sofia.c:158 sofia/default/97235555555 SOFIA ROUTING
  1114. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_ivr_originate.c:67 (sofia/default/97235555555) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  1115. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/97235555555) State ROUTING going to sleep
  1116. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/97235555555) Running State Change CS_CONSUME_MEDIA (Cur 6 Tot 1861)
  1117. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_core_state_machine.c:659 (sofia/default/97235555555) State CONSUME_MEDIA
  1118. 2023-02-07 13:27:38.913777 97.67% [DEBUG] switch_core_state_machine.c:659 (sofia/default/97235555555) State CONSUME_MEDIA going to sleep
  1119. 2023-02-07 13:27:38.913777 97.67% [DEBUG] sofia.c:7499 Channel sofia/default/97235555555 entering state [calling][0]
  1120. 2023-02-07 13:27:39.033782 97.67% [DEBUG] sofia.c:7499 Channel sofia/default/97235555555 entering state [terminated][500]
  1121. 2023-02-07 13:27:39.033782 97.67% [NOTICE] sofia.c:8738 Hangup sofia/default/97235555555 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  1122. 2023-02-07 13:27:39.033782 97.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/97235555555) Running State Change CS_HANGUP (Cur 6 Tot 1861)
  1123. 2023-02-07 13:27:39.033782 97.67% [DEBUG] switch_core_state_machine.c:844 (sofia/default/97235555555) Callstate Change DOWN -> HANGUP
  1124. 2023-02-07 13:27:39.033782 97.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/97235555555) State HANGUP
  1125. 2023-02-07 13:27:39.033782 97.67% [DEBUG] mod_sofia.c:468 Channel sofia/default/97235555555 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  1126. 2023-02-07 13:27:39.033782 97.67% [DEBUG] switch_core_state_machine.c:59 sofia/default/97235555555 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  1127. 2023-02-07 13:27:39.033782 97.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/97235555555) State HANGUP going to sleep
  1128. 2023-02-07 13:27:39.033782 97.67% [DEBUG] switch_core_state_machine.c:616 (sofia/default/97235555555) State Change CS_HANGUP -> CS_REPORTING
  1129. 2023-02-07 13:27:39.033782 97.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/97235555555) Running State Change CS_REPORTING (Cur 6 Tot 1861)
  1130. 2023-02-07 13:27:39.033782 97.67% [DEBUG] switch_core_state_machine.c:932 (sofia/default/97235555555) State REPORTING
  1131. 2023-02-07 13:27:39.033782 97.67% [INFO] mod_json_cdr.c:271 Process [321b749b-dd3b-480e-bd59-c6e7ca1ddfb7.cdr.json]
  1132. 2023-02-07 13:27:39.033782 97.67% [DEBUG] switch_core_state_machine.c:168 sofia/default/97235555555 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  1133. 2023-02-07 13:27:39.033782 97.67% [DEBUG] switch_core_state_machine.c:932 (sofia/default/97235555555) State REPORTING going to sleep
  1134. 2023-02-07 13:27:39.033782 97.67% [DEBUG] switch_core_state_machine.c:607 (sofia/default/97235555555) State Change CS_REPORTING -> CS_DESTROY
  1135. 2023-02-07 13:27:39.033782 97.67% [DEBUG] switch_core_session.c:1753 Session 1861 (sofia/default/97235555555) Locked, Waiting on external entities
  1136. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  1137. 2023-02-07 13:27:39.053780 97.67% [NOTICE] switch_core_session.c:1771 Session 1861 (sofia/default/97235555555) Ended
  1138. 2023-02-07 13:27:39.053780 97.67% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/97235555555 [CS_DESTROY]
  1139. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_core_state_machine.c:735 (sofia/default/97235555555) Running State Change CS_DESTROY (Cur 5 Tot 1861)
  1140. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_core_state_machine.c:745 (sofia/default/97235555555) State DESTROY
  1141. 2023-02-07 13:27:39.053780 97.67% [DEBUG] mod_sofia.c:379 sofia/default/97235555555 SOFIA DESTROY
  1142. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_core_state_machine.c:175 sofia/default/97235555555 Standard DESTROY
  1143. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_core_state_machine.c:745 (sofia/default/97235555555) State DESTROY going to sleep
  1144. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:2178 (sofia/default/0546322423@212.150.158.100) Callstate Change RING_WAIT -> ACTIVE
  1145. 2023-02-07 13:27:39.053780 97.67% [INFO] mod_dptools.c:3653 Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE
  1146. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(presence_data=x|||Amir(389385276538)|||^972.* // IsraelOther // 0.05125|||tata3 // ^972.* // IsraelOther // 0.02050|||CC)
  1147. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[x|||Amir(389385276538)|||^972.* // IsraelOther // 0.05125|||tata3 // ^972.* // IsraelOther // 0.02050|||CC]
  1148. 2023-02-07 13:27:39.053780 97.67% [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 = 587) AND status=0 limit 1
  1149. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(effective_caller_id_name=201501718238)
  1150. 2023-02-07 13:27:39.053780 97.67% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [effective_caller_id_name]=[201501718238]
  1151. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 set(effective_caller_id_number=201501718238)
  1152. 2023-02-07 13:27:39.053780 97.67% [DEBUG] mod_dptools.c:1685 SET sofia/default/0546322423@212.150.158.100 [effective_caller_id_number]=[201501718238]
  1153. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(effective_caller_id_name=201501718238)
  1154. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [effective_caller_id_name]=[201501718238]
  1155. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(effective_caller_id_number=201501718238)
  1156. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [effective_caller_id_number]=[201501718238]
  1157. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :0/0.....97235555555
  1158. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(termination_rates=ID:28239385|CODE:^972.*|DESTINATION:IsraelOther|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.02050|INC:1|TRUNK:33|PROVIDER:587)
  1159. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [termination_rates]=[ID:28239385|CODE:^972.*|DESTINATION:IsraelOther|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.02050|INC:1|TRUNK:33|PROVIDER:587]
  1160. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(trunk_id=33)
  1161. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [trunk_id]=[33]
  1162. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 export(provider_id=587)
  1163. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [provider_id]=[587]
  1164. EXECUTE [depth=0] sofia/default/0546322423@212.150.158.100 bridge([leg_timeout=0]sofia/gateway/tata3/97235555555)
  1165. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1954 (sofia/default/0546322423@212.150.158.100) Callstate Change ACTIVE -> RING_WAIT
  1166. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [domain_name]=[Amir4660.newsip.israelnumber.com] to event
  1167. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [sip_to_host]=[Amir4660.newsip.israelnumber.com] to event
  1168. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [presence_data]=[x|||Amir(389385276538)|||^972.* // IsraelOther // 0.05125|||tata3 // ^972.* // IsraelOther // 0.02050|||CC] to event
  1169. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [call_type]=[14] to event
  1170. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [call_processed]=[internal] to event
  1171. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [callstart]=[2023-02-07 13:27:38] to event
  1172. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [originated_destination_number]=[97235555555] to event
  1173. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [effective_destination_number]=[97235555555] to event
  1174. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [account_id]=[5203] to event
  1175. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [account_type]=[0] to event
  1176. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [resellerid]=[0] to event
  1177. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [parent_id]=[0] to event
  1178. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [accountcode]=[389385276538] to event
  1179. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [call_direction]=[outbound] to event
  1180. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [calltype]=[CALLINGCARD] to event
  1181. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [origination_rates]=[ID:46448781|CODE:^972.*|DESTINATION:IsraelOther|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.05125|INC:10|INITIALBLOCK:30|RATEGROUP:137|MARKUP:0|CI:0|ACCID:5203] to event
  1182. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [original_caller_id_name]=[201501718238] to event
  1183. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [original_caller_id_number]=[201501718238] to event
  1184. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [presence_data]=[x|||Amir(389385276538)|||^972.* // IsraelOther // 0.05125|||tata3 // ^972.* // IsraelOther // 0.02050|||CC] to event
  1185. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [effective_caller_id_name]=[201501718238] to event
  1186. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [effective_caller_id_number]=[201501718238] to event
  1187. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [termination_rates]=[ID:28239385|CODE:^972.*|DESTINATION:IsraelOther|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.02050|INC:1|TRUNK:33|PROVIDER:587] to event
  1188. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [trunk_id]=[33] to event
  1189. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [provider_id]=[587] to event
  1190. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [presence_data]=[x|||Amir(389385276538)|||^972.* // IsraelOther // 0.05125|||tata3 // ^972.* // IsraelOther // 0.02050|||CC] to event
  1191. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [effective_caller_id_name]=[201501718238] to event
  1192. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [effective_caller_id_number]=[201501718238] to event
  1193. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [termination_rates]=[ID:28239385|CODE:^972.*|DESTINATION:IsraelOther|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.02050|INC:1|TRUNK:33|PROVIDER:587] to event
  1194. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [trunk_id]=[33] to event
  1195. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_channel.c:1269 sofia/default/0546322423@212.150.158.100 EXPORTING[export_vars] [provider_id]=[587] to event
  1196. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  1197. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_ivr_originate.c:2843 Parsing session specific variables
  1198. 2023-02-07 13:27:39.053780 97.67% [NOTICE] switch_channel.c:1123 New Channel sofia/default/97235555555 [f8b9ff8c-ceed-4956-8582-efb86100fd0d]
  1199. 2023-02-07 13:27:39.053780 97.67% [DEBUG] mod_sofia.c:5121 (sofia/default/97235555555) State Change CS_NEW -> CS_INIT
  1200. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/97235555555) Running State Change CS_INIT (Cur 6 Tot 1862)
  1201. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/97235555555) State INIT
  1202. 2023-02-07 13:27:39.053780 97.67% [DEBUG] mod_sofia.c:97 sofia/default/97235555555 SOFIA INIT
  1203. 2023-02-07 13:27:39.053780 97.67% [INFO] sofia_glue.c:1651 sofia/default/97235555555 sending invite call-id: (null)
  1204. 2023-02-07 13:27:39.053780 97.67% [DEBUG] sofia_glue.c:1654 sofia/default/97235555555 sending invite version: 1.10.7 -release-19-883d2cb662 64bit
  1205. Local SDP:
  1206. v=0
  1207. o=FreeSWITCH 1675749733 1675749734 IN IP4 195.201.136.88
  1208. s=FreeSWITCH
  1209. c=IN IP4 195.201.136.88
  1210. t=0 0
  1211. m=audio 26726 RTP/AVP 8 101
  1212. a=rtpmap:8 PCMA/8000
  1213. a=rtpmap:101 telephone-event/8000
  1214. a=fmtp:101 0-15
  1215. a=ptime:20
  1216. a=sendrecv
  1217.  
  1218. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_core_state_machine.c:40 sofia/default/97235555555 Standard INIT
  1219. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_core_state_machine.c:48 (sofia/default/97235555555) State Change CS_INIT -> CS_ROUTING
  1220. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/97235555555) State INIT going to sleep
  1221. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/97235555555) Running State Change CS_ROUTING (Cur 6 Tot 1862)
  1222. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/97235555555) State ROUTING
  1223. 2023-02-07 13:27:39.053780 97.67% [DEBUG] mod_sofia.c:158 sofia/default/97235555555 SOFIA ROUTING
  1224. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_ivr_originate.c:67 (sofia/default/97235555555) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  1225. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/97235555555) State ROUTING going to sleep
  1226. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/97235555555) Running State Change CS_CONSUME_MEDIA (Cur 6 Tot 1862)
  1227. 2023-02-07 13:27:39.053780 97.67% [DEBUG] sofia.c:7499 Channel sofia/default/97235555555 entering state [calling][0]
  1228. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_core_state_machine.c:659 (sofia/default/97235555555) State CONSUME_MEDIA
  1229. 2023-02-07 13:27:39.053780 97.67% [DEBUG] switch_core_state_machine.c:659 (sofia/default/97235555555) State CONSUME_MEDIA going to sleep
  1230. 2023-02-07 13:27:39.193781 97.67% [DEBUG] sofia.c:7499 Channel sofia/default/97235555555 entering state [terminated][500]
  1231. 2023-02-07 13:27:39.193781 97.67% [NOTICE] sofia.c:8738 Hangup sofia/default/97235555555 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  1232. 2023-02-07 13:27:39.193781 97.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/97235555555) Running State Change CS_HANGUP (Cur 6 Tot 1862)
  1233. 2023-02-07 13:27:39.193781 97.67% [DEBUG] switch_core_state_machine.c:844 (sofia/default/97235555555) Callstate Change DOWN -> HANGUP
  1234. 2023-02-07 13:27:39.193781 97.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/97235555555) State HANGUP
  1235. 2023-02-07 13:27:39.193781 97.67% [DEBUG] mod_sofia.c:468 Channel sofia/default/97235555555 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  1236. 2023-02-07 13:27:39.193781 97.67% [DEBUG] switch_core_state_machine.c:59 sofia/default/97235555555 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  1237. 2023-02-07 13:27:39.193781 97.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/97235555555) State HANGUP going to sleep
  1238. 2023-02-07 13:27:39.193781 97.67% [DEBUG] switch_core_state_machine.c:616 (sofia/default/97235555555) State Change CS_HANGUP -> CS_REPORTING
  1239. 2023-02-07 13:27:39.193781 97.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/97235555555) Running State Change CS_REPORTING (Cur 6 Tot 1862)
  1240. 2023-02-07 13:27:39.193781 97.67% [DEBUG] switch_core_state_machine.c:932 (sofia/default/97235555555) State REPORTING
  1241. 2023-02-07 13:27:39.193781 97.67% [INFO] mod_json_cdr.c:271 Process [f8b9ff8c-ceed-4956-8582-efb86100fd0d.cdr.json]
  1242. 2023-02-07 13:27:39.193781 97.67% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  1243. 2023-02-07 13:27:39.193781 97.67% [DEBUG] switch_channel.c:2178 (sofia/default/0546322423@212.150.158.100) Callstate Change RING_WAIT -> ACTIVE
  1244. 2023-02-07 13:27:39.193781 97.67% [INFO] mod_dptools.c:3653 Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE
  1245. 2023-02-07 13:27:39.193781 97.67% [DEBUG] switch_core_state_machine.c:168 sofia/default/97235555555 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  1246. 2023-02-07 13:27:39.193781 97.67% [DEBUG] switch_core_state_machine.c:932 (sofia/default/97235555555) State REPORTING going to sleep
  1247. 2023-02-07 13:27:39.193781 97.67% [DEBUG] switch_core_file.c:444 File /usr/share/freeswitch/sounds/en/us/callie/astpp-callingcard-menu.wav sample rate 16000 doesn't match requested rate 8000
  1248. 2023-02-07 13:27:39.193781 97.67% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
  1249. 2023-02-07 13:27:39.193781 97.67% [DEBUG] switch_core_state_machine.c:607 (sofia/default/97235555555) State Change CS_REPORTING -> CS_DESTROY
  1250. 2023-02-07 13:27:39.193781 97.67% [DEBUG] switch_core_session.c:1753 Session 1862 (sofia/default/97235555555) Locked, Waiting on external entities
  1251. 2023-02-07 13:27:39.193781 97.67% [NOTICE] switch_core_session.c:1771 Session 1862 (sofia/default/97235555555) Ended
  1252. 2023-02-07 13:27:39.193781 97.67% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/97235555555 [CS_DESTROY]
  1253. 2023-02-07 13:27:39.193781 97.67% [DEBUG] switch_core_state_machine.c:735 (sofia/default/97235555555) Running State Change CS_DESTROY (Cur 5 Tot 1862)
  1254. 2023-02-07 13:27:39.193781 97.67% [DEBUG] switch_core_state_machine.c:745 (sofia/default/97235555555) State DESTROY
  1255. 2023-02-07 13:27:39.193781 97.67% [DEBUG] mod_sofia.c:379 sofia/default/97235555555 SOFIA DESTROY
  1256. 2023-02-07 13:27:39.193781 97.67% [DEBUG] switch_core_state_machine.c:175 sofia/default/97235555555 Standard DESTROY
  1257. 2023-02-07 13:27:39.193781 97.67% [DEBUG] switch_core_state_machine.c:745 (sofia/default/97235555555) State DESTROY going to sleep
  1258. 2023-02-07 13:27:40.153782 97.60% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [302@195.201.136.88] from ip 128.90.173.184
  1259. 2023-02-07 13:27:40.193774 97.60% [WARNING] sofia_reg.c:3223 Can't find user [302@195.201.136.88] from 128.90.173.184
  1260. You must define a domain called '195.201.136.88' in your directory and add a user with the id="302" attribute
  1261. and you must configure your device to use the proper domain in its authentication credentials.
  1262. 2023-02-07 13:27:40.193774 97.60% [WARNING] sofia_reg.c:1806 SIP auth failure (REGISTER) on sofia profile 'default' for [302@195.201.136.88] from ip 128.90.173.184
  1263. 2023-02-07 13:27:41.793798 97.60% [DEBUG] sofia.c:7499 Channel sofia/default/0546322423@212.150.158.100 entering state [received][100]
  1264. 2023-02-07 13:27:41.793798 97.60% [DEBUG] sofia.c:7509 Remote SDP:
  1265. v=0
  1266. o=root 7779 7781 IN IP4 212.150.158.100
  1267. s=session
  1268. c=IN IP4 212.150.158.100
  1269. t=0 0
  1270. m=audio 13310 RTP/AVP 8 101
  1271. a=rtpmap:8 PCMA/8000
  1272. a=rtpmap:101 telephone-event/8000
  1273. a=fmtp:101 0-16
  1274. a=silenceSupp:off - - - -
  1275. a=ptime:20
  1276.  
  1277. 2023-02-07 13:27:41.793798 97.60% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1278. 2023-02-07 13:27:41.793798 97.60% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  1279. 2023-02-07 13:27:41.793798 97.60% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  1280. 2023-02-07 13:27:41.793798 97.60% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  1281. 2023-02-07 13:27:41.793798 97.60% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  1282. 2023-02-07 13:27:41.793798 97.60% [DEBUG] switch_core_media.c:5973 sofia/default/0546322423@212.150.158.100 Set 2833 dtmf send payload to 101 recv payload to 101
  1283. 2023-02-07 13:27:41.793798 97.60% [DEBUG] switch_core_media.c:8765 Audio params changed for sofia/default/0546322423@212.150.158.100 from 82.166.67.145:9804 to 212.150.158.100:13310
  1284. 2023-02-07 13:27:41.793798 97.60% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/0546322423@212.150.158.100] 195.201.136.88 port 22604 -> 212.150.158.100 port 13310 codec: 8 ms: 20
  1285. 2023-02-07 13:27:41.793798 97.60% [DEBUG] switch_core_media.c:8806 AUDIO RTP CHANGING DEST TO: [212.150.158.100:13310]
  1286. 2023-02-07 13:27:41.793798 97.60% [DEBUG] sofia.c:8454 Processing updated SDP
  1287. 2023-02-07 13:27:41.813804 97.60% [DEBUG] sofia.c:7499 Channel sofia/default/0546322423@212.150.158.100 entering state [completed][200]
  1288. 2023-02-07 13:27:41.853795 97.60% [DEBUG] sofia.c:7499 Channel sofia/default/0546322423@212.150.158.100 entering state [ready][200]
  1289. 2023-02-07 13:27:41.873794 97.60% [NOTICE] sofia.c:1065 Hangup sofia/default/0546322423@212.150.158.100 [CS_EXECUTE] [NORMAL_CLEARING]
  1290. 2023-02-07 13:27:41.873794 97.60% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-callingcard-menu.wav
  1291. 2023-02-07 13:27:41.873794 97.60% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Got DTMF digits: retries:0
  1292. 2023-02-07 13:27:41.873794 97.60% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Got DTMF digits: retries:1
  1293. 2023-02-07 13:27:41.873794 97.60% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Got DTMF digits: retries:2
  1294. 2023-02-07 13:27:41.873794 97.60% [DEBUG] switch_cpp.cpp:749 CoreSession::hangup
  1295. 2023-02-07 13:27:41.873794 97.60% [DEBUG] switch_cpp.cpp:1209 sofia/default/0546322423@212.150.158.100 destroy/unlink session from object
  1296. 2023-02-07 13:27:41.873794 97.60% [DEBUG] switch_core_session.c:2983 sofia/default/0546322423@212.150.158.100 skip receive message [PHONE_EVENT] (channel is hungup already)
  1297. 2023-02-07 13:27:41.873794 97.60% [DEBUG] switch_core_state_machine.c:647 (sofia/default/0546322423@212.150.158.100) State EXECUTE going to sleep
  1298. 2023-02-07 13:27:41.873794 97.60% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0546322423@212.150.158.100) Running State Change CS_HANGUP (Cur 5 Tot 1862)
  1299. 2023-02-07 13:27:41.873794 97.60% [DEBUG] switch_core_state_machine.c:844 (sofia/default/0546322423@212.150.158.100) Callstate Change ACTIVE -> HANGUP
  1300. 2023-02-07 13:27:41.873794 97.60% [DEBUG] switch_core_state_machine.c:846 (sofia/default/0546322423@212.150.158.100) State HANGUP
  1301. 2023-02-07 13:27:41.873794 97.60% [DEBUG] mod_sofia.c:462 sofia/default/0546322423@212.150.158.100 Overriding SIP cause 480 with 500 from the other leg
  1302. 2023-02-07 13:27:41.873794 97.60% [DEBUG] mod_sofia.c:468 Channel sofia/default/0546322423@212.150.158.100 hanging up, cause: NORMAL_CLEARING
  1303. 2023-02-07 13:27:41.873794 97.60% [DEBUG] switch_core_state_machine.c:59 sofia/default/0546322423@212.150.158.100 Standard HANGUP, cause: NORMAL_CLEARING
  1304. 2023-02-07 13:27:41.873794 97.60% [DEBUG] switch_core_state_machine.c:846 (sofia/default/0546322423@212.150.158.100) State HANGUP going to sleep
  1305. 2023-02-07 13:27:41.873794 97.60% [DEBUG] switch_core_state_machine.c:616 (sofia/default/0546322423@212.150.158.100) State Change CS_HANGUP -> CS_REPORTING
  1306. 2023-02-07 13:27:41.873794 97.60% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0546322423@212.150.158.100) Running State Change CS_REPORTING (Cur 5 Tot 1862)
  1307. 2023-02-07 13:27:41.873794 97.60% [DEBUG] switch_core_state_machine.c:932 (sofia/default/0546322423@212.150.158.100) State REPORTING
  1308. 2023-02-07 13:27:41.873794 97.60% [INFO] mod_json_cdr.c:271 Process [02641f90-b524-448f-8f56-9cbd99102e61.cdr.json]
  1309. 2023-02-07 13:27:41.873794 97.60% [DEBUG] switch_core_state_machine.c:168 sofia/default/0546322423@212.150.158.100 Standard REPORTING, cause: NORMAL_CLEARING
  1310. 2023-02-07 13:27:41.873794 97.60% [DEBUG] switch_core_state_machine.c:932 (sofia/default/0546322423@212.150.158.100) State REPORTING going to sleep
  1311. 2023-02-07 13:27:41.873794 97.60% [DEBUG] switch_core_state_machine.c:607 (sofia/default/0546322423@212.150.158.100) State Change CS_REPORTING -> CS_DESTROY
  1312. 2023-02-07 13:27:41.873794 97.60% [DEBUG] switch_core_session.c:1753 Session 1860 (sofia/default/0546322423@212.150.158.100) Locked, Waiting on external entities
  1313. 2023-02-07 13:27:41.873794 97.60% [NOTICE] switch_core_session.c:1771 Session 1860 (sofia/default/0546322423@212.150.158.100) Ended
  1314. 2023-02-07 13:27:41.873794 97.60% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/0546322423@212.150.158.100 [CS_DESTROY]
  1315. 2023-02-07 13:27:41.873794 97.60% [DEBUG] switch_core_state_machine.c:735 (sofia/default/0546322423@212.150.158.100) Running State Change CS_DESTROY (Cur 4 Tot 1862)
  1316. 2023-02-07 13:27:41.873794 97.60% [DEBUG] switch_core_state_machine.c:745 (sofia/default/0546322423@212.150.158.100) State DESTROY
  1317. 2023-02-07 13:27:41.873794 97.60% [DEBUG] mod_sofia.c:379 sofia/default/0546322423@212.150.158.100 SOFIA DESTROY
  1318. 2023-02-07 13:27:41.873794 97.60% [DEBUG] switch_core_state_machine.c:175 sofia/default/0546322423@212.150.158.100 Standard DESTROY
  1319. 2023-02-07 13:27:41.873794 97.60% [DEBUG] switch_core_state_machine.c:745 (sofia/default/0546322423@212.150.158.100) State DESTROY going to sleep
  1320. 2023-02-07 13:27:41.893777 97.60% [DEBUG] switch_scheduler.c:147 Deleting task 54 switch_ivr_schedule_hangup (02641f90-b524-448f-8f56-9cbd99102e61)
  1321. 2023-02-07 13:27:41.893777 97.60% [DEBUG] switch_scheduler.c:147 Deleting task 55 switch_ivr_schedule_hangup (02641f90-b524-448f-8f56-9cbd99102e61)
  1322. 2023-02-07 13:27:46.493782 97.50% [NOTICE] switch_channel.c:1123 New Channel sofia/default/+447903175491@avoxi.io [6f7e3567-38b4-46b1-b91c-ffeba236d499]
  1323. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+447903175491@avoxi.io) Running State Change CS_NEW (Cur 5 Tot 1863)
  1324. 2023-02-07 13:27:46.493782 97.50% [INFO] sofia.c:10462 sofia/default/+447903175491@avoxi.io receiving invite from 195.201.136.94:5060 version: 1.10.7 -release-19-883d2cb662 64bit call-id: dac88e3d-8b52-43a2-a3b9-df8b9c19136f
  1325. 2023-02-07 13:27:46.493782 97.50% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 195.201.136.94:0.
  1326. 2023-02-07 13:27:46.493782 97.50% [DEBUG] sofia.c:10585 IP 195.201.136.94 Approved by acl "default[]". Access Granted.
  1327. 2023-02-07 13:27:46.493782 97.50% [DEBUG] sofia.c:7499 Channel sofia/default/+447903175491@avoxi.io entering state [received][100]
  1328. 2023-02-07 13:27:46.493782 97.50% [DEBUG] sofia.c:7509 Remote SDP:
  1329. v=0
  1330. o=- 1129890993 1129890993 IN IP4 34.75.227.126
  1331. s=Asterisk
  1332. c=IN IP4 34.75.227.126
  1333. t=0 0
  1334. m=audio 22704 RTP/AVP 101 0 8 18
  1335. a=rtpmap:101 telephone-event/8000
  1336. a=fmtp:101 0-16
  1337. a=rtpmap:0 PCMU/8000
  1338. a=rtpmap:8 PCMA/8000
  1339. a=rtpmap:18 G729/8000
  1340. a=fmtp:18 annexb=no
  1341. a=maxptime:150
  1342. a=rtcp:22705
  1343. a=rtcp-mux
  1344. a=ptime:20
  1345.  
  1346. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  1347. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1348. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  1349. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  1350. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1351. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  1352. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  1353. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  1354. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  1355. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/+447903175491@avoxi.io PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  1356. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_core_codec.c:111 sofia/default/+447903175491@avoxi.io Original read codec set to PCMU:0
  1357. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  1358. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_core_media.c:5973 sofia/default/+447903175491@avoxi.io Set 2833 dtmf send payload to 101 recv payload to 101
  1359. 2023-02-07 13:27:46.493782 97.50% [DEBUG] sofia.c:7933 (sofia/default/+447903175491@avoxi.io) State Change CS_NEW -> CS_INIT
  1360. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_core_state_machine.c:600 (sofia/default/+447903175491@avoxi.io) State NEW
  1361. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+447903175491@avoxi.io) Running State Change CS_INIT (Cur 5 Tot 1863)
  1362. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_core_state_machine.c:624 (sofia/default/+447903175491@avoxi.io) State INIT
  1363. 2023-02-07 13:27:46.493782 97.50% [DEBUG] mod_sofia.c:97 sofia/default/+447903175491@avoxi.io SOFIA INIT
  1364. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_core_state_machine.c:40 sofia/default/+447903175491@avoxi.io Standard INIT
  1365. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_core_state_machine.c:48 (sofia/default/+447903175491@avoxi.io) State Change CS_INIT -> CS_ROUTING
  1366. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_core_state_machine.c:624 (sofia/default/+447903175491@avoxi.io) State INIT going to sleep
  1367. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+447903175491@avoxi.io) Running State Change CS_ROUTING (Cur 5 Tot 1863)
  1368. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_channel.c:2380 (sofia/default/+447903175491@avoxi.io) Callstate Change DOWN -> RINGING
  1369. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_core_state_machine.c:640 (sofia/default/+447903175491@avoxi.io) State ROUTING
  1370. 2023-02-07 13:27:46.493782 97.50% [DEBUG] mod_sofia.c:158 sofia/default/+447903175491@avoxi.io SOFIA ROUTING
  1371. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_core_state_machine.c:230 sofia/default/+447903175491@avoxi.io Standard ROUTING
  1372. 2023-02-07 13:27:46.493782 97.50% [INFO] mod_dialplan_xml.c:639 Processing +447903175491 <+447903175491>->442039877750 in context default
  1373. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
  1374. 2023-02-07 13:27:46.493782 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  1375. 2023-02-07 13:27:46.513775 97.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] blind_flag: 0
  1376. 2023-02-07 13:27:46.513775 97.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 442039877750
  1377. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '442039877750' limit 1
  1378. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '442039877750' AND status=0 limit 1
  1379. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE domain = 'avoxi.io' limit 1
  1380. 2023-02-07 13:27:46.513775 97.50% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] in custom Caller ID
  1381. 2023-02-07 13:27:46.513775 97.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : +447903175491 / +447903175491
  1382. 2023-02-07 13:27:46.513775 97.50% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
  1383. 2023-02-07 13:27:46.513775 97.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
  1384. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....442039877750
  1385. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....442039877750
  1386. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....442039877750
  1387. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....442039877750
  1388. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....442039877750
  1389. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....442039877750
  1390. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....442039877750
  1391. 2023-02-07 13:27:46.513775 97.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 FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="442039877750" LIMIT 1
  1392. 2023-02-07 13:27:46.513775 97.50% [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="442039877750"  AND domains.domain="newsip.israelnumber.com" limit 1
  1393. 2023-02-07 13:27:46.513775 97.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
  1394. 2023-02-07 13:27:46.513775 97.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 594905904039]
  1395. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "594905904039" AND deleted = 0 limit 1
  1396. 2023-02-07 13:27:46.513775 97.50% [WARNING] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] [594905904039] Insufficent balance (-13.0735) to make calls..!!
  1397. 2023-02-07 13:27:46.513775 97.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 ="442039877750" AND A.accountid = '855' limit 1
  1398. 2023-02-07 13:27:46.513775 97.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 442039877750
  1399. 2023-02-07 13:27:46.513775 97.50% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
  1400. 2023-02-07 13:27:46.513775 97.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
  1401. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....442039877750
  1402. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....442039877750
  1403. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....442039877750
  1404. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....442039877750
  1405. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....442039877750
  1406. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....442039877750
  1407. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....442039877750
  1408. 2023-02-07 13:27:46.513775 97.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 FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="442039877750" LIMIT 1
  1409. 2023-02-07 13:27:46.513775 97.50% [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="442039877750"  AND domains.domain="newsip.israelnumber.com" limit 1
  1410. 2023-02-07 13:27:46.513775 97.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call direction : outbound
  1411. 2023-02-07 13:27:46.513775 97.50% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID global translation id: 13
  1412. 2023-02-07 13:27:46.513775 97.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
  1413. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....442039877750
  1414. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....442039877750
  1415. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....442039877750
  1416. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....442039877750
  1417. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....442039877750
  1418. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....442039877750
  1419. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*,03/9723,073/97273,09/9729,02/9722,04/9724,077/97277".....442039877750
  1420. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="442039877750" AND (accountid = 0 OR status = 1) LIMIT 1
  1421. 2023-02-07 13:27:46.513775 97.50% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call direction HEREE : outbound
  1422. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] post_cdrs:::0
  1423. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET RESELLERID] Query :SELECT reseller_id FROM accounts WHERE id = 855
  1424. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Generated XML:
  1425. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  1426. <document type="freeswitch/xml">
  1427. <section name="dialplan" description="ASTPP Dialplan">
  1428. <context name="default">
  1429. <extension name="442039877750">
  1430. <condition field="destination_number" expression="442039877750">
  1431. <action application="log" data="WARNING Accountcode . Dialed DID number (442039877750) routes not set!!"/>
  1432. <action application="set" data="original_caller_id_name=+447903175491"/>
  1433. <action application="set" data="original_caller_id_number=+447903175491"/>
  1434. <action application="set" data="error_cdr=1"/>
  1435. <action application="set" data="callstart=2023-02-07 13:27:46"/>
  1436. <action application="set" data="account_id=855"/>
  1437. <action application="set" data="parent_id=0"/>
  1438. <action application="set" data="call_direction=outbound"/>
  1439. <action application="set" data="sip_ignore_remote_cause=true"/>
  1440. <action application="set" data="call_processed=internal"/>
  1441. <action application="set" data="effective_destination_number=442039877750"/>
  1442. <action application="set" data="last_bridge_hangup_cause=NO_ROUTE_DESTINATION"/>
  1443. <action application="hangup" data="NO_ROUTE_DESTINATION"/>
  1444. </condition>
  1445. </extension>
  1446. </context>
  1447. </section>
  1448. </document>
  1449. Dialplan: sofia/default/+447903175491@avoxi.io parsing [default->442039877750] continue=false
  1450. Dialplan: sofia/default/+447903175491@avoxi.io Regex (PASS) [442039877750] destination_number(442039877750) =~ /442039877750/ break=on-false
  1451. Dialplan: sofia/default/+447903175491@avoxi.io Action log(WARNING Accountcode . Dialed DID number (442039877750) routes not set!!)
  1452. Dialplan: sofia/default/+447903175491@avoxi.io Action set(original_caller_id_name=+447903175491)
  1453. Dialplan: sofia/default/+447903175491@avoxi.io Action set(original_caller_id_number=+447903175491)
  1454. Dialplan: sofia/default/+447903175491@avoxi.io Action set(error_cdr=1)
  1455. Dialplan: sofia/default/+447903175491@avoxi.io Action set(callstart=2023-02-07 13:27:46)
  1456. Dialplan: sofia/default/+447903175491@avoxi.io Action set(account_id=855)
  1457. Dialplan: sofia/default/+447903175491@avoxi.io Action set(parent_id=0)
  1458. Dialplan: sofia/default/+447903175491@avoxi.io Action set(call_direction=outbound)
  1459. Dialplan: sofia/default/+447903175491@avoxi.io Action set(sip_ignore_remote_cause=true)
  1460. Dialplan: sofia/default/+447903175491@avoxi.io Action set(call_processed=internal)
  1461. Dialplan: sofia/default/+447903175491@avoxi.io Action set(effective_destination_number=442039877750)
  1462. Dialplan: sofia/default/+447903175491@avoxi.io Action set(last_bridge_hangup_cause=NO_ROUTE_DESTINATION)
  1463. Dialplan: sofia/default/+447903175491@avoxi.io Action hangup(NO_ROUTE_DESTINATION)
  1464. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_core_state_machine.c:281 (sofia/default/+447903175491@avoxi.io) State Change CS_ROUTING -> CS_EXECUTE
  1465. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_core_state_machine.c:640 (sofia/default/+447903175491@avoxi.io) State ROUTING going to sleep
  1466. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+447903175491@avoxi.io) Running State Change CS_EXECUTE (Cur 5 Tot 1863)
  1467. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_core_state_machine.c:647 (sofia/default/+447903175491@avoxi.io) State EXECUTE
  1468. 2023-02-07 13:27:46.513775 97.50% [DEBUG] mod_sofia.c:213 sofia/default/+447903175491@avoxi.io SOFIA EXECUTE
  1469. 2023-02-07 13:27:46.513775 97.50% [DEBUG] switch_core_state_machine.c:323 sofia/default/+447903175491@avoxi.io Standard EXECUTE
  1470. EXECUTE [depth=0] sofia/default/+447903175491@avoxi.io log(WARNING Accountcode . Dialed DID number (442039877750) routes not set!!)
  1471. 2023-02-07 13:27:46.513775 97.50% [WARNING] mod_dptools.c:1879 Accountcode . Dialed DID number (442039877750) routes not set!!
  1472. EXECUTE [depth=0] sofia/default/+447903175491@avoxi.io set(original_caller_id_name=+447903175491)
  1473. 2023-02-07 13:27:46.513775 97.50% [DEBUG] mod_dptools.c:1685 SET sofia/default/+447903175491@avoxi.io [original_caller_id_name]=[+447903175491]
  1474. EXECUTE [depth=0] sofia/default/+447903175491@avoxi.io set(original_caller_id_number=+447903175491)
  1475. 2023-02-07 13:27:46.513775 97.50% [DEBUG] mod_dptools.c:1685 SET sofia/default/+447903175491@avoxi.io [original_caller_id_number]=[+447903175491]
  1476. EXECUTE [depth=0] sofia/default/+447903175491@avoxi.io set(error_cdr=1)
  1477. 2023-02-07 13:27:46.533775 97.50% [DEBUG] mod_dptools.c:1685 SET sofia/default/+447903175491@avoxi.io [error_cdr]=[1]
  1478. EXECUTE [depth=0] sofia/default/+447903175491@avoxi.io set(callstart=2023-02-07 13:27:46)
  1479. 2023-02-07 13:27:46.533775 97.50% [DEBUG] mod_dptools.c:1685 SET sofia/default/+447903175491@avoxi.io [callstart]=[2023-02-07 13:27:46]
  1480. EXECUTE [depth=0] sofia/default/+447903175491@avoxi.io set(account_id=855)
  1481. 2023-02-07 13:27:46.533775 97.50% [DEBUG] mod_dptools.c:1685 SET sofia/default/+447903175491@avoxi.io [account_id]=[855]
  1482. EXECUTE [depth=0] sofia/default/+447903175491@avoxi.io set(parent_id=0)
  1483. 2023-02-07 13:27:46.533775 97.50% [DEBUG] mod_dptools.c:1685 SET sofia/default/+447903175491@avoxi.io [parent_id]=[0]
  1484. EXECUTE [depth=0] sofia/default/+447903175491@avoxi.io set(call_direction=outbound)
  1485. 2023-02-07 13:27:46.533775 97.50% [DEBUG] mod_dptools.c:1685 SET sofia/default/+447903175491@avoxi.io [call_direction]=[outbound]
  1486. EXECUTE [depth=0] sofia/default/+447903175491@avoxi.io set(sip_ignore_remote_cause=true)
  1487. 2023-02-07 13:27:46.533775 97.50% [DEBUG] mod_dptools.c:1685 SET sofia/default/+447903175491@avoxi.io [sip_ignore_remote_cause]=[true]
  1488. EXECUTE [depth=0] sofia/default/+447903175491@avoxi.io set(call_processed=internal)
  1489. 2023-02-07 13:27:46.533775 97.50% [DEBUG] mod_dptools.c:1685 SET sofia/default/+447903175491@avoxi.io [call_processed]=[internal]
  1490. EXECUTE [depth=0] sofia/default/+447903175491@avoxi.io set(effective_destination_number=442039877750)
  1491. 2023-02-07 13:27:46.533775 97.50% [DEBUG] mod_dptools.c:1685 SET sofia/default/+447903175491@avoxi.io [effective_destination_number]=[442039877750]
  1492. EXECUTE [depth=0] sofia/default/+447903175491@avoxi.io set(last_bridge_hangup_cause=NO_ROUTE_DESTINATION)
  1493. 2023-02-07 13:27:46.533775 97.50% [DEBUG] mod_dptools.c:1685 SET sofia/default/+447903175491@avoxi.io [last_bridge_hangup_cause]=[NO_ROUTE_DESTINATION]
  1494. EXECUTE [depth=0] sofia/default/+447903175491@avoxi.io hangup(NO_ROUTE_DESTINATION)
  1495. 2023-02-07 13:27:46.533775 97.50% [NOTICE] mod_dptools.c:1387 Hangup sofia/default/+447903175491@avoxi.io [CS_EXECUTE] [NO_ROUTE_DESTINATION]
  1496. 2023-02-07 13:27:46.533775 97.50% [DEBUG] switch_core_session.c:2983 sofia/default/+447903175491@avoxi.io skip receive message [PHONE_EVENT] (channel is hungup already)
  1497. 2023-02-07 13:27:46.533775 97.50% [DEBUG] switch_core_state_machine.c:647 (sofia/default/+447903175491@avoxi.io) State EXECUTE going to sleep
  1498. 2023-02-07 13:27:46.533775 97.50% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+447903175491@avoxi.io) Running State Change CS_HANGUP (Cur 5 Tot 1863)
  1499. 2023-02-07 13:27:46.533775 97.50% [DEBUG] switch_core_state_machine.c:844 (sofia/default/+447903175491@avoxi.io) Callstate Change RINGING -> HANGUP
  1500. 2023-02-07 13:27:46.533775 97.50% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+447903175491@avoxi.io) State HANGUP
  1501. 2023-02-07 13:27:46.533775 97.50% [DEBUG] mod_sofia.c:468 Channel sofia/default/+447903175491@avoxi.io hanging up, cause: NO_ROUTE_DESTINATION
  1502. 2023-02-07 13:27:46.533775 97.50% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 404
  1503. 2023-02-07 13:27:46.533775 97.50% [DEBUG] switch_core_state_machine.c:59 sofia/default/+447903175491@avoxi.io Standard HANGUP, cause: NO_ROUTE_DESTINATION
  1504. 2023-02-07 13:27:46.533775 97.50% [DEBUG] switch_core_state_machine.c:846 (sofia/default/+447903175491@avoxi.io) State HANGUP going to sleep
  1505. 2023-02-07 13:27:46.533775 97.50% [DEBUG] switch_core_state_machine.c:616 (sofia/default/+447903175491@avoxi.io) State Change CS_HANGUP -> CS_REPORTING
  1506. 2023-02-07 13:27:46.533775 97.50% [DEBUG] switch_core_state_machine.c:581 (sofia/default/+447903175491@avoxi.io) Running State Change CS_REPORTING (Cur 5 Tot 1863)
  1507. 2023-02-07 13:27:46.533775 97.50% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+447903175491@avoxi.io) State REPORTING
  1508. 2023-02-07 13:27:46.533775 97.50% [INFO] mod_json_cdr.c:271 Process [6f7e3567-38b4-46b1-b91c-ffeba236d499.cdr.json]
  1509. 2023-02-07 13:27:46.533775 97.50% [DEBUG] switch_core_state_machine.c:168 sofia/default/+447903175491@avoxi.io Standard REPORTING, cause: NO_ROUTE_DESTINATION
  1510. 2023-02-07 13:27:46.533775 97.50% [DEBUG] switch_core_state_machine.c:932 (sofia/default/+447903175491@avoxi.io) State REPORTING going to sleep
  1511. 2023-02-07 13:27:46.533775 97.50% [DEBUG] switch_core_state_machine.c:607 (sofia/default/+447903175491@avoxi.io) State Change CS_REPORTING -> CS_DESTROY
  1512. 2023-02-07 13:27:46.533775 97.50% [DEBUG] switch_core_session.c:1753 Session 1863 (sofia/default/+447903175491@avoxi.io) Locked, Waiting on external entities
  1513. 2023-02-07 13:27:46.533775 97.50% [NOTICE] switch_core_session.c:1771 Session 1863 (sofia/default/+447903175491@avoxi.io) Ended
  1514. 2023-02-07 13:27:46.533775 97.50% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/+447903175491@avoxi.io [CS_DESTROY]
  1515. 2023-02-07 13:27:46.533775 97.50% [DEBUG] switch_core_state_machine.c:735 (sofia/default/+447903175491@avoxi.io) Running State Change CS_DESTROY (Cur 4 Tot 1863)
  1516. 2023-02-07 13:27:46.533775 97.50% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+447903175491@avoxi.io) State DESTROY
  1517. 2023-02-07 13:27:46.533775 97.50% [DEBUG] mod_sofia.c:379 sofia/default/+447903175491@avoxi.io SOFIA DESTROY
  1518. 2023-02-07 13:27:46.533775 97.50% [DEBUG] switch_core_state_machine.c:175 sofia/default/+447903175491@avoxi.io Standard DESTROY
  1519. 2023-02-07 13:27:46.533775 97.50% [DEBUG] switch_core_state_machine.c:745 (sofia/default/+447903175491@avoxi.io) State DESTROY going to sleep
  1520.  

Reply to "Israel access card"