FreeSWITCH Pastebin

v5tov6 when select IVR, then unable recharge/call

From Stained Terrapin, 11 Months ago, written in FreeSWITCH.
- view diff
URL https://pastebin.freeswitch.org/view/242f63e0
Embed
Download Paste or View Raw
  1. freeswitch@beta>
  2. freeswitch@beta>
  3. freeswitch@beta>
  4. freeswitch@beta>
  5. 2023-04-28 10:16:51.059182 87.03% [NOTICE] switch_channel.c:1123 New Channel sofia/default/69464@astppqa603.astppbilling.org:5071 [3428156e-8f0d-4f08-960c-50d4312394f8]
  6. 2023-04-28 10:16:51.059182 87.03% [DEBUG] switch_core_state_machine.c:581 (sofia/default/69464@astppqa603.astppbilling.org:5071) Running State Change CS_NEW (Cur 1 Tot 334)
  7. 2023-04-28 10:16:51.059182 87.03% [INFO] sofia.c:10462 sofia/default/69464@astppqa603.astppbilling.org:5071 receiving invite from 103.240.35.46:60684 version: 1.10.7 -release 64bit call-id: OGU4Y2FiYTZlZGY3YWMyNGQ0ZDQ5M2QwMThhYzQxMDE.
  8. 2023-04-28 10:16:51.059182 87.03% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 103.240.35.46:0.
  9. 2023-04-28 10:16:51.059182 87.03% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [112233@astppqa603.astppbilling.org] from ip 103.240.35.46
  10. 2023-04-28 10:16:51.059182 87.03% [DEBUG] switch_core_state_machine.c:600 (sofia/default/69464@astppqa603.astppbilling.org:5071) State NEW
  11. 2023-04-28 10:16:51.059182 87.03% [DEBUG] sofia.c:2419 detaching session 3428156e-8f0d-4f08-960c-50d4312394f8
  12. 2023-04-28 10:16:51.659157 87.03% [DEBUG] sofia.c:2532 Re-attaching to session 3428156e-8f0d-4f08-960c-50d4312394f8
  13. 2023-04-28 10:16:51.679145 87.03% [INFO] sofia.c:10462 sofia/default/69464@astppqa603.astppbilling.org:5071 receiving invite from 103.240.35.46:60684 version: 1.10.7 -release 64bit call-id: OGU4Y2FiYTZlZGY3YWMyNGQ0ZDQ5M2QwMThhYzQxMDE.
  14. 2023-04-28 10:16:51.679145 87.03% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 103.240.35.46:0.
  15. 2023-04-28 10:16:51.699155 87.03% [DEBUG] sofia.c:11672 Setting NAT mode based on via received
  16. 2023-04-28 10:16:51.699155 87.03% [DEBUG] sofia.c:7499 Channel sofia/default/69464@astppqa603.astppbilling.org:5071 entering state [received][100]
  17. 2023-04-28 10:16:51.699155 87.03% [DEBUG] sofia.c:7509 Remote SDP:
  18. v=0
  19. o=Z 0 0 IN IP4 103.240.35.46
  20. s=Z
  21. c=IN IP4 103.240.35.46
  22. t=0 0
  23. m=audio 8000 RTP/AVP 3 110 8 0 98 101
  24. a=rtpmap:110 speex/8000
  25. a=rtpmap:98 iLBC/8000
  26. a=fmtp:98 mode=20
  27. a=rtpmap:101 telephone-event/8000
  28. a=fmtp:101 0-15
  29.  
  30. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  31. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  32. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  33. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  34. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  35. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  36. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  37. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  38. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  39. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  40. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
  41. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
  42. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  43. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/69464@astppqa603.astppbilling.org:5071 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  44. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_codec.c:111 sofia/default/69464@astppqa603.astppbilling.org:5071 Original read codec set to PCMA:8
  45. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  46. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5973 sofia/default/69464@astppqa603.astppbilling.org:5071 Set 2833 dtmf send payload to 101 recv payload to 101
  47. 2023-04-28 10:16:51.699155 87.03% [DEBUG] sofia.c:7933 (sofia/default/69464@astppqa603.astppbilling.org:5071) State Change CS_NEW -> CS_INIT
  48. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:581 (sofia/default/69464@astppqa603.astppbilling.org:5071) Running State Change CS_INIT (Cur 1 Tot 334)
  49. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:624 (sofia/default/69464@astppqa603.astppbilling.org:5071) State INIT
  50. 2023-04-28 10:16:51.699155 87.03% [DEBUG] mod_sofia.c:97 sofia/default/69464@astppqa603.astppbilling.org:5071 SOFIA INIT
  51. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:40 sofia/default/69464@astppqa603.astppbilling.org:5071 Standard INIT
  52. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:48 (sofia/default/69464@astppqa603.astppbilling.org:5071) State Change CS_INIT -> CS_ROUTING
  53. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:624 (sofia/default/69464@astppqa603.astppbilling.org:5071) State INIT going to sleep
  54. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:581 (sofia/default/69464@astppqa603.astppbilling.org:5071) Running State Change CS_ROUTING (Cur 1 Tot 334)
  55. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_channel.c:2380 (sofia/default/69464@astppqa603.astppbilling.org:5071) Callstate Change DOWN -> RINGING
  56. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:640 (sofia/default/69464@astppqa603.astppbilling.org:5071) State ROUTING
  57. 2023-04-28 10:16:51.699155 87.03% [DEBUG] mod_sofia.c:158 sofia/default/69464@astppqa603.astppbilling.org:5071 SOFIA ROUTING
  58. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:230 sofia/default/69464@astppqa603.astppbilling.org:5071 Standard ROUTING
  59. 2023-04-28 10:16:51.699155 87.03% [INFO] mod_dialplan_xml.c:639 Processing 69464 <69464>->112233 in context default
  60. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  61. 2023-04-28 10:16:51.699155 87.03% [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')
  62. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  63. 2023-04-28 10:16:51.699155 87.03% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
  64. 2023-04-28 10:16:51.699155 87.03% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 112233
  65. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '112233' limit 1
  66. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '112233' AND status=0 limit 1
  67. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Generated XML:
  68. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  69. <document type="freeswitch/xml">
  70. <section name="dialplan" description="ASTPP Dialplan">
  71. <context name="default">
  72. <extension name="112233">
  73. <condition field="destination_number" expression="112233">
  74. <action application="log" data="INFO ASTPP - Calling Card Call"/>
  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/69464@astppqa603.astppbilling.org:5071 parsing [default->112233] continue=false
  84. Dialplan: sofia/default/69464@astppqa603.astppbilling.org:5071 Regex (PASS) [112233] destination_number(112233) =~ /112233/ break=on-false
  85. Dialplan: sofia/default/69464@astppqa603.astppbilling.org:5071 Action log(INFO ASTPP - Calling Card Call)
  86. Dialplan: sofia/default/69464@astppqa603.astppbilling.org:5071 Action answer()
  87. Dialplan: sofia/default/69464@astppqa603.astppbilling.org:5071 Action sleep(2000)
  88. Dialplan: sofia/default/69464@astppqa603.astppbilling.org:5071 Action lua(astpp-callingcards.lua)
  89. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:281 (sofia/default/69464@astppqa603.astppbilling.org:5071) State Change CS_ROUTING -> CS_EXECUTE
  90. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:640 (sofia/default/69464@astppqa603.astppbilling.org:5071) State ROUTING going to sleep
  91. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:581 (sofia/default/69464@astppqa603.astppbilling.org:5071) Running State Change CS_EXECUTE (Cur 1 Tot 334)
  92. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:647 (sofia/default/69464@astppqa603.astppbilling.org:5071) State EXECUTE
  93. 2023-04-28 10:16:51.699155 87.03% [DEBUG] mod_sofia.c:213 sofia/default/69464@astppqa603.astppbilling.org:5071 SOFIA EXECUTE
  94. 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:323 sofia/default/69464@astppqa603.astppbilling.org:5071 Standard EXECUTE
  95. EXECUTE [depth=0] sofia/default/69464@astppqa603.astppbilling.org:5071 log(INFO ASTPP - Calling Card Call)
  96. 2023-04-28 10:16:51.719149 87.03% [INFO] mod_dptools.c:1879 ASTPP - Calling Card Call
  97. EXECUTE [depth=0] sofia/default/69464@astppqa603.astppbilling.org:5071 answer()
  98. 2023-04-28 10:16:51.719149 87.03% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/69464@astppqa603.astppbilling.org:5071] 65.109.231.66 port 30096 -> 103.240.35.46 port 8000 codec: 8 ms: 20
  99. 2023-04-28 10:16:51.719149 87.03% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms
  100. 2023-04-28 10:16:51.719149 87.03% [DEBUG] switch_core_media.c:9089 sofia/default/69464@astppqa603.astppbilling.org:5071 Set 2833 dtmf send payload to 101
  101. 2023-04-28 10:16:51.719149 87.03% [DEBUG] switch_core_media.c:9096 sofia/default/69464@astppqa603.astppbilling.org:5071 Set 2833 dtmf receive payload to 101
  102. 2023-04-28 10:16:51.719149 87.03% [DEBUG] switch_core_media.c:9119 sofia/default/69464@astppqa603.astppbilling.org:5071 Set rtp dtmf delay to 40
  103. 2023-04-28 10:16:51.719149 87.03% [DEBUG] mod_sofia.c:913 Local SDP sofia/default/69464@astppqa603.astppbilling.org:5071:
  104. v=0
  105. o=FreeSWITCH 1682646915 1682646916 IN IP4 65.109.231.66
  106. s=FreeSWITCH
  107. c=IN IP4 65.109.231.66
  108. t=0 0
  109. m=audio 30096 RTP/AVP 8 101
  110. a=rtpmap:8 PCMA/8000
  111. a=rtpmap:101 telephone-event/8000
  112. a=fmtp:101 0-15
  113. a=ptime:20
  114. a=sendrecv
  115.  
  116. 2023-04-28 10:16:51.719149 87.03% [NOTICE] mod_dptools.c:1419 Channel [sofia/default/69464@astppqa603.astppbilling.org:5071] has been answered
  117. 2023-04-28 10:16:51.719149 87.03% [DEBUG] switch_channel.c:3950 (sofia/default/69464@astppqa603.astppbilling.org:5071) Callstate Change RINGING -> ACTIVE
  118. 2023-04-28 10:16:51.719149 87.03% [DEBUG] sofia.c:7499 Channel sofia/default/69464@astppqa603.astppbilling.org:5071 entering state [completed][200]
  119. EXECUTE [depth=0] sofia/default/69464@astppqa603.astppbilling.org:5071 sleep(2000)
  120. 2023-04-28 10:16:52.119158 86.97% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed.
  121. 2023-04-28 10:16:52.119158 86.97% [DEBUG] sofia.c:7499 Channel sofia/default/69464@astppqa603.astppbilling.org:5071 entering state [ready][200]
  122. EXECUTE [depth=0] sofia/default/69464@astppqa603.astppbilling.org:5071 lua(astpp-callingcards.lua)
  123. 2023-04-28 10:16:53.719206 86.93% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  124. 2023-04-28 10:16:53.719206 86.93% [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')
  125. 2023-04-28 10:16:53.719206 86.93% [NOTICE] switch_cpp.cpp:1465 [ASTPP] SECTION
  126. 2023-04-28 10:16:53.719206 86.93% [DEBUG] switch_core_file.c:444 File /var/www/html/astpp/upload/astpp-welcome.wav sample rate 16000 doesn't match requested rate 8000
  127. 2023-04-28 10:16:53.719206 86.93% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
  128. 2023-04-28 10:16:55.519166 86.70% [DEBUG] switch_ivr_play_say.c:2001 done playing file /var/www/html/astpp/upload/astpp-welcome.wav
  129. 2023-04-28 10:16:55.519166 86.70% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_ani] Query :SELECT * FROM ani_map WHERE number = 69464
  130. 2023-04-28 10:16:55.519166 86.70% [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
  131. 2023-04-28 10:16:55.519166 86.70% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
  132. 2023-04-28 10:16:58.519201 86.57% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-accountnum.wav
  133. 2023-04-28 10:17:01.339165 86.57% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [7046557419@astppqa605.astppbilling.org] from ip 103.240.35.46
  134. 2023-04-28 10:17:01.879136 86.57% [WARNING] sofia_reg.c:3223 Can't find user [7046557419@astppqa605.astppbilling.org] from 103.240.35.46
  135. You must define a domain called 'astppqa605.astppbilling.org' in your directory and add a user with the id="7046557419" attribute
  136. and you must configure your device to use the proper domain in its authentication credentials.
  137. 2023-04-28 10:17:02.079143 86.53% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [7046557419@astppqa605.astppbilling.org] from ip 103.240.35.46
  138. 2023-04-28 10:17:10.659174 82.67% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 9:960
  139. 2023-04-28 10:17:10.659174 82.67% [INFO] switch_channel.c:527 RECV DTMF 9:960
  140. 2023-04-28 10:17:10.979149 82.67% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 7:960
  141. 2023-04-28 10:17:10.979149 82.67% [INFO] switch_channel.c:527 RECV DTMF 7:960
  142. 2023-04-28 10:17:11.379191 82.40% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 1:960
  143. 2023-04-28 10:17:11.379191 82.40% [INFO] switch_channel.c:527 RECV DTMF 1:960
  144. 2023-04-28 10:17:11.579158 82.40% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 2:960
  145. 2023-04-28 10:17:11.579158 82.40% [INFO] switch_channel.c:527 RECV DTMF 2:960
  146. 2023-04-28 10:17:13.539186 82.37% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 8:960
  147. 2023-04-28 10:17:13.539186 82.37% [INFO] switch_channel.c:527 RECV DTMF 8:960
  148. 2023-04-28 10:17:14.039187 82.60% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 0:960
  149. 2023-04-28 10:17:14.039187 82.60% [INFO] switch_channel.c:527 RECV DTMF 0:960
  150. 2023-04-28 10:17:15.539195 83.10% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 6:960
  151. 2023-04-28 10:17:15.539195 83.10% [INFO] switch_channel.c:527 RECV DTMF 6:960
  152. 2023-04-28 10:17:16.379169 83.13% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [7046557419@astppqa605.astppbilling.org] from ip 103.240.35.46
  153. 2023-04-28 10:17:16.919155 83.13% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 1:960
  154. 2023-04-28 10:17:16.919155 83.13% [INFO] switch_channel.c:527 RECV DTMF 1:960
  155. 2023-04-28 10:17:16.919155 83.13% [WARNING] sofia_reg.c:3223 Can't find user [7046557419@astppqa605.astppbilling.org] from 103.240.35.46
  156. You must define a domain called 'astppqa605.astppbilling.org' in your directory and add a user with the id="7046557419" attribute
  157. and you must configure your device to use the proper domain in its authentication credentials.
  158. 2023-04-28 10:17:17.119184 82.97% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [7046557419@astppqa605.astppbilling.org] from ip 103.240.35.46
  159. 2023-04-28 10:17:17.159151 82.97% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 1:960
  160. 2023-04-28 10:17:17.159151 82.97% [INFO] switch_channel.c:527 RECV DTMF 1:960
  161. 2023-04-28 10:17:17.599158 82.97% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 9:960
  162. 2023-04-28 10:17:17.599158 82.97% [INFO] switch_channel.c:527 RECV DTMF 9:960
  163. 2023-04-28 10:17:19.019183 82.17% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF #:960
  164. span style="color: green; font-weight: bold;"> 2023-04-28 10:17:19.019183 82.17% [INFO] switch_channel.c:527 RECV DTMF #:960
  165. span style="color: goldenrod; font-weight: bold;"> 2023-04-28 10:17:19.019183 82.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Got DTMF digits: 9712806119
  166. 2023-04-28 10:17:19.019183 82.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT *,(select currencyrate from currency where id=currency_id) as currencyrate FROM accounts WHERE number = "9712806119" AND status=0 AND deleted=0 limit 1
  167. 2023-04-28 10:17:19.019183 82.17% [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
  168. 2023-04-28 10:17:19.019183 82.17% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
  169. 2023-04-28 10:17:21.739160 82.60% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-pleasepin.wav
  170. 2023-04-28 10:17:24.039181 82.50% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 5:960
  171. 2023-04-28 10:17:24.039181 82.50% [INFO] switch_channel.c:527 RECV DTMF 5:960
  172. 2023-04-28 10:17:24.259161 82.50% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 6:960
  173. 2023-04-28 10:17:24.259161 82.50% [INFO] switch_channel.c:527 RECV DTMF 6:960
  174. 2023-04-28 10:17:26.359151 83.40% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 9:960
  175. 2023-04-28 10:17:26.359151 83.40% [INFO] switch_channel.c:527 RECV DTMF 9:960
  176. 2023-04-28 10:17:26.719149 83.40% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 8:960
  177. 2023-04-28 10:17:26.719149 83.40% [INFO] switch_channel.c:527 RECV DTMF 8:960
  178. 2023-04-28 10:17:28.139211 83.00% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 9:960
  179. 2023-04-28 10:17:28.139211 83.00% [INFO] switch_channel.c:527 RECV DTMF 9:960
  180. 2023-04-28 10:17:28.859192 83.00% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 6:960
  181. 2023-04-28 10:17:28.859192 83.00% [INFO] switch_channel.c:527 RECV DTMF 6:960
  182. 2023-04-28 10:17:30.299173 83.53% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF #:960
  183. span style="color: green; font-weight: bold;"> 2023-04-28 10:17:30.299173 83.53% [INFO] switch_channel.c:527 RECV DTMF #:960
  184. span style="color: goldenrod; font-weight: bold;"> 2023-04-28 10:17:30.299173 83.53% [DEBUG] switch_cpp.cpp:1465 [ASTPP] We Recieved A Pin Number : 569896
  185. 2023-04-28 10:17:30.299173 83.53% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [validate_card_usage] Query :SELECT DATE_FORMAT('2033-04-21 05:19:22' , '%Y%m%d%H%i%s') AS expiry
  186. 2023-04-28 10:17:30.299173 83.53% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [validate_card_usage] Query :SELECT DATE_FORMAT('2023-04-28 10:17:30' , '%Y%m%d%H%i%s') AS expiry
  187. 2023-04-28 10:17:30.299173 83.53% [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
  188. 2023-04-28 10:17:30.299173 83.53% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
  189. 2023-04-28 10:17:33.119148 84.73% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-register.wav
  190. 2023-04-28 10:17:33.659177 84.73% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [7046557419@astppqa605.astppbilling.org] from ip 103.240.35.46
  191. 2023-04-28 10:17:34.259140 85.60% [WARNING] sofia_reg.c:3223 Can't find user [7046557419@astppqa605.astppbilling.org] from 103.240.35.46
  192. You must define a domain called 'astppqa605.astppbilling.org' in your directory and add a user with the id="7046557419" attribute
  193. and you must configure your device to use the proper domain in its authentication credentials.
  194. 2023-04-28 10:17:34.379150 85.60% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [7046557419@astppqa605.astppbilling.org] from ip 103.240.35.46
  195. 2023-04-28 10:17:38.119169 87.37% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 9712806119]
  196. 2023-04-28 10:17:38.119169 87.37% [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
  197. 2023-04-28 10:17:38.119169 87.37% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
  198. 2023-04-28 10:17:39.439154 87.30% [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
  199. 2023-04-28 10:17:39.439154 87.30% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Play Amount First Part] Query :188
  200. 2023-04-28 10:17:39.439154 87.30% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Play Amount Second Part] Query :89
  201. EXECUTE [depth=0] sofia/default/69464@astppqa603.astppbilling.org:5071 say(en number pronounced 188)
  202. 2023-04-28 10:17:39.439154 87.30% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
  203. 2023-04-28 10:17:41.379258 87.23% [DEBUG] switch_ivr_play_say.c:2001 done playing file file_string://digits/1.wav!digits/hundred.wav!digits/80.wav!digits/8.wav
  204. 2023-04-28 10:17:41.379258 87.23% [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
  205. 2023-04-28 10:17:41.379258 87.23% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
  206. 2023-04-28 10:17:41.939172 87.23% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-point.wav
  207. EXECUTE [depth=0] sofia/default/69464@astppqa603.astppbilling.org:5071 say(en number pronounced 89)
  208. 2023-04-28 10:17:41.939172 87.23% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
  209. 2023-04-28 10:17:43.039164 86.27% [DEBUG] switch_ivr_play_say.c:2001 done playing file file_string://digits/80.wav!digits/9.wav
  210. 2023-04-28 10:17:43.039164 86.27% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [HP:access_number_call_flow[0:Go Direct Dial,1:Go IVR] : 1]
  211. 2023-04-28 10:17:43.039164 86.27% [INFO] switch_cpp.cpp:1465 [ASTPP] Press 1 for make call, Press 2 for Recharge using Coupon
  212. 2023-04-28 10:17:43.039164 86.27% [WARNING] mod_sndfile.c:281 Error Opening File [/usr/local/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.]
  213. 2023-04-28 10:17:43.039164 86.27% [WARNING] mod_sndfile.c:281 Error Opening File [/usr/local/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.]
  214. 2023-04-28 10:17:43.039164 86.27% [WARNING] mod_sndfile.c:281 Error Opening File [/usr/local/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.]
  215. 2023-04-28 10:17:43.039164 86.27% [INFO] switch_cpp.cpp:1465 [ASTPP] ivr_custom_dtmf::
  216. 2023-04-28 10:17:43.039164 86.27% [INFO] switch_cpp.cpp:1465 [ASTPP] Press 1 for make call, Press 2 for Recharge using Coupon
  217. 2023-04-28 10:17:43.039164 86.27% [WARNING] mod_sndfile.c:281 Error Opening File [/usr/local/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.]
  218. 2023-04-28 10:17:43.039164 86.27% [WARNING] mod_sndfile.c:281 Error Opening File [/usr/local/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.]
  219. 2023-04-28 10:17:43.039164 86.27% [WARNING] mod_sndfile.c:281 Error Opening File [/usr/local/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.]
  220. 2023-04-28 10:17:43.039164 86.27% [INFO] switch_cpp.cpp:1465 [ASTPP] ivr_custom_dtmf::
  221. 2023-04-28 10:17:43.039164 86.27% [INFO] switch_cpp.cpp:1465 [ASTPP] Press 1 for make call, Press 2 for Recharge using Coupon
  222. 2023-04-28 10:17:43.039164 86.27% [WARNING] mod_sndfile.c:281 Error Opening File [/usr/local/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.]
  223. 2023-04-28 10:17:43.039164 86.27% [WARNING] mod_sndfile.c:281 Error Opening File [/usr/local/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.]
  224. 2023-04-28 10:17:43.039164 86.27% [WARNING] mod_sndfile.c:281 Error Opening File [/usr/local/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.]
  225. 2023-04-28 10:17:43.039164 86.27% [INFO] switch_cpp.cpp:1465 [ASTPP] ivr_custom_dtmf::
  226. 2023-04-28 10:17:43.039164 86.27% [DEBUG] switch_core_file.c:444 File /usr/local/freeswitch/sounds/en/us/callie/astpp-goodbye.wav sample rate 16000 doesn't match requested rate 8000
  227. 2023-04-28 10:17:43.039164 86.27% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
  228. 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-goodbye.wav
  229. 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_cpp.cpp:749 CoreSession::hangup
  230. 2023-04-28 10:17:43.839200 86.27% [NOTICE] switch_cpp.cpp:751 Hangup sofia/default/69464@astppqa603.astppbilling.org:5071 [CS_EXECUTE] [NORMAL_CLEARING]
  231. 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Got DTMF Digits: retries:0
  232. 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Got DTMF Digits: retries:1
  233. 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Got DTMF Digits: retries:2
  234. 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_cpp.cpp:749 CoreSession::hangup
  235. 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_cpp.cpp:1209 sofia/default/69464@astppqa603.astppbilling.org:5071 destroy/unlink session from object
  236. 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_core_session.c:2983 sofia/default/69464@astppqa603.astppbilling.org:5071 skip receive message [PHONE_EVENT] (channel is hungup already)
  237. 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_core_state_machine.c:647 (sofia/default/69464@astppqa603.astppbilling.org:5071) State EXECUTE going to sleep
  238. 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/69464@astppqa603.astppbilling.org:5071) Running State Change CS_HANGUP (Cur 1 Tot 334)
  239. 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_core_state_machine.c:844 (sofia/default/69464@astppqa603.astppbilling.org:5071) Callstate Change ACTIVE -> HANGUP
  240. 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_core_state_machine.c:846 (sofia/default/69464@astppqa603.astppbilling.org:5071) State HANGUP
  241. 2023-04-28 10:17:43.839200 86.27% [DEBUG] mod_sofia.c:468 Channel sofia/default/69464@astppqa603.astppbilling.org:5071 hanging up, cause: NORMAL_CLEARING
  242. 2023-04-28 10:17:43.839200 86.27% [DEBUG] mod_sofia.c:522 Sending BYE to sofia/default/69464@astppqa603.astppbilling.org:5071
  243. 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_core_state_machine.c:59 sofia/default/69464@astppqa603.astppbilling.org:5071 Standard HANGUP, cause: NORMAL_CLEARING
  244. 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_core_state_machine.c:846 (sofia/default/69464@astppqa603.astppbilling.org:5071) State HANGUP going to sleep
  245. 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_core_state_machine.c:616 (sofia/default/69464@astppqa603.astppbilling.org:5071) State Change CS_HANGUP -> CS_REPORTING
  246. 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/69464@astppqa603.astppbilling.org:5071) Running State Change CS_REPORTING (Cur 1 Tot 334)
  247. 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_core_state_machine.c:932 (sofia/default/69464@astppqa603.astppbilling.org:5071) State REPORTING
  248. 2023-04-28 10:17:43.839200 86.27% [INFO] mod_json_cdr.c:271 Process [3428156e-8f0d-4f08-960c-50d4312394f8.cdr.json]
  249. 2023-04-28 10:17:43.859159 86.27% [DEBUG] switch_core_state_machine.c:168 sofia/default/69464@astppqa603.astppbilling.org:5071 Standard REPORTING, cause: NORMAL_CLEARING
  250. 2023-04-28 10:17:43.859159 86.27% [DEBUG] switch_core_state_machine.c:932 (sofia/default/69464@astppqa603.astppbilling.org:5071) State REPORTING going to sleep
  251. 2023-04-28 10:17:43.859159 86.27% [DEBUG] switch_core_state_machine.c:607 (sofia/default/69464@astppqa603.astppbilling.org:5071) State Change CS_REPORTING -> CS_DESTROY
  252. 2023-04-28 10:17:43.859159 86.27% [DEBUG] switch_core_session.c:1753 Session 334 (sofia/default/69464@astppqa603.astppbilling.org:5071) Locked, Waiting on external entities
  253. 2023-04-28 10:17:43.859159 86.27% [NOTICE] switch_core_session.c:1771 Session 334 (sofia/default/69464@astppqa603.astppbilling.org:5071) Ended
  254. 2023-04-28 10:17:43.859159 86.27% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/69464@astppqa603.astppbilling.org:5071 [CS_DESTROY]
  255. 2023-04-28 10:17:43.859159 86.27% [DEBUG] switch_core_state_machine.c:735 (sofia/default/69464@astppqa603.astppbilling.org:5071) Running State Change CS_DESTROY (Cur 0 Tot 334)
  256. 2023-04-28 10:17:43.859159 86.27% [DEBUG] switch_core_state_machine.c:745 (sofia/default/69464@astppqa603.astppbilling.org:5071) State DESTROY
  257. 2023-04-28 10:17:43.859159 86.27% [DEBUG] mod_sofia.c:379 sofia/default/69464@astppqa603.astppbilling.org:5071 SOFIA DESTROY
  258. 2023-04-28 10:17:43.859159 86.27% [DEBUG] switch_core_state_machine.c:175 sofia/default/69464@astppqa603.astppbilling.org:5071 Standard DESTROY
  259. 2023-04-28 10:17:43.859159 86.27% [DEBUG] switch_core_state_machine.c:745 (sofia/default/69464@astppqa603.astppbilling.org:5071) State DESTROY going to sleep
  260. 2023-04-28 10:18:05.959184 80.47% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [7046557419@astppqa605.astppbilling.org] from ip 103.240.35.46
  261.  

Reply to "v5tov6 when select IVR, then unable recharge/call"