From Prashant Kumar, 1 Year ago, written in FreeSWITCH.
- view diff
Embed
  1.  
  2. Type /help <enter> to see a list of commands
  3.  
  4.  
  5.  
  6. +OK log level  [7]
  7. 2023-08-09 05:59:43.448047 95.53% [NOTICE] switch_channel.c:1123 New Channel sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 [95795f0b-2afa-413e-aeb4-f59b6fc8f115]
  8. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) Running State Change CS_NEW (Cur 1 Tot 7241)
  9. 2023-08-09 05:59:43.448047 95.53% [INFO] sofia.c:10462 sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 receiving invite from 78.47.101.152:5073 version: 1.10.7 -release 64bit call-id: ZDc4Y2UzMGQwYmEyZjUzZWY4YzgwMjQwMjk2YWE1Y2U.
  10. 2023-08-09 05:59:43.448047 95.53% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 78.47.101.152:0.
  11. 2023-08-09 05:59:43.448047 95.53% [DEBUG] sofia.c:10585 IP 78.47.101.152 Approved by acl "default[]". Access Granted.
  12. 2023-08-09 05:59:43.448047 95.53% [DEBUG] sofia.c:7499 Channel sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 entering state [received][100]
  13. 2023-08-09 05:59:43.448047 95.53% [DEBUG] sofia.c:7509 Remote SDP:
  14. v=0
  15. o=Z 0 0 IN IP4 103.240.35.46
  16. s=Z
  17. c=IN IP4 103.240.35.46
  18. t=0 0
  19. m=audio 8000 RTP/AVP 3 110 8 0 98 101
  20. a=rtpmap:110 speex/8000
  21. a=rtpmap:98 iLBC/8000
  22. a=fmtp:98 mode=20
  23. a=rtpmap:101 telephone-event/8000
  24. a=fmtp:101 0-15
  25.  
  26. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  27. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  28. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  29. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  30. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  31. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  32. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  33. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  34. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  35. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  36. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
  37. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
  38. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  39. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  40. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_codec.c:111 sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 Original read codec set to PCMA:8
  41. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  42. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_media.c:5973 sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 Set 2833 dtmf send payload to 101 recv payload to 101
  43. 2023-08-09 05:59:43.448047 95.53% [DEBUG] sofia.c:7933 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) State Change CS_NEW -> CS_INIT
  44. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_state_machine.c:600 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) State NEW
  45. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) Running State Change CS_INIT (Cur 1 Tot 7241)
  46. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_state_machine.c:624 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) State INIT
  47. 2023-08-09 05:59:43.448047 95.53% [DEBUG] mod_sofia.c:97 sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 SOFIA INIT
  48. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_state_machine.c:40 sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 Standard INIT
  49. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_state_machine.c:48 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) State Change CS_INIT -> CS_ROUTING
  50. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_state_machine.c:624 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) State INIT going to sleep
  51. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) Running State Change CS_ROUTING (Cur 1 Tot 7241)
  52. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_channel.c:2380 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) Callstate Change DOWN -> RINGING
  53. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_state_machine.c:640 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) State ROUTING
  54. 2023-08-09 05:59:43.448047 95.53% [DEBUG] mod_sofia.c:158 sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 SOFIA ROUTING
  55. 2023-08-09 05:59:43.448047 95.53% [DEBUG] switch_core_state_machine.c:230 sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 Standard ROUTING
  56. 2023-08-09 05:59:43.448047 95.53% [INFO] mod_dialplan_xml.c:639 Processing 8800719333 <8800719333>->79100 in context default
  57. 2023-08-09 05:59:43.448047 95.53% [INFO] switch_cpp.cpp:1465 [ASTPP] CACHE FAIL
  58. 2023-08-09 05:59:43.487924 95.53% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  59. 2023-08-09 05:59:43.487924 95.53% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','international_prefixes','interconnect_code')
  60. 2023-08-09 05:59:43.487924 95.53% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  61. 2023-08-09 05:59:43.487924 95.53% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
  62. 2023-08-09 05:59:43.487924 95.53% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 79100
  63. 2023-08-09 05:59:43.487924 95.53% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '79100' limit 1
  64. 2023-08-09 05:59:43.507961 95.53% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '79100' AND status=0 limit 1
  65. 2023-08-09 05:59:43.507961 95.53% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Generated XML:
  66. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  67. <document type="freeswitch/xml">
  68. <section name="dialplan" description="ASTPP Dialplan">
  69. <context name="default">
  70. <extension name="79100">
  71. <condition field="destination_number" expression="79100">
  72. <action application="log" data="INFO ASTPP - Calling Card Call"/>
  73. <action application="set" data="caller_id_name=8800719333"/>
  74. <action application="set" data="caller_id_number=8800719333"/>
  75. <action application="answer"/>
  76. <action application="sleep" data="2000"/>
  77. <action application="lua" data="astpp-callingcards.lua"/>
  78. </condition>
  79. </extension>
  80. </context>
  81. </section>
  82. </document>
  83. Dialplan: sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 parsing [default->79100] continue=false
  84. Dialplan: sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 Regex (PASS) [79100] destination_number(79100) =~ /79100/ break=on-false
  85. Dialplan: sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 Action log(INFO ASTPP - Calling Card Call)
  86. Dialplan: sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 Action set(caller_id_name=8800719333)
  87. Dialplan: sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 Action set(caller_id_number=8800719333)
  88. Dialplan: sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 Action answer()
  89. Dialplan: sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 Action sleep(2000)
  90. Dialplan: sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 Action lua(astpp-callingcards.lua)
  91. 2023-08-09 05:59:43.507961 95.53% [DEBUG] switch_core_state_machine.c:281 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) State Change CS_ROUTING -> CS_EXECUTE
  92. 2023-08-09 05:59:43.507961 95.53% [DEBUG] switch_core_state_machine.c:640 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) State ROUTING going to sleep
  93. 2023-08-09 05:59:43.507961 95.53% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) Running State Change CS_EXECUTE (Cur 1 Tot 7241)
  94. 2023-08-09 05:59:43.507961 95.53% [DEBUG] switch_core_state_machine.c:647 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) State EXECUTE
  95. 2023-08-09 05:59:43.507961 95.53% [DEBUG] mod_sofia.c:213 sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 SOFIA EXECUTE
  96. 2023-08-09 05:59:43.507961 95.53% [DEBUG] switch_core_state_machine.c:323 sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 Standard EXECUTE
  97. EXECUTE [depth=0] sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 log(INFO ASTPP - Calling Card Call)
  98. 2023-08-09 05:59:43.507961 95.53% [INFO] mod_dptools.c:1879 ASTPP - Calling Card Call
  99. EXECUTE [depth=0] sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 set(caller_id_name=8800719333)
  100. 2023-08-09 05:59:43.507961 95.53% [DEBUG] mod_dptools.c:1685 SET sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 [caller_id_name]=[8800719333]
  101. EXECUTE [depth=0] sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 set(caller_id_number=8800719333)
  102. 2023-08-09 05:59:43.507961 95.53% [DEBUG] mod_dptools.c:1685 SET sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 [caller_id_number]=[8800719333]
  103. EXECUTE [depth=0] sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 answer()
  104. 2023-08-09 05:59:43.507961 95.53% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073] 167.235.194.5 port 26150 -> 103.240.35.46 port 8000 codec: 8 ms: 20
  105. 2023-08-09 05:59:43.507961 95.53% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms
  106. 2023-08-09 05:59:43.507961 95.53% [DEBUG] switch_core_media.c:9089 sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 Set 2833 dtmf send payload to 101
  107. 2023-08-09 05:59:43.507961 95.53% [DEBUG] switch_core_media.c:9096 sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 Set 2833 dtmf receive payload to 101
  108. 2023-08-09 05:59:43.507961 95.53% [DEBUG] switch_core_media.c:9119 sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 Set rtp dtmf delay to 40
  109. 2023-08-09 05:59:43.507961 95.53% [DEBUG] mod_sofia.c:913 Local SDP sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073:
  110. v=0
  111. o=FreeSWITCH 1691534633 1691534634 IN IP4 167.235.194.5
  112. s=FreeSWITCH
  113. c=IN IP4 167.235.194.5
  114. t=0 0
  115. m=audio 26150 RTP/AVP 8 101
  116. a=rtpmap:8 PCMA/8000
  117. a=rtpmap:101 telephone-event/8000
  118. a=fmtp:101 0-15
  119. a=ptime:20
  120. a=sendrecv
  121.  
  122. 2023-08-09 05:59:43.507961 95.53% [NOTICE] mod_dptools.c:1419 Channel [sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073] has been answered
  123. 2023-08-09 05:59:43.507961 95.53% [DEBUG] switch_channel.c:3950 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) Callstate Change RINGING -> ACTIVE
  124. 2023-08-09 05:59:43.507961 95.53% [DEBUG] sofia.c:7499 Channel sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 entering state [completed][200]
  125. EXECUTE [depth=0] sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 sleep(2000)
  126. 2023-08-09 05:59:44.307972 95.53% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed.
  127. 2023-08-09 05:59:44.307972 95.53% [DEBUG] sofia.c:7499 Channel sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 entering state [ready][200]
  128. EXECUTE [depth=0] sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 lua(astpp-callingcards.lua)
  129. 2023-08-09 05:59:45.527956 95.53% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  130. 2023-08-09 05:59:45.527956 95.53% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','international_prefixes','interconnect_code')
  131. 2023-08-09 05:59:45.527956 95.53% [NOTICE] switch_cpp.cpp:1465 [ASTPP] SECTION
  132. 2023-08-09 05:59:45.547937 95.53% [DEBUG] switch_core_file.c:444 File /var/www/html/astpp/upload/astpp-welcome.wav sample rate 16000 doesn't match requested rate 8000
  133. 2023-08-09 05:59:45.547937 95.53% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
  134. 2023-08-09 05:59:47.327967 95.50% [DEBUG] switch_ivr_play_say.c:2001 done playing file /var/www/html/astpp/upload/astpp-welcome.wav
  135. 2023-08-09 05:59:47.327967 95.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_ani] Query :SELECT * FROM ani_map WHERE number = 8800719333
  136. 2023-08-09 05:59:47.327967 95.50% [DEBUG] switch_core_file.c:444 File /usr/local/freeswitch/sounds/en/us/callie/astpp-accountnum.wav sample rate 16000 doesn't match requested rate 8000
  137. 2023-08-09 05:59:47.327967 95.50% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
  138. 2023-08-09 05:59:50.328067 95.50% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-accountnum.wav
  139. 2023-08-09 05:59:53.147971 95.30% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 8:960
  140. 2023-08-09 05:59:53.147971 95.30% [INFO] switch_channel.c:527 RECV DTMF 8:960
  141. 2023-08-09 05:59:53.348027 95.30% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 8:960
  142. 2023-08-09 05:59:53.348027 95.30% [INFO] switch_channel.c:527 RECV DTMF 8:960
  143. 2023-08-09 05:59:54.888017 95.27% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 0:960
  144. 2023-08-09 05:59:54.888017 95.27% [INFO] switch_channel.c:527 RECV DTMF 0:960
  145. 2023-08-09 05:59:55.027992 95.27% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 0:960
  146. 2023-08-09 05:59:55.027992 95.27% [INFO] switch_channel.c:527 RECV DTMF 0:960
  147. 2023-08-09 05:59:56.548012 95.47% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 7:960
  148. 2023-08-09 05:59:56.548012 95.47% [INFO] switch_channel.c:527 RECV DTMF 7:960
  149. 2023-08-09 05:59:57.248018 95.47% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 1:960
  150. 2023-08-09 05:59:57.248018 95.47% [INFO] switch_channel.c:527 RECV DTMF 1:960
  151. 2023-08-09 06:00:00.267993 95.33% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 9:960
  152. 2023-08-09 06:00:00.267993 95.33% [INFO] switch_channel.c:527 RECV DTMF 9:960
  153. 2023-08-09 06:00:01.847934 95.43% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 3:960
  154. 2023-08-09 06:00:01.847934 95.43% [INFO] switch_channel.c:527 RECV DTMF 3:960
  155. 2023-08-09 06:00:02.067963 95.43% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 3:960
  156. 2023-08-09 06:00:02.067963 95.43% [INFO] switch_channel.c:527 RECV DTMF 3:960
  157. 2023-08-09 06:00:02.708019 93.63% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 3:960
  158. 2023-08-09 06:00:02.708019 93.63% [INFO] switch_channel.c:527 RECV DTMF 3:960
  159. 2023-08-09 06:00:03.747934 92.13% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF #:960
  160. span style="color: green; font-weight: bold;"> 2023-08-09 06:00:03.747934 92.13% [INFO] switch_channel.c:527 RECV DTMF #:960
  161. span style="color: goldenrod; font-weight: bold;"> 2023-08-09 06:00:03.747934 92.13% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Got DTMF digits: 8800719333
  162. 2023-08-09 06:00:03.747934 92.13% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT *,(select currencyrate from currency where id=currency_id) as currencyrate FROM accounts WHERE number = "8800719333" AND status=0 AND deleted=0 limit 1
  163. 2023-08-09 06:00:03.767930 92.13% [DEBUG] switch_core_file.c:444 File /usr/local/freeswitch/sounds/en/us/callie/astpp-pleasepin.wav sample rate 16000 doesn't match requested rate 8000
  164. 2023-08-09 06:00:03.767930 92.13% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
  165. 2023-08-09 06:00:06.448015 87.40% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-pleasepin.wav
  166. 2023-08-09 06:00:07.407930 87.40% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 5:960
  167. 2023-08-09 06:00:07.407930 87.40% [INFO] switch_channel.c:527 RECV DTMF 5:960
  168. 2023-08-09 06:00:08.308844 86.20% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 5:960
  169. 2023-08-09 06:00:08.308844 86.20% [INFO] switch_channel.c:527 RECV DTMF 5:960
  170. 2023-08-09 06:00:09.887993 86.20% [NOTICE] switch_channel.c:1123 New Channel sofia/default/7716@167.235.194.5 [d0cfadd4-5a8a-4476-b120-4730dd2e131a]
  171. 2023-08-09 06:00:09.887993 86.20% [DEBUG] switch_core_state_machine.c:581 (sofia/default/7716@167.235.194.5) Running State Change CS_NEW (Cur 2 Tot 7242)
  172. 2023-08-09 06:00:09.887993 86.20% [INFO] sofia.c:10462 sofia/default/7716@167.235.194.5 receiving invite from 185.243.5.18:55997 version: 1.10.7 -release 64bit call-id: 1103109669-713099213-308461902
  173. 2023-08-09 06:00:09.887993 86.20% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 185.243.5.18:0.
  174. 2023-08-09 06:00:09.887993 86.20% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [011441519471756@167.235.194.5] from ip 185.243.5.18
  175. 2023-08-09 06:00:09.887993 86.20% [DEBUG] switch_core_state_machine.c:600 (sofia/default/7716@167.235.194.5) State NEW
  176. 2023-08-09 06:00:09.887993 86.20% [DEBUG] sofia.c:2419 detaching session d0cfadd4-5a8a-4476-b120-4730dd2e131a
  177. 2023-08-09 06:00:09.927983 86.20% [DEBUG] sofia.c:2532 Re-attaching to session d0cfadd4-5a8a-4476-b120-4730dd2e131a
  178. 2023-08-09 06:00:09.927983 86.20% [INFO] sofia.c:10462 sofia/default/7716@167.235.194.5 receiving invite from 185.243.5.18:55997 version: 1.10.7 -release 64bit call-id: 1103109669-713099213-308461902
  179. 2023-08-09 06:00:09.927983 86.20% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 185.243.5.18:0.
  180. 2023-08-09 06:00:09.947957 86.20% [WARNING] sofia_reg.c:3223 Can't find user [7716@167.235.194.5] from 185.243.5.18
  181. You must define a domain called '167.235.194.5' in your directory and add a user with the id="7716" attribute
  182. and you must configure your device to use the proper domain in its authentication credentials.
  183. 2023-08-09 06:00:09.947957 86.20% [WARNING] sofia_reg.c:1806 SIP auth failure (INVITE) on sofia profile 'default' for [011441519471756@167.235.194.5] from ip 185.243.5.18
  184. 2023-08-09 06:00:09.947957 86.20% [NOTICE] sofia.c:2417 Hangup sofia/default/7716@167.235.194.5 [CS_NEW] [CALL_REJECTED]
  185. 2023-08-09 06:00:09.968008 86.20% [DEBUG] sofia.c:1527 Channel is already hungup.
  186. 2023-08-09 06:00:09.968008 86.20% [DEBUG] switch_core_state_machine.c:581 (sofia/default/7716@167.235.194.5) Running State Change CS_HANGUP (Cur 2 Tot 7242)
  187. 2023-08-09 06:00:09.968008 86.20% [DEBUG] switch_core_state_machine.c:844 (sofia/default/7716@167.235.194.5) Callstate Change DOWN -> HANGUP
  188. 2023-08-09 06:00:09.968008 86.20% [DEBUG] switch_core_state_machine.c:846 (sofia/default/7716@167.235.194.5) State HANGUP
  189. 2023-08-09 06:00:09.968008 86.20% [DEBUG] mod_sofia.c:468 Channel sofia/default/7716@167.235.194.5 hanging up, cause: CALL_REJECTED
  190. 2023-08-09 06:00:09.968008 86.20% [DEBUG] switch_core_state_machine.c:59 sofia/default/7716@167.235.194.5 Standard HANGUP, cause: CALL_REJECTED
  191. 2023-08-09 06:00:09.968008 86.20% [DEBUG] switch_core_state_machine.c:846 (sofia/default/7716@167.235.194.5) State HANGUP going to sleep
  192. 2023-08-09 06:00:09.968008 86.20% [DEBUG] switch_core_state_machine.c:616 (sofia/default/7716@167.235.194.5) State Change CS_HANGUP -> CS_REPORTING
  193. 2023-08-09 06:00:09.968008 86.20% [DEBUG] switch_core_state_machine.c:581 (sofia/default/7716@167.235.194.5) Running State Change CS_REPORTING (Cur 2 Tot 7242)
  194. 2023-08-09 06:00:09.968008 86.20% [DEBUG] switch_core_state_machine.c:932 (sofia/default/7716@167.235.194.5) State REPORTING
  195. 2023-08-09 06:00:09.968008 86.20% [DEBUG] switch_core_state_machine.c:168 sofia/default/7716@167.235.194.5 Standard REPORTING, cause: CALL_REJECTED
  196. 2023-08-09 06:00:09.968008 86.20% [DEBUG] switch_core_state_machine.c:932 (sofia/default/7716@167.235.194.5) State REPORTING going to sleep
  197. 2023-08-09 06:00:09.968008 86.20% [DEBUG] switch_core_state_machine.c:607 (sofia/default/7716@167.235.194.5) State Change CS_REPORTING -> CS_DESTROY
  198. 2023-08-09 06:00:09.968008 86.20% [DEBUG] switch_core_session.c:1753 Session 7242 (sofia/default/7716@167.235.194.5) Locked, Waiting on external entities
  199. 2023-08-09 06:00:09.968008 86.20% [NOTICE] switch_core_session.c:1771 Session 7242 (sofia/default/7716@167.235.194.5) Ended
  200. 2023-08-09 06:00:09.968008 86.20% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/7716@167.235.194.5 [CS_DESTROY]
  201. 2023-08-09 06:00:09.968008 86.20% [DEBUG] switch_core_state_machine.c:735 (sofia/default/7716@167.235.194.5) Running State Change CS_DESTROY (Cur 1 Tot 7242)
  202. 2023-08-09 06:00:09.968008 86.20% [DEBUG] switch_core_state_machine.c:745 (sofia/default/7716@167.235.194.5) State DESTROY
  203. 2023-08-09 06:00:09.968008 86.20% [DEBUG] mod_sofia.c:379 sofia/default/7716@167.235.194.5 SOFIA DESTROY
  204. 2023-08-09 06:00:09.968008 86.20% [DEBUG] switch_core_state_machine.c:175 sofia/default/7716@167.235.194.5 Standard DESTROY
  205. 2023-08-09 06:00:09.968008 86.20% [DEBUG] switch_core_state_machine.c:745 (sofia/default/7716@167.235.194.5) State DESTROY going to sleep
  206. 2023-08-09 06:00:10.348057 86.20% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 4:960
  207. 2023-08-09 06:00:10.348057 86.20% [INFO] switch_channel.c:527 RECV DTMF 4:960
  208. 2023-08-09 06:00:10.967985 86.13% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 9:960
  209. 2023-08-09 06:00:10.967985 86.13% [INFO] switch_channel.c:527 RECV DTMF 9:960
  210. 2023-08-09 06:00:11.567996 86.10% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 2:960
  211. 2023-08-09 06:00:11.567996 86.10% [INFO] switch_channel.c:527 RECV DTMF 2:960
  212. 2023-08-09 06:00:11.787988 86.10% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 4:960
  213. 2023-08-09 06:00:11.787988 86.10% [INFO] switch_channel.c:527 RECV DTMF 4:960
  214. 2023-08-09 06:00:12.907989 87.07% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF #:960
  215. span style="color: green; font-weight: bold;"> 2023-08-09 06:00:12.907989 87.07% [INFO] switch_channel.c:527 RECV DTMF #:960
  216. span style="color: goldenrod; font-weight: bold;"> 2023-08-09 06:00:12.907989 87.07% [DEBUG] switch_cpp.cpp:1465 [ASTPP] We Recieved A Pin Number : 554924
  217. 2023-08-09 06:00:12.907989 87.07% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [validate_card_usage] Query :SELECT DATE_FORMAT('2033-07-25 14:53:16' , '%Y%m%d%H%i%s') AS expiry
  218. 2023-08-09 06:00:12.907989 87.07% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [validate_card_usage] Query :SELECT DATE_FORMAT('2023-08-09 06:00:12' , '%Y%m%d%H%i%s') AS expiry
  219. 2023-08-09 06:00:12.907989 87.07% [DEBUG] switch_core_file.c:444 File /usr/local/freeswitch/sounds/en/us/callie/astpp-register.wav sample rate 16000 doesn't match requested rate 8000
  220. 2023-08-09 06:00:12.907989 87.07% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
  221. 2023-08-09 06:00:15.747952 87.37% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-register.wav
  222. 2023-08-09 06:00:20.767966 87.50% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 8800719333]
  223. 2023-08-09 06:00:20.767966 87.50% [DEBUG] switch_core_file.c:444 File /usr/local/freeswitch/sounds/en/us/callie/astpp-this-card-has-a-balance-of.wav sample rate 16000 doesn't match requested rate 8000
  224. 2023-08-09 06:00:20.767966 87.50% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
  225. 2023-08-09 06:00:22.068000 87.50% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-this-card-has-a-balance-of.wav
  226. 2023-08-09 06:00:22.068000 87.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Play Amount First Part] Query :48572
  227. 2023-08-09 06:00:22.068000 87.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Play Amount Second Part] Query :36
  228. EXECUTE [depth=0] sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 say(en number pronounced 48572)
  229. 2023-08-09 06:00:22.107949 87.50% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
  230. 2023-08-09 06:00:25.547952 87.53% [DEBUG] switch_ivr_play_say.c:2001 done playing file file_string://digits/40.wav!digits/8.wav!digits/thousand.wav!digits/5.wav!digits/hundred.wav!digits/70.wav!digits/2.wav
  231. 2023-08-09 06:00:25.547952 87.53% [DEBUG] switch_core_file.c:444 File /usr/local/freeswitch/sounds/en/us/callie/astpp-point.wav sample rate 16000 doesn't match requested rate 8000
  232. 2023-08-09 06:00:25.547952 87.53% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
  233. 2023-08-09 06:00:26.007935 87.53% [NOTICE] switch_channel.c:1123 New Channel sofia/default/4355@167.235.194.5 [cda6eff7-a054-483c-bdd1-69bc6971f308]
  234. 2023-08-09 06:00:26.007935 87.53% [DEBUG] switch_core_state_machine.c:581 (sofia/default/4355@167.235.194.5) Running State Change CS_NEW (Cur 2 Tot 7243)
  235. 2023-08-09 06:00:26.007935 87.53% [INFO] sofia.c:10462 sofia/default/4355@167.235.194.5 receiving invite from 185.243.5.18:55885 version: 1.10.7 -release 64bit call-id: 1786176098-1200189903-1864547822
  236. 2023-08-09 06:00:26.007935 87.53% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 185.243.5.18:0.
  237. 2023-08-09 06:00:26.007935 87.53% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [00441519471756@167.235.194.5] from ip 185.243.5.18
  238. 2023-08-09 06:00:26.007935 87.53% [DEBUG] switch_core_state_machine.c:600 (sofia/default/4355@167.235.194.5) State NEW
  239. 2023-08-09 06:00:26.007935 87.53% [DEBUG] sofia.c:2419 detaching session cda6eff7-a054-483c-bdd1-69bc6971f308
  240. 2023-08-09 06:00:26.067959 87.53% [DEBUG] sofia.c:2532 Re-attaching to session cda6eff7-a054-483c-bdd1-69bc6971f308
  241. 2023-08-09 06:00:26.087961 87.53% [INFO] sofia.c:10462 sofia/default/4355@167.235.194.5 receiving invite from 185.243.5.18:55885 version: 1.10.7 -release 64bit call-id: 1786176098-1200189903-1864547822
  242. 2023-08-09 06:00:26.087961 87.53% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 185.243.5.18:0.
  243. 2023-08-09 06:00:26.107968 87.53% [WARNING] sofia_reg.c:3223 Can't find user [4355@167.235.194.5] from 185.243.5.18
  244. You must define a domain called '167.235.194.5' in your directory and add a user with the id="4355" attribute
  245. and you must configure your device to use the proper domain in its authentication credentials.
  246. 2023-08-09 06:00:26.107968 87.53% [WARNING] sofia_reg.c:1806 SIP auth failure (INVITE) on sofia profile 'default' for [00441519471756@167.235.194.5] from ip 185.243.5.18
  247. 2023-08-09 06:00:26.107968 87.53% [NOTICE] sofia.c:2417 Hangup sofia/default/4355@167.235.194.5 [CS_NEW] [CALL_REJECTED]
  248. 2023-08-09 06:00:26.107968 87.53% [DEBUG] sofia.c:1527 Channel is already hungup.
  249. 2023-08-09 06:00:26.107968 87.53% [DEBUG] sofia.c:1527 Channel is already hungup.
  250. 2023-08-09 06:00:26.107968 87.53% [DEBUG] switch_core_state_machine.c:581 (sofia/default/4355@167.235.194.5) Running State Change CS_HANGUP (Cur 2 Tot 7243)
  251. 2023-08-09 06:00:26.107968 87.53% [DEBUG] switch_core_state_machine.c:844 (sofia/default/4355@167.235.194.5) Callstate Change DOWN -> HANGUP
  252. 2023-08-09 06:00:26.107968 87.53% [DEBUG] switch_core_state_machine.c:846 (sofia/default/4355@167.235.194.5) State HANGUP
  253. 2023-08-09 06:00:26.107968 87.53% [DEBUG] mod_sofia.c:468 Channel sofia/default/4355@167.235.194.5 hanging up, cause: CALL_REJECTED
  254. 2023-08-09 06:00:26.107968 87.53% [DEBUG] switch_core_state_machine.c:59 sofia/default/4355@167.235.194.5 Standard HANGUP, cause: CALL_REJECTED
  255. 2023-08-09 06:00:26.107968 87.53% [DEBUG] switch_core_state_machine.c:846 (sofia/default/4355@167.235.194.5) State HANGUP going to sleep
  256. 2023-08-09 06:00:26.107968 87.53% [DEBUG] switch_core_state_machine.c:616 (sofia/default/4355@167.235.194.5) State Change CS_HANGUP -> CS_REPORTING
  257. 2023-08-09 06:00:26.107968 87.53% [DEBUG] switch_core_state_machine.c:581 (sofia/default/4355@167.235.194.5) Running State Change CS_REPORTING (Cur 2 Tot 7243)
  258. 2023-08-09 06:00:26.107968 87.53% [DEBUG] switch_core_state_machine.c:932 (sofia/default/4355@167.235.194.5) State REPORTING
  259. 2023-08-09 06:00:26.107968 87.53% [DEBUG] switch_core_state_machine.c:168 sofia/default/4355@167.235.194.5 Standard REPORTING, cause: CALL_REJECTED
  260. 2023-08-09 06:00:26.107968 87.53% [DEBUG] switch_core_state_machine.c:932 (sofia/default/4355@167.235.194.5) State REPORTING going to sleep
  261. 2023-08-09 06:00:26.107968 87.53% [DEBUG] switch_core_state_machine.c:607 (sofia/default/4355@167.235.194.5) State Change CS_REPORTING -> CS_DESTROY
  262. 2023-08-09 06:00:26.107968 87.53% [DEBUG] switch_core_session.c:1753 Session 7243 (sofia/default/4355@167.235.194.5) Locked, Waiting on external entities
  263. 2023-08-09 06:00:26.107968 87.53% [NOTICE] switch_core_session.c:1771 Session 7243 (sofia/default/4355@167.235.194.5) Ended
  264. 2023-08-09 06:00:26.107968 87.53% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/4355@167.235.194.5 [CS_DESTROY]
  265. 2023-08-09 06:00:26.107968 87.53% [DEBUG] switch_core_state_machine.c:735 (sofia/default/4355@167.235.194.5) Running State Change CS_DESTROY (Cur 1 Tot 7243)
  266. 2023-08-09 06:00:26.107968 87.53% [DEBUG] switch_core_state_machine.c:745 (sofia/default/4355@167.235.194.5) State DESTROY
  267. 2023-08-09 06:00:26.107968 87.53% [DEBUG] mod_sofia.c:379 sofia/default/4355@167.235.194.5 SOFIA DESTROY
  268. 2023-08-09 06:00:26.107968 87.53% [DEBUG] switch_core_state_machine.c:175 sofia/default/4355@167.235.194.5 Standard DESTROY
  269. 2023-08-09 06:00:26.107968 87.53% [DEBUG] switch_core_state_machine.c:745 (sofia/default/4355@167.235.194.5) State DESTROY going to sleep
  270. 2023-08-09 06:00:26.107968 87.53% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-point.wav
  271. EXECUTE [depth=0] sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 say(en number pronounced 36)
  272. 2023-08-09 06:00:26.127932 87.53% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
  273. 2023-08-09 06:00:27.287989 87.33% [DEBUG] switch_ivr_play_say.c:2001 done playing file file_string://digits/30.wav!digits/6.wav
  274. 2023-08-09 06:00:27.287989 87.33% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [HP:access_number_call_flow[0:Go Direct Dial,1:Go IVR] : 0]
  275. 2023-08-09 06:00:27.287989 87.33% [DEBUG] switch_core_file.c:444 File /usr/local/freeswitch/sounds/en/us/callie/astpp-phonenum.wav sample rate 16000 doesn't match requested rate 8000
  276. 2023-08-09 06:00:27.287989 87.33% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
  277. 2023-08-09 06:00:31.007980 87.47% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-phonenum.wav
  278. 2023-08-09 06:00:32.847941 89.23% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 1:960
  279. 2023-08-09 06:00:32.847941 89.23% [INFO] switch_channel.c:527 RECV DTMF 1:960
  280. 2023-08-09 06:00:33.308179 89.23% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 0:960
  281. 2023-08-09 06:00:33.308179 89.23% [INFO] switch_channel.c:527 RECV DTMF 0:960
  282. 2023-08-09 06:00:33.647964 90.73% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 7:960
  283. 2023-08-09 06:00:33.647964 90.73% [INFO] switch_channel.c:527 RECV DTMF 7:960
  284. 2023-08-09 06:00:33.948003 90.73% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 4:960
  285. 2023-08-09 06:00:33.948003 90.73% [INFO] switch_channel.c:527 RECV DTMF 4:960
  286. 2023-08-09 06:00:34.087964 90.73% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 5:960
  287. 2023-08-09 06:00:34.087964 90.73% [INFO] switch_channel.c:527 RECV DTMF 5:960
  288. 2023-08-09 06:00:34.229230 90.73% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 4:960
  289. 2023-08-09 06:00:34.229230 90.73% [INFO] switch_channel.c:527 RECV DTMF 4:960
  290. 2023-08-09 06:00:34.448185 92.27% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 4:960
  291. 2023-08-09 06:00:34.448185 92.27% [INFO] switch_channel.c:527 RECV DTMF 4:960
  292. 2023-08-09 06:00:34.568013 92.27% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 5:960
  293. 2023-08-09 06:00:34.568013 92.27% [INFO] switch_channel.c:527 RECV DTMF 5:960
  294. 2023-08-09 06:00:35.769435 93.83% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF #:960
  295. span style="color: green; font-weight: bold;"> 2023-08-09 06:00:35.769435 93.83% [INFO] switch_channel.c:527 RECV DTMF #:960
  296. span style="color: green; font-weight: bold;"> 2023-08-09 06:00:35.769435 93.83% [INFO] switch_cpp.cpp:1465 [ASTPP] [CHECK Destination] Dialed Destination Number :10745445
  297. 2023-08-09 06:00:35.769435 93.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type,accountid FROM call_barring WHERE ((number = '10745445' OR number = '1074544' OR number = '107454' OR number = '10745' OR number = '1074' OR number = '107' OR number = '10' OR number = '1' OR number ='--') OR (number = '10745445*' OR number = '1074544*' OR number = '107454*' OR number = '10745*' OR number = '1074*' OR number = '107*' OR number = '10*' OR number = '1*' OR number ='--') OR (number = '8800719333' OR number = '880071933' OR number = '88007193' OR number = '8800719' OR number = '880071' OR number = '88007' OR number = '8800' OR number = '880' OR number = '88' OR number = '8' OR number ='--') OR (number = '8800719333*' OR number = '880071933*' OR number = '88007193*' OR number = '8800719*' OR number = '880071*' OR number = '88007*' OR number = '8800*' OR number = '880*' OR number = '88*' OR number = '8*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
  298. 2023-08-09 06:00:35.769435 93.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1
  299. EXECUTE [depth=0] sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 set(daily_call_credit_limit=0.20000)
  300. 2023-08-09 06:00:35.769435 93.83% [DEBUG] mod_dptools.c:1685 SET sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 [daily_call_credit_limit]=[0.20000]
  301. EXECUTE [depth=0] sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 export(daily_call_credit_limit=0.20000)
  302. 2023-08-09 06:00:35.769435 93.83% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [daily_call_credit_limit]=[0.20000]
  303. EXECUTE [depth=0] sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 set(daily_call_used_limit=0.38500)
  304. 2023-08-09 06:00:35.769435 93.83% [DEBUG] mod_dptools.c:1685 SET sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 [daily_call_used_limit]=[0.38500]
  305. EXECUTE [depth=0] sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 export(daily_call_used_limit=0.38500)
  306. 2023-08-09 06:00:35.769435 93.83% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [daily_call_used_limit]=[0.38500]
  307. EXECUTE [depth=0] sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 set(monthly_call_credit_limit=400.00000)
  308. 2023-08-09 06:00:35.769435 93.83% [DEBUG] mod_dptools.c:1685 SET sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 [monthly_call_credit_limit]=[400.00000]
  309. EXECUTE [depth=0] sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 export(monthly_call_credit_limit=400.00000)
  310. 2023-08-09 06:00:35.769435 93.83% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [monthly_call_credit_limit]=[400.00000]
  311. EXECUTE [depth=0] sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 set(monthly_call_used_limit=420.38500)
  312. 2023-08-09 06:00:35.769435 93.83% [DEBUG] mod_dptools.c:1685 SET sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 [monthly_call_used_limit]=[420.38500]
  313. EXECUTE [depth=0] sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 export(monthly_call_used_limit=420.38500)
  314. 2023-08-09 06:00:35.769435 93.83% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [monthly_call_used_limit]=[420.38500]
  315. 2023-08-09 06:00:35.769435 93.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP]  Post Cdrs : 0
  316. 2023-08-09 06:00:35.769435 93.83% [ERR] mod_lua.cpp:202 ./astpp/scripts/astpp.xml.lua:908: attempt to concatenate a nil value
  317. stack traceback:
  318.         ./astpp/scripts/astpp.xml.lua:908: in function 'xml_header'
  319.        ./astpp/scripts/astpp.xml.lua:1089: in function 'error_xml_without_cdr'
  320.         ./astpp/lib/astpp.callingcard.functions.lua:978: in function 'process_destination'
  321.         ./astpp-callingcards.lua:122: in main chunk
  322. 2023-08-09 06:00:35.769435 93.83% [DEBUG] switch_cpp.cpp:1209 sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 destroy/unlink session from object
  323. 2023-08-09 06:00:35.769435 93.83% [NOTICE] switch_core_state_machine.c:382 sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 has executed the last dialplan instruction, hanging up.
  324. 2023-08-09 06:00:35.769435 93.83% [NOTICE] switch_core_state_machine.c:384 Hangup sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 [CS_EXECUTE] [NORMAL_CLEARING]
  325. 2023-08-09 06:00:35.807935 93.83% [DEBUG] switch_core_state_machine.c:647 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) State EXECUTE going to sleep
  326. 2023-08-09 06:00:35.807935 93.83% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) Running State Change CS_HANGUP (Cur 1 Tot 7243)
  327. 2023-08-09 06:00:35.807935 93.83% [DEBUG] switch_core_state_machine.c:844 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) Callstate Change ACTIVE -> HANGUP
  328. 2023-08-09 06:00:35.807935 93.83% [DEBUG] switch_core_state_machine.c:846 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) State HANGUP
  329. 2023-08-09 06:00:35.807935 93.83% [DEBUG] mod_sofia.c:468 Channel sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 hanging up, cause: NORMAL_CLEARING
  330. 2023-08-09 06:00:35.807935 93.83% [DEBUG] mod_sofia.c:522 Sending BYE to sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073
  331. 2023-08-09 06:00:35.807935 93.83% [DEBUG] switch_core_state_machine.c:59 sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 Standard HANGUP, cause: NORMAL_CLEARING
  332. 2023-08-09 06:00:35.807935 93.83% [DEBUG] switch_core_state_machine.c:846 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) State HANGUP going to sleep
  333. 2023-08-09 06:00:35.807935 93.83% [DEBUG] switch_core_state_machine.c:616 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) State Change CS_HANGUP -> CS_REPORTING
  334. 2023-08-09 06:00:35.807935 93.83% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) Running State Change CS_REPORTING (Cur 1 Tot 7243)
  335. 2023-08-09 06:00:35.807935 93.83% [DEBUG] switch_core_state_machine.c:932 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) State REPORTING
  336. 2023-08-09 06:00:35.807935 93.83% [INFO] mod_json_cdr.c:271 Process [95795f0b-2afa-413e-aeb4-f59b6fc8f115.cdr.json]
  337. 2023-08-09 06:00:35.827925 93.83% [DEBUG] switch_core_state_machine.c:168 sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 Standard REPORTING, cause: NORMAL_CLEARING
  338. 2023-08-09 06:00:35.827925 93.83% [DEBUG] switch_core_state_machine.c:932 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) State REPORTING going to sleep
  339. 2023-08-09 06:00:35.827925 93.83% [DEBUG] switch_core_state_machine.c:607 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) State Change CS_REPORTING -> CS_DESTROY
  340. 2023-08-09 06:00:35.827925 93.83% [DEBUG] switch_core_session.c:1753 Session 7241 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) Locked, Waiting on external entities
  341. 2023-08-09 06:00:35.827925 93.83% [NOTICE] switch_core_session.c:1771 Session 7241 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) Ended
  342. 2023-08-09 06:00:35.827925 93.83% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 [CS_DESTROY]
  343. 2023-08-09 06:00:35.827925 93.83% [DEBUG] switch_core_state_machine.c:735 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) Running State Change CS_DESTROY (Cur 0 Tot 7243)
  344. 2023-08-09 06:00:35.827925 93.83% [DEBUG] switch_core_state_machine.c:745 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) State DESTROY
  345. 2023-08-09 06:00:35.827925 93.83% [DEBUG] mod_sofia.c:379 sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 SOFIA DESTROY
  346. 2023-08-09 06:00:35.827925 93.83% [DEBUG] switch_core_state_machine.c:175 sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073 Standard DESTROY
  347. 2023-08-09 06:00:35.827925 93.83% [DEBUG] switch_core_state_machine.c:745 (sofia/default/8800719333@clusterguibeta2.astppbilling.org:5073) State DESTROY going to sleep
  348. 2023-08-09 06:00:55.287927 95.93% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1047@167.235.194.5 [d4513107-9c7f-45d7-bed7-86856981c27a]
  349. 2023-08-09 06:00:55.287927 95.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1047@167.235.194.5) Running State Change CS_NEW (Cur 1 Tot 7244)
  350. 2023-08-09 06:00:55.287927 95.93% [INFO] sofia.c:10462 sofia/default/1047@167.235.194.5 receiving invite from 185.243.5.18:51078 version: 1.10.7 -release 64bit call-id: 386319922-2043683197-201340684
  351. 2023-08-09 06:00:55.287927 95.93% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 185.243.5.18:0.
  352. 2023-08-09 06:00:55.287927 95.93% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [011441519471756@167.235.194.5] from ip 185.243.5.18
  353. 2023-08-09 06:00:55.287927 95.93% [DEBUG] switch_core_state_machine.c:600 (sofia/default/1047@167.235.194.5) State NEW
  354. 2023-08-09 06:00:55.287927 95.93% [DEBUG] sofia.c:2419 detaching session d4513107-9c7f-45d7-bed7-86856981c27a
  355. 2023-08-09 06:00:55.328023 95.93% [DEBUG] sofia.c:2532 Re-attaching to session d4513107-9c7f-45d7-bed7-86856981c27a
  356. 2023-08-09 06:00:55.328023 95.93% [INFO] sofia.c:10462 sofia/default/1047@167.235.194.5 receiving invite from 185.243.5.18:51078 version: 1.10.7 -release 64bit call-id: 386319922-2043683197-201340684
  357. 2023-08-09 06:00:55.328023 95.93% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 185.243.5.18:0.
  358. 2023-08-09 06:00:55.328023 95.93% [WARNING] sofia_reg.c:3223 Can't find user [1047@167.235.194.5] from 185.243.5.18
  359. You must define a domain called '167.235.194.5' in your directory and add a user with the id="1047" attribute
  360. and you must configure your device to use the proper domain in its authentication credentials.
  361. 2023-08-09 06:00:55.328023 95.93% [WARNING] sofia_reg.c:1806 SIP auth failure (INVITE) on sofia profile 'default' for [011441519471756@167.235.194.5] from ip 185.243.5.18
  362. 2023-08-09 06:00:55.328023 95.93% [NOTICE] sofia.c:2417 Hangup sofia/default/1047@167.235.194.5 [CS_NEW] [CALL_REJECTED]
  363. 2023-08-09 06:00:55.367958 95.93% [DEBUG] sofia.c:1527 Channel is already hungup.
  364. 2023-08-09 06:00:55.367958 95.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1047@167.235.194.5) Running State Change CS_HANGUP (Cur 1 Tot 7244)
  365. 2023-08-09 06:00:55.367958 95.93% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1047@167.235.194.5) Callstate Change DOWN -> HANGUP
  366. 2023-08-09 06:00:55.367958 95.93% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1047@167.235.194.5) State HANGUP
  367. 2023-08-09 06:00:55.367958 95.93% [DEBUG] mod_sofia.c:468 Channel sofia/default/1047@167.235.194.5 hanging up, cause: CALL_REJECTED
  368. 2023-08-09 06:00:55.367958 95.93% [DEBUG] switch_core_state_machine.c:59 sofia/default/1047@167.235.194.5 Standard HANGUP, cause: CALL_REJECTED
  369. 2023-08-09 06:00:55.367958 95.93% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1047@167.235.194.5) State HANGUP going to sleep
  370. 2023-08-09 06:00:55.367958 95.93% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1047@167.235.194.5) State Change CS_HANGUP -> CS_REPORTING
  371. 2023-08-09 06:00:55.367958 95.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1047@167.235.194.5) Running State Change CS_REPORTING (Cur 1 Tot 7244)
  372. 2023-08-09 06:00:55.367958 95.93% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1047@167.235.194.5) State REPORTING
  373. 2023-08-09 06:00:55.367958 95.93% [DEBUG] switch_core_state_machine.c:168 sofia/default/1047@167.235.194.5 Standard REPORTING, cause: CALL_REJECTED
  374. 2023-08-09 06:00:55.367958 95.93% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1047@167.235.194.5) State REPORTING going to sleep
  375. 2023-08-09 06:00:55.367958 95.93% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1047@167.235.194.5) State Change CS_REPORTING -> CS_DESTROY
  376. 2023-08-09 06:00:55.367958 95.93% [DEBUG] switch_core_session.c:1753 Session 7244 (sofia/default/1047@167.235.194.5) Locked, Waiting on external entities
  377. 2023-08-09 06:00:55.367958 95.93% [NOTICE] switch_core_session.c:1771 Session 7244 (sofia/default/1047@167.235.194.5) Ended
  378. 2023-08-09 06:00:55.367958 95.93% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1047@167.235.194.5 [CS_DESTROY]
  379. 2023-08-09 06:00:55.367958 95.93% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1047@167.235.194.5) Running State Change CS_DESTROY (Cur 0 Tot 7244)
  380. 2023-08-09 06:00:55.367958 95.93% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1047@167.235.194.5) State DESTROY
  381. 2023-08-09 06:00:55.367958 95.93% [DEBUG] mod_sofia.c:379 sofia/default/1047@167.235.194.5 SOFIA DESTROY
  382. 2023-08-09 06:00:55.367958 95.93% [DEBUG] switch_core_state_machine.c:175 sofia/default/1047@167.235.194.5 Standard DESTROY
  383. 2023-08-09 06:00:55.367958 95.93% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1047@167.235.194.5) State DESTROY going to sleep
  384. 2023-08-09 06:01:11.527995 94.90% [NOTICE] switch_channel.c:1123 New Channel sofia/default/7717@167.235.194.5 [11c46a90-9806-4264-a9fe-b11885eedf6b]
  385. 2023-08-09 06:01:11.527995 94.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/7717@167.235.194.5) Running State Change CS_NEW (Cur 1 Tot 7245)
  386. 2023-08-09 06:01:11.527995 94.90% [INFO] sofia.c:10462 sofia/default/7717@167.235.194.5 receiving invite from 185.243.5.18:59918 version: 1.10.7 -release 64bit call-id: 1136922132-321230269-1596113213
  387. 2023-08-09 06:01:11.527995 94.90% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 185.243.5.18:0.
  388. 2023-08-09 06:01:11.527995 94.90% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [00441519471756@167.235.194.5] from ip 185.243.5.18
  389. 2023-08-09 06:01:11.527995 94.90% [DEBUG] switch_core_state_machine.c:600 (sofia/default/7717@167.235.194.5) State NEW
  390. 2023-08-09 06:01:11.527995 94.90% [DEBUG] sofia.c:2419 detaching session 11c46a90-9806-4264-a9fe-b11885eedf6b
  391. 2023-08-09 06:01:11.587962 94.90% [DEBUG] sofia.c:2532 Re-attaching to session 11c46a90-9806-4264-a9fe-b11885eedf6b
  392. 2023-08-09 06:01:11.607929 94.90% [INFO] sofia.c:10462 sofia/default/7717@167.235.194.5 receiving invite from 185.243.5.18:59918 version: 1.10.7 -release 64bit call-id: 1136922132-321230269-1596113213
  393. 2023-08-09 06:01:11.607929 94.90% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 185.243.5.18:0.
  394. 2023-08-09 06:01:11.647935 94.90% [WARNING] sofia_reg.c:3223 Can't find user [7717@167.235.194.5] from 185.243.5.18
  395. You must define a domain called '167.235.194.5' in your directory and add a user with the id="7717" attribute
  396. and you must configure your device to use the proper domain in its authentication credentials.
  397. 2023-08-09 06:01:11.647935 94.90% [WARNING] sofia_reg.c:1806 SIP auth failure (INVITE) on sofia profile 'default' for [00441519471756@167.235.194.5] from ip 185.243.5.18
  398. 2023-08-09 06:01:11.647935 94.90% [NOTICE] sofia.c:2417 Hangup sofia/default/7717@167.235.194.5 [CS_NEW] [CALL_REJECTED]
  399. 2023-08-09 06:01:11.667958 94.90% [DEBUG] sofia.c:1527 Channel is already hungup.
  400. 2023-08-09 06:01:11.667958 94.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/7717@167.235.194.5) Running State Change CS_HANGUP (Cur 1 Tot 7245)
  401. 2023-08-09 06:01:11.667958 94.90% [DEBUG] switch_core_state_machine.c:844 (sofia/default/7717@167.235.194.5) Callstate Change DOWN -> HANGUP
  402. 2023-08-09 06:01:11.667958 94.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/7717@167.235.194.5) State HANGUP
  403. 2023-08-09 06:01:11.667958 94.90% [DEBUG] mod_sofia.c:468 Channel sofia/default/7717@167.235.194.5 hanging up, cause: CALL_REJECTED
  404. 2023-08-09 06:01:11.667958 94.90% [DEBUG] switch_core_state_machine.c:59 sofia/default/7717@167.235.194.5 Standard HANGUP, cause: CALL_REJECTED
  405. 2023-08-09 06:01:11.667958 94.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/7717@167.235.194.5) State HANGUP going to sleep
  406. 2023-08-09 06:01:11.667958 94.90% [DEBUG] switch_core_state_machine.c:616 (sofia/default/7717@167.235.194.5) State Change CS_HANGUP -> CS_REPORTING
  407. 2023-08-09 06:01:11.667958 94.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/7717@167.235.194.5) Running State Change CS_REPORTING (Cur 1 Tot 7245)
  408. 2023-08-09 06:01:11.667958 94.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/7717@167.235.194.5) State REPORTING
  409. 2023-08-09 06:01:11.667958 94.90% [DEBUG] switch_core_state_machine.c:168 sofia/default/7717@167.235.194.5 Standard REPORTING, cause: CALL_REJECTED
  410. 2023-08-09 06:01:11.667958 94.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/7717@167.235.194.5) State REPORTING going to sleep
  411. 2023-08-09 06:01:11.667958 94.90% [DEBUG] switch_core_state_machine.c:607 (sofia/default/7717@167.235.194.5) State Change CS_REPORTING -> CS_DESTROY
  412. 2023-08-09 06:01:11.667958 94.90% [DEBUG] switch_core_session.c:1753 Session 7245 (sofia/default/7717@167.235.194.5) Locked, Waiting on external entities
  413. 2023-08-09 06:01:11.667958 94.90% [NOTICE] switch_core_session.c:1771 Session 7245 (sofia/default/7717@167.235.194.5) Ended
  414. 2023-08-09 06:01:11.667958 94.90% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/7717@167.235.194.5 [CS_DESTROY]
  415. 2023-08-09 06:01:11.667958 94.90% [DEBUG] switch_core_state_machine.c:735 (sofia/default/7717@167.235.194.5) Running State Change CS_DESTROY (Cur 0 Tot 7245)
  416. 2023-08-09 06:01:11.667958 94.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/7717@167.235.194.5) State DESTROY
  417. 2023-08-09 06:01:11.667958 94.90% [DEBUG] mod_sofia.c:379 sofia/default/7717@167.235.194.5 SOFIA DESTROY
  418. 2023-08-09 06:01:11.667958 94.90% [DEBUG] switch_core_state_machine.c:175 sofia/default/7717@167.235.194.5 Standard DESTROY
  419. 2023-08-09 06:01:11.667958 94.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/7717@167.235.194.5) State DESTROY going to sleep
  420. 2023-08-09 06:01:26.367977 95.67% [NOTICE] switch_channel.c:1123 New Channel sofia/default/4355@167.235.194.5 [fe106337-6ac1-4462-af94-a6fbc8559162]
  421. 2023-08-09 06:01:26.367977 95.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/4355@167.235.194.5) Running State Change CS_NEW (Cur 1 Tot 7246)
  422. 2023-08-09 06:01:26.367977 95.67% [INFO] sofia.c:10462 sofia/default/4355@167.235.194.5 receiving invite from 185.243.5.18:61670 version: 1.10.7 -release 64bit call-id: 1217705584-611803020-2128651088
  423. 2023-08-09 06:01:26.367977 95.67% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 185.243.5.18:0.
  424. 2023-08-09 06:01:26.387931 95.67% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [900441519471756@167.235.194.5] from ip 185.243.5.18
  425. 2023-08-09 06:01:26.387931 95.67% [DEBUG] switch_core_state_machine.c:600 (sofia/default/4355@167.235.194.5) State NEW
  426. 2023-08-09 06:01:26.387931 95.67% [DEBUG] sofia.c:2419 detaching session fe106337-6ac1-4462-af94-a6fbc8559162
  427. 2023-08-09 06:01:26.427942 95.67% [DEBUG] sofia.c:2532 Re-attaching to session fe106337-6ac1-4462-af94-a6fbc8559162
  428. 2023-08-09 06:01:26.447933 95.67% [INFO] sofia.c:10462 sofia/default/4355@167.235.194.5 receiving invite from 185.243.5.18:61670 version: 1.10.7 -release 64bit call-id: 1217705584-611803020-2128651088
  429. 2023-08-09 06:01:26.447933 95.67% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 185.243.5.18:0.
  430. 2023-08-09 06:01:26.467933 95.67% [WARNING] sofia_reg.c:3223 Can't find user [4355@167.235.194.5] from 185.243.5.18
  431. You must define a domain called '167.235.194.5' in your directory and add a user with the id="4355" attribute
  432. and you must configure your device to use the proper domain in its authentication credentials.
  433. 2023-08-09 06:01:26.467933 95.67% [WARNING] sofia_reg.c:1806 SIP auth failure (INVITE) on sofia profile 'default' for [900441519471756@167.235.194.5] from ip 185.243.5.18
  434. 2023-08-09 06:01:26.467933 95.67% [NOTICE] sofia.c:2417 Hangup sofia/default/4355@167.235.194.5 [CS_NEW] [CALL_REJECTED]
  435. 2023-08-09 06:01:26.487948 95.67% [DEBUG] sofia.c:1527 Channel is already hungup.
  436. 2023-08-09 06:01:26.487948 95.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/4355@167.235.194.5) Running State Change CS_HANGUP (Cur 1 Tot 7246)
  437. 2023-08-09 06:01:26.487948 95.67% [DEBUG] switch_core_state_machine.c:844 (sofia/default/4355@167.235.194.5) Callstate Change DOWN -> HANGUP
  438. 2023-08-09 06:01:26.487948 95.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/4355@167.235.194.5) State HANGUP
  439. 2023-08-09 06:01:26.487948 95.67% [DEBUG] mod_sofia.c:468 Channel sofia/default/4355@167.235.194.5 hanging up, cause: CALL_REJECTED
  440. 2023-08-09 06:01:26.487948 95.67% [DEBUG] switch_core_state_machine.c:59 sofia/default/4355@167.235.194.5 Standard HANGUP, cause: CALL_REJECTED
  441. 2023-08-09 06:01:26.487948 95.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/4355@167.235.194.5) State HANGUP going to sleep
  442. 2023-08-09 06:01:26.487948 95.67% [DEBUG] switch_core_state_machine.c:616 (sofia/default/4355@167.235.194.5) State Change CS_HANGUP -> CS_REPORTING
  443. 2023-08-09 06:01:26.487948 95.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/4355@167.235.194.5) Running State Change CS_REPORTING (Cur 1 Tot 7246)
  444. 2023-08-09 06:01:26.487948 95.67% [DEBUG] switch_core_state_machine.c:932 (sofia/default/4355@167.235.194.5) State REPORTING
  445. 2023-08-09 06:01:26.487948 95.67% [DEBUG] switch_core_state_machine.c:168 sofia/default/4355@167.235.194.5 Standard REPORTING, cause: CALL_REJECTED
  446. 2023-08-09 06:01:26.487948 95.67% [DEBUG] switch_core_state_machine.c:932 (sofia/default/4355@167.235.194.5) State REPORTING going to sleep
  447. 2023-08-09 06:01:26.487948 95.67% [DEBUG] switch_core_state_machine.c:607 (sofia/default/4355@167.235.194.5) State Change CS_REPORTING -> CS_DESTROY
  448. 2023-08-09 06:01:26.487948 95.67% [DEBUG] switch_core_session.c:1753 Session 7246 (sofia/default/4355@167.235.194.5) Locked, Waiting on external entities
  449. 2023-08-09 06:01:26.487948 95.67% [NOTICE] switch_core_session.c:1771 Session 7246 (sofia/default/4355@167.235.194.5) Ended
  450. 2023-08-09 06:01:26.487948 95.67% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/4355@167.235.194.5 [CS_DESTROY]
  451. 2023-08-09 06:01:26.487948 95.67% [DEBUG] switch_core_state_machine.c:735 (sofia/default/4355@167.235.194.5) Running State Change CS_DESTROY (Cur 0 Tot 7246)
  452. 2023-08-09 06:01:26.487948 95.67% [DEBUG] switch_core_state_machine.c:745 (sofia/default/4355@167.235.194.5) State DESTROY
  453. 2023-08-09 06:01:26.487948 95.67% [DEBUG] mod_sofia.c:379 sofia/default/4355@167.235.194.5 SOFIA DESTROY
  454. 2023-08-09 06:01:26.487948 95.67% [DEBUG] switch_core_state_machine.c:175 sofia/default/4355@167.235.194.5 Standard DESTROY
  455. 2023-08-09 06:01:26.487948 95.67% [DEBUG] switch_core_state_machine.c:745 (sofia/default/4355@167.235.194.5) State DESTROY going to sleep
  456. freeswitch@FS1ClusterQA>
  457. freeswitch@FS1ClusterQA>
  458. freeswitch@FS1ClusterQA> /exit
  459.  
  460. root@FS1ClusterQA:~#
  461. root@FS1ClusterQA:~#
  462. root@FS1ClusterQA:~#