From Prashant Kumar, 1 Year ago, written in FreeSWITCH.
- go back
Embed
Viewing differences between and Callcenter strategy Ring All getting error opensip
  1. and you must configure your device to use the proper domain in its authentication credentials.
  2. 2023-03-01 11:40:27.374854 94.47% [NOTICE] switch_channel.c:1123 New Channel sofia/default/4810338297@95.217.238.195 [8b13f8a3-6026-4cd9-94b9-19166a7cc79f]
  3. 2023-03-01 11:40:27.374854 94.47% [DEBUG] switch_core_state_machine.c:581 (sofia/default/4810338297@95.217.238.195) Running State Change CS_NEW (Cur 1 Tot 1)
  4. 2023-03-01 11:40:27.374854 94.47% [INFO] sofia.c:10462 sofia/default/4810338297@95.217.238.195 receiving invite from 142.132.184.134:5060 version: 1.10.7 -release 64bit call-id: b4071744-32c8-123c-9e93-960001f424d3
  5. 2023-03-01 11:40:27.374854 94.47% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 142.132.184.134:0.
  6. 2023-03-01 11:40:27.374854 94.47% [DEBUG] sofia.c:10585 IP 142.132.184.134 Approved by acl "default[]". Access Granted.
  7. 2023-03-01 11:40:27.374854 94.47% [DEBUG] sofia.c:7499 Channel sofia/default/4810338297@95.217.238.195 entering state [received][100]
  8. 2023-03-01 11:40:27.374854 94.47% [DEBUG] sofia.c:7509 Remote SDP:
  9. v=0
  10. o=FreeSWITCH 1677649515 1677649516 IN IP4 95.217.238.195
  11. s=FreeSWITCH
  12. c=IN IP4 95.217.238.195
  13. t=0 0
  14. m=audio 21312 RTP/AVP 0 101
  15. a=rtpmap:0 PCMU/8000
  16. a=rtpmap:101 telephone-event/8000
  17. a=fmtp:101 0-15
  18. a=ptime:20
  19.  
  20. 2023-03-01 11:40:27.374854 94.47% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  21. 2023-03-01 11:40:27.374854 94.47% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  22. 2023-03-01 11:40:27.374854 94.47% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  23. 2023-03-01 11:40:27.374854 94.47% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  24. 2023-03-01 11:40:27.374854 94.47% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/4810338297@95.217.238.195 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  25. 2023-03-01 11:40:27.374854 94.47% [DEBUG] switch_core_codec.c:111 sofia/default/4810338297@95.217.238.195 Original read codec set to PCMU:0
  26. 2023-03-01 11:40:27.374854 94.47% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  27. 2023-03-01 11:40:27.374854 94.47% [DEBUG] switch_core_media.c:5973 sofia/default/4810338297@95.217.238.195 Set 2833 dtmf send payload to 101 recv payload to 101
  28. 2023-03-01 11:40:27.374854 94.47% [DEBUG] sofia.c:7933 (sofia/default/4810338297@95.217.238.195) State Change CS_NEW -> CS_INIT
  29. 2023-03-01 11:40:27.374854 94.47% [DEBUG] switch_core_state_machine.c:600 (sofia/default/4810338297@95.217.238.195) State NEW
  30. 2023-03-01 11:40:27.374854 94.47% [DEBUG] switch_core_state_machine.c:581 (sofia/default/4810338297@95.217.238.195) Running State Change CS_INIT (Cur 1 Tot 1)
  31. 2023-03-01 11:40:27.374854 94.47% [DEBUG] switch_core_state_machine.c:624 (sofia/default/4810338297@95.217.238.195) State INIT
  32. 2023-03-01 11:40:27.374854 94.47% [DEBUG] mod_sofia.c:97 sofia/default/4810338297@95.217.238.195 SOFIA INIT
  33. 2023-03-01 11:40:27.374854 94.47% [DEBUG] switch_core_state_machine.c:40 sofia/default/4810338297@95.217.238.195 Standard INIT
  34. 2023-03-01 11:40:27.374854 94.47% [DEBUG] switch_core_state_machine.c:48 (sofia/default/4810338297@95.217.238.195) State Change CS_INIT -> CS_ROUTING
  35. 2023-03-01 11:40:27.374854 94.47% [DEBUG] switch_core_state_machine.c:624 (sofia/default/4810338297@95.217.238.195) State INIT going to sleep
  36. 2023-03-01 11:40:27.374854 94.47% [DEBUG] switch_core_state_machine.c:581 (sofia/default/4810338297@95.217.238.195) Running State Change CS_ROUTING (Cur 1 Tot 1)
  37. 2023-03-01 11:40:27.374854 94.47% [DEBUG] switch_channel.c:2380 (sofia/default/4810338297@95.217.238.195) Callstate Change DOWN -> RINGING
  38. 2023-03-01 11:40:27.374854 94.47% [DEBUG] switch_core_state_machine.c:640 (sofia/default/4810338297@95.217.238.195) State ROUTING
  39. 2023-03-01 11:40:27.374854 94.47% [DEBUG] mod_sofia.c:158 sofia/default/4810338297@95.217.238.195 SOFIA ROUTING
  40. 2023-03-01 11:40:27.374854 94.47% [DEBUG] switch_core_state_machine.c:230 sofia/default/4810338297@95.217.238.195 Standard ROUTING
  41. 2023-03-01 11:40:27.374854 94.47% [INFO] mod_dialplan_xml.c:639 Processing ASTPP <4810338297>->1011 in context default
  42. 2023-03-01 11:40:27.394864 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] USING CACHE
  43. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  44. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  45. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  46. 2023-03-01 11:40:27.394864 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
  47. 2023-03-01 11:40:27.394864 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 1011
  48. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '1011' limit 1
  49. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_ACCESS_NUMBER]1.0
  50. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '1011' and status=0 LIMIT 1
  51. 2023-03-01 11:40:27.394864 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] SOURCE NUMBERadmin
  52. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  53. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION LOCAL SOURCE] Query :SELECT accountid FROM sip_devices WHERE username = '1011' limit 1
  54. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION LOCAL DOMAIN] Query :SELECT accountid from sip_devices where accountid = '63' and username = '1011' limit 1
  55. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION LOCAL] Query :SELECT local_effective_caller_id_name,local_effective_caller_id_number FROM sip_devices WHERE username = 'admin' limit 1
  56. 2023-03-01 11:40:27.394864 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : "ASTPP" / 4810338297
  57. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Interconnect code destination number
  58. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_interconnectcode_info] Query :SELECT * FROM interconnectcode WHERE interconnectcode = 1011 AND status=0 limit 1
  59. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]0.0
  60. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]1.1
  61. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]1.1 :: CALL IS DID
  62. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag, A.codec as did_codec,A.provider_id as provider_id FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="1011" LIMIT 1
  63. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  64. 2023-03-01 11:40:27.394864 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : inbound
  65. 2023-03-01 11:40:27.394864 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [OPENSIP_FROM_IP] from_ip : 95.217.238.195
  66. 2023-03-01 11:40:27.394864 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 990004]
  67. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "990004" AND deleted = 0 limit 1
  68. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Ashish account call balance :SELECT * FROM accounts WHERE id =63
  69. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DID]0
  70. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DID]1
  71. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] Call Direction : inbound
  72. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] DID ACCOUNT ID : 63
  73. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE P.is_terminated = 0 AND (patterns = '^1011.*' OR patterns = '^101.*' OR patterns = '^10.*' OR patterns = '^1.*' OR patterns ='--') AND accountid = 63 ORDER BY LENGTH(PKGPTR.patterns) DESC
  74. 2023-03-01 11:40:27.394864 94.47% [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 ="1011" AND A.accountid = '63' limit 1
  75. 2023-03-01 11:40:27.394864 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 1011
  76. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]0.0
  77. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]1.1
  78. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]1.1 :: CALL IS DID
  79. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag, A.codec as did_codec,A.provider_id as provider_id FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="1011" LIMIT 1
  80. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  81. 2023-03-01 11:40:27.394864 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : inbound
  82. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type,accountid FROM call_barring WHERE ((number = '1011' OR number = '101' OR number = '10' OR number = '1' OR number ='--') OR (number = '1011*' OR number = '101*' OR number = '10*' OR number = '1*' OR number ='--') OR (number = '4810338297' OR number = '481033829' OR number = '48103382' OR number = '4810338' OR number = '481033' OR number = '48103' OR number = '4810' OR number = '481' OR number = '48' OR number = '4' OR number ='--') OR (number = '4810338297*' OR number = '481033829*' OR number = '48103382*' OR number = '4810338*' OR number = '481033*' OR number = '48103*' OR number = '4810*' OR number = '481*' OR number = '48*' OR number = '4*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
  83. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1
  84. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '1011' OR routing_prefix = '101' OR routing_prefix = '10' OR routing_prefix = '1' OR routing_prefix ='--') AND status = 0 and reseller_id = 61 limit 1
  85. 2023-03-01 11:40:27.394864 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
  86. 2023-03-01 11:40:27.394864 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 63
  87. 2023-03-01 11:40:27.394864 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 990004
  88. 2023-03-01 11:40:27.394864 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Ashish account call balance :SELECT * FROM accounts WHERE id =63
  89. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 1029397.01
  90. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
  91. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 138
  92. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
  93. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
  94. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.0
  95. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.1:CACHING ID:::138
  96. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :inbound
  97. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag, A.codec as did_codec,A.provider_id as provider_id FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="1011" LIMIT 1
  98. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  99. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : inbound
  100. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
  101. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 102
  102. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
  103. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
  104. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.00000
  105. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : 1011
  106. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 88
  107. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 63
  108. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
  109. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Ashish account call balance :SELECT * FROM accounts WHERE id =63
  110. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your1029397.01 balance Accountid 63 !!!
  111. 2023-03-01 11:40:27.414884 94.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Call Is Free - Assigning Max Length  :: 100
  112. 2023-03-01 11:40:27.414884 94.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] FINDING LIMIT FOR RESELLER: 61
  113. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "61" AND deleted = 0 limit 1
  114. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Ashish account call balance :SELECT * FROM accounts WHERE id =63
  115. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Reseller Information ===================
  116. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 61
  117. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 990003
  118. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Ashish account call balance :SELECT * FROM accounts WHERE id =63
  119. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 1029397.01
  120. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
  121. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 137
  122. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.0
  123. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.1:CACHING ID:::137
  124. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :inbound
  125. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag, A.codec as did_codec,A.provider_id as provider_id FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="1011" LIMIT 1
  126. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  127. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : inbound
  128. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
  129. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 102
  130. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
  131. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
  132. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.00000
  133. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : 1011
  134. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 88
  135. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 61
  136. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
  137. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Ashish account call balance :SELECT * FROM accounts WHERE id =63
  138. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your1029397.01 balance Accountid 61 !!!
  139. 2023-03-01 11:40:27.414884 94.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Call Is Free - Assigning Max Length  :: 100
  140. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Reseller xml_user_rates : ID:102|CODE:^1011.*|DESTINATION:1011|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:14|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:88|ACCID:63||ID:102|CODE:^1011.*|DESTINATION:1011|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:14|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:88|ACCID:63
  141. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
  142. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Lossless Routing Status : 0
  143. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 100 minutes
  144. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] INB_FREE:TRUE
  145. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] free_inbound:1
  146. 2023-03-01 11:40:27.414884 94.47% [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 = 63 AND status=0 LIMIT 1
  147. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "63" AND deleted = 0 limit 1
  148. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Ashish account call balance :SELECT * FROM accounts WHERE id =63
  149. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML:63
  150. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Userinfo XML:63
  151. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML : 63
  152. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_domain_info] Query :SELECT * FROM domains WHERE accountid = 63 limit 1
  153. 2023-03-01 11:40:27.414884 94.47% [WARNING] switch_cpp.cpp:1465 [ASTPP] [user_domain] User Domain : astppqa603.astppbilling.org
  154. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 100
  155. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  156. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 0
  157. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] Call Direction : inbound
  158. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET get_record_info] Query :SELECT sip_device_routing.is_recording FROM sip_devices AS sip_devices, accounts AS accounts ,sip_device_routing AS sip_device_routing  WHERE sip_devices.accountid = accounts.id AND sip_devices.id = sip_device_routing.sip_device_id AND sip_devices.username = "6" AND accounts.status = 0
  159. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Set number as redial] destination_number :1011
  160. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Set number as return] destination_number :1011
  161. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_domain_info] Query :SELECT * FROM domains WHERE accountid = 63 limit 1
  162. 2023-03-01 11:40:27.414884 94.47% [WARNING] switch_cpp.cpp:1465 [ASTPP] [XML] User Domain : astppqa603.astppbilling.org
  163. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_accounts_info] Query :SELECT * FROM accounts WHERE id = 63  and status = 0 limit 1
  164. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [XML] Custom Function Name :: custom_inbound_14
  165. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] Call Type : 14
  166. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] call_type : Call Center
  167. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET_CALLCENTER_INFO] Query :SELECT * from callcenter_queues WHERE id = 6 and status=0 LIMIT 1
  168. 2023-03-01 11:40:27.414884 94.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX]  Call center greetings_file::10
  169. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET_RING_GROUP_INFO] Query :SELECT file_name from pbx_recording WHERE id = '10' LIMIT 1
  170. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET_RING_GROUP_INFO] Query :SELECT file_name from pbx_recording WHERE id = '11' LIMIT 1
  171. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Generated XML:<?xml version="1.0" encoding="UTF-8" standalone="no"?>
  172. <document type="freeswitch/xml">
  173. <section name="dialplan" description="ASTPP Dialplan">
  174. <context name="default">
  175. <extension name="1011">
  176. <condition field="destination_number" expression="1011">
  177. <action application="set" data="effective_destination_number=1011"/>
  178. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
  179. <action application="set" data="bridge_pre_execute_bleg_data=+6000 normal_clearing"/>
  180. <action application="set" data="type=0"/>
  181. <action application="set" data="callstart=2023-03-01 11:40:27"/>
  182. <action application="set" data="hangup_after_bridge=true"/>
  183. <action application="set" data="continue_on_fail=TRUE"/>
  184. <action application="set" data="account_id=63"/>
  185. <action application="set" data="parent_id=61"/>
  186. <action application="set" data="entity_id=0"/>
  187. <action application="set" data="call_processed=internal"/>
  188. <action application="set" data="call_direction=inbound"/>
  189. <action application="set" data="accountname=default"/>
  190. <action application="export" data="t38_passthru=true"/>
  191. <action application="set" data="fax_enable_t38=true"/>
  192. <action application="set" data="fax_enable_t38_request=true"/>
  193. <action application="set" data="origination_rates_did=ID:102|CODE:^1011.*|DESTINATION:1011|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:14|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:88|ACCID:63||ID:102|CODE:^1011.*|DESTINATION:1011|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:14|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:88|ACCID:63"/>
  194. <action application="set" data="origination_rates=0"/>
  195. <action application="set" data="original_caller_id_name="ASTPP""/>
  196. <action application="set" data="original_caller_id_number=4810338297"/>
  197. <action application="export" data="is_recording=1"/>
  198. <action application="export" data="media_bug_answer_req=true"/>
  199. <action application="export" data="RECORD_STEREO=true"/>
  200. <action application="export" data="record_sample_rate=8000"/>
  201. <action application="export" data="execute_on_answer=record_session $${recordings_dir}/${uuid}.wav"/>
  202. <action application="hash" data="insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}"/>
  203. <action application="hash" data="insert/${domain_name}-call_return/${destination_number}/${caller_id_number}"/>
  204. <action application="set" data="effective_caller_id_name="ASTPP""/>
  205. <action application="set" data="effective_caller_id_number=4810338297"/>
  206. <action application="set" data="receiver_accid=63"/>
  207. <action application="export" data="domain_name=astppqa603.astppbilling.org"/>
  208. <action application="export" data="sip_to_host=astppqa603.astppbilling.org"/>
  209. <action application="export" data="presence_data=x|||Reseller PK  Client Customer(990004)|||||||||DID|||95.217.238.195"/>
  210. <action application="export" data="call_type=14"/>
  211. <action application="set" data="did_calltype=14"/>
  212. <action application="set" data="did_extensions=6"/>
  213. <action application="set" data="daily_call_credit_limit=0.00000"/>
  214. <action application="set" data="monthly_call_credit_limit=0.00000"/>
  215. <action application="set" data="daily_call_used_limit=0.00000"/>
  216. <action application="set" data="monthly_call_used_limit=0.00000"/>
  217. <action application="export" data="is_recording=1"/>
  218. <action application="export" data="media_bug_answer_req=true"/>
  219. <action application="export" data="RECORD_STEREO=true"/>
  220. <action application="export" data="record_sample_rate=8000"/>
  221. <action application="export" data="execute_on_answer=record_session $${recordings_dir}/${uuid}.wav"/>
  222. <action application="answer"/>
  223. <action application="playback" data="/var/www/html/astpp/upload/pbx/taunt_010323081438.wav"/>
  224. <action application="playback" data="/var/www/html/astpp/upload/pbx/sample-12s_010323091804.wav"/>
  225. <action application="callcenter" data="dataQA@default"/>
  226. </condition>
  227. </extension>
  228. </context>
  229. </section>
  230. </document>
  231. Dialplan: sofia/default/4810338297@95.217.238.195 parsing [default->1011] continue=false
  232. Dialplan: sofia/default/4810338297@95.217.238.195 Regex (PASS) [1011] destination_number(1011) =~ /1011/ break=on-false
  233. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(effective_destination_number=1011)
  234. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(bridge_pre_execute_bleg_app=sched_hangup)
  235. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
  236. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(type=0)
  237. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(callstart=2023-03-01 11:40:27)
  238. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(hangup_after_bridge=true)
  239. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(continue_on_fail=TRUE)
  240. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(account_id=63)
  241. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(parent_id=61)
  242. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(entity_id=0)
  243. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(call_processed=internal)
  244. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(call_direction=inbound)
  245. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(accountname=default)
  246. Dialplan: sofia/default/4810338297@95.217.238.195 Action export(t38_passthru=true)
  247. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(fax_enable_t38=true)
  248. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(fax_enable_t38_request=true)
  249. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(origination_rates_did=ID:102|CODE:^1011.*|DESTINATION:1011|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:14|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:88|ACCID:63||ID:102|CODE:^1011.*|DESTINATION:1011|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:14|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:88|ACCID:63)
  250. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(origination_rates=0)
  251. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(original_caller_id_name=)
  252. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(original_caller_id_number=4810338297)
  253. Dialplan: sofia/default/4810338297@95.217.238.195 Action export(is_recording=1)
  254. Dialplan: sofia/default/4810338297@95.217.238.195 Action export(media_bug_answer_req=true)
  255. Dialplan: sofia/default/4810338297@95.217.238.195 Action export(RECORD_STEREO=true)
  256. Dialplan: sofia/default/4810338297@95.217.238.195 Action export(record_sample_rate=8000)
  257. Dialplan: sofia/default/4810338297@95.217.238.195 Action export(execute_on_answer=record_session $${recordings_dir}/${uuid}.wav)
  258. Dialplan: sofia/default/4810338297@95.217.238.195 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
  259. Dialplan: sofia/default/4810338297@95.217.238.195 Action hash(insert/${domain_name}-call_return/${destination_number}/${caller_id_number})
  260. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(effective_caller_id_name=)
  261. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(effective_caller_id_number=4810338297)
  262. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(receiver_accid=63)
  263. Dialplan: sofia/default/4810338297@95.217.238.195 Action export(domain_name=astppqa603.astppbilling.org)
  264. Dialplan: sofia/default/4810338297@95.217.238.195 Action export(sip_to_host=astppqa603.astppbilling.org)
  265. Dialplan: sofia/default/4810338297@95.217.238.195 Action export(presence_data=x|||Reseller PK  Client Customer(990004)|||||||||DID|||95.217.238.195)
  266. Dialplan: sofia/default/4810338297@95.217.238.195 Action export(call_type=14)
  267. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(did_calltype=14)
  268. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(did_extensions=6)
  269. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(daily_call_credit_limit=0.00000)
  270. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(monthly_call_credit_limit=0.00000)
  271. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(daily_call_used_limit=0.00000)
  272. Dialplan: sofia/default/4810338297@95.217.238.195 Action set(monthly_call_used_limit=0.00000)
  273. Dialplan: sofia/default/4810338297@95.217.238.195 Action export(is_recording=1)
  274. Dialplan: sofia/default/4810338297@95.217.238.195 Action export(media_bug_answer_req=true)
  275. Dialplan: sofia/default/4810338297@95.217.238.195 Action export(RECORD_STEREO=true)
  276. Dialplan: sofia/default/4810338297@95.217.238.195 Action export(record_sample_rate=8000)
  277. Dialplan: sofia/default/4810338297@95.217.238.195 Action export(execute_on_answer=record_session $${recordings_dir}/${uuid}.wav)
  278. Dialplan: sofia/default/4810338297@95.217.238.195 Action answer()
  279. Dialplan: sofia/default/4810338297@95.217.238.195 Action playback(/var/www/html/astpp/upload/pbx/taunt_010323081438.wav)
  280. Dialplan: sofia/default/4810338297@95.217.238.195 Action playback(/var/www/html/astpp/upload/pbx/sample-12s_010323091804.wav)
  281. Dialplan: sofia/default/4810338297@95.217.238.195 Action callcenter(dataQA@default)
  282. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_core_state_machine.c:281 (sofia/default/4810338297@95.217.238.195) State Change CS_ROUTING -> CS_EXECUTE
  283. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_core_state_machine.c:640 (sofia/default/4810338297@95.217.238.195) State ROUTING going to sleep
  284. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_core_state_machine.c:581 (sofia/default/4810338297@95.217.238.195) Running State Change CS_EXECUTE (Cur 1 Tot 1)
  285. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_core_state_machine.c:647 (sofia/default/4810338297@95.217.238.195) State EXECUTE
  286. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_sofia.c:213 sofia/default/4810338297@95.217.238.195 SOFIA EXECUTE
  287. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_core_state_machine.c:323 sofia/default/4810338297@95.217.238.195 Standard EXECUTE
  288. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(effective_destination_number=1011)
  289. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [effective_destination_number]=[1011]
  290. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(bridge_pre_execute_bleg_app=sched_hangup)
  291. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [bridge_pre_execute_bleg_app]=[sched_hangup]
  292. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
  293. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [bridge_pre_execute_bleg_data]=[+6000 normal_clearing]
  294. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(type=0)
  295. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [type]=[0]
  296. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(callstart=2023-03-01 11:40:27)
  297. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [callstart]=[2023-03-01 11:40:27]
  298. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(hangup_after_bridge=true)
  299. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [hangup_after_bridge]=[true]
  300. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(continue_on_fail=TRUE)
  301. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [continue_on_fail]=[TRUE]
  302. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(account_id=63)
  303. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [account_id]=[63]
  304. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(parent_id=61)
  305. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [parent_id]=[61]
  306. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(entity_id=0)
  307. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [entity_id]=[0]
  308. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(call_processed=internal)
  309. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [call_processed]=[internal]
  310. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(call_direction=inbound)
  311. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [call_direction]=[inbound]
  312. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(accountname=default)
  313. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [accountname]=[default]
  314. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 export(t38_passthru=true)
  315. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [t38_passthru]=[true]
  316. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(fax_enable_t38=true)
  317. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [fax_enable_t38]=[true]
  318. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(fax_enable_t38_request=true)
  319. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [fax_enable_t38_request]=[true]
  320. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(origination_rates_did=ID:102|CODE:^1011.*|DESTINATION:1011|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:14|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:88|ACCID:63||ID:102|CODE:^1011.*|DESTINATION:1011|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:14|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:88|ACCID:63)
  321. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [origination_rates_did]=[ID:102|CODE:^1011.*|DESTINATION:1011|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:14|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:88|ACCID:63||ID:102|CODE:^1011.*|DESTINATION:1011|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:14|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:88|ACCID:63]
  322. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(origination_rates=0)
  323. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [origination_rates]=[0]
  324. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(original_caller_id_name=)
  325. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [original_caller_id_name]=[UNDEF]
  326. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(original_caller_id_number=4810338297)
  327. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [original_caller_id_number]=[4810338297]
  328. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 export(is_recording=1)
  329. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [is_recording]=[1]
  330. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 export(media_bug_answer_req=true)
  331. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [media_bug_answer_req]=[true]
  332. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 export(RECORD_STEREO=true)
  333. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [RECORD_STEREO]=[true]
  334. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 export(record_sample_rate=8000)
  335. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [record_sample_rate]=[8000]
  336. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 export(execute_on_answer=record_session /usr/local/freeswitch/recordings/8b13f8a3-6026-4cd9-94b9-19166a7cc79f.wav)
  337. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [execute_on_answer]=[record_session /usr/local/freeswitch/recordings/8b13f8a3-6026-4cd9-94b9-19166a7cc79f.wav]
  338. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 hash(insert/142.132.184.134-last_dial/4810338297/1011)
  339. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 hash(insert/142.132.184.134-call_return/1011/4810338297)
  340. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(effective_caller_id_name=)
  341. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [effective_caller_id_name]=[UNDEF]
  342. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(effective_caller_id_number=4810338297)
  343. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [effective_caller_id_number]=[4810338297]
  344. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(receiver_accid=63)
  345. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [receiver_accid]=[63]
  346. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 export(domain_name=astppqa603.astppbilling.org)
  347. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [domain_name]=[astppqa603.astppbilling.org]
  348. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 export(sip_to_host=astppqa603.astppbilling.org)
  349. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [sip_to_host]=[astppqa603.astppbilling.org]
  350. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 export(presence_data=x|||Reseller PK  Client Customer(990004)|||||||||DID|||95.217.238.195)
  351. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[x|||Reseller PK  Client Customer(990004)|||||||||DID|||95.217.238.195]
  352. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 export(call_type=14)
  353. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [call_type]=[14]
  354. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(did_calltype=14)
  355. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [did_calltype]=[14]
  356. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(did_extensions=6)
  357. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [did_extensions]=[6]
  358. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(daily_call_credit_limit=0.00000)
  359. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [daily_call_credit_limit]=[0.00000]
  360. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(monthly_call_credit_limit=0.00000)
  361. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [monthly_call_credit_limit]=[0.00000]
  362. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(daily_call_used_limit=0.00000)
  363. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [daily_call_used_limit]=[0.00000]
  364. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 set(monthly_call_used_limit=0.00000)
  365. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_dptools.c:1685 SET sofia/default/4810338297@95.217.238.195 [monthly_call_used_limit]=[0.00000]
  366. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 export(is_recording=1)
  367. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [is_recording]=[1]
  368. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 export(media_bug_answer_req=true)
  369. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [media_bug_answer_req]=[true]
  370. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 export(RECORD_STEREO=true)
  371. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [RECORD_STEREO]=[true]
  372. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 export(record_sample_rate=8000)
  373. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [record_sample_rate]=[8000]
  374. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 export(execute_on_answer=record_session /usr/local/freeswitch/recordings/8b13f8a3-6026-4cd9-94b9-19166a7cc79f.wav)
  375. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [execute_on_answer]=[record_session /usr/local/freeswitch/recordings/8b13f8a3-6026-4cd9-94b9-19166a7cc79f.wav]
  376. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 answer()
  377. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/4810338297@95.217.238.195] 142.132.184.134 port 20944 -> 95.217.238.195 port 21312 codec: 0 ms: 20
  378. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms
  379. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_core_media.c:9089 sofia/default/4810338297@95.217.238.195 Set 2833 dtmf send payload to 101
  380. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_core_media.c:9096 sofia/default/4810338297@95.217.238.195 Set 2833 dtmf receive payload to 101
  381. 2023-03-01 11:40:27.414884 94.47% [DEBUG] switch_core_media.c:9119 sofia/default/4810338297@95.217.238.195 Set rtp dtmf delay to 40
  382. 2023-03-01 11:40:27.414884 94.47% [DEBUG] mod_sofia.c:913 Local SDP sofia/default/4810338297@95.217.238.195:
  383. v=0
  384. o=FreeSWITCH 1677649883 1677649884 IN IP4 142.132.184.134
  385. s=FreeSWITCH
  386. c=IN IP4 142.132.184.134
  387. t=0 0
  388. m=audio 20944 RTP/AVP 0 101
  389. a=rtpmap:0 PCMU/8000
  390. a=rtpmap:101 telephone-event/8000
  391. a=fmtp:101 0-15
  392. a=ptime:20
  393. a=sendrecv
  394.  
  395. 2023-03-01 11:40:27.414884 94.47% [NOTICE] mod_dptools.c:1419 Channel [sofia/default/4810338297@95.217.238.195] has been answered
  396. 2023-03-01 11:40:27.414884 94.47% [DEBUG] sofia.c:7499 Channel sofia/default/4810338297@95.217.238.195 entering state [completed][200]
  397. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 record_session(/usr/local/freeswitch/recordings/8b13f8a3-6026-4cd9-94b9-19166a7cc79f.wav)
  398. 2023-03-01 11:40:27.454830 94.47% [DEBUG] switch_ivr_async.c:1503 Record session sample rate: 8000 -> 8000
  399. 2023-03-01 11:40:27.454830 94.47% [DEBUG] switch_core_media_bug.c:978 Attaching BUG to sofia/default/4810338297@95.217.238.195
  400. 2023-03-01 11:40:27.454830 94.47% [DEBUG] switch_channel.c:3950 (sofia/default/4810338297@95.217.238.195) Callstate Change RINGING -> ACTIVE
  401. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 playback(/var/www/html/astpp/upload/pbx/taunt_010323081438.wav)
  402. 2023-03-01 11:40:27.454830 94.47% [DEBUG] switch_core_file.c:444 File /var/www/html/astpp/upload/pbx/taunt_010323081438.wav sample rate 22257 doesn't match requested rate 8000
  403. 2023-03-01 11:40:27.454830 94.47% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
  404. 2023-03-01 11:40:27.454830 94.47% [DEBUG] switch_ivr_async.c:1777 No silence detection configured; assuming start of speech
  405. 2023-03-01 11:40:27.474835 94.47% [DEBUG] sofia.c:7499 Channel sofia/default/4810338297@95.217.238.195 entering state [ready][200]
  406. 2023-03-01 11:40:27.994862 94.47% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed.
  407. 2023-03-01 11:40:27.994862 94.47% [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMU:0
  408. 2023-03-01 11:40:28.574875 94.33% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [816845@astppqa604.astppbilling.org] from ip 150.129.107.251
  409. 2023-03-01 11:40:28.714859 94.33% [WARNING] sofia_reg.c:3223 Can't find user [816845@astppqa604.astppbilling.org] from 150.129.107.251
  410. You must define a domain called 'astppqa604.astppbilling.org' in your directory and add a user with the id="816845" attribute
  411. and you must configure your device to use the proper domain in its authentication credentials.
  412. 2023-03-01 11:40:31.514852 94.27% [DEBUG] switch_ivr_play_say.c:2001 done playing file /var/www/html/astpp/upload/pbx/taunt_010323081438.wav
  413. EXECUTE [depth=0] sofia/default/4810338297@95.217.238.195 playback(/var/www/html/astpp/upload/pbx/sample-12s_010323091804.wav)
  414. 2023-03-01 11:40:31.514852 94.27% [DEBUG] switch_core_file.c:444 File /var/www/html/astpp/upload/pbx/sample-12s_010323091804.wav sample rate 44100 doesn't match requested rate 8000
  415. 2023-03-01 11:40:31.514852 94.27% [WARNING] switch_core_file.c:463 File has 2 channels, muxing to 1 channel will occur.
  416. 2023-03-01 11:40:31.514852 94.27% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
  417. 2023-03-01 11:40:44.314849 94.63% [DEBUG] switch_ivr_play_say.c:2001 done playing file /var/www/html/astpp/upload/pbx/sample-12s_010323091804.wav
  418. 2023-03-01 11:40:44.314849 94.63% [ERR] switch_core_session.c:2770 Invalid Application callcenter
  419. 2023-03-01 11:40:44.314849 94.63% [NOTICE] switch_core_session.c:2771 Hangup sofia/default/4810338297@95.217.238.195 [CS_EXECUTE] [DESTINATION_OUT_OF_ORDER]
  420. 2023-03-01 11:40:44.314849 94.63% [DEBUG] switch_core_state_machine.c:647 (sofia/default/4810338297@95.217.238.195) State EXECUTE going to sleep
  421. 2023-03-01 11:40:44.314849 94.63% [DEBUG] switch_core_state_machine.c:581 (sofia/default/4810338297@95.217.238.195) Running State Change CS_HANGUP (Cur 1 Tot 1)
  422. 2023-03-01 11:40:44.314849 94.63% [DEBUG] switch_ivr_async.c:1596 Stop recording file /usr/local/freeswitch/recordings/8b13f8a3-6026-4cd9-94b9-19166a7cc79f.wav
  423. 2023-03-01 11:40:44.314849 94.63% [DEBUG] switch_ivr_async.c:1677 Channel is hung up
  424. 2023-03-01 11:40:44.314849 94.63% [DEBUG] switch_core_media_bug.c:1328 Removing BUG from sofia/default/4810338297@95.217.238.195
  425. 2023-03-01 11:40:44.314849 94.63% [DEBUG] switch_core_state_machine.c:844 (sofia/default/4810338297@95.217.238.195) Callstate Change ACTIVE -> HANGUP
  426. 2023-03-01 11:40:44.314849 94.63% [DEBUG] switch_core_state_machine.c:846 (sofia/default/4810338297@95.217.238.195) State HANGUP
  427. 2023-03-01 11:40:44.314849 94.63% [DEBUG] mod_sofia.c:468 Channel sofia/default/4810338297@95.217.238.195 hanging up, cause: DESTINATION_OUT_OF_ORDER
  428. 2023-03-01 11:40:44.314849 94.63% [DEBUG] mod_sofia.c:522 Sending BYE to sofia/default/4810338297@95.217.238.195
  429. 2023-03-01 11:40:44.314849 94.63% [DEBUG] switch_core_state_machine.c:59 sofia/default/4810338297@95.217.238.195 Standard HANGUP, cause: DESTINATION_OUT_OF_ORDER
  430. 2023-03-01 11:40:44.314849 94.63% [DEBUG] switch_core_state_machine.c:846 (sofia/default/4810338297@95.217.238.195) State HANGUP going to sleep
  431. 2023-03-01 11:40:44.314849 94.63% [DEBUG] switch_core_state_machine.c:616 (sofia/default/4810338297@95.217.238.195) State Change CS_HANGUP -> CS_REPORTING
  432. 2023-03-01 11:40:44.314849 94.63% [DEBUG] switch_core_state_machine.c:581 (sofia/default/4810338297@95.217.238.195) Running State Change CS_REPORTING (Cur 1 Tot 1)
  433. 2023-03-01 11:40:44.314849 94.63% [DEBUG] switch_core_state_machine.c:932 (sofia/default/4810338297@95.217.238.195) State REPORTING
  434. 2023-03-01 11:40:44.314849 94.63% [INFO] mod_json_cdr.c:271 Process [8b13f8a3-6026-4cd9-94b9-19166a7cc79f.cdr.json]
  435. 2023-03-01 11:40:44.374847 94.63% [DEBUG] switch_core_state_machine.c:168 sofia/default/4810338297@95.217.238.195 Standard REPORTING, cause: DESTINATION_OUT_OF_ORDER
  436. 2023-03-01 11:40:44.374847 94.63% [DEBUG] switch_core_state_machine.c:932 (sofia/default/4810338297@95.217.238.195) State REPORTING going to sleep
  437. 2023-03-01 11:40:44.374847 94.63% [DEBUG] switch_core_state_machine.c:607 (sofia/default/4810338297@95.217.238.195) State Change CS_REPORTING -> CS_DESTROY
  438. 2023-03-01 11:40:44.374847 94.63% [DEBUG] switch_core_session.c:1753 Session 1 (sofia/default/4810338297@95.217.238.195) Locked, Waiting on external entities
  439. 2023-03-01 11:40:44.374847 94.63% [NOTICE] switch_core_session.c:1771 Session 1 (sofia/default/4810338297@95.217.238.195) Ended
  440. 2023-03-01 11:40:44.374847 94.63% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/4810338297@95.217.238.195 [CS_DESTROY]
  441. 2023-03-01 11:40:44.374847 94.63% [DEBUG] switch_core_state_machine.c:735 (sofia/default/4810338297@95.217.238.195) Running State Change CS_DESTROY (Cur 0 Tot 1)
  442. 2023-03-01 11:40:44.374847 94.63% [DEBUG] switch_core_state_machine.c:745 (sofia/default/4810338297@95.217.238.195) State DESTROY
  443. 2023-03-01 11:40:44.374847 94.63% [DEBUG] mod_sofia.c:379 sofia/default/4810338297@95.217.238.195 SOFIA DESTROY
  444. 2023-03-01 11:40:44.374847 94.63% [DEBUG] switch_core_state_machine.c:175 sofia/default/4810338297@95.217.238.195 Standard DESTROY
  445. 2023-03-01 11:40:44.374847 94.63% [DEBUG] switch_core_state_machine.c:745 (sofia/default/4810338297@95.217.238.195) State DESTROY going to sleep
  446. freeswitch@BetaQA> /exit
  447.  
  448. root@BetaQA:~#