FreeSWITCH Pastebin

we

From mayur valand, 1 Year ago, written in HTML5.
- view diff
URL https://pastebin.freeswitch.org/view/d6952079
Embed
Download Paste or View Raw
  1. 2022-05-25 13:49:03.557591 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^31854832191.*' OR blocked_patterns = '^3185483219.*' OR blocked_patterns = '^318548321.*' OR blocked_patterns = '^31854832.*' OR blocked_patterns = '^3185483.*' OR blocked_patterns = '^318548.*' OR blocked_patterns = '^31854.*' OR blocked_patterns = '^3185.*' OR blocked_patterns = '^318.*' OR blocked_patterns = '^31.*' OR blocked_patterns = '^3.*' OR blocked_patterns ='--') AND accountid = 12 limit 1
  2. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Reseller Information ===================
  3. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] User id : 12
  4. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Account code : 3847299300
  5. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Balance : 99616.3866
  6. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
  7. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Ratecard id : 44
  8. 2022-05-25 13:49:03.557591 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 44 AND status = 0
  9. 2022-05-25 13:49:03.557591 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] call_direction :outbound
  10. 2022-05-25 13:49:03.557591 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 44 AND status = 0
  11. 2022-05-25 13:49:03.557591 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^31854832191.*' OR pattern = '^3185483219.*' OR pattern = '^318548321.*' OR pattern = '^31854832.*' OR pattern = '^3185483.*' OR pattern = '^318548.*' OR pattern = '^31854.*' OR pattern = '^3185.*' OR pattern = '^318.*' OR pattern = '^31.*' OR pattern = '^3.*' OR pattern ='--') AND status = 0 AND effective_date <= UTC_TIMESTAMP() AND (pricelist_id = 44 OR accountid=12)  ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC,effective_date DESC LIMIT 1
  12. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP]  Call Direction : outbound
  13. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Rates Information ===================
  14. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] ID : 2846856
  15. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Connectcost : 0.00000
  16. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Includedseconds : 0
  17. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Cost : 0.00210
  18. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Comment : Netherlands 085 Personal Numbers
  19. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Country Id : 0
  20. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Accid : 12
  21. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Trunk ID: 4
  22. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Routing type:
  23. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] ================================================================
  24. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Your99616.3866 balance Accountid 12 !!!
  25. 2022-05-25 13:49:03.557591 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Limiting Call To Config Max Length 100 mins!
  26. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Reseller xml_user_rates : ID:8158|CODE:^3185.*|DESTINATION:Nederland special|CONNECTIONCOST:0.07200|INCLUDEDSECONDS:1|CT:|COST:0.05400|INC:60|INITIALBLOCK:60|RATEGROUP:6|MARKUP:0|CI:0|ACCID:160||ID:2846856|CODE:^3185.*|DESTINATION:Netherlands 085 Personal Numbers|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.00210|INC:1|INITIALBLOCK:1|RATEGROUP:44|MARKUP:0|CI:0|ACCID:12
  27. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] ========================================================
  28. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Lossless Routing Status : 1
  29. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Call Max Length Duration : 100 minutes
  30. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] [DIALPLAN] User Rate ID : 8158
  31. 2022-05-25 13:49:03.557591 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_CARRIER_RATES] Query Rate Carrier Id        :4
  32. 2022-05-25 13:49:03.557591 [DEBUG] switch_cpp.cpp:1447 [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 = '^31854832191.*' OR pattern = '^3185483219.*' OR pattern = '^318548321.*' OR pattern = '^31854832.*' OR pattern = '^3185483.*' OR pattern = '^318548.*' OR pattern = '^31854.*' OR pattern = '^3185.*' OR pattern = '^318.*' OR pattern = '^31.*' OR pattern = '^3.*' OR pattern ='--') AND TR.status = 0  AND TR.trunk_id IN (4)  AND TR.effective_date <= UTC_TIMESTAMP() ORDER by FIELD(TR.trunk_id,4),LENGTH (pattern) DESC,TR.cost ASC,TR.precedence ASC,TR.effective_date DESC
  33. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Termination Rates Information ===================
  34. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] ID : 114263
  35. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Code : ^3185.*
  36. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Destination : Netherlands 085 Personal Numbers
  37. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Connectcost : 0.00000
  38. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Free Seconds : 0
  39. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Prefix : ^3185.*
  40. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Strip :
  41. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Prepend :
  42. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Carrier id : 4
  43. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] carrier_name : SBC1
  44. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] dialplan_variable :
  45. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Failover gateway : MOR
  46. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Vendor id : 231
  47. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Max channels : 0
  48. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] Trunk Name : SBCs
  49. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] ========================END OF TERMINATION RATES=======================
  50. 2022-05-25 13:49:03.557591 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 160 AND status=0 LIMIT 1
  51. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP]  Maxlength : 100
  52. 2022-05-25 13:49:03.557591 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  53. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] [XML] Customer Recording Flag : 1
  54. 2022-05-25 13:49:03.557591 [INFO] switch_cpp.cpp:1447 [ASTPP] [PBX] Call Direction : outbound
  55. 2022-05-25 13:49:03.557591 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 44 AND status = 0
  56. 2022-05-25 13:49:03.557591 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from accounts where id = 231) AND status=0 limit 1
  57. 2022-05-25 13:49:03.557591 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from trunks where id = 4) AND status=0 limit 1
  58. 2022-05-25 13:49:03.557591 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_LOCALIZATION] Query :"0/31".....31854832191
  59. 2022-05-25 13:49:03.557591 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="31854832191" LIMIT 1
  60. 2022-05-25 13:49:03.557591 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Generated XML:
  61. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  62. <document type="freeswitch/xml">
  63. <section name="dialplan" description="ASTPP Dialplan">
  64. <context name="default">
  65. <extension name="31854832191">
  66. <condition field="destination_number" expression="31854832191">
  67. <action application="set" data="effective_destination_number=31854832191"/>
  68. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
  69. <action application="set" data="bridge_pre_execute_bleg_data=+6000 normal_clearing"/>
  70. <action application="set" data="callstart=2022-05-25 11:49:03"/>
  71. <action application="set" data="hangup_after_bridge=true"/>
  72. <action application="set" data="continue_on_fail=TRUE"/>
  73. <action application="set" data="account_id=160"/>
  74. <action application="set" data="parent_id=12"/>
  75. <action application="set" data="entity_id=0"/>
  76. <action application="set" data="call_processed=internal"/>
  77. <action application="set" data="call_direction=outbound"/>
  78. <action application="set" data="accountname=default"/>
  79. <action application="set" data="filename1=astpp.pbx.lua"/>
  80. <action application="set" data="origination_rates=ID:8158|CODE:^3185.*|DESTINATION:Nederland special|CONNECTIONCOST:0.07200|INCLUDEDSECONDS:1|CT:|COST:0.05400|INC:60|INITIALBLOCK:60|RATEGROUP:6|MARKUP:0|CI:0|ACCID:160||ID:2846856|CODE:^3185.*|DESTINATION:Netherlands 085 Personal Numbers|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.00210|INC:1|INITIALBLOCK:1|RATEGROUP:44|MARKUP:0|CI:0|ACCID:12"/>
  81. <action application="set" data="original_caller_id_name=31652033157"/>
  82. <action application="set" data="original_caller_id_number=31652033157"/>
  83. <action application="limit" data="db 160 user_160 10 "/>
  84. <action application="limit" data="hash CPS_160 CPS_user_160 1/1 "/>
  85.  
  86. <action application="set" data="effective_caller_id_name=31652033157"/>
  87. <action application="set" data="effective_caller_id_number=31652033157"/>
  88. <action application="set" data="calltype=STANDARD"/>
  89. <action application="set" data="termination_rates=ID:114263|CODE:^3185.*|DESTINATION:Netherlands 085 Personal Numbers|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00077|INC:1|INITIALBLOCK:1|TRUNK:4|PROVIDER:231"/>
  90. <action application="set" data="trunk_id=4"/>
  91. <action application="set" data="provider_id=231"/>
  92. <action application="set" data="rate_flag=0"/>
  93. <action application="set" data="force_trunk_flag=8158"/>
  94. <action application="export" data="presence_data=trunk_id=4"/>
  95. <action application="set" data="intcall=0"/>
  96. <action application="export" data="presence_id=31854832191"/>
  97. <action application="export" data="presence_data=x//12|||Roy(134271)|||^3185.* // Nederland special // 0.05400 |||SBCs // ^3185.* // Netherlands 085 Personal Numbers // 0.00077 // trunk_id=4|||STD|||185.249.40.236|||0.00210 // ^3185.* // Netherlands 085 Personal Numbers"/>
  98. <action application="bridge" data="[leg_timeout=0,absolute_codec_string=^^:PCMA]sofia/gateway/SBC1/31854832191"/>
  99. <action application="bridge" data="[leg_timeout=0,absolute_codec_string=^^:PCMA]sofia/gateway/MOR/31854832191"/>
  100. <action application="bridge" data="[leg_timeout=0,absolute_codec_string=^^:PCMA]sofia/gateway/SBC2/31854832191"/>
  101. </condition>
  102. </extension>
  103. </context>
  105. </document>
  106. Dialplan: sofia/default/31652033157@185.249.40.236 parsing [default->31854832191] continue=false
  107. Dialplan: sofia/default/31652033157@185.249.40.236 Regex (PASS) [31854832191] destination_number(31854832191) =~ /31854832191/ break=on-false
  108. Dialplan: sofia/default/31652033157@185.249.40.236 Action set(effective_destination_number=31854832191)
  109. Dialplan: sofia/default/31652033157@185.249.40.236 Action set(bridge_pre_execute_bleg_app=sched_hangup)
  110. Dialplan: sofia/default/31652033157@185.249.40.236 Action set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
  111. Dialplan: sofia/default/31652033157@185.249.40.236 Action set(callstart=2022-05-25 11:49:03)
  112. Dialplan: sofia/default/31652033157@185.249.40.236 Action set(hangup_after_bridge=true)
  113. Dialplan: sofia/default/31652033157@185.249.40.236 Action set(continue_on_fail=TRUE)
  114. Dialplan: sofia/default/31652033157@185.249.40.236 Action set(account_id=160)
  115. Dialplan: sofia/default/31652033157@185.249.40.236 Action set(parent_id=12)
  116. Dialplan: sofia/default/31652033157@185.249.40.236 Action set(entity_id=0)
  117. Dialplan: sofia/default/31652033157@185.249.40.236 Action set(call_processed=internal)
  118. Dialplan: sofia/default/31652033157@185.249.40.236 Action set(call_direction=outbound)
  119. Dialplan: sofia/default/31652033157@185.249.40.236 Action set(accountname=default)
  120. Dialplan: sofia/default/31652033157@185.249.40.236 Action set(filename1=astpp.pbx.lua)
  121. Dialplan: sofia/default/31652033157@185.249.40.236 Action set(origination_rates=ID:8158|CODE:^3185.*|DESTINATION:Nederland special|CONNECTIONCOST:0.07200|INCLUDEDSECONDS:1|CT:|COST:0.05400|INC:60|INITIALBLOCK:60|RATEGROUP:6|MARKUP:0|CI:0|ACCID:160||ID:2846856|CODE:^3185.*|DESTINATION:Netherlands 085 Personal Numbers|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.00210|INC:1|INITIALBLOCK:1|RATEGROUP:44|MARKUP:0|CI:0|ACCID:12)
  122. Dialplan: sofia/default/31652033157@185.249.40.236 Action set(original_caller_id_name=31652033157)
  123. Dialplan: sofia/default/31652033157@185.249.40.236 Action set(original_caller_id_number=31652033157)
  124. Dialplan: sofia/default/31652033157@185.249.40.236 Action limit(db 160 user_160 10 )
  125. Dialplan: sofia/default/31652033157@185.249.40.236 Action limit(hash CPS_160 CPS_user_160 1/1 )
  126. Dialplan: sofia/default/31652033157@185.249.40.236 Action set(effective_caller_id_name=31652033157)
  127. Dialplan: sofia/default/31652033157@185.249.40.236 Action set(effective_caller_id_number=31652033157)
  128. Dialplan: sofia/default/31652033157@185.249.40.236 Action set(calltype=STANDARD)
  129. Dialplan: sofia/default/31652033157@185.249.40.236 Action set(termination_rates=ID:114263|CODE:^3185.*|DESTINATION:Netherlands 085 Personal Numbers|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00077|INC:1|INITIALBLOCK:1|TRUNK:4|PROVIDER:231)
  130. Dialplan: sofia/default/31652033157@185.249.40.236 Action set(trunk_id=4)
  131. Dialplan: sofia/default/31652033157@185.249.40.236 Action set(provider_id=231)
  132. Dialplan: sofia/default/31652033157@185.249.40.236 Action set(rate_flag=0)
  133. Dialplan: sofia/default/31652033157@185.249.40.236 Action set(force_trunk_flag=8158)
  134. Dialplan: sofia/default/31652033157@185.249.40.236 Action export(presence_data=trunk_id=4)
  135. Dialplan: sofia/default/31652033157@185.249.40.236 Action set(intcall=0)
  136. Dialplan: sofia/default/31652033157@185.249.40.236 Action export(presence_id=31854832191)
  137. Dialplan: sofia/default/31652033157@185.249.40.236 Action export(presence_data=x//12|||Roy(134271)|||^3185.* // Nederland special // 0.05400 |||SBCs // ^3185.* // Netherlands 085 Personal Numbers // 0.00077 // trunk_id=4|||STD|||185.249.40.236|||0.00210 // ^3185.* // Netherlands 085 Personal Numbers)
  138. Dialplan: sofia/default/31652033157@185.249.40.236 Action bridge([leg_timeout=0,absolute_codec_string=^^:PCMA]sofia/gateway/SBC1/31854832191)
  139. Dialplan: sofia/default/31652033157@185.249.40.236 Action bridge([leg_timeout=0,absolute_codec_string=^^:PCMA]sofia/gateway/MOR/31854832191)
  140. Dialplan: sofia/default/31652033157@185.249.40.236 Action bridge([leg_timeout=0,absolute_codec_string=^^:PCMA]sofia/gateway/SBC2/31854832191)
  141. 2022-05-25 13:49:03.557591 [DEBUG] switch_core_state_machine.c:287 (sofia/default/31652033157@185.249.40.236) State Change CS_ROUTING -> CS_EXECUTE
  142. 2022-05-25 13:49:03.557591 [DEBUG] switch_core_state_machine.c:644 (sofia/default/31652033157@185.249.40.236) State ROUTING going to sleep
  143. 2022-05-25 13:49:03.557591 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31652033157@185.249.40.236) Running State Change CS_EXECUTE (Cur 9 Tot 24045)
  144. 2022-05-25 13:49:03.557591 [DEBUG] switch_core_state_machine.c:651 (sofia/default/31652033157@185.249.40.236) State EXECUTE
  145. 2022-05-25 13:49:03.557591 [DEBUG] mod_sofia.c:209 sofia/default/31652033157@185.249.40.236 SOFIA EXECUTE
  146. 2022-05-25 13:49:03.557591 [DEBUG] switch_core_state_machine.c:329 sofia/default/31652033157@185.249.40.236 Standard EXECUTE
  147. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 set(effective_destination_number=31854832191)
  148. 2022-05-25 13:49:03.557591 [DEBUG] mod_dptools.c:1672 SET sofia/default/31652033157@185.249.40.236 [effective_destination_number]=[31854832191]
  149. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 set(bridge_pre_execute_bleg_app=sched_hangup)
  150. 2022-05-25 13:49:03.557591 [DEBUG] mod_dptools.c:1672 SET sofia/default/31652033157@185.249.40.236 [bridge_pre_execute_bleg_app]=[sched_hangup]
  151. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
  152. 2022-05-25 13:49:03.557591 [DEBUG] mod_dptools.c:1672 SET sofia/default/31652033157@185.249.40.236 [bridge_pre_execute_bleg_data]=[+6000 normal_clearing]
  153. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 set(callstart=2022-05-25 11:49:03)
  154. 2022-05-25 13:49:03.557591 [DEBUG] mod_dptools.c:1672 SET sofia/default/31652033157@185.249.40.236 [callstart]=[2022-05-25 11:49:03]
  155. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 set(hangup_after_bridge=true)
  156. 2022-05-25 13:49:03.557591 [DEBUG] mod_dptools.c:1672 SET sofia/default/31652033157@185.249.40.236 [hangup_after_bridge]=[true]
  157. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 set(continue_on_fail=TRUE)
  158. 2022-05-25 13:49:03.557591 [DEBUG] mod_dptools.c:1672 SET sofia/default/31652033157@185.249.40.236 [continue_on_fail]=[TRUE]
  159. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 set(account_id=160)
  160. 2022-05-25 13:49:03.557591 [DEBUG] mod_dptools.c:1672 SET sofia/default/31652033157@185.249.40.236 [account_id]=[160]
  161. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 set(parent_id=12)
  162. 2022-05-25 13:49:03.557591 [DEBUG] mod_dptools.c:1672 SET sofia/default/31652033157@185.249.40.236 [parent_id]=[12]
  163. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 set(entity_id=0)
  164. 2022-05-25 13:49:03.557591 [DEBUG] mod_dptools.c:1672 SET sofia/default/31652033157@185.249.40.236 [entity_id]=[0]
  165. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 set(call_processed=internal)
  166. 2022-05-25 13:49:03.557591 [DEBUG] mod_dptools.c:1672 SET sofia/default/31652033157@185.249.40.236 [call_processed]=[internal]
  167. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 set(call_direction=outbound)
  168. 2022-05-25 13:49:03.557591 [DEBUG] mod_dptools.c:1672 SET sofia/default/31652033157@185.249.40.236 [call_direction]=[outbound]
  169. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 set(accountname=default)
  170. 2022-05-25 13:49:03.557591 [DEBUG] mod_dptools.c:1672 SET sofia/default/31652033157@185.249.40.236 [accountname]=[default]
  171. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 set(filename1=astpp.pbx.lua)
  172. 2022-05-25 13:49:03.557591 [DEBUG] mod_dptools.c:1672 SET sofia/default/31652033157@185.249.40.236 [filename1]=[astpp.pbx.lua]
  173. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 set(origination_rates=ID:8158|CODE:^3185.*|DESTINATION:Nederland special|CONNECTIONCOST:0.07200|INCLUDEDSECONDS:1|CT:|COST:0.05400|INC:60|INITIALBLOCK:60|RATEGROUP:6|MARKUP:0|CI:0|ACCID:160||ID:2846856|CODE:^3185.*|DESTINATION:Netherlands 085 Personal Numbers|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.00210|INC:1|INITIALBLOCK:1|RATEGROUP:44|MARKUP:0|CI:0|ACCID:12)
  174. 2022-05-25 13:49:03.577595 [DEBUG] mod_dptools.c:1672 SET sofia/default/31652033157@185.249.40.236 [origination_rates]=[ID:8158|CODE:^3185.*|DESTINATION:Nederland special|CONNECTIONCOST:0.07200|INCLUDEDSECONDS:1|CT:|COST:0.05400|INC:60|INITIALBLOCK:60|RATEGROUP:6|MARKUP:0|CI:0|ACCID:160||ID:2846856|CODE:^3185.*|DESTINATION:Netherlands 085 Personal Numbers|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.00210|INC:1|INITIALBLOCK:1|RATEGROUP:44|MARKUP:0|CI:0|ACCID:12]
  175. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 set(original_caller_id_name=31652033157)
  176. 2022-05-25 13:49:03.577595 [DEBUG] mod_dptools.c:1672 SET sofia/default/31652033157@185.249.40.236 [original_caller_id_name]=[31652033157]
  177. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 set(original_caller_id_number=31652033157)
  178. 2022-05-25 13:49:03.577595 [DEBUG] mod_dptools.c:1672 SET sofia/default/31652033157@185.249.40.236 [original_caller_id_number]=[31652033157]
  179. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 limit(db 160 user_160 10 )
  180. 2022-05-25 13:49:03.577595 [DEBUG] switch_limit.c:124 incr called: 160_user_160 max:10, interval:0
  181. 2022-05-25 13:49:03.577595 [DEBUG] mod_db.c:194 Usage for 160_user_160 is now 1/10
  182. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 limit(hash CPS_160 CPS_user_160 1/1 )
  183. 2022-05-25 13:49:03.577595 [DEBUG] switch_limit.c:124 incr called: CPS_160_CPS_user_160 max:1, interval:1
  184. 2022-05-25 13:49:03.577595 [DEBUG] mod_hash.c:198 Usage for CPS_160_CPS_user_160 is now 1/1 for the last 1 seconds
  185. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 set(effective_caller_id_name=31652033157)
  186. 2022-05-25 13:49:03.577595 [DEBUG] mod_dptools.c:1672 SET sofia/default/31652033157@185.249.40.236 [effective_caller_id_name]=[31652033157]
  187. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 set(effective_caller_id_number=31652033157)
  188. 2022-05-25 13:49:03.577595 [DEBUG] mod_dptools.c:1672 SET sofia/default/31652033157@185.249.40.236 [effective_caller_id_number]=[31652033157]
  189. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 set(calltype=STANDARD)
  190. 2022-05-25 13:49:03.577595 [DEBUG] mod_dptools.c:1672 SET sofia/default/31652033157@185.249.40.236 [calltype]=[STANDARD]
  191. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 set(termination_rates=ID:114263|CODE:^3185.*|DESTINATION:Netherlands 085 Personal Numbers|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00077|INC:1|INITIALBLOCK:1|TRUNK:4|PROVIDER:231)
  192. 2022-05-25 13:49:03.577595 [DEBUG] mod_dptools.c:1672 SET sofia/default/31652033157@185.249.40.236 [termination_rates]=[ID:114263|CODE:^3185.*|DESTINATION:Netherlands 085 Personal Numbers|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00077|INC:1|INITIALBLOCK:1|TRUNK:4|PROVIDER:231]
  193. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 set(trunk_id=4)
  194. 2022-05-25 13:49:03.577595 [DEBUG] mod_dptools.c:1672 SET sofia/default/31652033157@185.249.40.236 [trunk_id]=[4]
  195. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 set(provider_id=231)
  196. 2022-05-25 13:49:03.577595 [DEBUG] mod_dptools.c:1672 SET sofia/default/31652033157@185.249.40.236 [provider_id]=[231]
  197. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 set(rate_flag=0)
  198. 2022-05-25 13:49:03.577595 [DEBUG] mod_dptools.c:1672 SET sofia/default/31652033157@185.249.40.236 [rate_flag]=[0]
  199. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 set(force_trunk_flag=8158)
  200. 2022-05-25 13:49:03.577595 [DEBUG] mod_dptools.c:1672 SET sofia/default/31652033157@185.249.40.236 [force_trunk_flag]=[8158]
  201. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 export(presence_data=trunk_id=4)
  202. 2022-05-25 13:49:03.577595 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[trunk_id=4]
  203. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 set(intcall=0)
  204. 2022-05-25 13:49:03.577595 [DEBUG] mod_dptools.c:1672 SET sofia/default/31652033157@185.249.40.236 [intcall]=[0]
  205. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 export(presence_id=31854832191)
  206. 2022-05-25 13:49:03.577595 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_id]=[31854832191]
  207. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 export(presence_data=x//12|||Roy(134271)|||^3185.* // Nederland special // 0.05400 |||SBCs // ^3185.* // Netherlands 085 Personal Numbers // 0.00077 // trunk_id=4|||STD|||185.249.40.236|||0.00210 // ^3185.* // Netherlands 085 Personal Numbers)
  208. 2022-05-25 13:49:03.577595 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[x//12|||Roy(134271)|||^3185.* // Nederland special // 0.05400 |||SBCs // ^3185.* // Netherlands 085 Personal Numbers // 0.00077 // trunk_id=4|||STD|||185.249.40.236|||0.00210 // ^3185.* // Netherlands 085 Personal Numbers]
  209. EXECUTE [depth=0] sofia/default/31652033157@185.249.40.236 bridge([leg_timeout=0,absolute_codec_string=^^:PCMA]sofia/gateway/SBC1/31854832191)
  210. 2022-05-25 13:49:03.577595 [DEBUG] switch_channel.c:1264 sofia/default/31652033157@185.249.40.236 EXPORTING[export_vars] [presence_data]=[x//12|||Roy(134271)|||^3185.* // Nederland special // 0.05400 |||SBCs // ^3185.* // Netherlands 085 Personal Numbers // 0.00077 // trunk_id=4|||STD|||185.249.40.236|||0.00210 // ^3185.* // Netherlands 085 Personal Numbers] to event
  211. 2022-05-25 13:49:03.577595 [DEBUG] switch_channel.c:1264 sofia/default/31652033157@185.249.40.236 EXPORTING[export_vars] [presence_id]=[31854832191] to event
  212. 2022-05-25 13:49:03.577595 [DEBUG] switch_channel.c:1264 sofia/default/31652033157@185.249.40.236 EXPORTING[export_vars] [presence_data]=[x//12|||Roy(134271)|||^3185.* // Nederland special // 0.05400 |||SBCs // ^3185.* // Netherlands 085 Personal Numbers // 0.00077 // trunk_id=4|||STD|||185.249.40.236|||0.00210 // ^3185.* // Netherlands 085 Personal Numbers] to event
  213. 2022-05-25 13:49:03.577595 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables
  214. 2022-05-25 13:49:03.577595 [DEBUG] switch_ivr_originate.c:2804 Parsing session specific variables
  215. 2022-05-25 13:49:03.577595 [NOTICE] switch_channel.c:1118 New Channel sofia/default/31854832191 [dec85590-dd9a-4c17-b395-11063cd1d6ea]
  216. 2022-05-25 13:49:03.577595 [DEBUG] mod_sofia.c:5089 (sofia/default/31854832191) State Change CS_NEW -> CS_INIT
  217. 2022-05-25 13:49:03.577595 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31854832191) Running State Change CS_INIT (Cur 10 Tot 24046)
  218. 2022-05-25 13:49:03.577595 [DEBUG] switch_core_state_machine.c:628 (sofia/default/31854832191) State INIT
  219. 2022-05-25 13:49:03.577595 [DEBUG] mod_sofia.c:93 sofia/default/31854832191 SOFIA INIT
  220. 2022-05-25 13:49:03.577595 [DEBUG] sofia_glue.c:1618 sofia/default/31854832191 sending invite version: 1.10.5 -release-17-25569c1631 64bit
  221. Local SDP:
  222. v=0
  223. o=FreeSWITCH 1653453971 1653453972 IN IP4 185.249.40.236
  224. s=FreeSWITCH
  225. c=IN IP4 185.249.40.236
  226. t=0 0
  227. m=audio 25372 RTP/AVP 8 101
  228. a=rtpmap:8 PCMA/8000
  229. a=rtpmap:101 telephone-event/8000
  230. a=fmtp:101 0-16
  231. a=ptime:20
  232. a=sendrecv
  233.  
  234. 2022-05-25 13:49:03.577595 [DEBUG] switch_core_state_machine.c:40 sofia/default/31854832191 Standard INIT
  235. 2022-05-25 13:49:03.577595 [DEBUG] switch_core_state_machine.c:48 (sofia/default/31854832191) State Change CS_INIT -> CS_ROUTING
  236. 2022-05-25 13:49:03.577595 [DEBUG] switch_core_state_machine.c:628 (sofia/default/31854832191) State INIT going to sleep
  237. 2022-05-25 13:49:03.577595 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31854832191) Running State Change CS_ROUTING (Cur 10 Tot 24046)
  238. 2022-05-25 13:49:03.577595 [DEBUG] switch_core_state_machine.c:644 (sofia/default/31854832191) State ROUTING
  239. 2022-05-25 13:49:03.577595 [DEBUG] mod_sofia.c:154 sofia/default/31854832191 SOFIA ROUTING
  240. 2022-05-25 13:49:03.577595 [DEBUG] switch_ivr_originate.c:67 (sofia/default/31854832191) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  241. 2022-05-25 13:49:03.577595 [DEBUG] switch_core_state_machine.c:644 (sofia/default/31854832191) State ROUTING going to sleep
  242. 2022-05-25 13:49:03.577595 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31854832191) Running State Change CS_CONSUME_MEDIA (Cur 10 Tot 24046)
  243. 2022-05-25 13:49:03.577595 [DEBUG] sofia.c:7326 Channel sofia/default/31854832191 entering state [calling][0]
  244. 2022-05-25 13:49:03.577595 [DEBUG] switch_core_state_machine.c:663 (sofia/default/31854832191) State CONSUME_MEDIA
  245. 2022-05-25 13:49:03.577595 [DEBUG] switch_core_state_machine.c:663 (sofia/default/31854832191) State CONSUME_MEDIA going to sleep
  246. 2022-05-25 13:49:04.177627 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [100@262941.yoursipcloud.com] from ip 46.231.21.123
  247. 2022-05-25 13:49:04.777626 [DEBUG] sofia.c:7326 Channel sofia/default/31854832191 entering state [proceeding][180]
  248. 2022-05-25 13:49:04.777626 [NOTICE] sofia.c:7437 Ring-Ready sofia/default/31854832191!
  249. 2022-05-25 13:49:04.777626 [DEBUG] switch_channel.c:3437 (sofia/default/31854832191) Callstate Change DOWN -> RINGING
  250. 2022-05-25 13:49:04.777626 [NOTICE] mod_sofia.c:2498 Ring-Ready sofia/default/31652033157@185.249.40.236!
  251. 2022-05-25 13:49:04.777626 [DEBUG] sofia.c:7326 Channel sofia/default/31652033157@185.249.40.236 entering state [early][180]
  252. 2022-05-25 13:49:04.777626 [NOTICE] switch_ivr_originate.c:556 Ring Ready sofia/default/31652033157@185.249.40.236!
  253. 2022-05-25 13:49:04.777626 [DEBUG] sofia.c:7326 Channel sofia/default/31854832191@134271.yoursipcloud.com:5060 entering state [proceeding][180]
  254. 2022-05-25 13:49:04.777626 [NOTICE] sofia.c:7437 Ring-Ready sofia/default/31854832191@134271.yoursipcloud.com:5060!
  255. 2022-05-25 13:49:04.777626 [DEBUG] switch_channel.c:3437 (sofia/default/31854832191@134271.yoursipcloud.com:5060) Callstate Change DOWN -> RINGING
  256. 2022-05-25 13:49:04.797610 [NOTICE] mod_sofia.c:2498 Ring-Ready sofia/default/31652033157@185.249.40.254!
  257. 2022-05-25 13:49:04.797610 [DEBUG] sofia.c:7326 Channel sofia/default/31652033157@185.249.40.254 entering state [early][180]
  258. 2022-05-25 13:49:04.797610 [NOTICE] switch_ivr_originate.c:556 Ring Ready sofia/default/31652033157@185.249.40.254!
  259. 2022-05-25 13:49:04.997591 [DEBUG] sofia.c:7326 Channel sofia/default/31638762086 entering state [completing][200]
  260. 2022-05-25 13:49:04.997591 [DEBUG] sofia.c:7333 Duplicate SDP
  261. v=0
  262. o=- 3909785042 1 IN IP4 185.249.40.253
  263. s=-
  264. c=IN IP4 185.249.40.253
  265. t=0 0
  266. m=audio 37124 RTP/AVP 8 101
  267. a=rtpmap:101 telephone-event/8000
  268. a=ptime:20
  269.  
  270. 2022-05-25 13:49:05.017593 [DEBUG] sofia.c:7326 Channel sofia/default/31638762086 entering state [ready][200]
  271. 2022-05-25 13:49:05.017593 [NOTICE] sofia.c:8445 Channel [sofia/default/31638762086] has been answered
  272. 2022-05-25 13:49:05.017593 [DEBUG] switch_channel.c:3865 (sofia/default/31638762086) Callstate Change EARLY -> ACTIVE
  273. 2022-05-25 13:49:05.037591 [DEBUG] switch_core_media.c:8645 Audio params are unchanged for sofia/default/183284@213.144.239.110:9933.
  274. 2022-05-25 13:49:05.037591 [DEBUG] mod_sofia.c:898 Local SDP sofia/default/183284@213.144.239.110:9933:
  275. v=0
  276. o=FreeSWITCH 1653456223 1653456225 IN IP4 185.249.40.236
  277. s=FreeSWITCH
  278. c=IN IP4 185.249.40.236
  279. t=0 0
  280. m=audio 23090 RTP/AVP 0 101
  281. a=rtpmap:0 PCMU/8000
  282. a=rtpmap:101 telephone-event/8000
  283. a=fmtp:101 0-16
  284. a=ptime:20
  285. a=sendrecv
  286.  
  287. 2022-05-25 13:49:05.037591 [NOTICE] switch_ivr_bridge.c:731 Channel [sofia/default/183284@213.144.239.110:9933] has been answered
  288. 2022-05-25 13:49:05.037591 [DEBUG] switch_channel.c:3865 (sofia/default/183284@213.144.239.110:9933) Callstate Change EARLY -> ACTIVE
  289. 2022-05-25 13:49:05.037591 [DEBUG] sofia.c:7326 Channel sofia/default/183284@213.144.239.110:9933 entering state [completed][200]
  290. 2022-05-25 13:49:05.037591 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
  291. 2022-05-25 13:49:05.057605 [DEBUG] sofia.c:7326 Channel sofia/default/183284@213.144.239.110:9933 entering state [ready][200]
  292. 2022-05-25 13:49:05.097673 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
  293. 2022-05-25 13:49:05.177596 [DEBUG] sofia.c:7326 Channel sofia/default/31854832191 entering state [proceeding][183]
  294. 2022-05-25 13:49:05.177596 [DEBUG] sofia.c:7336 Remote SDP:
  295. v=0
  296. o=- 3909785555 1 IN IP4 185.249.40.253
  297. s=-
  298. c=IN IP4 185.249.40.253
  299. t=0 0
  300. m=audio 37694 RTP/AVP 8 101
  301. a=rtpmap:101 telephone-event/8000
  302. a=ptime:20
  303.  
  304. 2022-05-25 13:49:05.177596 [NOTICE] sofia.c:7339 Pre-Answer sofia/default/31854832191!
  305. 2022-05-25 13:49:05.177596 [DEBUG] switch_channel.c:3565 (sofia/default/31854832191) Callstate Change RINGING -> EARLY
  306. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  307. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  308. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
  309. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/31854832191 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  310. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_codec.c:111 sofia/default/31854832191 Original read codec set to PCMA:8
  311. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
  312. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_media.c:5911 sofia/default/31854832191 Set 2833 dtmf send payload to 101 recv payload to 101
  313. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/31854832191] 185.249.40.236 port 25372 -> 185.249.40.253 port 37694 codec: 8 ms: 20
  314. 2022-05-25 13:49:05.177596 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
  315. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_media.c:8977 sofia/default/31854832191 Set 2833 dtmf send payload to 101
  316. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_media.c:8984 sofia/default/31854832191 Set 2833 dtmf receive payload to 101
  317. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_media.c:9007 sofia/default/31854832191 Set rtp dtmf delay to 40
  318. 2022-05-25 13:49:05.177596 [INFO] switch_ivr_originate.c:3801 Sending early media
  319. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/31652033157@185.249.40.236] 185.249.40.236 port 25380 -> 185.249.40.236 port 26394 codec: 8 ms: 20
  320. 2022-05-25 13:49:05.177596 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
  321. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_media.c:8977 sofia/default/31652033157@185.249.40.236 Set 2833 dtmf send payload to 101
  322. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_media.c:8984 sofia/default/31652033157@185.249.40.236 Set 2833 dtmf receive payload to 101
  323. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_media.c:9007 sofia/default/31652033157@185.249.40.236 Set rtp dtmf delay to 40
  324. 2022-05-25 13:49:05.177596 [DEBUG] mod_sofia.c:2593 Ring SDP:
  325. v=0
  326. o=FreeSWITCH 1653453965 1653453966 IN IP4 185.249.40.236
  327. s=FreeSWITCH
  328. c=IN IP4 185.249.40.236
  329. t=0 0
  330. m=audio 25380 RTP/AVP 8 101
  331. a=rtpmap:8 PCMA/8000
  332. a=rtpmap:101 telephone-event/8000
  333. a=fmtp:101 0-16
  334. a=ptime:20
  335. a=sendrecv
  336.  
  337. 2022-05-25 13:49:05.177596 [NOTICE] mod_sofia.c:2596 Pre-Answer sofia/default/31652033157@185.249.40.236!
  338. 2022-05-25 13:49:05.177596 [DEBUG] switch_channel.c:3565 (sofia/default/31652033157@185.249.40.236) Callstate Change RINGING -> EARLY
  339. 2022-05-25 13:49:05.177596 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/default/31854832191]
  340. 2022-05-25 13:49:05.177596 [DEBUG] sofia.c:7326 Channel sofia/default/31652033157@185.249.40.236 entering state [early][183]
  341. 2022-05-25 13:49:05.177596 [DEBUG] switch_ivr_bridge.c:1793 (sofia/default/31854832191) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  342. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31854832191) Running State Change CS_EXCHANGE_MEDIA (Cur 10 Tot 24046)
  343. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_state_machine.c:654 (sofia/default/31854832191) State EXCHANGE_MEDIA
  344. 2022-05-25 13:49:05.177596 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA
  345. 2022-05-25 13:49:05.177596 [DEBUG] sofia.c:7326 Channel sofia/default/31854832191@134271.yoursipcloud.com:5060 entering state [proceeding][183]
  346. 2022-05-25 13:49:05.177596 [DEBUG] sofia.c:7336 Remote SDP:
  347. v=0
  348. o=FreeSWITCH 1653453965 1653453966 IN IP4 185.249.40.236
  349. s=FreeSWITCH
  350. c=IN IP4 185.249.40.236
  351. t=0 0
  352. m=audio 25380 RTP/AVP 8 101
  353. a=rtpmap:8 PCMA/8000
  354. a=rtpmap:101 telephone-event/8000
  355. a=fmtp:101 0-16
  356. a=ptime:20
  357.  
  358. 2022-05-25 13:49:05.177596 [NOTICE] sofia.c:7339 Pre-Answer sofia/default/31854832191@134271.yoursipcloud.com:5060!
  359. 2022-05-25 13:49:05.177596 [DEBUG] switch_channel.c:3565 (sofia/default/31854832191@134271.yoursipcloud.com:5060) Callstate Change RINGING -> EARLY
  360. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  361. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  362. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
  363. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/31854832191@134271.yoursipcloud.com:5060 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  364. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_codec.c:111 sofia/default/31854832191@134271.yoursipcloud.com:5060 Original read codec set to PCMA:8
  365. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
  366. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_media.c:5911 sofia/default/31854832191@134271.yoursipcloud.com:5060 Set 2833 dtmf send payload to 101 recv payload to 101
  367. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/31854832191@134271.yoursipcloud.com:5060] 185.249.40.236 port 26394 -> 185.249.40.236 port 25380 codec: 8 ms: 20
  368. 2022-05-25 13:49:05.177596 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
  369. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_media.c:8977 sofia/default/31854832191@134271.yoursipcloud.com:5060 Set 2833 dtmf send payload to 101
  370. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_media.c:8984 sofia/default/31854832191@134271.yoursipcloud.com:5060 Set 2833 dtmf receive payload to 101
  371. 2022-05-25 13:49:05.177596 [DEBUG] switch_core_media.c:9007 sofia/default/31854832191@134271.yoursipcloud.com:5060 Set rtp dtmf delay to 40
  372. EXECUTE [depth=0] sofia/default/31854832191@134271.yoursipcloud.com:5060 lua(/usr/share/freeswitch/scripts/astpp/lib/pbx/astpp.confirm.lua)
  373. 2022-05-25 13:49:05.197617 [NOTICE] switch_cpp.cpp:1447 [Astpp_Call-Ring Group]  Ring group confirm flag :false
  374. 2022-05-25 13:49:05.197617 [DEBUG] switch_cpp.cpp:1191 sofia/default/31854832191@134271.yoursipcloud.com:5060 destroy/unlink session from object
  375. 2022-05-25 13:49:05.217636 [INFO] switch_ivr_originate.c:3801 Sending early media
  376. 2022-05-25 13:49:05.217636 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/31652033157@185.249.40.254] 185.249.40.236 port 18602 -> 185.249.40.254 port 24420 codec: 8 ms: 20
  377. 2022-05-25 13:49:05.217636 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
  378. 2022-05-25 13:49:05.217636 [DEBUG] switch_core_media.c:8977 sofia/default/31652033157@185.249.40.254 Set 2833 dtmf send payload to 96
  379. 2022-05-25 13:49:05.217636 [DEBUG] switch_core_media.c:8984 sofia/default/31652033157@185.249.40.254 Set 2833 dtmf receive payload to 96
  380. 2022-05-25 13:49:05.217636 [DEBUG] switch_core_media.c:9007 sofia/default/31652033157@185.249.40.254 Set rtp dtmf delay to 40
  381. 2022-05-25 13:49:05.217636 [DEBUG] mod_sofia.c:2593 Ring SDP:
  382. v=0
  383. o=FreeSWITCH 1653460743 1653460744 IN IP4 185.249.40.236
  384. s=FreeSWITCH
  385. c=IN IP4 185.249.40.236
  386. t=0 0
  387. m=audio 18602 RTP/AVP 8 96
  388. a=rtpmap:8 PCMA/8000
  389. a=rtpmap:96 telephone-event/8000
  390. a=fmtp:96 0-16
  391. a=ptime:20
  392. a=sendrecv
  393.  
  394. 2022-05-25 13:49:05.217636 [NOTICE] mod_sofia.c:2596 Pre-Answer sofia/default/31652033157@185.249.40.254!
  395. 2022-05-25 13:49:05.217636 [DEBUG] switch_channel.c:3565 (sofia/default/31652033157@185.249.40.254) Callstate Change RINGING -> EARLY
  396. 2022-05-25 13:49:05.217636 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/default/31854832191@134271.yoursipcloud.com:5060]
  397. 2022-05-25 13:49:05.217636 [DEBUG] sofia.c:7326 Channel sofia/default/31652033157@185.249.40.254 entering state [early][183]
  398. 2022-05-25 13:49:05.217636 [DEBUG] switch_ivr_bridge.c:1793 (sofia/default/31854832191@134271.yoursipcloud.com:5060) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  399. 2022-05-25 13:49:05.217636 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31854832191@134271.yoursipcloud.com:5060) Running State Change CS_EXCHANGE_MEDIA (Cur 10 Tot 24046)
  400. 2022-05-25 13:49:05.217636 [DEBUG] switch_core_state_machine.c:654 (sofia/default/31854832191@134271.yoursipcloud.com:5060) State EXCHANGE_MEDIA
  401. 2022-05-25 13:49:05.217636 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA
  402. 2022-05-25 13:49:05.277667 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
  403. 2022-05-25 13:49:05.277667 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
  404. 2022-05-25 13:49:05.377642 [DEBUG] switch_ivr_bridge.c:655 sofia/default/31854832191 Bridge execute app sched_hangup(+6000 normal_clearing)
  405. 2022-05-25 13:49:05.377642 [DEBUG] switch_ivr_bridge.c:655 sofia/default/31854832191@134271.yoursipcloud.com:5060 Bridge execute app sched_hangup(+6000 normal_clearing)
  406. 2022-05-25 13:49:05.397613 [DEBUG] switch_ivr.c:632 sofia/default/31854832191 Command Execute [depth=0] sched_hangup(+6000 normal_clearing)
  407. EXECUTE [depth=0] sofia/default/31854832191 sched_hangup(+6000 normal_clearing)
  408. 2022-05-25 13:49:05.397613 [DEBUG] switch_scheduler.c:252 Added task 8709 switch_ivr_schedule_hangup (dec85590-dd9a-4c17-b395-11063cd1d6ea) to run at 1653485345
  409. 2022-05-25 13:49:05.397613 [DEBUG] switch_ivr.c:632 sofia/default/31854832191@134271.yoursipcloud.com:5060 Command Execute [depth=0] sched_hangup(+6000 normal_clearing)
  410. EXECUTE [depth=0] sofia/default/31854832191@134271.yoursipcloud.com:5060 sched_hangup(+6000 normal_clearing)
  411. 2022-05-25 13:49:05.397613 [DEBUG] switch_scheduler.c:252 Added task 8710 switch_ivr_schedule_hangup (a2366741-e803-478d-a3bb-30b36d6167b2) to run at 1653485345
  412. 2022-05-25 13:49:10.517631 [NOTICE] sofia.c:1089 Hangup sofia/default/183284@213.144.239.110:9933 [CS_EXECUTE] [NORMAL_CLEARING]
  413. 2022-05-25 13:49:10.517631 [DEBUG] switch_ivr_bridge.c:820 sofia/default/183284@213.144.239.110:9933 ending bridge by request from write function
  414. 2022-05-25 13:49:10.517631 [DEBUG] mod_hash.c:296 Usage for CPS_510514_CPS_user_510514 is now 1
  415. 2022-05-25 13:49:10.517631 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/31638762086]
  416. 2022-05-25 13:49:10.517631 [NOTICE] switch_ivr_bridge.c:1029 Hangup sofia/default/31638762086 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  417. 2022-05-25 13:49:10.517631 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/183284@213.144.239.110:9933]
  418. 2022-05-25 13:49:10.517631 [DEBUG] switch_core_state_machine.c:654 (sofia/default/31638762086) State EXCHANGE_MEDIA going to sleep
  419. 2022-05-25 13:49:10.517631 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31638762086) Running State Change CS_HANGUP (Cur 10 Tot 24046)
  420. 2022-05-25 13:49:10.517631 [DEBUG] switch_core_state_machine.c:848 (sofia/default/31638762086) Callstate Change ACTIVE -> HANGUP
  421. 2022-05-25 13:49:10.517631 [DEBUG] switch_ivr_bridge.c:1892 sofia/default/31638762086 skip receive message [UNBRIDGE] (channel is hungup already)
  422. 2022-05-25 13:49:10.517631 [DEBUG] switch_ivr_bridge.c:1895 sofia/default/183284@213.144.239.110:9933 skip receive message [UNBRIDGE] (channel is hungup already)
  423. 2022-05-25 13:49:10.517631 [DEBUG] switch_core_state_machine.c:850 (sofia/default/31638762086) State HANGUP
  424. 2022-05-25 13:49:10.517631 [DEBUG] mod_sofia.c:447 sofia/default/31638762086 Overriding SIP cause 480 with 200 from the other leg
  425. 2022-05-25 13:49:10.517631 [DEBUG] mod_sofia.c:453 Channel sofia/default/31638762086 hanging up, cause: NORMAL_CLEARING
  426. 2022-05-25 13:49:10.517631 [DEBUG] switch_core_session.c:2905 sofia/default/183284@213.144.239.110:9933 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  427. 2022-05-25 13:49:10.517631 [DEBUG] switch_core_state_machine.c:651 (sofia/default/183284@213.144.239.110:9933) State EXECUTE going to sleep
  428. 2022-05-25 13:49:10.517631 [DEBUG] switch_core_state_machine.c:585 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_HANGUP (Cur 10 Tot 24046)
  429. 2022-05-25 13:49:10.517631 [DEBUG] switch_core_state_machine.c:848 (sofia/default/183284@213.144.239.110:9933) Callstate Change ACTIVE -> HANGUP
  430. 2022-05-25 13:49:10.517631 [DEBUG] switch_core_state_machine.c:850 (sofia/default/183284@213.144.239.110:9933) State HANGUP
  431. 2022-05-25 13:49:10.517631 [DEBUG] mod_sofia.c:453 Channel sofia/default/183284@213.144.239.110:9933 hanging up, cause: NORMAL_CLEARING
  432. 2022-05-25 13:49:10.517631 [DEBUG] mod_sofia.c:507 Sending BYE to sofia/default/31638762086
  433. 2022-05-25 13:49:10.517631 [DEBUG] switch_core_state_machine.c:60 sofia/default/31638762086 Standard HANGUP, cause: NORMAL_CLEARING
  434. 2022-05-25 13:49:10.517631 [DEBUG] switch_core_state_machine.c:850 (sofia/default/31638762086) State HANGUP going to sleep
  435. 2022-05-25 13:49:10.517631 [DEBUG] switch_core_state_machine.c:620 (sofia/default/31638762086) State Change CS_HANGUP -> CS_REPORTING
  436. 2022-05-25 13:49:10.517631 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31638762086) Running State Change CS_REPORTING (Cur 10 Tot 24046)
  437. 2022-05-25 13:49:10.517631 [DEBUG] switch_core_state_machine.c:936 (sofia/default/31638762086) State REPORTING
  438. 2022-05-25 13:49:10.517631 [DEBUG] switch_core_state_machine.c:60 sofia/default/183284@213.144.239.110:9933 Standard HANGUP, cause: NORMAL_CLEARING
  439. 2022-05-25 13:49:10.517631 [DEBUG] switch_core_state_machine.c:850 (sofia/default/183284@213.144.239.110:9933) State HANGUP going to sleep
  440. 2022-05-25 13:49:10.517631 [DEBUG] switch_core_state_machine.c:620 (sofia/default/183284@213.144.239.110:9933) State Change CS_HANGUP -> CS_REPORTING
  441. 2022-05-25 13:49:10.517631 [DEBUG] switch_core_state_machine.c:585 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_REPORTING (Cur 10 Tot 24046)
  442. 2022-05-25 13:49:10.517631 [DEBUG] switch_core_state_machine.c:936 (sofia/default/183284@213.144.239.110:9933) State REPORTING
  443. 2022-05-25 13:49:10.517631 [INFO] mod_json_cdr.c:271 Process [70403836-0b64-474d-b337-125afff9f46c.cdr.json]
  444. 2022-05-25 13:49:10.517631 [INFO] mod_json_cdr.c:271 Process [eac8e916-3455-4c6e-8494-f43030bc3833.cdr.json]
  445. 2022-05-25 13:49:10.537658 [DEBUG] switch_core_state_machine.c:174 sofia/default/31638762086 Standard REPORTING, cause: NORMAL_CLEARING
  446. 2022-05-25 13:49:10.537658 [DEBUG] switch_core_state_machine.c:936 (sofia/default/31638762086) State REPORTING going to sleep
  447. 2022-05-25 13:49:10.537658 [DEBUG] switch_core_state_machine.c:611 (sofia/default/31638762086) State Change CS_REPORTING -> CS_DESTROY
  448. 2022-05-25 13:49:10.537658 [DEBUG] switch_core_session.c:1726 Session 24042 (sofia/default/31638762086) Locked, Waiting on external entities
  449. 2022-05-25 13:49:10.537658 [NOTICE] switch_core_session.c:1744 Session 24042 (sofia/default/31638762086) Ended
  450. 2022-05-25 13:49:10.537658 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/31638762086 [CS_DESTROY]
  451. 2022-05-25 13:49:10.537658 [DEBUG] switch_core_state_machine.c:739 (sofia/default/31638762086) Running State Change CS_DESTROY (Cur 9 Tot 24046)
  452. 2022-05-25 13:49:10.537658 [DEBUG] switch_core_state_machine.c:749 (sofia/default/31638762086) State DESTROY
  453. 2022-05-25 13:49:10.537658 [DEBUG] mod_sofia.c:364 sofia/default/31638762086 SOFIA DESTROY
  454. 2022-05-25 13:49:10.537658 [DEBUG] switch_core_state_machine.c:181 sofia/default/31638762086 Standard DESTROY
  455. 2022-05-25 13:49:10.537658 [DEBUG] switch_core_state_machine.c:749 (sofia/default/31638762086) State DESTROY going to sleep
  456. 2022-05-25 13:49:10.557591 [DEBUG] switch_core_state_machine.c:174 sofia/default/183284@213.144.239.110:9933 Standard REPORTING, cause: NORMAL_CLEARING
  457. 2022-05-25 13:49:10.557591 [DEBUG] switch_core_state_machine.c:936 (sofia/default/183284@213.144.239.110:9933) State REPORTING going to sleep
  458. 2022-05-25 13:49:10.557591 [DEBUG] switch_core_state_machine.c:611 (sofia/default/183284@213.144.239.110:9933) State Change CS_REPORTING -> CS_DESTROY
  459. 2022-05-25 13:49:10.557591 [DEBUG] switch_core_session.c:1726 Session 24041 (sofia/default/183284@213.144.239.110:9933) Locked, Waiting on external entities
  460. 2022-05-25 13:49:10.557591 [NOTICE] switch_core_session.c:1744 Session 24041 (sofia/default/183284@213.144.239.110:9933) Ended
  461. 2022-05-25 13:49:10.557591 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/183284@213.144.239.110:9933 [CS_DESTROY]
  462. 2022-05-25 13:49:10.557591 [DEBUG] switch_core_state_machine.c:739 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_DESTROY (Cur 8 Tot 24046)
  463. 2022-05-25 13:49:10.557591 [DEBUG] switch_core_state_machine.c:749 (sofia/default/183284@213.144.239.110:9933) State DESTROY
  464. 2022-05-25 13:49:10.557591 [DEBUG] mod_sofia.c:364 sofia/default/183284@213.144.239.110:9933 SOFIA DESTROY
  465. 2022-05-25 13:49:10.557591 [DEBUG] switch_core_state_machine.c:181 sofia/default/183284@213.144.239.110:9933 Standard DESTROY
  466. 2022-05-25 13:49:10.557591 [DEBUG] switch_core_state_machine.c:749 (sofia/default/183284@213.144.239.110:9933) State DESTROY going to sleep
  467. 2022-05-25 13:49:10.897631 [DEBUG] switch_scheduler.c:147 Deleting task 8708 switch_ivr_schedule_hangup (70403836-0b64-474d-b337-125afff9f46c)
  468. 2022-05-25 13:49:26.237611 [NOTICE] switch_channel.c:1118 New Channel sofia/default/183284@213.144.239.110:9933 [04c87f1b-54b2-4174-b03b-5b24ac034185]
  469. 2022-05-25 13:49:26.237611 [DEBUG] switch_core_state_machine.c:585 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_NEW (Cur 9 Tot 24047)
  470. 2022-05-25 13:49:26.237611 [DEBUG] sofia.c:10280 sofia/default/183284@213.144.239.110:9933 receiving invite from 213.144.239.110:9933 version: 1.10.5 -release-17-25569c1631 64bit
  471. 2022-05-25 13:49:26.237611 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 213.144.239.110:0.
  472. 2022-05-25 13:49:26.237611 [DEBUG] sofia.c:10403 IP 213.144.239.110 Approved by acl "default[]". Access Granted.
  473. 2022-05-25 13:49:26.237611 [DEBUG] sofia.c:7326 Channel sofia/default/183284@213.144.239.110:9933 entering state [received][100]
  474. 2022-05-25 13:49:26.237611 [DEBUG] sofia.c:7336 Remote SDP:
  475. v=0
  476. o=root 1285019198 1285019198 IN IP4 213.144.239.110
  477. s=Asterisk PBX 11.4.0
  478. c=IN IP4 213.144.239.110
  479. t=0 0
  480. m=audio 10782 RTP/AVP 0 8 101
  481. a=rtpmap:0 PCMU/8000
  482. a=rtpmap:8 PCMA/8000
  483. a=rtpmap:101 telephone-event/8000
  484. a=fmtp:101 0-16
  485. a=ptime:20
  486.  
  487. 2022-05-25 13:49:26.237611 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  488. 2022-05-25 13:49:26.237611 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  489. 2022-05-25 13:49:26.237611 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  490. 2022-05-25 13:49:26.237611 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  491. 2022-05-25 13:49:26.237611 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  492. 2022-05-25 13:49:26.237611 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  493. 2022-05-25 13:49:26.237611 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
  494. 2022-05-25 13:49:26.237611 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/183284@213.144.239.110:9933 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  495. 2022-05-25 13:49:26.237611 [DEBUG] switch_core_codec.c:111 sofia/default/183284@213.144.239.110:9933 Original read codec set to PCMU:0
  496. 2022-05-25 13:49:26.237611 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
  497. 2022-05-25 13:49:26.237611 [DEBUG] switch_core_media.c:5911 sofia/default/183284@213.144.239.110:9933 Set 2833 dtmf send payload to 101 recv payload to 101
  498. 2022-05-25 13:49:26.237611 [DEBUG] sofia.c:7760 (sofia/default/183284@213.144.239.110:9933) State Change CS_NEW -> CS_INIT
  499. 2022-05-25 13:49:26.237611 [DEBUG] switch_core_state_machine.c:604 (sofia/default/183284@213.144.239.110:9933) State NEW
  500. 2022-05-25 13:49:26.237611 [DEBUG] switch_core_state_machine.c:585 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_INIT (Cur 9 Tot 24047)
  501. 2022-05-25 13:49:26.237611 [DEBUG] switch_core_state_machine.c:628 (sofia/default/183284@213.144.239.110:9933) State INIT
  502. 2022-05-25 13:49:26.237611 [DEBUG] mod_sofia.c:93 sofia/default/183284@213.144.239.110:9933 SOFIA INIT
  503. 2022-05-25 13:49:26.237611 [DEBUG] switch_core_state_machine.c:40 sofia/default/183284@213.144.239.110:9933 Standard INIT
  504. 2022-05-25 13:49:26.237611 [DEBUG] switch_core_state_machine.c:48 (sofia/default/183284@213.144.239.110:9933) State Change CS_INIT -> CS_ROUTING
  505. 2022-05-25 13:49:26.237611 [DEBUG] switch_core_state_machine.c:628 (sofia/default/183284@213.144.239.110:9933) State INIT going to sleep
  506. 2022-05-25 13:49:26.237611 [DEBUG] switch_core_state_machine.c:585 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_ROUTING (Cur 9 Tot 24047)
  507. 2022-05-25 13:49:26.237611 [DEBUG] switch_channel.c:2332 (sofia/default/183284@213.144.239.110:9933) Callstate Change DOWN -> RINGING
  508. 2022-05-25 13:49:26.237611 [DEBUG] switch_core_state_machine.c:644 (sofia/default/183284@213.144.239.110:9933) State ROUTING
  509. 2022-05-25 13:49:26.237611 [DEBUG] mod_sofia.c:154 sofia/default/183284@213.144.239.110:9933 SOFIA ROUTING
  510. 2022-05-25 13:49:26.237611 [DEBUG] switch_core_state_machine.c:236 sofia/default/183284@213.144.239.110:9933 Standard ROUTING
  511. 2022-05-25 13:49:26.237611 [INFO] mod_dialplan_xml.c:637 Processing Anonymous <31232051002>->32484453104 in context default
  512. 2022-05-25 13:49:26.237611 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
  513. 2022-05-25 13:49:26.237611 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  514. 2022-05-25 13:49:26.237611 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [xml_handler] Params:
  515. Event-Name: REQUEST_PARAMS
  516. Core-UUID: fca97a4c-8577-49ad-81f5-986f27b1f2fa
  517. FreeSWITCH-Hostname: multitenant-1
  518. FreeSWITCH-Switchname: multitenant-1
  519. FreeSWITCH-IPv4: 185.249.40.236
  520. FreeSWITCH-IPv6: %3A%3A1
  521. Event-Date-Local: 2022-05-25%2013%3A49%3A26
  522. Event-Date-GMT: Wed,%2025%20May%202022%2011%3A49%3A26%20GMT
  523. Event-Date-Timestamp: 1653479366237611
  524. Event-Calling-File: mod_dialplan_xml.c
  525. Event-Calling-Function: dialplan_xml_locate
  526. Event-Calling-Line-Number: 608
  527. Event-Sequence: 1914926
  528. Channel-State: CS_ROUTING
  529. Channel-Call-State: RINGING
  530. Channel-State-Number: 2
  531. Channel-Name: sofia/default/183284%40213.144.239.110%3A9933
  532. Unique-ID: 04c87f1b-54b2-4174-b03b-5b24ac034185
  533. Call-Direction: inbound
  534. Presence-Call-Direction: inbound
  535. Channel-HIT-Dialplan: true
  536. Channel-Presence-ID: 183284%40213.144.239.110
  537. Channel-Call-UUID: 04c87f1b-54b2-4174-b03b-5b24ac034185
  538. Answer-State: ringing
  539. Channel-Read-Codec-Name: PCMU
  540. Channel-Read-Codec-Rate: 8000
  541. Channel-Read-Codec-Bit-Rate: 64000
  542. Channel-Write-Codec-Name: PCMU
  543. Channel-Write-Codec-Rate: 8000
  544. Channel-Write-Codec-Bit-Rate: 64000
  545. Caller-Direction: inbound
  546. Caller-Logical-Direction: inbound
  547. Caller-Username: 31232051002
  548. Caller-Dialplan: XML
  549. Caller-Caller-ID-Name: Anonymous
  550. Caller-Caller-ID-Number: 31232051002
  551. Caller-Orig-Caller-ID-Name: Anonymous
  552. Caller-Orig-Caller-ID-Number: 31232051002
  553. Caller-Network-Addr: 213.144.239.110
  554. Caller-ANI: 31232051002
  555. Caller-Destination-Number: 32484453104
  556. Caller-Unique-ID: 04c87f1b-54b2-4174-b03b-5b24ac034185
  557. Caller-Source: mod_sofia
  558. Caller-Context: default
  559. Caller-Channel-Name: sofia/default/183284%40213.144.239.110%3A9933
  560. Caller-Profile-Index: 1
  561. Caller-Profile-Created-Time: 1653479366237611
  562. Caller-Channel-Created-Time: 1653479366237611
  563. Caller-Channel-Answered-Time: 0
  564. Caller-Channel-Progress-Time: 0
  565. Caller-Channel-Progress-Media-Time: 0
  566. Caller-Channel-Hangup-Time: 0
  567. Caller-Channel-Transfer-Time: 0
  568. Caller-Channel-Resurrect-Time: 0
  569. Caller-Channel-Bridged-Time: 0
  570. Caller-Channel-Last-Hold: 0
  571. Caller-Channel-Hold-Accum: 0
  572. Caller-Screen-Bit: false
  573. Caller-Privacy-Hide-Name: true
  574. Caller-Privacy-Hide-Number: true
  575. variable_direction: inbound
  576. variable_uuid: 04c87f1b-54b2-4174-b03b-5b24ac034185
  577. variable_session_id: 24047
  578. variable_sip_from_user: 183284
  579. variable_sip_from_port: 9933
  580. variable_sip_from_uri: 183284%40213.144.239.110%3A9933
  581. variable_sip_from_host: 213.144.239.110
  582. variable_video_media_flow: disabled
  583. variable_text_media_flow: disabled
  584. variable_channel_name: sofia/default/183284%40213.144.239.110%3A9933
  585. variable_sip_call_id: 2ae4150d474b79087b4c75f912dbb680%40213.144.239.110%3A9933
  586. variable_sip_local_network_addr: 185.249.40.236
  587. variable_sip_network_ip: 213.144.239.110
  588. variable_sip_network_port: 9933
  589. variable_sip_invite_stamp: 1653479366237611
  590. variable_sip_received_ip: 213.144.239.110
  591. variable_sip_received_port: 9933
  592. variable_sip_via_protocol: udp
  593. variable_sip_authorized: true
  594. variable_sip_acl_authed_by: default
  595. variable_sip_from_user_stripped: 183284
  596. variable_sip_from_tag: as6c719997
  597. variable_sofia_profile_name: default
  598. variable_sofia_profile_url: sip%3Amod_sofia%40185.249.40.236%3A5060
  599. variable_recovery_profile_name: default
  600. variable_sip_Remote-Party-ID: %22Anonymous%22%20%3Csip%3A31232051002%40213.144.239.110%3E%3Bparty%3Dcalling%3Bprivacy%3Doff%3Bscreen%3Dno
  601. variable_sip_cid_type: rpid
  602. variable_sip_full_via: SIP/2.0/UDP%20213.144.239.110%3A9933%3Bbranch%3Dz9hG4bK71f5404f%3Brport%3D9933
  603. variable_sip_from_display: Anonymous
  604. variable_sip_full_from: %22Anonymous%22%20%3Csip%3A183284%40213.144.239.110%3A9933%3E%3Btag%3Das6c719997
  605. variable_sip_full_to: %3Csip%3A32484453104%40emolife138.yoursipcloud.com%3E
  606. variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20OPTIONS,%20BYE,%20REFER,%20SUBSCRIBE,%20NOTIFY,%20INFO,%20PUBLISH
  607. variable_sip_req_user: 32484453104
  608. variable_sip_req_uri: 32484453104%40emolife138.yoursipcloud.com
  609. variable_sip_req_host: emolife138.yoursipcloud.com
  610. variable_sip_to_user: 32484453104
  611. variable_sip_to_uri: 32484453104%40emolife138.yoursipcloud.com
  612. variable_sip_to_host: emolife138.yoursipcloud.com
  613. variable_sip_contact_user: 183284
  614. variable_sip_contact_port: 9933
  615. variable_sip_contact_uri: 183284%40213.144.239.110%3A9933
  616. variable_sip_contact_host: 213.144.239.110
  617. variable_sip_user_agent: FPBX-2.11.0(11.4.0)
  618. variable_sip_via_host: 213.144.239.110
  619. variable_sip_via_port: 9933
  620. variable_sip_via_rport: 9933
  621. variable_max_forwards: 70
  622. variable_presence_id: 183284%40213.144.239.110
  623. variable_sip_Privacy: id
  624. variable_switch_r_sdp: v%3D0%0D%0Ao%3Droot%201285019198%201285019198%20IN%20IP4%20213.144.239.110%0D%0As%3DAsterisk%20PBX%2011.4.0%0D%0Ac%3DIN%20IP4%20213.144.239.110%0D%0At%3D0%200%0D%0Am%3Daudio%2010782%20RTP/AVP%200%208%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0Aa%3Dptime%3A20%0D%0A
  625. variable_ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b
  626. variable_rtp_use_codec_string: PCMA,PCMU
  627. variable_remote_video_media_flow: inactive
  628. variable_remote_text_media_flow: inactive
  629. variable_remote_audio_media_flow: sendrecv
  630. variable_audio_media_flow: sendrecv
  631. variable_remote_media_ip: 213.144.239.110
  632. variable_remote_media_port: 10782
  633. variable_rtp_audio_recv_pt: 0
  634. variable_rtp_use_codec_name: PCMU
  635. variable_rtp_use_codec_rate: 8000
  636. variable_rtp_use_codec_ptime: 20
  637. variable_rtp_use_codec_channels: 1
  638. variable_rtp_last_audio_codec_string: PCMU%408000h%4020i%401c
  639. variable_read_codec: PCMU
  640. variable_original_read_codec: PCMU
  641. variable_read_rate: 8000
  642. variable_original_read_rate: 8000
  643. variable_write_codec: PCMU
  644. variable_write_rate: 8000
  645. variable_dtmf_type: rfc2833
  646. variable_endpoint_disposition: RECEIVED
  647. variable_call_uuid: 04c87f1b-54b2-4174-b03b-5b24ac034185
  648. Hunt-Direction: inbound
  649. Hunt-Logical-Direction: inbound
  650. Hunt-Username: 31232051002
  651. Hunt-Dialplan: XML
  652. Hunt-Caller-ID-Name: Anonymous
  653. Hunt-Caller-ID-Number: 31232051002
  654. Hunt-Orig-Caller-ID-Name: Anonymous
  655. Hunt-Orig-Caller-ID-Number: 31232051002
  656. Hunt-Network-Addr: 213.144.239.110
  657. Hunt-ANI: 31232051002
  658. Hunt-Destination-Number: 32484453104
  659. Hunt-Unique-ID: 04c87f1b-54b2-4174-b03b-5b24ac034185
  660. Hunt-Source: mod_sofia
  661. Hunt-Context: default
  662. Hunt-Channel-Name: sofia/default/183284%40213.144.239.110%3A9933
  663. Hunt-Profile-Index: 1
  664. Hunt-Profile-Created-Time: 1653479366237611
  665. Hunt-Channel-Created-Time: 1653479366237611
  666. Hunt-Channel-Answered-Time: 0
  667. Hunt-Channel-Progress-Time: 0
  668. Hunt-Channel-Progress-Media-Time: 0
  669. Hunt-Channel-Hangup-Time: 0
  670. Hunt-Channel-Transfer-Time: 0
  671. Hunt-Channel-Resurrect-Time: 0
  672. Hunt-Channel-Bridged-Time: 0
  673. Hunt-Channel-Last-Hold: 0
  674. Hunt-Channel-Hold-Accum: 0
  675. Hunt-Screen-Bit: false
  676. Hunt-Privacy-Hide-Name: true
  677. Hunt-Privacy-Hide-Number: true
  678.  
  679.  
  680. 2022-05-25 13:49:26.237611 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] key_value:
  681. 2022-05-25 13:49:26.237611 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] section: dialplan
  682. 2022-05-25 13:49:26.237611 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] tag_name:
  683. 2022-05-25 13:49:26.237611 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] key_name:
  684. 2022-05-25 13:49:26.237611 [INFO] switch_cpp.cpp:1447 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
  685. 2022-05-25 13:49:26.237611 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Dialed number : 32484453104
  686. 2022-05-25 13:49:26.237611 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '32484453104' limit 1
  687. 2022-05-25 13:49:26.237611 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '32484453104' AND status=0 limit 1
  688. 2022-05-25 13:49:26.237611 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '32484453104' and status=0 LIMIT 1
  689. 2022-05-25 13:49:26.237611 [WARNING] switch_cpp.cpp:1447 [ASTPP] [FAXER] Custom Caller ID
  690. 2022-05-25 13:49:26.237611 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Caller Id name / number  : Anonymous / 31232051002
  691. 2022-05-25 13:49:26.237611 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="32484453104" LIMIT 1
  692. 2022-05-25 13:49:26.237611 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as  accounts ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="32484453104"  AND domains.domain="emolife138.yoursipcloud.com" limit 1
  693. 2022-05-25 13:49:26.237611 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Call direction : outbound
  694. 2022-05-25 13:49:26.237611 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [IPAUTHENTICATION] Query :SELECT ip_map.*, (SELECT number FROM accounts where id=accountid AND status=0 AND deleted=0) AS account_code FROM ip_map WHERE INET_ATON("213.144.239.110") BETWEEN(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) & 0xffffffff ^((0x1 <<(32 -  SUBSTRING_INDEX(`ip`, '/', -1))) -1 )) AND(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) |((0x100000000 >> SUBSTRING_INDEX(`ip`,'/', -1)) -1))  AND "32484453104"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
  695. 2022-05-25 13:49:26.237611 [INFO] switch_cpp.cpp:1447 [ASTPP] [Accountcode : 183284]
  696. 2022-05-25 13:49:26.237611 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "183284" AND deleted = 0 limit 1
  697. 2022-05-25 13:49:26.257596 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^32484453104.*' OR blocked_patterns = '^3248445310.*' OR blocked_patterns = '^324844531.*' OR blocked_patterns = '^32484453.*' OR blocked_patterns = '^3248445.*' OR blocked_patterns = '^324844.*' OR blocked_patterns = '^32484.*' OR blocked_patterns = '^3248.*' OR blocked_patterns = '^324.*' OR blocked_patterns = '^32.*' OR blocked_patterns = '^3.*' OR blocked_patterns ='--') AND accountid = 199 limit 1
  698. 2022-05-25 13:49:26.257596 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="32484453104" AND (accountid = 0 OR status = 1) LIMIT 1
  699. 2022-05-25 13:49:26.257596 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE P.is_terminated = 0 AND (patterns = '^32484453104.*' OR patterns = '^3248445310.*' OR patterns = '^324844531.*' OR patterns = '^32484453.*' OR patterns = '^3248445.*' OR patterns = '^324844.*' OR patterns = '^32484.*' OR patterns = '^3248.*' OR patterns = '^324.*' OR patterns = '^32.*' OR patterns = '^3.*' OR patterns ='--') AND accountid = 199 ORDER BY LENGTH(PKGPTR.patterns) DESC
  700. 2022-05-25 13:49:26.257596 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="32484453104" AND A.accountid = '199' limit 1
  701. 2022-05-25 13:49:26.257596 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 32484453104
  702. 2022-05-25 13:49:26.257596 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="32484453104" LIMIT 1
  703. 2022-05-25 13:49:26.257596 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as  accounts ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="32484453104"  AND domains.domain="emolife138.yoursipcloud.com" limit 1
  704. 2022-05-25 13:49:26.257596 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] New Call Direction : outbound
  705. 2022-05-25 13:49:26.257596 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '32484453104' OR number = '3248445310' OR number = '324844531' OR number = '32484453' OR number = '3248445' OR number = '324844' OR number = '32484' OR number = '3248' OR number = '324' OR number = '32' OR number = '3' OR number ='--') OR (number = '32484453104*' OR number = '3248445310*' OR number = '324844531*' OR number = '32484453*' OR number = '3248445*' OR number = '324844*' OR number = '32484*' OR number = '3248*' OR number = '324*' OR number = '32*' OR number = '3*' OR number ='--') OR (number = '31232051002' OR number = '3123205100' OR number = '312320510' OR number = '31232051' OR number = '3123205' OR number = '312320' OR number = '31232' OR number = '3123' OR number = '312' OR number = '31' OR number = '3' OR number ='--') OR (number = '31232051002*' OR number = '3123205100*' OR number = '312320510*' OR number = '31232051*' OR number = '3123205*' OR number = '312320*' OR number = '31232*' OR number = '3123*' OR number = '312*' OR number = '31*' OR number = '3*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
  706. 2022-05-25 13:49:26.257596 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Call Barring Status : -1
  707. 2022-05-25 13:49:26.257596 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '32484453104' OR routing_prefix = '3248445310' OR routing_prefix = '324844531' OR routing_prefix = '32484453' OR routing_prefix = '3248445' OR routing_prefix = '324844' OR routing_prefix = '32484' OR routing_prefix = '3248' OR routing_prefix = '324' OR routing_prefix = '32' OR routing_prefix = '3' OR routing_prefix ='--') AND status = 0 and reseller_id = 189 limit 1
  708. 2022-05-25 13:49:26.277636 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Account Information ===================
  709. 2022-05-25 13:49:26.277636 [INFO] switch_cpp.cpp:1447 [ASTPP] User id : 199
  710. 2022-05-25 13:49:26.277636 [INFO] switch_cpp.cpp:1447 [ASTPP] Account code : 183284
  711. 2022-05-25 13:49:26.277636 [INFO] switch_cpp.cpp:1447 [ASTPP] Balance : 700.2309
  712. 2022-05-25 13:49:26.277636 [INFO] switch_cpp.cpp:1447 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
  713. 2022-05-25 13:49:26.277636 [INFO] switch_cpp.cpp:1447 [ASTPP] Ratecard id : 25
  714. 2022-05-25 13:49:26.277636 [INFO] switch_cpp.cpp:1447 [ASTPP] CID Pool : 0
  715. 2022-05-25 13:49:26.277636 [INFO] switch_cpp.cpp:1447 [ASTPP] ========================================================
  716. 2022-05-25 13:49:26.277636 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 25 AND status = 0
  717. 2022-05-25 13:49:26.277636 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] call_direction :outbound
  718. 2022-05-25 13:49:26.277636 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 25 AND status = 0
  719. 2022-05-25 13:49:26.277636 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^32484453104.*' OR pattern = '^3248445310.*' OR pattern = '^324844531.*' OR pattern = '^32484453.*' OR pattern = '^3248445.*' OR pattern = '^324844.*' OR pattern = '^32484.*' OR pattern = '^3248.*' OR pattern = '^324.*' OR pattern = '^32.*' OR pattern = '^3.*' OR pattern ='--') AND status = 0 AND effective_date <= UTC_TIMESTAMP() AND (pricelist_id = 25 OR accountid=199)  ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC,effective_date DESC LIMIT 1
  720. 2022-05-25 13:49:26.277636 [INFO] switch_cpp.cpp:1447 [ASTPP]  Call Direction : outbound
  721. 2022-05-25 13:49:26.277636 [WARNING] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Rates Not Found :
  722. 2022-05-25 13:49:26.277636 [DEBUG] switch_cpp.cpp:1447 [ASTPP]  Post Cdrs : 0
  723. 2022-05-25 13:49:26.277636 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET RESELLERID] Query :SELECT reseller_id FROM accounts WHERE id = 199
  724. 2022-05-25 13:49:26.277636 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Generated XML:
  725. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  726. <document type="freeswitch/xml">
  727. <section name="dialplan" description="ASTPP Dialplan">
  728. <context name="default">
  729. <extension name="32484453104">
  730. <condition field="destination_number" expression="32484453104">
  731. <action application="log" data="WARNING Accountcode . Dialed number (32484453104)  origination rates not found!!"/>
  732. <action application="set" data="original_caller_id_name=Anonymous"/>
  733. <action application="set" data="original_caller_id_number=Anonymous"/>
  734. <action application="set" data="error_cdr=1"/>
  735. <action application="set" data="callstart=2022-05-25 11:49:26"/>
  736. <action application="set" data="account_id=199"/>
  737. <action application="set" data="parent_id=189"/>
  738. <action application="set" data="call_direction=outbound"/>
  739. <action application="set" data="sip_ignore_remote_cause=true"/>
  740. <action application="set" data="call_processed=internal"/>
  741. <action application="set" data="effective_destination_number=32484453104"/>
  742. <action application="set" data="last_bridge_hangup_cause=ORIGINATION_RATE_NOT_FOUND"/>
  743. <action application="hangup" data="ORIGINATION_RATE_NOT_FOUND"/>
  744. </condition>
  745. </extension>
  746. </context>
  747. </section>
  748. </document>
  749. Dialplan: sofia/default/183284@213.144.239.110:9933 parsing [default->32484453104] continue=false
  750. Dialplan: sofia/default/183284@213.144.239.110:9933 Regex (PASS) [32484453104] destination_number(32484453104) =~ /32484453104/ break=on-false
  751. Dialplan: sofia/default/183284@213.144.239.110:9933 Action log(WARNING Accountcode . Dialed number (32484453104)  origination rates not found!!)
  752. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(original_caller_id_name=Anonymous)
  753. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(original_caller_id_number=Anonymous)
  754. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(error_cdr=1)
  755. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(callstart=2022-05-25 11:49:26)
  756. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(account_id=199)
  757. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(parent_id=189)
  758. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(call_direction=outbound)
  759. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(sip_ignore_remote_cause=true)
  760. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(call_processed=internal)
  761. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(effective_destination_number=32484453104)
  762. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(last_bridge_hangup_cause=ORIGINATION_RATE_NOT_FOUND)
  763. Dialplan: sofia/default/183284@213.144.239.110:9933 Action hangup(ORIGINATION_RATE_NOT_FOUND)
  764. 2022-05-25 13:49:26.277636 [DEBUG] switch_core_state_machine.c:287 (sofia/default/183284@213.144.239.110:9933) State Change CS_ROUTING -> CS_EXECUTE
  765. 2022-05-25 13:49:26.277636 [DEBUG] switch_core_state_machine.c:644 (sofia/default/183284@213.144.239.110:9933) State ROUTING going to sleep
  766. 2022-05-25 13:49:26.277636 [DEBUG] switch_core_state_machine.c:585 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_EXECUTE (Cur 9 Tot 24047)
  767. 2022-05-25 13:49:26.277636 [DEBUG] switch_core_state_machine.c:651 (sofia/default/183284@213.144.239.110:9933) State EXECUTE
  768. 2022-05-25 13:49:26.277636 [DEBUG] mod_sofia.c:209 sofia/default/183284@213.144.239.110:9933 SOFIA EXECUTE
  769. 2022-05-25 13:49:26.277636 [DEBUG] switch_core_state_machine.c:329 sofia/default/183284@213.144.239.110:9933 Standard EXECUTE
  770. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 log(WARNING Accountcode . Dialed number (32484453104)  origination rates not found!!)
  771. 2022-05-25 13:49:26.277636 [WARNING] mod_dptools.c:1866 Accountcode . Dialed number (32484453104)  origination rates not found!!
  772. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(original_caller_id_name=Anonymous)
  773. 2022-05-25 13:49:26.277636 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [original_caller_id_name]=[Anonymous]
  774. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(original_caller_id_number=Anonymous)
  775. 2022-05-25 13:49:26.277636 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [original_caller_id_number]=[Anonymous]
  776. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(error_cdr=1)
  777. 2022-05-25 13:49:26.277636 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [error_cdr]=[1]
  778. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(callstart=2022-05-25 11:49:26)
  779. 2022-05-25 13:49:26.277636 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [callstart]=[2022-05-25 11:49:26]
  780. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(account_id=199)
  781. 2022-05-25 13:49:26.277636 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [account_id]=[199]
  782. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(parent_id=189)
  783. 2022-05-25 13:49:26.277636 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [parent_id]=[189]
  784. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(call_direction=outbound)
  785. 2022-05-25 13:49:26.277636 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [call_direction]=[outbound]
  786. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(sip_ignore_remote_cause=true)
  787. 2022-05-25 13:49:26.277636 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [sip_ignore_remote_cause]=[true]
  788. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(call_processed=internal)
  789. 2022-05-25 13:49:26.277636 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [call_processed]=[internal]
  790. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(effective_destination_number=32484453104)
  791. 2022-05-25 13:49:26.277636 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [effective_destination_number]=[32484453104]
  792. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(last_bridge_hangup_cause=ORIGINATION_RATE_NOT_FOUND)
  793. 2022-05-25 13:49:26.277636 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [last_bridge_hangup_cause]=[ORIGINATION_RATE_NOT_FOUND]
  794. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 hangup(ORIGINATION_RATE_NOT_FOUND)
  795. 2022-05-25 13:49:26.277636 [NOTICE] mod_dptools.c:1380 Hangup sofia/default/183284@213.144.239.110:9933 [CS_EXECUTE] [NORMAL_CLEARING]
  796. 2022-05-25 13:49:26.277636 [DEBUG] switch_core_session.c:2905 sofia/default/183284@213.144.239.110:9933 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  797. 2022-05-25 13:49:26.277636 [DEBUG] switch_core_state_machine.c:651 (sofia/default/183284@213.144.239.110:9933) State EXECUTE going to sleep
  798. 2022-05-25 13:49:26.277636 [DEBUG] switch_core_state_machine.c:585 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_HANGUP (Cur 9 Tot 24047)
  799. 2022-05-25 13:49:26.277636 [DEBUG] switch_core_state_machine.c:848 (sofia/default/183284@213.144.239.110:9933) Callstate Change RINGING -> HANGUP
  800. 2022-05-25 13:49:26.277636 [DEBUG] switch_core_state_machine.c:850 (sofia/default/183284@213.144.239.110:9933) State HANGUP
  801. 2022-05-25 13:49:26.277636 [DEBUG] mod_sofia.c:453 Channel sofia/default/183284@213.144.239.110:9933 hanging up, cause: NORMAL_CLEARING
  802. 2022-05-25 13:49:26.277636 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 480
  803. 2022-05-25 13:49:26.277636 [DEBUG] switch_core_state_machine.c:60 sofia/default/183284@213.144.239.110:9933 Standard HANGUP, cause: NORMAL_CLEARING
  804. 2022-05-25 13:49:26.277636 [DEBUG] switch_core_state_machine.c:850 (sofia/default/183284@213.144.239.110:9933) State HANGUP going to sleep
  805. 2022-05-25 13:49:26.277636 [DEBUG] switch_core_state_machine.c:620 (sofia/default/183284@213.144.239.110:9933) State Change CS_HANGUP -> CS_REPORTING
  806. 2022-05-25 13:49:26.277636 [DEBUG] switch_core_state_machine.c:585 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_REPORTING (Cur 9 Tot 24047)
  807. 2022-05-25 13:49:26.277636 [DEBUG] switch_core_state_machine.c:936 (sofia/default/183284@213.144.239.110:9933) State REPORTING
  808. 2022-05-25 13:49:26.277636 [INFO] mod_json_cdr.c:271 Process [04c87f1b-54b2-4174-b03b-5b24ac034185.cdr.json]
  809. 2022-05-25 13:49:26.297758 [DEBUG] switch_core_state_machine.c:174 sofia/default/183284@213.144.239.110:9933 Standard REPORTING, cause: NORMAL_CLEARING
  810. 2022-05-25 13:49:26.297758 [DEBUG] switch_core_state_machine.c:936 (sofia/default/183284@213.144.239.110:9933) State REPORTING going to sleep
  811. 2022-05-25 13:49:26.297758 [DEBUG] switch_core_state_machine.c:611 (sofia/default/183284@213.144.239.110:9933) State Change CS_REPORTING -> CS_DESTROY
  812. 2022-05-25 13:49:26.297758 [DEBUG] switch_core_session.c:1726 Session 24047 (sofia/default/183284@213.144.239.110:9933) Locked, Waiting on external entities
  813. 2022-05-25 13:49:26.297758 [NOTICE] switch_core_session.c:1744 Session 24047 (sofia/default/183284@213.144.239.110:9933) Ended
  814. 2022-05-25 13:49:26.297758 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/183284@213.144.239.110:9933 [CS_DESTROY]
  815. 2022-05-25 13:49:26.297758 [DEBUG] switch_core_state_machine.c:739 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_DESTROY (Cur 8 Tot 24047)
  816. 2022-05-25 13:49:26.297758 [DEBUG] switch_core_state_machine.c:749 (sofia/default/183284@213.144.239.110:9933) State DESTROY
  817. 2022-05-25 13:49:26.297758 [DEBUG] mod_sofia.c:364 sofia/default/183284@213.144.239.110:9933 SOFIA DESTROY
  818. 2022-05-25 13:49:26.297758 [DEBUG] switch_core_state_machine.c:181 sofia/default/183284@213.144.239.110:9933 Standard DESTROY
  819. 2022-05-25 13:49:26.297758 [DEBUG] switch_core_state_machine.c:749 (sofia/default/183284@213.144.239.110:9933) State DESTROY going to sleep
  820. 2022-05-25 13:49:27.537628 [NOTICE] switch_channel.c:1118 New Channel sofia/default/183284@213.144.239.110:9933 [511441b0-10dd-49f2-9daa-dc541ea80c1a]
  821. 2022-05-25 13:49:27.537628 [DEBUG] switch_core_state_machine.c:585 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_NEW (Cur 9 Tot 24048)
  822. 2022-05-25 13:49:27.537628 [DEBUG] sofia.c:10280 sofia/default/183284@213.144.239.110:9933 receiving invite from 213.144.239.110:9933 version: 1.10.5 -release-17-25569c1631 64bit
  823. 2022-05-25 13:49:27.537628 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 213.144.239.110:0.
  824. 2022-05-25 13:49:27.537628 [DEBUG] sofia.c:10403 IP 213.144.239.110 Approved by acl "default[]". Access Granted.
  825. 2022-05-25 13:49:27.537628 [DEBUG] sofia.c:7326 Channel sofia/default/183284@213.144.239.110:9933 entering state [received][100]
  826. 2022-05-25 13:49:27.537628 [DEBUG] sofia.c:7336 Remote SDP:
  827. v=0
  828. o=root 1839978719 1839978719 IN IP4 213.144.239.110
  829. s=Asterisk PBX 11.4.0
  830. c=IN IP4 213.144.239.110
  831. t=0 0
  832. m=audio 19804 RTP/AVP 0 8 101
  833. a=rtpmap:0 PCMU/8000
  834. a=rtpmap:8 PCMA/8000
  835. a=rtpmap:101 telephone-event/8000
  836. a=fmtp:101 0-16
  837. a=ptime:20
  838.  
  839. 2022-05-25 13:49:27.537628 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  840. 2022-05-25 13:49:27.537628 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  841. 2022-05-25 13:49:27.537628 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  842. 2022-05-25 13:49:27.537628 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  843. 2022-05-25 13:49:27.537628 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  844. 2022-05-25 13:49:27.537628 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  845. 2022-05-25 13:49:27.537628 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
  846. 2022-05-25 13:49:27.537628 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/183284@213.144.239.110:9933 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  847. 2022-05-25 13:49:27.537628 [DEBUG] switch_core_codec.c:111 sofia/default/183284@213.144.239.110:9933 Original read codec set to PCMU:0
  848. 2022-05-25 13:49:27.537628 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
  849. 2022-05-25 13:49:27.537628 [DEBUG] switch_core_media.c:5911 sofia/default/183284@213.144.239.110:9933 Set 2833 dtmf send payload to 101 recv payload to 101
  850. 2022-05-25 13:49:27.537628 [DEBUG] sofia.c:7760 (sofia/default/183284@213.144.239.110:9933) State Change CS_NEW -> CS_INIT
  851. 2022-05-25 13:49:27.537628 [DEBUG] switch_core_state_machine.c:604 (sofia/default/183284@213.144.239.110:9933) State NEW
  852. 2022-05-25 13:49:27.537628 [DEBUG] switch_core_state_machine.c:585 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_INIT (Cur 9 Tot 24048)
  853. 2022-05-25 13:49:27.537628 [DEBUG] switch_core_state_machine.c:628 (sofia/default/183284@213.144.239.110:9933) State INIT
  854. 2022-05-25 13:49:27.537628 [DEBUG] mod_sofia.c:93 sofia/default/183284@213.144.239.110:9933 SOFIA INIT
  855. 2022-05-25 13:49:27.537628 [DEBUG] switch_core_state_machine.c:40 sofia/default/183284@213.144.239.110:9933 Standard INIT
  856. 2022-05-25 13:49:27.537628 [DEBUG] switch_core_state_machine.c:48 (sofia/default/183284@213.144.239.110:9933) State Change CS_INIT -> CS_ROUTING
  857. 2022-05-25 13:49:27.537628 [DEBUG] switch_core_state_machine.c:628 (sofia/default/183284@213.144.239.110:9933) State INIT going to sleep
  858. 2022-05-25 13:49:27.537628 [DEBUG] switch_core_state_machine.c:585 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_ROUTING (Cur 9 Tot 24048)
  859. 2022-05-25 13:49:27.537628 [DEBUG] switch_channel.c:2332 (sofia/default/183284@213.144.239.110:9933) Callstate Change DOWN -> RINGING
  860. 2022-05-25 13:49:27.537628 [DEBUG] switch_core_state_machine.c:644 (sofia/default/183284@213.144.239.110:9933) State ROUTING
  861. 2022-05-25 13:49:27.537628 [DEBUG] mod_sofia.c:154 sofia/default/183284@213.144.239.110:9933 SOFIA ROUTING
  862. 2022-05-25 13:49:27.537628 [DEBUG] switch_core_state_machine.c:236 sofia/default/183284@213.144.239.110:9933 Standard ROUTING
  863. 2022-05-25 13:49:27.537628 [INFO] mod_dialplan_xml.c:637 Processing 31332030246 <31332030246>->31642236728 in context default
  864. 2022-05-25 13:49:27.537628 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
  865. 2022-05-25 13:49:27.537628 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  866. 2022-05-25 13:49:27.537628 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [xml_handler] Params:
  867. Event-Name: REQUEST_PARAMS
  868. Core-UUID: fca97a4c-8577-49ad-81f5-986f27b1f2fa
  869. FreeSWITCH-Hostname: multitenant-1
  870. FreeSWITCH-Switchname: multitenant-1
  871. FreeSWITCH-IPv4: 185.249.40.236
  872. FreeSWITCH-IPv6: %3A%3A1
  873. Event-Date-Local: 2022-05-25%2013%3A49%3A27
  874. Event-Date-GMT: Wed,%2025%20May%202022%2011%3A49%3A27%20GMT
  875. Event-Date-Timestamp: 1653479367537628
  876. Event-Calling-File: mod_dialplan_xml.c
  877. Event-Calling-Function: dialplan_xml_locate
  878. Event-Calling-Line-Number: 608
  879. Event-Sequence: 1914970
  880. Channel-State: CS_ROUTING
  881. Channel-Call-State: RINGING
  882. Channel-State-Number: 2
  883. Channel-Name: sofia/default/183284%40213.144.239.110%3A9933
  884. Unique-ID: 511441b0-10dd-49f2-9daa-dc541ea80c1a
  885. Call-Direction: inbound
  886. Presence-Call-Direction: inbound
  887. Channel-HIT-Dialplan: true
  888. Channel-Presence-ID: 183284%40213.144.239.110
  889. Channel-Call-UUID: 511441b0-10dd-49f2-9daa-dc541ea80c1a
  890. Answer-State: ringing
  891. Channel-Read-Codec-Name: PCMU
  892. Channel-Read-Codec-Rate: 8000
  893. Channel-Read-Codec-Bit-Rate: 64000
  894. Channel-Write-Codec-Name: PCMU
  895. Channel-Write-Codec-Rate: 8000
  896. Channel-Write-Codec-Bit-Rate: 64000
  897. Caller-Direction: inbound
  898. Caller-Logical-Direction: inbound
  899. Caller-Username: 31332030246
  900. Caller-Dialplan: XML
  901. Caller-Caller-ID-Name: 31332030246
  902. Caller-Caller-ID-Number: 31332030246
  903. Caller-Orig-Caller-ID-Name: 31332030246
  904. Caller-Orig-Caller-ID-Number: 31332030246
  905. Caller-Network-Addr: 213.144.239.110
  906. Caller-ANI: 31332030246
  907. Caller-Destination-Number: 31642236728
  908. Caller-Unique-ID: 511441b0-10dd-49f2-9daa-dc541ea80c1a
  909. Caller-Source: mod_sofia
  910. Caller-Context: default
  911. Caller-Channel-Name: sofia/default/183284%40213.144.239.110%3A9933
  912. Caller-Profile-Index: 1
  913. Caller-Profile-Created-Time: 1653479367537628
  914. Caller-Channel-Created-Time: 1653479367537628
  915. Caller-Channel-Answered-Time: 0
  916. Caller-Channel-Progress-Time: 0
  917. Caller-Channel-Progress-Media-Time: 0
  918. Caller-Channel-Hangup-Time: 0
  919. Caller-Channel-Transfer-Time: 0
  920. Caller-Channel-Resurrect-Time: 0
  921. Caller-Channel-Bridged-Time: 0
  922. Caller-Channel-Last-Hold: 0
  923. Caller-Channel-Hold-Accum: 0
  924. Caller-Screen-Bit: false
  925. Caller-Privacy-Hide-Name: false
  926. Caller-Privacy-Hide-Number: false
  927. variable_direction: inbound
  928. variable_uuid: 511441b0-10dd-49f2-9daa-dc541ea80c1a
  929. variable_session_id: 24048
  930. variable_sip_from_user: 183284
  931. variable_sip_from_port: 9933
  932. variable_sip_from_uri: 183284%40213.144.239.110%3A9933
  933. variable_sip_from_host: 213.144.239.110
  934. variable_video_media_flow: disabled
  935. variable_text_media_flow: disabled
  936. variable_channel_name: sofia/default/183284%40213.144.239.110%3A9933
  937. variable_sip_call_id: 3fb2be6f40ffef4d77f1bb9f637f7a66%40213.144.239.110%3A9933
  938. variable_sip_local_network_addr: 185.249.40.236
  939. variable_sip_network_ip: 213.144.239.110
  940. variable_sip_network_port: 9933
  941. variable_sip_invite_stamp: 1653479367537628
  942. variable_sip_received_ip: 213.144.239.110
  943. variable_sip_received_port: 9933
  944. variable_sip_via_protocol: udp
  945. variable_sip_authorized: true
  946. variable_sip_acl_authed_by: default
  947. variable_sip_from_user_stripped: 183284
  948. variable_sip_from_tag: as36d5e460
  949. variable_sofia_profile_name: default
  950. variable_sofia_profile_url: sip%3Amod_sofia%40185.249.40.236%3A5060
  951. variable_recovery_profile_name: default
  952. variable_sip_Remote-Party-ID: %2231332030246%22%20%3Csip%3A31332030246%40213.144.239.110%3E%3Bparty%3Dcalling%3Bprivacy%3Doff%3Bscreen%3Dno
  953. variable_sip_cid_type: rpid
  954. variable_sip_full_via: SIP/2.0/UDP%20213.144.239.110%3A9933%3Bbranch%3Dz9hG4bK1ba60c9f%3Brport%3D9933
  955. variable_sip_full_from: %3Csip%3A183284%40213.144.239.110%3A9933%3E%3Btag%3Das36d5e460
  956. variable_sip_full_to: %3Csip%3A31642236728%40emolife138.yoursipcloud.com%3E
  957. variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20OPTIONS,%20BYE,%20REFER,%20SUBSCRIBE,%20NOTIFY,%20INFO,%20PUBLISH
  958. variable_sip_req_user: 31642236728
  959. variable_sip_req_uri: 31642236728%40emolife138.yoursipcloud.com
  960. variable_sip_req_host: emolife138.yoursipcloud.com
  961. variable_sip_to_user: 31642236728
  962. variable_sip_to_uri: 31642236728%40emolife138.yoursipcloud.com
  963. variable_sip_to_host: emolife138.yoursipcloud.com
  964. variable_sip_contact_user: 183284
  965. variable_sip_contact_port: 9933
  966. variable_sip_contact_uri: 183284%40213.144.239.110%3A9933
  967. variable_sip_contact_host: 213.144.239.110
  968. variable_sip_user_agent: FPBX-2.11.0(11.4.0)
  969. variable_sip_via_host: 213.144.239.110
  970. variable_sip_via_port: 9933
  971. variable_sip_via_rport: 9933
  972. variable_max_forwards: 70
  973. variable_presence_id: 183284%40213.144.239.110
  974. variable_switch_r_sdp: v%3D0%0D%0Ao%3Droot%201839978719%201839978719%20IN%20IP4%20213.144.239.110%0D%0As%3DAsterisk%20PBX%2011.4.0%0D%0Ac%3DIN%20IP4%20213.144.239.110%0D%0At%3D0%200%0D%0Am%3Daudio%2019804%20RTP/AVP%200%208%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0Aa%3Dptime%3A20%0D%0A
  975. variable_ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b
  976. variable_rtp_use_codec_string: PCMA,PCMU
  977. variable_remote_video_media_flow: inactive
  978. variable_remote_text_media_flow: inactive
  979. variable_remote_audio_media_flow: sendrecv
  980. variable_audio_media_flow: sendrecv
  981. variable_remote_media_ip: 213.144.239.110
  982. variable_remote_media_port: 19804
  983. variable_rtp_audio_recv_pt: 0
  984. variable_rtp_use_codec_name: PCMU
  985. variable_rtp_use_codec_rate: 8000
  986. variable_rtp_use_codec_ptime: 20
  987. variable_rtp_use_codec_channels: 1
  988. variable_rtp_last_audio_codec_string: PCMU%408000h%4020i%401c
  989. variable_read_codec: PCMU
  990. variable_original_read_codec: PCMU
  991. variable_read_rate: 8000
  992. variable_original_read_rate: 8000
  993. variable_write_codec: PCMU
  994. variable_write_rate: 8000
  995. variable_dtmf_type: rfc2833
  996. variable_endpoint_disposition: RECEIVED
  997. variable_call_uuid: 511441b0-10dd-49f2-9daa-dc541ea80c1a
  998. Hunt-Direction: inbound
  999. Hunt-Logical-Direction: inbound
  1000. Hunt-Username: 31332030246
  1001. Hunt-Dialplan: XML
  1002. Hunt-Caller-ID-Name: 31332030246
  1003. Hunt-Caller-ID-Number: 31332030246
  1004. Hunt-Orig-Caller-ID-Name: 31332030246
  1005. Hunt-Orig-Caller-ID-Number: 31332030246
  1006. Hunt-Network-Addr: 213.144.239.110
  1007. Hunt-ANI: 31332030246
  1008. Hunt-Destination-Number: 31642236728
  1009. Hunt-Unique-ID: 511441b0-10dd-49f2-9daa-dc541ea80c1a
  1010. Hunt-Source: mod_sofia
  1011. Hunt-Context: default
  1012. Hunt-Channel-Name: sofia/default/183284%40213.144.239.110%3A9933
  1013. Hunt-Profile-Index: 1
  1014. Hunt-Profile-Created-Time: 1653479367537628
  1015. Hunt-Channel-Created-Time: 1653479367537628
  1016. Hunt-Channel-Answered-Time: 0
  1017. Hunt-Channel-Progress-Time: 0
  1018. Hunt-Channel-Progress-Media-Time: 0
  1019. Hunt-Channel-Hangup-Time: 0
  1020. Hunt-Channel-Transfer-Time: 0
  1021. Hunt-Channel-Resurrect-Time: 0
  1022. Hunt-Channel-Bridged-Time: 0
  1023. Hunt-Channel-Last-Hold: 0
  1024. Hunt-Channel-Hold-Accum: 0
  1025. Hunt-Screen-Bit: false
  1026. Hunt-Privacy-Hide-Name: false
  1027. Hunt-Privacy-Hide-Number: false
  1028.  
  1029.  
  1030. 2022-05-25 13:49:27.537628 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] key_value:
  1031. 2022-05-25 13:49:27.537628 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] key_name:
  1032. 2022-05-25 13:49:27.537628 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] tag_name:
  1033. 2022-05-25 13:49:27.537628 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] section: dialplan
  1034. 2022-05-25 13:49:27.537628 [INFO] switch_cpp.cpp:1447 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
  1035. 2022-05-25 13:49:27.537628 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Dialed number : 31642236728
  1036. 2022-05-25 13:49:27.537628 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '31642236728' limit 1
  1037. 2022-05-25 13:49:27.537628 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '31642236728' AND status=0 limit 1
  1038. 2022-05-25 13:49:27.537628 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '31642236728' and status=0 LIMIT 1
  1039. 2022-05-25 13:49:27.537628 [WARNING] switch_cpp.cpp:1447 [ASTPP] [FAXER] Custom Caller ID
  1040. 2022-05-25 13:49:27.537628 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Caller Id name / number  : 31332030246 / 31332030246
  1041. 2022-05-25 13:49:27.537628 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="31642236728" LIMIT 1
  1042. 2022-05-25 13:49:27.537628 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as  accounts ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="31642236728"  AND domains.domain="emolife138.yoursipcloud.com" limit 1
  1043. 2022-05-25 13:49:27.537628 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Call direction : outbound
  1044. 2022-05-25 13:49:27.537628 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [IPAUTHENTICATION] Query :SELECT ip_map.*, (SELECT number FROM accounts where id=accountid AND status=0 AND deleted=0) AS account_code FROM ip_map WHERE INET_ATON("213.144.239.110") BETWEEN(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) & 0xffffffff ^((0x1 <<(32 -  SUBSTRING_INDEX(`ip`, '/', -1))) -1 )) AND(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) |((0x100000000 >> SUBSTRING_INDEX(`ip`,'/', -1)) -1))  AND "31642236728"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
  1045. 2022-05-25 13:49:27.557596 [INFO] switch_cpp.cpp:1447 [ASTPP] [Accountcode : 183284]
  1046. 2022-05-25 13:49:27.557596 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "183284" AND deleted = 0 limit 1
  1047. 2022-05-25 13:49:27.557596 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^31642236728.*' OR blocked_patterns = '^3164223672.*' OR blocked_patterns = '^316422367.*' OR blocked_patterns = '^31642236.*' OR blocked_patterns = '^3164223.*' OR blocked_patterns = '^316422.*' OR blocked_patterns = '^31642.*' OR blocked_patterns = '^3164.*' OR blocked_patterns = '^316.*' OR blocked_patterns = '^31.*' OR blocked_patterns = '^3.*' OR blocked_patterns ='--') AND accountid = 199 limit 1
  1048. 2022-05-25 13:49:27.557596 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="31642236728" AND (accountid = 0 OR status = 1) LIMIT 1
  1049. 2022-05-25 13:49:27.557596 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE P.is_terminated = 0 AND (patterns = '^31642236728.*' OR patterns = '^3164223672.*' OR patterns = '^316422367.*' OR patterns = '^31642236.*' OR patterns = '^3164223.*' OR patterns = '^316422.*' OR patterns = '^31642.*' OR patterns = '^3164.*' OR patterns = '^316.*' OR patterns = '^31.*' OR patterns = '^3.*' OR patterns ='--') AND accountid = 199 ORDER BY LENGTH(PKGPTR.patterns) DESC
  1050. 2022-05-25 13:49:27.557596 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="31642236728" AND A.accountid = '199' limit 1
  1051. 2022-05-25 13:49:27.557596 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 31642236728
  1052. 2022-05-25 13:49:27.557596 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="31642236728" LIMIT 1
  1053. 2022-05-25 13:49:27.557596 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as  accounts ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="31642236728"  AND domains.domain="emolife138.yoursipcloud.com" limit 1
  1054. 2022-05-25 13:49:27.557596 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] New Call Direction : outbound
  1055. 2022-05-25 13:49:27.557596 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '31642236728' OR number = '3164223672' OR number = '316422367' OR number = '31642236' OR number = '3164223' OR number = '316422' OR number = '31642' OR number = '3164' OR number = '316' OR number = '31' OR number = '3' OR number ='--') OR (number = '31642236728*' OR number = '3164223672*' OR number = '316422367*' OR number = '31642236*' OR number = '3164223*' OR number = '316422*' OR number = '31642*' OR number = '3164*' OR number = '316*' OR number = '31*' OR number = '3*' OR number ='--') OR (number = '31332030246' OR number = '3133203024' OR number = '313320302' OR number = '31332030' OR number = '3133203' OR number = '313320' OR number = '31332' OR number = '3133' OR number = '313' OR number = '31' OR number = '3' OR number ='--') OR (number = '31332030246*' OR number = '3133203024*' OR number = '313320302*' OR number = '31332030*' OR number = '3133203*' OR number = '313320*' OR number = '31332*' OR number = '3133*' OR number = '313*' OR number = '31*' OR number = '3*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
  1056. 2022-05-25 13:49:27.557596 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Call Barring Status : -1
  1057. 2022-05-25 13:49:27.557596 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '31642236728' OR routing_prefix = '3164223672' OR routing_prefix = '316422367' OR routing_prefix = '31642236' OR routing_prefix = '3164223' OR routing_prefix = '316422' OR routing_prefix = '31642' OR routing_prefix = '3164' OR routing_prefix = '316' OR routing_prefix = '31' OR routing_prefix = '3' OR routing_prefix ='--') AND status = 0 and reseller_id = 189 limit 1
  1058. 2022-05-25 13:49:27.557596 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Account Information ===================
  1059. 2022-05-25 13:49:27.557596 [INFO] switch_cpp.cpp:1447 [ASTPP] User id : 199
  1060. 2022-05-25 13:49:27.557596 [INFO] switch_cpp.cpp:1447 [ASTPP] Account code : 183284
  1061. 2022-05-25 13:49:27.557596 [INFO] switch_cpp.cpp:1447 [ASTPP] Balance : 700.2309
  1062. 2022-05-25 13:49:27.557596 [INFO] switch_cpp.cpp:1447 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
  1063. 2022-05-25 13:49:27.557596 [INFO] switch_cpp.cpp:1447 [ASTPP] Ratecard id : 25
  1064. 2022-05-25 13:49:27.557596 [INFO] switch_cpp.cpp:1447 [ASTPP] CID Pool : 0
  1065. 2022-05-25 13:49:27.557596 [INFO] switch_cpp.cpp:1447 [ASTPP] ========================================================
  1066. 2022-05-25 13:49:27.557596 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 25 AND status = 0
  1067. 2022-05-25 13:49:27.557596 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] call_direction :outbound
  1068. 2022-05-25 13:49:27.557596 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 25 AND status = 0
  1069. 2022-05-25 13:49:27.557596 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^31642236728.*' OR pattern = '^3164223672.*' OR pattern = '^316422367.*' OR pattern = '^31642236.*' OR pattern = '^3164223.*' OR pattern = '^316422.*' OR pattern = '^31642.*' OR pattern = '^3164.*' OR pattern = '^316.*' OR pattern = '^31.*' OR pattern = '^3.*' OR pattern ='--') AND status = 0 AND effective_date <= UTC_TIMESTAMP() AND (pricelist_id = 25 OR accountid=199)  ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC,effective_date DESC LIMIT 1
  1070. 2022-05-25 13:49:27.557596 [INFO] switch_cpp.cpp:1447 [ASTPP]  Call Direction : outbound
  1071. 2022-05-25 13:49:27.557596 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Rates Information ===================
  1072. 2022-05-25 13:49:27.557596 [INFO] switch_cpp.cpp:1447 [ASTPP] ID : 1597624
  1073. 2022-05-25 13:49:27.557596 [INFO] switch_cpp.cpp:1447 [ASTPP] Connectcost : 0.00000
  1074. 2022-05-25 13:49:27.557596 [INFO] switch_cpp.cpp:1447 [ASTPP] Includedseconds : 0
  1075. 2022-05-25 13:49:27.557596 [INFO] switch_cpp.cpp:1447 [ASTPP] Cost : 0.04100
  1076. 2022-05-25 13:49:27.557596 [INFO] switch_cpp.cpp:1447 [ASTPP] Comment :
  1077. 2022-05-25 13:49:27.557596 [INFO] switch_cpp.cpp:1447 [ASTPP] Country Id : 0
  1078. 2022-05-25 13:49:27.557596 [INFO] switch_cpp.cpp:1447 [ASTPP] Accid : 199
  1079. 2022-05-25 13:49:27.557596 [INFO] switch_cpp.cpp:1447 [ASTPP] Trunk ID:
  1080. 2022-05-25 13:49:27.557596 [INFO] switch_cpp.cpp:1447 [ASTPP] Routing type:
  1081. 2022-05-25 13:49:27.557596 [INFO] switch_cpp.cpp:1447 [ASTPP] ================================================================
  1082. 2022-05-25 13:49:27.557596 [INFO] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Your700.2309 balance Accountid 199 !!!
  1083. 2022-05-25 13:49:27.557596 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Limiting Call To Config Max Length 100 mins!
  1084. 2022-05-25 13:49:27.557596 [NOTICE] switch_cpp.cpp:1447 [ASTPP] FINDING LIMIT FOR RESELLER: 189
  1085. 2022-05-25 13:49:27.557596 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "189" AND deleted = 0 limit 1
  1086. 2022-05-25 13:49:27.577608 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^31642236728.*' OR blocked_patterns = '^3164223672.*' OR blocked_patterns = '^316422367.*' OR blocked_patterns = '^31642236.*' OR blocked_patterns = '^3164223.*' OR blocked_patterns = '^316422.*' OR blocked_patterns = '^31642.*' OR blocked_patterns = '^3164.*' OR blocked_patterns = '^316.*' OR blocked_patterns = '^31.*' OR blocked_patterns = '^3.*' OR blocked_patterns ='--') AND accountid = 189 limit 1
  1087. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Reseller Information ===================
  1088. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] User id : 189
  1089. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Account code : 510514
  1090. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Balance : 195.5748
  1091. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
  1092. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Ratecard id : 44
  1093. 2022-05-25 13:49:27.577608 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 44 AND status = 0
  1094. 2022-05-25 13:49:27.577608 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] call_direction :outbound
  1095. 2022-05-25 13:49:27.577608 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 44 AND status = 0
  1096. 2022-05-25 13:49:27.577608 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^31642236728.*' OR pattern = '^3164223672.*' OR pattern = '^316422367.*' OR pattern = '^31642236.*' OR pattern = '^3164223.*' OR pattern = '^316422.*' OR pattern = '^31642.*' OR pattern = '^3164.*' OR pattern = '^316.*' OR pattern = '^31.*' OR pattern = '^3.*' OR pattern ='--') AND status = 0 AND effective_date <= UTC_TIMESTAMP() AND (pricelist_id = 44 OR accountid=189)  ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC,effective_date DESC LIMIT 1
  1097. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP]  Call Direction : outbound
  1098. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Rates Information ===================
  1099. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] ID : 2846861
  1100. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Connectcost : 0.00000
  1101. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Includedseconds : 0
  1102. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Cost : 0.00605
  1103. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Comment : Netherlands Mobile
  1104. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Country Id : 0
  1105. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Accid : 189
  1106. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Trunk ID: 4
  1107. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Routing type:
  1108. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] ================================================================
  1109. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Your195.5748 balance Accountid 189 !!!
  1110. 2022-05-25 13:49:27.577608 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Limiting Call To Config Max Length 100 mins!
  1111. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Reseller xml_user_rates : ID:1597624|CODE:^316.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.04100|INC:1|INITIALBLOCK:1|RATEGROUP:25|MARKUP:0|CI:0|ACCID:199||ID:2846861|CODE:^3164.*|DESTINATION:Netherlands Mobile|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.00605|INC:1|INITIALBLOCK:1|RATEGROUP:44|MARKUP:0|CI:0|ACCID:189
  1112. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] ========================================================
  1113. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Lossless Routing Status : 1
  1114. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Call Max Length Duration : 100 minutes
  1115. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] [DIALPLAN] User Rate ID : 1597624
  1116. 2022-05-25 13:49:27.577608 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_CARRIER_RATES] Query Rate Carrier Id        :4
  1117. 2022-05-25 13:49:27.577608 [DEBUG] switch_cpp.cpp:1447 [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 = '^31642236728.*' OR pattern = '^3164223672.*' OR pattern = '^316422367.*' OR pattern = '^31642236.*' OR pattern = '^3164223.*' OR pattern = '^316422.*' OR pattern = '^31642.*' OR pattern = '^3164.*' OR pattern = '^316.*' OR pattern = '^31.*' OR pattern = '^3.*' OR pattern ='--') AND TR.status = 0  AND TR.trunk_id IN (4)  AND TR.effective_date <= UTC_TIMESTAMP() ORDER by FIELD(TR.trunk_id,4),LENGTH (pattern) DESC,TR.cost ASC,TR.precedence ASC,TR.effective_date DESC
  1118. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Termination Rates Information ===================
  1119. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] ID : 114268
  1120. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Code : ^3164.*
  1121. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Destination : Netherlands Mobile
  1122. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Connectcost : 0.00000
  1123. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Free Seconds : 0
  1124. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Prefix : ^3164.*
  1125. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Strip :
  1126. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Prepend :
  1127. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Carrier id : 4
  1128. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] carrier_name : SBC1
  1129. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] dialplan_variable :
  1130. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Failover gateway : MOR
  1131. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Vendor id : 231
  1132. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Max channels : 0
  1133. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] Trunk Name : SBCs
  1134. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] ========================END OF TERMINATION RATES=======================
  1135. 2022-05-25 13:49:27.577608 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 199 AND status=0 LIMIT 1
  1136. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP]  Maxlength : 100
  1137. 2022-05-25 13:49:27.577608 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  1138. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] [XML] Customer Recording Flag : 1
  1139. 2022-05-25 13:49:27.577608 [INFO] switch_cpp.cpp:1447 [ASTPP] [PBX] Call Direction : outbound
  1140. 2022-05-25 13:49:27.577608 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 44 AND status = 0
  1141. 2022-05-25 13:49:27.577608 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from accounts where id = 231) AND status=0 limit 1
  1142. 2022-05-25 13:49:27.577608 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from trunks where id = 4) AND status=0 limit 1
  1143. 2022-05-25 13:49:27.577608 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_LOCALIZATION] Query :"0/31".....31642236728
  1144. 2022-05-25 13:49:27.577608 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="31642236728" LIMIT 1
  1145. 2022-05-25 13:49:27.577608 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Generated XML:
  1146. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  1147. <document type="freeswitch/xml">
  1148. <section name="dialplan" description="ASTPP Dialplan">
  1149. <context name="default">
  1150. <extension name="31642236728">
  1151. <condition field="destination_number" expression="31642236728">
  1152. <action application="set" data="effective_destination_number=31642236728"/>
  1153. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
  1154. <action application="set" data="bridge_pre_execute_bleg_data=+6000 normal_clearing"/>
  1155. <action application="set" data="callstart=2022-05-25 11:49:27"/>
  1156. <action application="set" data="hangup_after_bridge=true"/>
  1157. <action application="set" data="continue_on_fail=TRUE"/>
  1158. <action application="set" data="account_id=199"/>
  1159. <action application="set" data="parent_id=189"/>
  1160. <action application="set" data="entity_id=0"/>
  1161. <action application="set" data="call_processed=internal"/>
  1162. <action application="set" data="call_direction=outbound"/>
  1163. <action application="set" data="accountname=emopbx"/>
  1164. <action application="set" data="filename1=astpp.pbx.lua"/>
  1165. <action application="set" data="origination_rates=ID:1597624|CODE:^316.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.04100|INC:1|INITIALBLOCK:1|RATEGROUP:25|MARKUP:0|CI:0|ACCID:199||ID:2846861|CODE:^3164.*|DESTINATION:Netherlands Mobile|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.00605|INC:1|INITIALBLOCK:1|RATEGROUP:44|MARKUP:0|CI:0|ACCID:189"/>
  1166. <action application="set" data="original_caller_id_name=31332030246"/>
  1167. <action application="set" data="original_caller_id_number=31332030246"/>
  1168. <action application="limit" data="db 183284 user_183284 10 "/>
  1169. <action application="limit" data="db 510514 user_510514 20 "/><action application="limit" data="hash CPS_510514 CPS_user_510514 1/1 "/>
  1170. <action application="set" data="effective_caller_id_name=31332030246"/>
  1171. <action application="set" data="effective_caller_id_number=31332030246"/>
  1172. <action application="set" data="calltype=STANDARD"/>
  1173. <action application="set" data="termination_rates=ID:114268|CODE:^3164.*|DESTINATION:Netherlands Mobile|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00065|INC:1|INITIALBLOCK:1|TRUNK:4|PROVIDER:231"/>
  1174. <action application="set" data="trunk_id=4"/>
  1175. <action application="set" data="provider_id=231"/>
  1176. <action application="set" data="rate_flag=0"/>
  1177. <action application="set" data="force_trunk_flag=1597624"/>
  1178. <action application="export" data="presence_data=trunk_id=4"/>
  1179. <action application="set" data="intcall=0"/>
  1180. <action application="export" data="presence_id=31642236728"/>
  1181. <action application="export" data="presence_data=x//189|||emolife(183284)|||^316.* //  // 0.04100 |||SBCs // ^3164.* // Netherlands Mobile // 0.00065 // trunk_id=4|||STD|||213.144.239.110|||0.00605 // ^3164.* // Netherlands Mobile"/>
  1182. <action application="bridge" data="[leg_timeout=0,absolute_codec_string=^^:PCMA]sofia/gateway/SBC1/31642236728"/>
  1183. <action application="bridge" data="[leg_timeout=0,absolute_codec_string=^^:PCMA]sofia/gateway/MOR/31642236728"/>
  1184. <action application="bridge" data="[leg_timeout=0,absolute_codec_string=^^:PCMA]sofia/gateway/SBC2/31642236728"/>
  1185. </condition>
  1186. </extension>
  1187. </context>
  1188. </section>
  1189. </document>
  1190. Dialplan: sofia/default/183284@213.144.239.110:9933 parsing [default->31642236728] continue=false
  1191. Dialplan: sofia/default/183284@213.144.239.110:9933 Regex (PASS) [31642236728] destination_number(31642236728) =~ /31642236728/ break=on-false
  1192. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(effective_destination_number=31642236728)
  1193. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(bridge_pre_execute_bleg_app=sched_hangup)
  1194. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
  1195. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(callstart=2022-05-25 11:49:27)
  1196. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(hangup_after_bridge=true)
  1197. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(continue_on_fail=TRUE)
  1198. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(account_id=199)
  1199. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(parent_id=189)
  1200. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(entity_id=0)
  1201. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(call_processed=internal)
  1202. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(call_direction=outbound)
  1203. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(accountname=emopbx)
  1204. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(filename1=astpp.pbx.lua)
  1205. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(origination_rates=ID:1597624|CODE:^316.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.04100|INC:1|INITIALBLOCK:1|RATEGROUP:25|MARKUP:0|CI:0|ACCID:199||ID:2846861|CODE:^3164.*|DESTINATION:Netherlands Mobile|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.00605|INC:1|INITIALBLOCK:1|RATEGROUP:44|MARKUP:0|CI:0|ACCID:189)
  1206. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(original_caller_id_name=31332030246)
  1207. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(original_caller_id_number=31332030246)
  1208. Dialplan: sofia/default/183284@213.144.239.110:9933 Action limit(db 183284 user_183284 10 )
  1209. Dialplan: sofia/default/183284@213.144.239.110:9933 Action limit(db 510514 user_510514 20 )
  1210. Dialplan: sofia/default/183284@213.144.239.110:9933 Action limit(hash CPS_510514 CPS_user_510514 1/1 )
  1211. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(effective_caller_id_name=31332030246)
  1212. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(effective_caller_id_number=31332030246)
  1213. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(calltype=STANDARD)
  1214. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(termination_rates=ID:114268|CODE:^3164.*|DESTINATION:Netherlands Mobile|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00065|INC:1|INITIALBLOCK:1|TRUNK:4|PROVIDER:231)
  1215. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(trunk_id=4)
  1216. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(provider_id=231)
  1217. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(rate_flag=0)
  1218. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(force_trunk_flag=1597624)
  1219. Dialplan: sofia/default/183284@213.144.239.110:9933 Action export(presence_data=trunk_id=4)
  1220. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(intcall=0)
  1221. Dialplan: sofia/default/183284@213.144.239.110:9933 Action export(presence_id=31642236728)
  1222. Dialplan: sofia/default/183284@213.144.239.110:9933 Action export(presence_data=x//189|||emolife(183284)|||^316.* //  // 0.04100 |||SBCs // ^3164.* // Netherlands Mobile // 0.00065 // trunk_id=4|||STD|||213.144.239.110|||0.00605 // ^3164.* // Netherlands Mobile)
  1223. Dialplan: sofia/default/183284@213.144.239.110:9933 Action bridge([leg_timeout=0,absolute_codec_string=^^:PCMA]sofia/gateway/SBC1/31642236728)
  1224. Dialplan: sofia/default/183284@213.144.239.110:9933 Action bridge([leg_timeout=0,absolute_codec_string=^^:PCMA]sofia/gateway/MOR/31642236728)
  1225. Dialplan: sofia/default/183284@213.144.239.110:9933 Action bridge([leg_timeout=0,absolute_codec_string=^^:PCMA]sofia/gateway/SBC2/31642236728)
  1226. 2022-05-25 13:49:27.577608 [DEBUG] switch_core_state_machine.c:287 (sofia/default/183284@213.144.239.110:9933) State Change CS_ROUTING -> CS_EXECUTE
  1227. 2022-05-25 13:49:27.577608 [DEBUG] switch_core_state_machine.c:644 (sofia/default/183284@213.144.239.110:9933) State ROUTING going to sleep
  1228. 2022-05-25 13:49:27.577608 [DEBUG] switch_core_state_machine.c:585 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_EXECUTE (Cur 9 Tot 24048)
  1229. 2022-05-25 13:49:27.577608 [DEBUG] switch_core_state_machine.c:651 (sofia/default/183284@213.144.239.110:9933) State EXECUTE
  1230. 2022-05-25 13:49:27.577608 [DEBUG] mod_sofia.c:209 sofia/default/183284@213.144.239.110:9933 SOFIA EXECUTE
  1231. 2022-05-25 13:49:27.577608 [DEBUG] switch_core_state_machine.c:329 sofia/default/183284@213.144.239.110:9933 Standard EXECUTE
  1232. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(effective_destination_number=31642236728)
  1233. 2022-05-25 13:49:27.577608 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [effective_destination_number]=[31642236728]
  1234. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(bridge_pre_execute_bleg_app=sched_hangup)
  1235. 2022-05-25 13:49:27.577608 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [bridge_pre_execute_bleg_app]=[sched_hangup]
  1236. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
  1237. 2022-05-25 13:49:27.577608 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [bridge_pre_execute_bleg_data]=[+6000 normal_clearing]
  1238. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(callstart=2022-05-25 11:49:27)
  1239. 2022-05-25 13:49:27.577608 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [callstart]=[2022-05-25 11:49:27]
  1240. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(hangup_after_bridge=true)
  1241. 2022-05-25 13:49:27.577608 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [hangup_after_bridge]=[true]
  1242. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(continue_on_fail=TRUE)
  1243. 2022-05-25 13:49:27.577608 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [continue_on_fail]=[TRUE]
  1244. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(account_id=199)
  1245. 2022-05-25 13:49:27.577608 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [account_id]=[199]
  1246. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(parent_id=189)
  1247. 2022-05-25 13:49:27.577608 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [parent_id]=[189]
  1248. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(entity_id=0)
  1249. 2022-05-25 13:49:27.577608 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [entity_id]=[0]
  1250. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(call_processed=internal)
  1251. 2022-05-25 13:49:27.577608 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [call_processed]=[internal]
  1252. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(call_direction=outbound)
  1253. 2022-05-25 13:49:27.577608 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [call_direction]=[outbound]
  1254. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(accountname=emopbx)
  1255. 2022-05-25 13:49:27.577608 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [accountname]=[emopbx]
  1256. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(filename1=astpp.pbx.lua)
  1257. 2022-05-25 13:49:27.577608 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [filename1]=[astpp.pbx.lua]
  1258. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(origination_rates=ID:1597624|CODE:^316.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.04100|INC:1|INITIALBLOCK:1|RATEGROUP:25|MARKUP:0|CI:0|ACCID:199||ID:2846861|CODE:^3164.*|DESTINATION:Netherlands Mobile|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.00605|INC:1|INITIALBLOCK:1|RATEGROUP:44|MARKUP:0|CI:0|ACCID:189)
  1259. 2022-05-25 13:49:27.577608 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [origination_rates]=[ID:1597624|CODE:^316.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.04100|INC:1|INITIALBLOCK:1|RATEGROUP:25|MARKUP:0|CI:0|ACCID:199||ID:2846861|CODE:^3164.*|DESTINATION:Netherlands Mobile|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.00605|INC:1|INITIALBLOCK:1|RATEGROUP:44|MARKUP:0|CI:0|ACCID:189]
  1260. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(original_caller_id_name=31332030246)
  1261. 2022-05-25 13:49:27.577608 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [original_caller_id_name]=[31332030246]
  1262. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(original_caller_id_number=31332030246)
  1263. 2022-05-25 13:49:27.577608 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [original_caller_id_number]=[31332030246]
  1264. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 limit(db 183284 user_183284 10 )
  1265. 2022-05-25 13:49:27.577608 [DEBUG] switch_limit.c:124 incr called: 183284_user_183284 max:10, interval:0
  1266. 2022-05-25 13:49:27.577608 [DEBUG] mod_db.c:194 Usage for 183284_user_183284 is now 1/10
  1267. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 limit(db 510514 user_510514 20 )
  1268. 2022-05-25 13:49:27.577608 [DEBUG] switch_limit.c:124 incr called: 510514_user_510514 max:20, interval:0
  1269. 2022-05-25 13:49:27.577608 [DEBUG] mod_db.c:194 Usage for 510514_user_510514 is now 2/20
  1270. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 limit(hash CPS_510514 CPS_user_510514 1/1 )
  1271. 2022-05-25 13:49:27.577608 [DEBUG] switch_limit.c:124 incr called: CPS_510514_CPS_user_510514 max:1, interval:1
  1272. 2022-05-25 13:49:27.577608 [DEBUG] mod_hash.c:198 Usage for CPS_510514_CPS_user_510514 is now 1/1 for the last 1 seconds
  1273. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(effective_caller_id_name=31332030246)
  1274. 2022-05-25 13:49:27.577608 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [effective_caller_id_name]=[31332030246]
  1275. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(effective_caller_id_number=31332030246)
  1276. 2022-05-25 13:49:27.577608 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [effective_caller_id_number]=[31332030246]
  1277. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(calltype=STANDARD)
  1278. 2022-05-25 13:49:27.577608 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [calltype]=[STANDARD]
  1279. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(termination_rates=ID:114268|CODE:^3164.*|DESTINATION:Netherlands Mobile|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00065|INC:1|INITIALBLOCK:1|TRUNK:4|PROVIDER:231)
  1280. 2022-05-25 13:49:27.577608 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [termination_rates]=[ID:114268|CODE:^3164.*|DESTINATION:Netherlands Mobile|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00065|INC:1|INITIALBLOCK:1|TRUNK:4|PROVIDER:231]
  1281. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(trunk_id=4)
  1282. 2022-05-25 13:49:27.577608 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [trunk_id]=[4]
  1283. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(provider_id=231)
  1284. 2022-05-25 13:49:27.577608 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [provider_id]=[231]
  1285. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(rate_flag=0)
  1286. 2022-05-25 13:49:27.577608 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [rate_flag]=[0]
  1287. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(force_trunk_flag=1597624)
  1288. 2022-05-25 13:49:27.577608 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [force_trunk_flag]=[1597624]
  1289. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 export(presence_data=trunk_id=4)
  1290. 2022-05-25 13:49:27.577608 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[trunk_id=4]
  1291. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(intcall=0)
  1292. 2022-05-25 13:49:27.577608 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [intcall]=[0]
  1293. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 export(presence_id=31642236728)
  1294. 2022-05-25 13:49:27.577608 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_id]=[31642236728]
  1295. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 export(presence_data=x//189|||emolife(183284)|||^316.* //  // 0.04100 |||SBCs // ^3164.* // Netherlands Mobile // 0.00065 // trunk_id=4|||STD|||213.144.239.110|||0.00605 // ^3164.* // Netherlands Mobile)
  1296. 2022-05-25 13:49:27.597605 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[x//189|||emolife(183284)|||^316.* //  // 0.04100 |||SBCs // ^3164.* // Netherlands Mobile // 0.00065 // trunk_id=4|||STD|||213.144.239.110|||0.00605 // ^3164.* // Netherlands Mobile]
  1297. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 bridge([leg_timeout=0,absolute_codec_string=^^:PCMA]sofia/gateway/SBC1/31642236728)
  1298. 2022-05-25 13:49:27.597605 [DEBUG] switch_channel.c:1264 sofia/default/183284@213.144.239.110:9933 EXPORTING[export_vars] [presence_data]=[x//189|||emolife(183284)|||^316.* //  // 0.04100 |||SBCs // ^3164.* // Netherlands Mobile // 0.00065 // trunk_id=4|||STD|||213.144.239.110|||0.00605 // ^3164.* // Netherlands Mobile] to event
  1299. 2022-05-25 13:49:27.597605 [DEBUG] switch_channel.c:1264 sofia/default/183284@213.144.239.110:9933 EXPORTING[export_vars] [presence_id]=[31642236728] to event
  1300. 2022-05-25 13:49:27.597605 [DEBUG] switch_channel.c:1264 sofia/default/183284@213.144.239.110:9933 EXPORTING[export_vars] [presence_data]=[x//189|||emolife(183284)|||^316.* //  // 0.04100 |||SBCs // ^3164.* // Netherlands Mobile // 0.00065 // trunk_id=4|||STD|||213.144.239.110|||0.00605 // ^3164.* // Netherlands Mobile] to event
  1301. 2022-05-25 13:49:27.597605 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables
  1302. 2022-05-25 13:49:27.597605 [DEBUG] switch_ivr_originate.c:2804 Parsing session specific variables
  1303. 2022-05-25 13:49:27.597605 [NOTICE] switch_channel.c:1118 New Channel sofia/default/31642236728 [9d8bccce-64de-4d11-b953-3de41de24139]
  1304. 2022-05-25 13:49:27.597605 [DEBUG] mod_sofia.c:5089 (sofia/default/31642236728) State Change CS_NEW -> CS_INIT
  1305. 2022-05-25 13:49:27.597605 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31642236728) Running State Change CS_INIT (Cur 10 Tot 24049)
  1306. 2022-05-25 13:49:27.597605 [DEBUG] switch_core_state_machine.c:628 (sofia/default/31642236728) State INIT
  1307. 2022-05-25 13:49:27.597605 [DEBUG] mod_sofia.c:93 sofia/default/31642236728 SOFIA INIT
  1308. 2022-05-25 13:49:27.597605 [DEBUG] sofia_glue.c:1618 sofia/default/31642236728 sending invite version: 1.10.5 -release-17-25569c1631 64bit
  1309. Local SDP:
  1310. v=0
  1311. o=FreeSWITCH 1653455331 1653455332 IN IP4 185.249.40.236
  1312. s=FreeSWITCH
  1313. c=IN IP4 185.249.40.236
  1314. t=0 0
  1315. m=audio 24036 RTP/AVP 8 101
  1316. a=rtpmap:8 PCMA/8000
  1317. a=rtpmap:101 telephone-event/8000
  1318. a=fmtp:101 0-16
  1319. a=ptime:20
  1320. a=sendrecv
  1321.  
  1322. 2022-05-25 13:49:27.597605 [DEBUG] switch_core_state_machine.c:40 sofia/default/31642236728 Standard INIT
  1323. 2022-05-25 13:49:27.597605 [DEBUG] switch_core_state_machine.c:48 (sofia/default/31642236728) State Change CS_INIT -> CS_ROUTING
  1324. 2022-05-25 13:49:27.597605 [DEBUG] switch_core_state_machine.c:628 (sofia/default/31642236728) State INIT going to sleep
  1325. 2022-05-25 13:49:27.597605 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31642236728) Running State Change CS_ROUTING (Cur 10 Tot 24049)
  1326. 2022-05-25 13:49:27.597605 [DEBUG] switch_core_state_machine.c:644 (sofia/default/31642236728) State ROUTING
  1327. 2022-05-25 13:49:27.597605 [DEBUG] mod_sofia.c:154 sofia/default/31642236728 SOFIA ROUTING
  1328. 2022-05-25 13:49:27.597605 [DEBUG] switch_ivr_originate.c:67 (sofia/default/31642236728) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  1329. 2022-05-25 13:49:27.597605 [DEBUG] switch_core_state_machine.c:644 (sofia/default/31642236728) State ROUTING going to sleep
  1330. 2022-05-25 13:49:27.597605 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31642236728) Running State Change CS_CONSUME_MEDIA (Cur 10 Tot 24049)
  1331. 2022-05-25 13:49:27.597605 [DEBUG] sofia.c:7326 Channel sofia/default/31642236728 entering state [calling][0]
  1332. 2022-05-25 13:49:27.597605 [DEBUG] switch_core_state_machine.c:663 (sofia/default/31642236728) State CONSUME_MEDIA
  1333. 2022-05-25 13:49:27.597605 [DEBUG] switch_core_state_machine.c:663 (sofia/default/31642236728) State CONSUME_MEDIA going to sleep
  1334. 2022-05-25 13:49:28.057602 [DEBUG] sofia.c:7326 Channel sofia/default/31642236728 entering state [proceeding][183]
  1335. 2022-05-25 13:49:28.057602 [DEBUG] sofia.c:7336 Remote SDP:
  1336. v=0
  1337. o=- 3909785936 1 IN IP4 185.249.40.253
  1338. s=-
  1339. c=IN IP4 185.249.40.253
  1340. t=0 0
  1341. m=audio 38132 RTP/AVP 8 101
  1342. a=rtpmap:101 telephone-event/8000
  1343. a=ptime:20
  1344.  
  1345. 2022-05-25 13:49:28.057602 [NOTICE] sofia.c:7339 Pre-Answer sofia/default/31642236728!
  1346. 2022-05-25 13:49:28.057602 [DEBUG] switch_channel.c:3565 (sofia/default/31642236728) Callstate Change DOWN -> EARLY
  1347. 2022-05-25 13:49:28.057602 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1348. 2022-05-25 13:49:28.057602 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  1349. 2022-05-25 13:49:28.057602 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
  1350. 2022-05-25 13:49:28.057602 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/31642236728 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  1351. 2022-05-25 13:49:28.057602 [DEBUG] switch_core_codec.c:111 sofia/default/31642236728 Original read codec set to PCMA:8
  1352. 2022-05-25 13:49:28.057602 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
  1353. 2022-05-25 13:49:28.057602 [DEBUG] switch_core_media.c:5911 sofia/default/31642236728 Set 2833 dtmf send payload to 101 recv payload to 101
  1354. 2022-05-25 13:49:28.057602 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/31642236728] 185.249.40.236 port 24036 -> 185.249.40.253 port 38132 codec: 8 ms: 20
  1355. 2022-05-25 13:49:28.057602 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
  1356. 2022-05-25 13:49:28.057602 [DEBUG] switch_core_media.c:8977 sofia/default/31642236728 Set 2833 dtmf send payload to 101
  1357. 2022-05-25 13:49:28.057602 [DEBUG] switch_core_media.c:8984 sofia/default/31642236728 Set 2833 dtmf receive payload to 101
  1358. 2022-05-25 13:49:28.057602 [DEBUG] switch_core_media.c:9007 sofia/default/31642236728 Set rtp dtmf delay to 40
  1359. 2022-05-25 13:49:28.077587 [INFO] switch_ivr_originate.c:3801 Sending early media
  1360. 2022-05-25 13:49:28.077587 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/183284@213.144.239.110:9933] 185.249.40.236 port 27398 -> 213.144.239.110 port 19804 codec: 0 ms: 20
  1361. 2022-05-25 13:49:28.077587 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
  1362. 2022-05-25 13:49:28.077587 [DEBUG] switch_core_media.c:8977 sofia/default/183284@213.144.239.110:9933 Set 2833 dtmf send payload to 101
  1363. 2022-05-25 13:49:28.077587 [DEBUG] switch_core_media.c:8984 sofia/default/183284@213.144.239.110:9933 Set 2833 dtmf receive payload to 101
  1364. 2022-05-25 13:49:28.077587 [DEBUG] switch_core_media.c:9007 sofia/default/183284@213.144.239.110:9933 Set rtp dtmf delay to 40
  1365. 2022-05-25 13:49:28.077587 [DEBUG] mod_sofia.c:2593 Ring SDP:
  1366. v=0
  1367. o=FreeSWITCH 1653451970 1653451971 IN IP4 185.249.40.236
  1368. s=FreeSWITCH
  1369. c=IN IP4 185.249.40.236
  1370. t=0 0
  1371. m=audio 27398 RTP/AVP 0 101
  1372. a=rtpmap:0 PCMU/8000
  1373. a=rtpmap:101 telephone-event/8000
  1374. a=fmtp:101 0-16
  1375. a=ptime:20
  1376. a=sendrecv
  1377.  
  1378. 2022-05-25 13:49:28.077587 [NOTICE] mod_sofia.c:2596 Pre-Answer sofia/default/183284@213.144.239.110:9933!
  1379. 2022-05-25 13:49:28.077587 [DEBUG] switch_channel.c:3565 (sofia/default/183284@213.144.239.110:9933) Callstate Change RINGING -> EARLY
  1380. 2022-05-25 13:49:28.077587 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/default/31642236728]
  1381. 2022-05-25 13:49:28.077587 [DEBUG] sofia.c:7326 Channel sofia/default/183284@213.144.239.110:9933 entering state [early][183]
  1382. 2022-05-25 13:49:28.077587 [DEBUG] switch_ivr_bridge.c:1793 (sofia/default/31642236728) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  1383. 2022-05-25 13:49:28.077587 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31642236728) Running State Change CS_EXCHANGE_MEDIA (Cur 10 Tot 24049)
  1384. 2022-05-25 13:49:28.077587 [DEBUG] switch_core_state_machine.c:654 (sofia/default/31642236728) State EXCHANGE_MEDIA
  1385. 2022-05-25 13:49:28.077587 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA
  1386. 2022-05-25 13:49:28.157655 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
  1387. 2022-05-25 13:49:28.257593 [DEBUG] switch_ivr_bridge.c:655 sofia/default/31642236728 Bridge execute app sched_hangup(+6000 normal_clearing)
  1388. 2022-05-25 13:49:28.277589 [DEBUG] switch_ivr.c:632 sofia/default/31642236728 Command Execute [depth=0] sched_hangup(+6000 normal_clearing)
  1389. EXECUTE [depth=0] sofia/default/31642236728 sched_hangup(+6000 normal_clearing)
  1390. 2022-05-25 13:49:28.277589 [DEBUG] switch_scheduler.c:252 Added task 8711 switch_ivr_schedule_hangup (9d8bccce-64de-4d11-b953-3de41de24139) to run at 1653485368
  1391. 2022-05-25 13:49:28.717604 [DEBUG] sofia.c:7326 Channel sofia/default/31642236728 entering state [proceeding][183]
  1392. 2022-05-25 13:49:28.717604 [DEBUG] sofia.c:7333 Duplicate SDP
  1393. v=0
  1394. o=- 3909785936 1 IN IP4 185.249.40.253
  1395. s=-
  1396. c=IN IP4 185.249.40.253
  1397. t=0 0
  1398. m=audio 38132 RTP/AVP 8 101
  1399. a=rtpmap:101 telephone-event/8000
  1400. a=ptime:20
  1401.  
  1402. 2022-05-25 13:49:28.717604 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1403. 2022-05-25 13:49:28.717604 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  1404. 2022-05-25 13:49:28.717604 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
  1405. 2022-05-25 13:49:28.717604 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
  1406. 2022-05-25 13:49:28.717604 [DEBUG] switch_core_media.c:5911 sofia/default/31642236728 Set 2833 dtmf send payload to 101 recv payload to 101
  1407. 2022-05-25 13:49:28.717604 [DEBUG] switch_core_media.c:8645 Audio params are unchanged for sofia/default/31642236728.
  1408. 2022-05-25 13:49:28.737592 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
  1409. 2022-05-25 13:49:34.257624 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [7985166681@136056.yoursipcloud.com] from ip 143.178.159.127
  1410. 2022-05-25 13:49:42.197611 [DEBUG] sofia.c:7326 Channel sofia/default/31652033157@185.249.40.254 entering state [terminated][487]
  1411. 2022-05-25 13:49:42.197611 [NOTICE] sofia.c:8559 Hangup sofia/default/31652033157@185.249.40.254 [CS_EXECUTE] [ORIGINATOR_CANCEL]
  1412. 2022-05-25 13:49:42.197611 [DEBUG] switch_ivr_bridge.c:826 sofia/default/31652033157@185.249.40.254 ending bridge by request from read function
  1413. 2022-05-25 13:49:42.197611 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/31652033157@185.249.40.254]
  1414. 2022-05-25 13:49:42.197611 [DEBUG] switch_ivr_bridge.c:820 sofia/default/31652033157@185.249.40.254 ending bridge by request from write function
  1415. 2022-05-25 13:49:42.197611 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/31854832191@134271.yoursipcloud.com:5060]
  1416. 2022-05-25 13:49:42.197611 [NOTICE] switch_ivr_bridge.c:1025 Hangup sofia/default/31854832191@134271.yoursipcloud.com:5060 [CS_EXCHANGE_MEDIA] [ORIGINATOR_CANCEL]
  1417. 2022-05-25 13:49:42.197611 [DEBUG] switch_core_state_machine.c:654 (sofia/default/31854832191@134271.yoursipcloud.com:5060) State EXCHANGE_MEDIA going to sleep
  1418. 2022-05-25 13:49:42.197611 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31854832191@134271.yoursipcloud.com:5060) Running State Change CS_HANGUP (Cur 10 Tot 24049)
  1419. 2022-05-25 13:49:42.197611 [DEBUG] switch_core_state_machine.c:848 (sofia/default/31854832191@134271.yoursipcloud.com:5060) Callstate Change EARLY -> HANGUP
  1420. 2022-05-25 13:49:42.197611 [DEBUG] switch_core_state_machine.c:850 (sofia/default/31854832191@134271.yoursipcloud.com:5060) State HANGUP
  1421. 2022-05-25 13:49:42.197611 [DEBUG] mod_sofia.c:447 sofia/default/31854832191@134271.yoursipcloud.com:5060 Overriding SIP cause 487 with 487 from the other leg
  1422. 2022-05-25 13:49:42.197611 [DEBUG] mod_sofia.c:453 Channel sofia/default/31854832191@134271.yoursipcloud.com:5060 hanging up, cause: ORIGINATOR_CANCEL
  1423. 2022-05-25 13:49:42.217596 [DEBUG] switch_ivr_bridge.c:1892 sofia/default/31854832191@134271.yoursipcloud.com:5060 skip receive message [UNBRIDGE] (channel is hungup already)
  1424. 2022-05-25 13:49:42.217596 [DEBUG] switch_ivr_bridge.c:1895 sofia/default/31652033157@185.249.40.254 skip receive message [UNBRIDGE] (channel is hungup already)
  1425. 2022-05-25 13:49:42.217596 [DEBUG] switch_core_session.c:2905 sofia/default/31652033157@185.249.40.254 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  1426. 2022-05-25 13:49:42.217596 [DEBUG] switch_core_state_machine.c:651 (sofia/default/31652033157@185.249.40.254) State EXECUTE going to sleep
  1427. 2022-05-25 13:49:42.217596 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31652033157@185.249.40.254) Running State Change CS_HANGUP (Cur 10 Tot 24049)
  1428. 2022-05-25 13:49:42.217596 [DEBUG] mod_sofia.c:521 Sending CANCEL to sofia/default/31854832191@134271.yoursipcloud.com:5060
  1429. 2022-05-25 13:49:42.217596 [DEBUG] switch_core_state_machine.c:60 sofia/default/31854832191@134271.yoursipcloud.com:5060 Standard HANGUP, cause: ORIGINATOR_CANCEL
  1430. 2022-05-25 13:49:42.217596 [DEBUG] switch_core_state_machine.c:850 (sofia/default/31854832191@134271.yoursipcloud.com:5060) State HANGUP going to sleep
  1431. 2022-05-25 13:49:42.217596 [DEBUG] switch_core_state_machine.c:848 (sofia/default/31652033157@185.249.40.254) Callstate Change EARLY -> HANGUP
  1432. 2022-05-25 13:49:42.217596 [DEBUG] switch_core_state_machine.c:620 (sofia/default/31854832191@134271.yoursipcloud.com:5060) State Change CS_HANGUP -> CS_REPORTING
  1433. 2022-05-25 13:49:42.217596 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31854832191@134271.yoursipcloud.com:5060) Running State Change CS_REPORTING (Cur 10 Tot 24049)
  1434. 2022-05-25 13:49:42.217596 [DEBUG] switch_core_state_machine.c:850 (sofia/default/31652033157@185.249.40.254) State HANGUP
  1435. 2022-05-25 13:49:42.217596 [DEBUG] mod_sofia.c:453 Channel sofia/default/31652033157@185.249.40.254 hanging up, cause: ORIGINATOR_CANCEL
  1436. 2022-05-25 13:49:42.217596 [DEBUG] switch_core_state_machine.c:936 (sofia/default/31854832191@134271.yoursipcloud.com:5060) State REPORTING
  1437. 2022-05-25 13:49:42.217596 [INFO] mod_json_cdr.c:271 Process [a2366741-e803-478d-a3bb-30b36d6167b2.cdr.json]
  1438. 2022-05-25 13:49:42.217596 [DEBUG] switch_core_state_machine.c:60 sofia/default/31652033157@185.249.40.254 Standard HANGUP, cause: ORIGINATOR_CANCEL
  1439. 2022-05-25 13:49:42.217596 [DEBUG] switch_core_state_machine.c:850 (sofia/default/31652033157@185.249.40.254) State HANGUP going to sleep
  1440. 2022-05-25 13:49:42.217596 [DEBUG] switch_core_state_machine.c:620 (sofia/default/31652033157@185.249.40.254) State Change CS_HANGUP -> CS_REPORTING
  1441. 2022-05-25 13:49:42.217596 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31652033157@185.249.40.254) Running State Change CS_REPORTING (Cur 10 Tot 24049)
  1442. 2022-05-25 13:49:42.217596 [DEBUG] switch_core_state_machine.c:936 (sofia/default/31652033157@185.249.40.254) State REPORTING
  1443. 2022-05-25 13:49:42.217596 [INFO] mod_json_cdr.c:271 Process [2ba34e64-c3c9-4388-8917-5760a947bc3f.cdr.json]
  1444. 2022-05-25 13:49:42.217596 [DEBUG] sofia.c:7326 Channel sofia/default/31652033157@185.249.40.236 entering state [terminated][487]
  1445. 2022-05-25 13:49:42.217596 [NOTICE] sofia.c:8559 Hangup sofia/default/31652033157@185.249.40.236 [CS_EXECUTE] [ORIGINATOR_CANCEL]
  1446. 2022-05-25 13:49:42.217596 [DEBUG] mod_hash.c:296 Usage for CPS_160_CPS_user_160 is now 0
  1447. 2022-05-25 13:49:42.217596 [DEBUG] switch_ivr_bridge.c:826 sofia/default/31652033157@185.249.40.236 ending bridge by request from read function
  1448. 2022-05-25 13:49:42.217596 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/31652033157@185.249.40.236]
  1449. 2022-05-25 13:49:42.217596 [DEBUG] switch_core_state_machine.c:174 sofia/default/31854832191@134271.yoursipcloud.com:5060 Standard REPORTING, cause: ORIGINATOR_CANCEL
  1450. 2022-05-25 13:49:42.217596 [DEBUG] switch_core_state_machine.c:936 (sofia/default/31854832191@134271.yoursipcloud.com:5060) State REPORTING going to sleep
  1451. 2022-05-25 13:49:42.217596 [DEBUG] switch_core_state_machine.c:611 (sofia/default/31854832191@134271.yoursipcloud.com:5060) State Change CS_REPORTING -> CS_DESTROY
  1452. 2022-05-25 13:49:42.217596 [DEBUG] switch_core_session.c:1726 Session 24044 (sofia/default/31854832191@134271.yoursipcloud.com:5060) Locked, Waiting on external entities
  1453. 2022-05-25 13:49:42.217596 [NOTICE] switch_core_session.c:1744 Session 24044 (sofia/default/31854832191@134271.yoursipcloud.com:5060) Ended
  1454. 2022-05-25 13:49:42.217596 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/31854832191@134271.yoursipcloud.com:5060 [CS_DESTROY]
  1455. 2022-05-25 13:49:42.217596 [DEBUG] switch_core_state_machine.c:739 (sofia/default/31854832191@134271.yoursipcloud.com:5060) Running State Change CS_DESTROY (Cur 9 Tot 24049)
  1456. 2022-05-25 13:49:42.217596 [DEBUG] switch_core_state_machine.c:749 (sofia/default/31854832191@134271.yoursipcloud.com:5060) State DESTROY
  1457. 2022-05-25 13:49:42.217596 [DEBUG] mod_sofia.c:364 sofia/default/31854832191@134271.yoursipcloud.com:5060 SOFIA DESTROY
  1458. 2022-05-25 13:49:42.217596 [DEBUG] switch_core_state_machine.c:181 sofia/default/31854832191@134271.yoursipcloud.com:5060 Standard DESTROY
  1459. 2022-05-25 13:49:42.217596 [DEBUG] switch_core_state_machine.c:749 (sofia/default/31854832191@134271.yoursipcloud.com:5060) State DESTROY going to sleep
  1460. 2022-05-25 13:49:42.237591 [DEBUG] switch_ivr_bridge.c:820 sofia/default/31652033157@185.249.40.236 ending bridge by request from write function
  1461. 2022-05-25 13:49:42.237591 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/31854832191]
  1462. 2022-05-25 13:49:42.237591 [NOTICE] switch_ivr_bridge.c:1025 Hangup sofia/default/31854832191 [CS_EXCHANGE_MEDIA] [ORIGINATOR_CANCEL]
  1463. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_state_machine.c:654 (sofia/default/31854832191) State EXCHANGE_MEDIA going to sleep
  1464. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31854832191) Running State Change CS_HANGUP (Cur 9 Tot 24049)
  1465. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_state_machine.c:848 (sofia/default/31854832191) Callstate Change EARLY -> HANGUP
  1466. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_state_machine.c:850 (sofia/default/31854832191) State HANGUP
  1467. 2022-05-25 13:49:42.237591 [DEBUG] mod_sofia.c:447 sofia/default/31854832191 Overriding SIP cause 487 with 487 from the other leg
  1468. 2022-05-25 13:49:42.237591 [DEBUG] mod_sofia.c:453 Channel sofia/default/31854832191 hanging up, cause: ORIGINATOR_CANCEL
  1469. 2022-05-25 13:49:42.237591 [DEBUG] switch_ivr_bridge.c:1892 sofia/default/31854832191 skip receive message [UNBRIDGE] (channel is hungup already)
  1470. 2022-05-25 13:49:42.237591 [DEBUG] switch_ivr_bridge.c:1895 sofia/default/31652033157@185.249.40.236 skip receive message [UNBRIDGE] (channel is hungup already)
  1471. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_session.c:2905 sofia/default/31652033157@185.249.40.236 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  1472. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_state_machine.c:651 (sofia/default/31652033157@185.249.40.236) State EXECUTE going to sleep
  1473. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31652033157@185.249.40.236) Running State Change CS_HANGUP (Cur 9 Tot 24049)
  1474. 2022-05-25 13:49:42.237591 [DEBUG] mod_sofia.c:521 Sending CANCEL to sofia/default/31854832191
  1475. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_state_machine.c:60 sofia/default/31854832191 Standard HANGUP, cause: ORIGINATOR_CANCEL
  1476. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_state_machine.c:850 (sofia/default/31854832191) State HANGUP going to sleep
  1477. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_state_machine.c:848 (sofia/default/31652033157@185.249.40.236) Callstate Change EARLY -> HANGUP
  1478. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_state_machine.c:620 (sofia/default/31854832191) State Change CS_HANGUP -> CS_REPORTING
  1479. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_state_machine.c:850 (sofia/default/31652033157@185.249.40.236) State HANGUP
  1480. 2022-05-25 13:49:42.237591 [DEBUG] mod_sofia.c:453 Channel sofia/default/31652033157@185.249.40.236 hanging up, cause: ORIGINATOR_CANCEL
  1481. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31854832191) Running State Change CS_REPORTING (Cur 9 Tot 24049)
  1482. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_state_machine.c:936 (sofia/default/31854832191) State REPORTING
  1483. 2022-05-25 13:49:42.237591 [INFO] mod_json_cdr.c:271 Process [dec85590-dd9a-4c17-b395-11063cd1d6ea.cdr.json]
  1484. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_state_machine.c:60 sofia/default/31652033157@185.249.40.236 Standard HANGUP, cause: ORIGINATOR_CANCEL
  1485. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_state_machine.c:850 (sofia/default/31652033157@185.249.40.236) State HANGUP going to sleep
  1486. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_state_machine.c:620 (sofia/default/31652033157@185.249.40.236) State Change CS_HANGUP -> CS_REPORTING
  1487. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31652033157@185.249.40.236) Running State Change CS_REPORTING (Cur 9 Tot 24049)
  1488. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_state_machine.c:174 sofia/default/31652033157@185.249.40.254 Standard REPORTING, cause: ORIGINATOR_CANCEL
  1489. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_state_machine.c:936 (sofia/default/31652033157@185.249.40.254) State REPORTING going to sleep
  1490. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_state_machine.c:936 (sofia/default/31652033157@185.249.40.236) State REPORTING
  1491. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_state_machine.c:611 (sofia/default/31652033157@185.249.40.254) State Change CS_REPORTING -> CS_DESTROY
  1492. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_session.c:1726 Session 24043 (sofia/default/31652033157@185.249.40.254) Locked, Waiting on external entities
  1493. 2022-05-25 13:49:42.237591 [NOTICE] switch_core_session.c:1744 Session 24043 (sofia/default/31652033157@185.249.40.254) Ended
  1494. 2022-05-25 13:49:42.237591 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/31652033157@185.249.40.254 [CS_DESTROY]
  1495. 2022-05-25 13:49:42.237591 [INFO] mod_json_cdr.c:271 Process [e6d7cddf-6c38-464e-be0a-2f58684ba3ce.cdr.json]
  1496. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_state_machine.c:739 (sofia/default/31652033157@185.249.40.254) Running State Change CS_DESTROY (Cur 8 Tot 24049)
  1497. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_state_machine.c:749 (sofia/default/31652033157@185.249.40.254) State DESTROY
  1498. 2022-05-25 13:49:42.237591 [DEBUG] mod_sofia.c:364 sofia/default/31652033157@185.249.40.254 SOFIA DESTROY
  1499. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_state_machine.c:181 sofia/default/31652033157@185.249.40.254 Standard DESTROY
  1500. 2022-05-25 13:49:42.237591 [DEBUG] switch_core_state_machine.c:749 (sofia/default/31652033157@185.249.40.254) State DESTROY going to sleep
  1501. 2022-05-25 13:49:42.257592 [DEBUG] switch_core_state_machine.c:174 sofia/default/31854832191 Standard REPORTING, cause: ORIGINATOR_CANCEL
  1502. 2022-05-25 13:49:42.257592 [DEBUG] switch_core_state_machine.c:936 (sofia/default/31854832191) State REPORTING going to sleep
  1503. 2022-05-25 13:49:42.257592 [DEBUG] switch_core_state_machine.c:611 (sofia/default/31854832191) State Change CS_REPORTING -> CS_DESTROY
  1504. 2022-05-25 13:49:42.257592 [DEBUG] switch_core_session.c:1726 Session 24046 (sofia/default/31854832191) Locked, Waiting on external entities
  1505. 2022-05-25 13:49:42.257592 [NOTICE] switch_core_session.c:1744 Session 24046 (sofia/default/31854832191) Ended
  1506. 2022-05-25 13:49:42.257592 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/31854832191 [CS_DESTROY]
  1507. 2022-05-25 13:49:42.257592 [DEBUG] switch_core_state_machine.c:739 (sofia/default/31854832191) Running State Change CS_DESTROY (Cur 7 Tot 24049)
  1508. 2022-05-25 13:49:42.257592 [DEBUG] switch_core_state_machine.c:749 (sofia/default/31854832191) State DESTROY
  1509. 2022-05-25 13:49:42.257592 [DEBUG] mod_sofia.c:364 sofia/default/31854832191 SOFIA DESTROY
  1510. 2022-05-25 13:49:42.257592 [DEBUG] switch_core_state_machine.c:181 sofia/default/31854832191 Standard DESTROY
  1511. 2022-05-25 13:49:42.257592 [DEBUG] switch_core_state_machine.c:749 (sofia/default/31854832191) State DESTROY going to sleep
  1512. 2022-05-25 13:49:42.257592 [DEBUG] switch_core_state_machine.c:174 sofia/default/31652033157@185.249.40.236 Standard REPORTING, cause: ORIGINATOR_CANCEL
  1513. 2022-05-25 13:49:42.257592 [DEBUG] switch_core_state_machine.c:936 (sofia/default/31652033157@185.249.40.236) State REPORTING going to sleep
  1514. 2022-05-25 13:49:42.257592 [DEBUG] switch_core_state_machine.c:611 (sofia/default/31652033157@185.249.40.236) State Change CS_REPORTING -> CS_DESTROY
  1515. 2022-05-25 13:49:42.257592 [DEBUG] switch_core_session.c:1726 Session 24045 (sofia/default/31652033157@185.249.40.236) Locked, Waiting on external entities
  1516. 2022-05-25 13:49:42.257592 [NOTICE] switch_core_session.c:1744 Session 24045 (sofia/default/31652033157@185.249.40.236) Ended
  1517. 2022-05-25 13:49:42.257592 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/31652033157@185.249.40.236 [CS_DESTROY]
  1518. 2022-05-25 13:49:42.257592 [DEBUG] switch_core_state_machine.c:739 (sofia/default/31652033157@185.249.40.236) Running State Change CS_DESTROY (Cur 6 Tot 24049)
  1519. 2022-05-25 13:49:42.257592 [DEBUG] switch_core_state_machine.c:749 (sofia/default/31652033157@185.249.40.236) State DESTROY
  1520. 2022-05-25 13:49:42.257592 [DEBUG] mod_sofia.c:364 sofia/default/31652033157@185.249.40.236 SOFIA DESTROY
  1521. 2022-05-25 13:49:42.257592 [DEBUG] switch_core_state_machine.c:181 sofia/default/31652033157@185.249.40.236 Standard DESTROY
  1522. 2022-05-25 13:49:42.257592 [DEBUG] switch_core_state_machine.c:749 (sofia/default/31652033157@185.249.40.236) State DESTROY going to sleep
  1523. 2022-05-25 13:49:42.297589 [DEBUG] switch_scheduler.c:147 Deleting task 8709 switch_ivr_schedule_hangup (dec85590-dd9a-4c17-b395-11063cd1d6ea)
  1524. 2022-05-25 13:49:42.297589 [DEBUG] switch_scheduler.c:147 Deleting task 8710 switch_ivr_schedule_hangup (a2366741-e803-478d-a3bb-30b36d6167b2)
  1525. 2022-05-25 13:49:45.817619 [NOTICE] sofia.c:1089 Hangup sofia/default/31773893232 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  1526. 2022-05-25 13:49:45.817619 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/31773893232]
  1527. 2022-05-25 13:49:45.817619 [DEBUG] switch_core_state_machine.c:654 (sofia/default/31773893232) State EXCHANGE_MEDIA going to sleep
  1528. 2022-05-25 13:49:45.817619 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31773893232) Running State Change CS_HANGUP (Cur 6 Tot 24049)
  1529. 2022-05-25 13:49:45.817619 [DEBUG] switch_core_state_machine.c:848 (sofia/default/31773893232) Callstate Change ACTIVE -> HANGUP
  1530. 2022-05-25 13:49:45.817619 [DEBUG] switch_core_state_machine.c:850 (sofia/default/31773893232) State HANGUP
  1531. 2022-05-25 13:49:45.817619 [DEBUG] mod_sofia.c:453 Channel sofia/default/31773893232 hanging up, cause: NORMAL_CLEARING
  1532. 2022-05-25 13:49:45.817619 [DEBUG] switch_core_state_machine.c:60 sofia/default/31773893232 Standard HANGUP, cause: NORMAL_CLEARING
  1533. 2022-05-25 13:49:45.817619 [DEBUG] switch_core_state_machine.c:850 (sofia/default/31773893232) State HANGUP going to sleep
  1534. 2022-05-25 13:49:45.817619 [DEBUG] switch_core_state_machine.c:620 (sofia/default/31773893232) State Change CS_HANGUP -> CS_REPORTING
  1535. 2022-05-25 13:49:45.817619 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31773893232) Running State Change CS_REPORTING (Cur 6 Tot 24049)
  1536. 2022-05-25 13:49:45.817619 [DEBUG] switch_core_state_machine.c:936 (sofia/default/31773893232) State REPORTING
  1537. 2022-05-25 13:49:45.817619 [INFO] mod_json_cdr.c:271 Process [13be1f7c-bda8-454c-8e89-79c3fabdc31c.cdr.json]
  1538. 2022-05-25 13:49:45.837607 [DEBUG] switch_ivr_bridge.c:820 sofia/default/31773893232 ending bridge by request from write function
  1539. 2022-05-25 13:49:45.837607 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/788323@89.255.20.14]
  1540. 2022-05-25 13:49:45.837607 [DEBUG] switch_ivr_bridge.c:1892 sofia/default/31773893232 skip receive message [UNBRIDGE] (channel is hungup already)
  1541. 2022-05-25 13:49:45.837607 [NOTICE] switch_ivr_bridge.c:1944 Hangup sofia/default/788323@89.255.20.14 [CS_EXECUTE] [NORMAL_CLEARING]
  1542. 2022-05-25 13:49:45.837607 [DEBUG] mod_hash.c:296 Usage for CPS_510514_CPS_user_510514 is now 1
  1543. 2022-05-25 13:49:45.837607 [DEBUG] switch_core_session.c:2905 sofia/default/788323@89.255.20.14 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  1544. 2022-05-25 13:49:45.837607 [DEBUG] switch_core_state_machine.c:651 (sofia/default/788323@89.255.20.14) State EXECUTE going to sleep
  1545. 2022-05-25 13:49:45.837607 [DEBUG] switch_core_state_machine.c:585 (sofia/default/788323@89.255.20.14) Running State Change CS_HANGUP (Cur 6 Tot 24049)
  1546. 2022-05-25 13:49:45.837607 [DEBUG] switch_core_state_machine.c:848 (sofia/default/788323@89.255.20.14) Callstate Change ACTIVE -> HANGUP
  1547. 2022-05-25 13:49:45.837607 [DEBUG] switch_core_state_machine.c:850 (sofia/default/788323@89.255.20.14) State HANGUP
  1548. 2022-05-25 13:49:45.837607 [DEBUG] mod_sofia.c:447 sofia/default/788323@89.255.20.14 Overriding SIP cause 480 with 200 from the other leg
  1549. 2022-05-25 13:49:45.837607 [DEBUG] mod_sofia.c:453 Channel sofia/default/788323@89.255.20.14 hanging up, cause: NORMAL_CLEARING
  1550. 2022-05-25 13:49:45.837607 [DEBUG] mod_sofia.c:507 Sending BYE to sofia/default/788323@89.255.20.14
  1551. 2022-05-25 13:49:45.837607 [DEBUG] switch_core_state_machine.c:60 sofia/default/788323@89.255.20.14 Standard HANGUP, cause: NORMAL_CLEARING
  1552. 2022-05-25 13:49:45.837607 [DEBUG] switch_core_state_machine.c:850 (sofia/default/788323@89.255.20.14) State HANGUP going to sleep
  1553. 2022-05-25 13:49:45.837607 [DEBUG] switch_core_state_machine.c:620 (sofia/default/788323@89.255.20.14) State Change CS_HANGUP -> CS_REPORTING
  1554. 2022-05-25 13:49:45.837607 [DEBUG] switch_core_state_machine.c:585 (sofia/default/788323@89.255.20.14) Running State Change CS_REPORTING (Cur 6 Tot 24049)
  1555. 2022-05-25 13:49:45.837607 [DEBUG] switch_core_state_machine.c:936 (sofia/default/788323@89.255.20.14) State REPORTING
  1556. 2022-05-25 13:49:45.837607 [INFO] mod_json_cdr.c:271 Process [78be853d-10f6-41f7-80cb-4c771fa1f019.cdr.json]
  1557. 2022-05-25 13:49:45.837607 [DEBUG] switch_core_state_machine.c:174 sofia/default/31773893232 Standard REPORTING, cause: NORMAL_CLEARING
  1558. 2022-05-25 13:49:45.837607 [DEBUG] switch_core_state_machine.c:936 (sofia/default/31773893232) State REPORTING going to sleep
  1559. 2022-05-25 13:49:45.837607 [DEBUG] switch_core_state_machine.c:611 (sofia/default/31773893232) State Change CS_REPORTING -> CS_DESTROY
  1560. 2022-05-25 13:49:45.837607 [DEBUG] switch_core_session.c:1726 Session 24036 (sofia/default/31773893232) Locked, Waiting on external entities
  1561. 2022-05-25 13:49:45.837607 [NOTICE] switch_core_session.c:1744 Session 24036 (sofia/default/31773893232) Ended
  1562. 2022-05-25 13:49:45.837607 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/31773893232 [CS_DESTROY]
  1563. 2022-05-25 13:49:45.837607 [DEBUG] switch_core_state_machine.c:739 (sofia/default/31773893232) Running State Change CS_DESTROY (Cur 5 Tot 24049)
  1564. 2022-05-25 13:49:45.837607 [DEBUG] switch_core_state_machine.c:749 (sofia/default/31773893232) State DESTROY
  1565. 2022-05-25 13:49:45.837607 [DEBUG] mod_sofia.c:364 sofia/default/31773893232 SOFIA DESTROY
  1566. 2022-05-25 13:49:45.837607 [DEBUG] switch_core_state_machine.c:181 sofia/default/31773893232 Standard DESTROY
  1567. 2022-05-25 13:49:45.837607 [DEBUG] switch_core_state_machine.c:749 (sofia/default/31773893232) State DESTROY going to sleep
  1568. 2022-05-25 13:49:45.857588 [DEBUG] switch_core_state_machine.c:174 sofia/default/788323@89.255.20.14 Standard REPORTING, cause: NORMAL_CLEARING
  1569. 2022-05-25 13:49:45.857588 [DEBUG] switch_core_state_machine.c:936 (sofia/default/788323@89.255.20.14) State REPORTING going to sleep
  1570. 2022-05-25 13:49:45.857588 [DEBUG] switch_core_state_machine.c:611 (sofia/default/788323@89.255.20.14) State Change CS_REPORTING -> CS_DESTROY
  1571. 2022-05-25 13:49:45.857588 [DEBUG] switch_core_session.c:1726 Session 24035 (sofia/default/788323@89.255.20.14) Locked, Waiting on external entities
  1572. 2022-05-25 13:49:45.857588 [NOTICE] switch_core_session.c:1744 Session 24035 (sofia/default/788323@89.255.20.14) Ended
  1573. 2022-05-25 13:49:45.857588 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/788323@89.255.20.14 [CS_DESTROY]
  1574. 2022-05-25 13:49:45.857588 [DEBUG] switch_core_state_machine.c:739 (sofia/default/788323@89.255.20.14) Running State Change CS_DESTROY (Cur 4 Tot 24049)
  1575. 2022-05-25 13:49:45.857588 [DEBUG] switch_core_state_machine.c:749 (sofia/default/788323@89.255.20.14) State DESTROY
  1576. 2022-05-25 13:49:45.857588 [DEBUG] mod_sofia.c:364 sofia/default/788323@89.255.20.14 SOFIA DESTROY
  1577. 2022-05-25 13:49:45.857588 [DEBUG] switch_core_state_machine.c:181 sofia/default/788323@89.255.20.14 Standard DESTROY
  1578. 2022-05-25 13:49:45.857588 [DEBUG] switch_core_state_machine.c:749 (sofia/default/788323@89.255.20.14) State DESTROY going to sleep
  1579. 2022-05-25 13:49:46.297635 [DEBUG] switch_scheduler.c:147 Deleting task 8706 switch_ivr_schedule_hangup (13be1f7c-bda8-454c-8e89-79c3fabdc31c)
  1580. 2022-05-25 13:49:49.417644 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [101@262239.yoursipcloud.com] from ip 86.93.235.249
  1581. freeswitch@multitenant-1> /exit
  1582.  

Reply to "we"