freeswitch@ip-172-31-36-75>
freeswitch@ip-172-31-36-75>
2021-12-27 11:34:01.712274 99.73% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [me@65.0.92.207] from ip 51.89.124.57
2021-12-27 11:34:35.652294 99.00% [NOTICE] switch_channel.c:1123 New Channel sofia/default/9225270812@65.0.92.207:5060 [0e48ce9d-b279-4a2c-a950-40b6ce7e0507]
2021-12-27 11:34:35.652294 99.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9225270812@65.0.92.207:5060) Running State Change CS_NEW (Cur 1 Tot 31)
2021-12-27 11:34:35.652294 99.00% [INFO] sofia.c:10462 sofia/default/9225270812@65.0.92.207:5060 receiving invite from 117.247.91.162:48258 version: 1.10.7 -release-19-883d2cb662 64bit call-id: NWQ2YWIzMzIwNzk2ZjQ4YzFiZDJiNTEzNzRkNDg4Zjc.
2021-12-27 11:34:35.652294 99.00% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 117.247.91.162:0.
2021-12-27 11:34:35.652294 99.00% [DEBUG] sofia.c:2419 detaching session 0e48ce9d-b279-4a2c-a950-40b6ce7e0507
2021-12-27 11:34:35.652294 99.00% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [102345@65.0.92.207] from ip 117.247.91.162
2021-12-27 11:34:35.652294 99.00% [DEBUG] switch_core_state_machine.c:600 (sofia/default/9225270812@65.0.92.207:5060) State NEW
2021-12-27 11:34:35.732300 99.00% [DEBUG] sofia.c:2532 Re-attaching to session 0e48ce9d-b279-4a2c-a950-40b6ce7e0507
2021-12-27 11:34:35.732300 99.00% [INFO] sofia.c:10462 sofia/default/9225270812@65.0.92.207:5060 receiving invite from 117.247.91.162:48258 version: 1.10.7 -release-19-883d2cb662 64bit call-id: NWQ2YWIzMzIwNzk2ZjQ4YzFiZDJiNTEzNzRkNDg4Zjc.
2021-12-27 11:34:35.732300 99.00% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 117.247.91.162:0.
2021-12-27 11:34:35.752275 99.00% [DEBUG] sofia.c:11672 Setting NAT mode based on via received
2021-12-27 11:34:35.752275 99.00% [DEBUG] sofia.c:7499 Channel sofia/default/9225270812@65.0.92.207:5060 entering state [received][100]
2021-12-27 11:34:35.752275 99.00% [DEBUG] sofia.c:7509 Remote SDP:
v=0
o=Z 0 0 IN IP4 117.247.91.162
s=Z
c=IN IP4 117.247.91.162
t=0 0
m=audio 8000 RTP/AVP 3 110 8 0 98 101
a=rtpmap:110 speex/8000
a=rtpmap:98 iLBC/8000
a=fmtp:98 mode=20
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/9225270812@65.0.92.207:5060 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_codec.c:111 sofia/default/9225270812@65.0.92.207:5060 Original read codec set to PCMA:8
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_media.c:5973 sofia/default/9225270812@65.0.92.207:5060 Set 2833 dtmf send payload to 101 recv payload to 101
2021-12-27 11:34:35.752275 99.00% [DEBUG] sofia.c:7933 (sofia/default/9225270812@65.0.92.207:5060) State Change CS_NEW -> CS_INIT
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9225270812@65.0.92.207:5060) Running State Change CS_INIT (Cur 1 Tot 31)
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9225270812@65.0.92.207:5060) State INIT
2021-12-27 11:34:35.752275 99.00% [DEBUG] mod_sofia.c:97 sofia/default/9225270812@65.0.92.207:5060 SOFIA INIT
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_state_machine.c:40 sofia/default/9225270812@65.0.92.207:5060 Standard INIT
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_state_machine.c:48 (sofia/default/9225270812@65.0.92.207:5060) State Change CS_INIT -> CS_ROUTING
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9225270812@65.0.92.207:5060) State INIT going to sleep
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9225270812@65.0.92.207:5060) Running State Change CS_ROUTING (Cur 1 Tot 31)
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_channel.c:2380 (sofia/default/9225270812@65.0.92.207:5060) Callstate Change DOWN -> RINGING
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9225270812@65.0.92.207:5060) State ROUTING
2021-12-27 11:34:35.752275 99.00% [DEBUG] mod_sofia.c:158 sofia/default/9225270812@65.0.92.207:5060 SOFIA ROUTING
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_state_machine.c:230 sofia/default/9225270812@65.0.92.207:5060 Standard ROUTING
2021-12-27 11:34:35.752275 99.00% [INFO] mod_dialplan_xml.c:639 Processing 9225270812 <9225270812>->102345 in context default
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
2021-12-27 11:34:35.752275 99.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 102345
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '102345' AND status=0 limit 1
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Generated XML:
<?xml version="1.0" encoding="UTF-8" standalone="no"?>
<document type="freeswitch/xml">
<section name="dialplan" description="ASTPP Dialplan">
<context name="default">
<extension name="102345">
<condition field="destination_number" expression="102345">
<action application="log" data="INFO ASTPP - Calling Card Call"/>
<action application="answer"/>
<action application="sleep" data="2000"/>
<action application="lua" data="astpp-callingcards.lua"/>
</condition>
</extension>
</context>
</section>
</document>
Dialplan: sofia/default/9225270812@65.0.92.207:5060 parsing [default->102345] continue=false
Dialplan: sofia/default/9225270812@65.0.92.207:5060 Regex (PASS) [102345] destination_number(102345) =~ /102345/ break=on-false
Dialplan: sofia/default/9225270812@65.0.92.207:5060 Action log(INFO ASTPP - Calling Card Call)
Dialplan: sofia/default/9225270812@65.0.92.207:5060 Action answer()
Dialplan: sofia/default/9225270812@65.0.92.207:5060 Action sleep(2000)
Dialplan: sofia/default/9225270812@65.0.92.207:5060 Action lua(astpp-callingcards.lua)
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_state_machine.c:281 (sofia/default/9225270812@65.0.92.207:5060) State Change CS_ROUTING -> CS_EXECUTE
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9225270812@65.0.92.207:5060) State ROUTING going to sleep
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9225270812@65.0.92.207:5060) Running State Change CS_EXECUTE (Cur 1 Tot 31)
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_state_machine.c:647 (sofia/default/9225270812@65.0.92.207:5060) State EXECUTE
2021-12-27 11:34:35.752275 99.00% [DEBUG] mod_sofia.c:213 sofia/default/9225270812@65.0.92.207:5060 SOFIA EXECUTE
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_state_machine.c:323 sofia/default/9225270812@65.0.92.207:5060 Standard EXECUTE
EXECUTE [depth=0] sofia/default/9225270812@65.0.92.207:5060 log(INFO ASTPP - Calling Card Call)
2021-12-27 11:34:35.752275 99.00% [INFO] mod_dptools.c:1879 ASTPP - Calling Card Call
EXECUTE [depth=0] sofia/default/9225270812@65.0.92.207:5060 answer()
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/9225270812@65.0.92.207:5060] 172.31.36.75 port 17780 -> 117.247.91.162 port 8000 codec: 8 ms: 20
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_media.c:9089 sofia/default/9225270812@65.0.92.207:5060 Set 2833 dtmf send payload to 101
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_media.c:9096 sofia/default/9225270812@65.0.92.207:5060 Set 2833 dtmf receive payload to 101
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_core_media.c:9119 sofia/default/9225270812@65.0.92.207:5060 Set rtp dtmf delay to 40
2021-12-27 11:34:35.752275 99.00% [DEBUG] mod_sofia.c:913 Local SDP sofia/default/9225270812@65.0.92.207:5060:
v=0
o=FreeSWITCH 1640587095 1640587096 IN IP4 65.0.92.207
s=FreeSWITCH
c=IN IP4 65.0.92.207
t=0 0
m=audio 17780 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
a=sendrecv
2021-12-27 11:34:35.752275 99.00% [DEBUG] sofia.c:7499 Channel sofia/default/9225270812@65.0.92.207:5060 entering state [completed][200]
2021-12-27 11:34:35.752275 99.00% [NOTICE] mod_dptools.c:1419 Channel [sofia/default/9225270812@65.0.92.207:5060] has been answered
2021-12-27 11:34:35.752275 99.00% [DEBUG] switch_channel.c:3950 (sofia/default/9225270812@65.0.92.207:5060) Callstate Change RINGING -> ACTIVE
EXECUTE [depth=0] sofia/default/9225270812@65.0.92.207:5060 sleep(2000)
2021-12-27 11:34:35.792271 99.00% [DEBUG] sofia.c:7499 Channel sofia/default/9225270812@65.0.92.207:5060 entering state [ready][200]
2021-12-27 11:34:35.812273 99.00% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed.
EXECUTE [depth=0] sofia/default/9225270812@65.0.92.207:5060 lua(astpp-callingcards.lua)
2021-12-27 11:34:37.792267 98.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
2021-12-27 11:34:37.792267 98.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] SECTION
2021-12-27 11:34:37.792267 98.67% [DEBUG] switch_core_file.c:444 File /var/www/html/astpp/upload/astpp-welcome.wav sample rate 16000 doesn't match requested rate 8000
2021-12-27 11:34:37.792267 98.67% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
2021-12-27 11:34:39.572299 98.53% [DEBUG] switch_ivr_play_say.c:2001 done playing file /var/www/html/astpp/upload/astpp-welcome.wav
2021-12-27 11:34:39.572299 98.53% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_ani] Query :SELECT * FROM ani_map WHERE number = 9225270812
2021-12-27 11:34:39.592272 98.53% [DEBUG] switch_core_file.c:444 File /usr/share/freeswitch/sounds/en/us/callie/astpp-accountnum.wav sample rate 16000 doesn't match requested rate 8000
2021-12-27 11:34:39.592272 98.53% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
2021-12-27 11:34:42.572315 98.43% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-accountnum.wav
2021-12-27 11:34:47.832297 99.30% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 9:960
2021-12-27 11:34:47.832297 99.30% [INFO] switch_channel.c:527 RECV DTMF 9:960
2021-12-27 11:34:49.112281 99.30% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 2:960
2021-12-27 11:34:49.112281 99.30% [INFO] switch_channel.c:527 RECV DTMF 2:960
2021-12-27 11:34:49.392300 99.30% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 2:960
2021-12-27 11:34:49.392300 99.30% [INFO] switch_channel.c:527 RECV DTMF 2:960
2021-12-27 11:34:51.672307 99.27% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 5:960
2021-12-27 11:34:51.672307 99.27% [INFO] switch_channel.c:527 RECV DTMF 5:960
2021-12-27 11:34:52.372294 99.27% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 2:960
2021-12-27 11:34:52.372294 99.27% [INFO] switch_channel.c:527 RECV DTMF 2:960
2021-12-27 11:34:53.392293 99.20% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 7:960
2021-12-27 11:34:53.392293 99.20% [INFO] switch_channel.c:527 RECV DTMF 7:960
2021-12-27 11:35:01.412300 99.20% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 0:960
2021-12-27 11:35:01.412300 99.20% [INFO] switch_channel.c:527 RECV DTMF 0:960
2021-12-27 11:35:02.152272 99.17% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 8:960
2021-12-27 11:35:02.152272 99.17% [INFO] switch_channel.c:527 RECV DTMF 8:960
2021-12-27 11:35:02.532281 99.17% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 1:960
2021-12-27 11:35:02.532281 99.17% [INFO] switch_channel.c:527 RECV DTMF 1:960
2021-12-27 11:35:02.732305 99.17% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 2:960
2021-12-27 11:35:02.732305 99.17% [INFO] switch_channel.c:527 RECV DTMF 2:960
2021-12-27 11:35:05.392305 96.93% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF #:960
span style="color: green; font-weight: bold;"> 2021-12-27 11:35:05.392305 96.93% [INFO] switch_channel.c:527 RECV DTMF #:960
span style="color: goldenrod; font-weight: bold;"> 2021-12-27 11:35:05.392305 96.93% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Got DTMF digits: 9225270812
2021-12-27 11:35:05.392305 96.93% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT *,(select currencyrate from currency where id=currency_id) as currencyrate FROM accounts WHERE number = "9225270812" AND status=0 AND deleted=0 limit 1
2021-12-27 11:35:05.412274 96.93% [DEBUG] switch_core_file.c:444 File /usr/share/freeswitch/sounds/en/us/callie/astpp-pleasepin.wav sample rate 16000 doesn't match requested rate 8000
2021-12-27 11:35:05.412274 96.93% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
2021-12-27 11:35:08.112297 97.17% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-pleasepin.wav
2021-12-27 11:35:09.192295 97.20% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 5:960
2021-12-27 11:35:09.192295 97.20% [INFO] switch_channel.c:527 RECV DTMF 5:960
2021-12-27 11:35:09.592300 97.20% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 8:960
2021-12-27 11:35:09.592300 97.20% [INFO] switch_channel.c:527 RECV DTMF 8:960
2021-12-27 11:35:10.212298 97.20% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 5:960
2021-12-27 11:35:10.212298 97.20% [INFO] switch_channel.c:527 RECV DTMF 5:960
2021-12-27 11:35:10.692304 97.20% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 3:960
2021-12-27 11:35:10.692304 97.20% [INFO] switch_channel.c:527 RECV DTMF 3:960
2021-12-27 11:35:10.832301 97.20% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 3:960
2021-12-27 11:35:10.832301 97.20% [INFO] switch_channel.c:527 RECV DTMF 3:960
2021-12-27 11:35:11.452304 97.13% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 1:960
2021-12-27 11:35:11.452304 97.13% [INFO] switch_channel.c:527 RECV DTMF 1:960
2021-12-27 11:35:12.372304 97.13% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF #:960
span style="color: green; font-weight: bold;"> 2021-12-27 11:35:12.372304 97.13% [INFO] switch_channel.c:527 RECV DTMF #:960
span style="color: goldenrod; font-weight: bold;"> 2021-12-27 11:35:12.372304 97.13% [DEBUG] switch_cpp.cpp:1465 [ASTPP] We Recieved A Pin Number : 585331
2021-12-27 11:35:12.372304 97.13% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [validate_card_usage] Query :SELECT DATE_FORMAT('2031-12-25 11:06:11' , '%Y%m%d%H%i%s') AS expiry
2021-12-27 11:35:12.372304 97.13% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [validate_card_usage] Query :SELECT DATE_FORMAT('2021-12-27 11:35:12' , '%Y%m%d%H%i%s') AS expiry
2021-12-27 11:35:12.372304 97.13% [DEBUG] switch_core_file.c:444 File /usr/share/freeswitch/sounds/en/us/callie/astpp-register.wav sample rate 16000 doesn't match requested rate 8000
2021-12-27 11:35:12.372304 97.13% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
2021-12-27 11:35:15.192290 97.10% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-register.wav
2021-12-27 11:35:20.192295 97.17% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 9225270812]
2021-12-27 11:35:20.192295 97.17% [DEBUG] switch_core_file.c:444 File /usr/share/freeswitch/sounds/en/us/callie/astpp-this-card-has-a-balance-of.wav sample rate 16000 doesn't match requested rate 8000
2021-12-27 11:35:20.192295 97.17% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
2021-12-27 11:35:21.512283 97.13% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-this-card-has-a-balance-of.wav
2021-12-27 11:35:21.512283 97.13% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Play Amount First Part] Query :400
2021-12-27 11:35:21.512283 97.13% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Play Amount Second Part] Query :00
EXECUTE [depth=0] sofia/default/9225270812@65.0.92.207:5060 say(en number pronounced 400)
2021-12-27 11:35:21.532265 97.13% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
2021-12-27 11:35:22.692280 97.07% [DEBUG] switch_ivr_play_say.c:2001 done playing file file_string://digits/4.wav!digits/hundred.wav
2021-12-27 11:35:22.692280 97.07% [DEBUG] switch_core_file.c:444 File /usr/share/freeswitch/sounds/en/us/callie/astpp-point.wav sample rate 16000 doesn't match requested rate 8000
2021-12-27 11:35:22.692280 97.07% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
2021-12-27 11:35:23.252329 97.10% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-point.wav
EXECUTE [depth=0] sofia/default/9225270812@65.0.92.207:5060 say(en number pronounced 00)
2021-12-27 11:35:23.272266 97.10% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
2021-12-27 11:35:24.032293 97.07% [DEBUG] switch_ivr_play_say.c:2001 done playing file file_string://digits/0.wav
2021-12-27 11:35:24.032293 97.07% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [HP:access_number_call_flow[0:Go Direct Dial,1:Go IVR] : 1]
2021-12-27 11:35:24.032293 97.07% [INFO] switch_cpp.cpp:1465 [ASTPP] Press 1 for make call, Press 2 for Recharge using Coupon
2021-12-27 11:35:24.032293 97.07% [DEBUG] switch_core_file.c:444 File /usr/share/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav sample rate 22050 doesn't match requested rate 8000
2021-12-27 11:35:24.032293 97.07% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
2021-12-27 11:35:28.692279 97.03% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav
2021-12-27 11:35:32.792275 97.00% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 2:960
2021-12-27 11:35:32.792275 97.00% [INFO] switch_channel.c:527 RECV DTMF 2:960
2021-12-27 11:35:32.792275 97.00% [INFO] switch_cpp.cpp:1465 [ASTPP] ivr_custom_dtmf:: 2
2021-12-27 11:35:32.792275 97.00% [INFO] switch_cpp.cpp:1465 [ASTPP] DTMF:2 Going for Recharge Coupon:
2021-12-27 11:35:32.792275 97.00% [DEBUG] switch_core_file.c:444 File /usr/share/freeswitch/sounds/en/us/callie/astpp-this-card-has-a-balance-of.wav sample rate 16000 doesn't match requested rate 8000
2021-12-27 11:35:32.792275 97.00% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
2021-12-27 11:35:33.232289 99.13% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [9225270812@65.0.92.207] from ip 117.247.91.162
2021-12-27 11:35:33.732302 99.13% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [9225270812@65.0.92.207] from ip 117.247.91.162
2021-12-27 11:35:33.732302 99.13% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [102345@65.0.92.207] from ip 117.247.91.162
2021-12-27 11:35:34.112291 99.07% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-this-card-has-a-balance-of.wav
2021-12-27 11:35:34.112291 99.07% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Play Amount First Part] Query :400
2021-12-27 11:35:34.112291 99.07% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Play Amount Second Part] Query :00
EXECUTE [depth=0] sofia/default/9225270812@65.0.92.207:5060 say(en number pronounced 400)
2021-12-27 11:35:34.132265 99.07% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
2021-12-27 11:35:35.292299 99.07% [DEBUG] switch_ivr_play_say.c:2001 done playing file file_string://digits/4.wav!digits/hundred.wav
2021-12-27 11:35:35.292299 99.07% [DEBUG] switch_core_file.c:444 File /usr/share/freeswitch/sounds/en/us/callie/astpp-point.wav sample rate 16000 doesn't match requested rate 8000
2021-12-27 11:35:35.292299 99.07% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
2021-12-27 11:35:35.852323 99.07% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-point.wav
EXECUTE [depth=0] sofia/default/9225270812@65.0.92.207:5060 say(en number pronounced 00)
2021-12-27 11:35:35.872266 99.07% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
2021-12-27 11:35:36.632291 99.20% [DEBUG] switch_ivr_play_say.c:2001 done playing file file_string://digits/0.wav
2021-12-27 11:35:36.632291 99.20% [INFO] switch_cpp.cpp:1465 [ASTPP] recharge_retries:: 0
2021-12-27 11:35:36.632291 99.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Please enter refill coupon code.
2021-12-27 11:35:36.652275 99.20% [DEBUG] switch_core_file.c:444 File /usr/share/freeswitch/sounds/en/us/callie/astpp-enter-refill-coupon-code.wav sample rate 22050 doesn't match requested rate 8000
2021-12-27 11:35:36.652275 99.20% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
2021-12-27 11:35:39.952275 99.10% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-enter-refill-coupon-code.wav
2021-12-27 11:35:54.212270 99.10% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 1:960
2021-12-27 11:35:54.212270 99.10% [INFO] switch_channel.c:527 RECV DTMF 1:960
2021-12-27 11:35:54.672296 99.10% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 0:960
2021-12-27 11:35:54.672296 99.10% [INFO] switch_channel.c:527 RECV DTMF 0:960
2021-12-27 11:35:55.152304 99.10% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 0:960
2021-12-27 11:35:55.152304 99.10% [INFO] switch_channel.c:527 RECV DTMF 0:960
2021-12-27 11:36:03.332299 98.63% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 5:960
2021-12-27 11:36:03.332299 98.63% [INFO] switch_channel.c:527 RECV DTMF 5:960
2021-12-27 11:36:03.792294 98.63% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 7:960
2021-12-27 11:36:03.792294 98.63% [INFO] switch_channel.c:527 RECV DTMF 7:960
2021-12-27 11:36:06.412294 98.83% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 2:960
2021-12-27 11:36:06.412294 98.83% [INFO] switch_channel.c:527 RECV DTMF 2:960
2021-12-27 11:36:06.872282 98.83% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 3:960
2021-12-27 11:36:06.872282 98.83% [INFO] switch_channel.c:527 RECV DTMF 3:960
2021-12-27 11:36:07.732292 98.83% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 0:960
2021-12-27 11:36:07.732292 98.83% [INFO] switch_channel.c:527 RECV DTMF 0:960
2021-12-27 11:36:09.632295 98.90% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF #:960
span style="color: green; font-weight: bold;"> 2021-12-27 11:36:09.632295 98.90% [INFO] switch_channel.c:527 RECV DTMF #:960
span style="color: green; font-weight: bold;"> 2021-12-27 11:36:09.632295 98.90% [INFO] switch_cpp.cpp:1465 [ASTPP] refill_coupon_number:: 10057230
2021-12-27 11:36:09.632295 98.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [refill_coupon] Query :SELECT * from refill_coupon where number = '10057230' reseller_id = '5'
2021-12-27 11:36:09.652275 98.90% [ERR] switch_core_sqldb.c:1310 ERR: [SELECT * from refill_coupon where number = '10057230' reseller_id = '5']
[STATE: 42000 CODE 1064 ERROR: [MySQL][ODBC 8.0(a) Driver][mysqld-8.0.25]You have an error in your SQL syntax; check the manual that corresponds to your MySQL server version for the right syntax to use near 'reseller_id = '5'' at line 1
]
2021-12-27 11:36:09.652275 98.90% [ERR] mod_lua.cpp:202 ...switch/scripts/astpp/lib/astpp.callingcard.functions.lua:639: assertion failed!
stack traceback:
[C]: in function 'assert'
...switch/scripts/astpp/lib/astpp.callingcard.functions.lua:639: in function 'process_refill_recharge'
...switch/scripts/astpp/lib/astpp.callingcard.functions.lua:616: in function 'process_ivr_custom'
/usr/share/freeswitch/scripts/astpp-callingcards.lua:108: in main chunk
2021-12-27 11:36:09.652275 98.90% [DEBUG] switch_cpp.cpp:1209 sofia/default/9225270812@65.0.92.207:5060 destroy/unlink session from object
2021-12-27 11:36:09.652275 98.90% [NOTICE] switch_core_state_machine.c:382 sofia/default/9225270812@65.0.92.207:5060 has executed the last dialplan instruction, hanging up.
2021-12-27 11:36:09.652275 98.90% [NOTICE] switch_core_state_machine.c:384 Hangup sofia/default/9225270812@65.0.92.207:5060 [CS_EXECUTE] [NORMAL_CLEARING]
2021-12-27 11:36:09.652275 98.90% [DEBUG] switch_core_state_machine.c:647 (sofia/default/9225270812@65.0.92.207:5060) State EXECUTE going to sleep
2021-12-27 11:36:09.652275 98.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9225270812@65.0.92.207:5060) Running State Change CS_HANGUP (Cur 1 Tot 31)
2021-12-27 11:36:09.652275 98.90% [DEBUG] switch_core_state_machine.c:844 (sofia/default/9225270812@65.0.92.207:5060) Callstate Change ACTIVE -> HANGUP
2021-12-27 11:36:09.652275 98.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9225270812@65.0.92.207:5060) State HANGUP
2021-12-27 11:36:09.652275 98.90% [DEBUG] mod_sofia.c:468 Channel sofia/default/9225270812@65.0.92.207:5060 hanging up, cause: NORMAL_CLEARING
2021-12-27 11:36:09.652275 98.90% [DEBUG] mod_sofia.c:522 Sending BYE to sofia/default/9225270812@65.0.92.207:5060
2021-12-27 11:36:09.652275 98.90% [DEBUG] switch_core_state_machine.c:59 sofia/default/9225270812@65.0.92.207:5060 Standard HANGUP, cause: NORMAL_CLEARING
2021-12-27 11:36:09.652275 98.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9225270812@65.0.92.207:5060) State HANGUP going to sleep
2021-12-27 11:36:09.652275 98.90% [DEBUG] switch_core_state_machine.c:616 (sofia/default/9225270812@65.0.92.207:5060) State Change CS_HANGUP -> CS_REPORTING
2021-12-27 11:36:09.652275 98.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9225270812@65.0.92.207:5060) Running State Change CS_REPORTING (Cur 1 Tot 31)
2021-12-27 11:36:09.652275 98.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9225270812@65.0.92.207:5060) State REPORTING
2021-12-27 11:36:09.652275 98.90% [INFO] mod_json_cdr.c:271 Process [0e48ce9d-b279-4a2c-a950-40b6ce7e0507.cdr.json]
2021-12-27 11:36:09.652275 98.90% [DEBUG] switch_core_state_machine.c:168 sofia/default/9225270812@65.0.92.207:5060 Standard REPORTING, cause: NORMAL_CLEARING
2021-12-27 11:36:09.652275 98.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9225270812@65.0.92.207:5060) State REPORTING going to sleep
2021-12-27 11:36:09.652275 98.90% [DEBUG] switch_core_state_machine.c:607 (sofia/default/9225270812@65.0.92.207:5060) State Change CS_REPORTING -> CS_DESTROY
2021-12-27 11:36:09.652275 98.90% [DEBUG] switch_core_session.c:1753 Session 31 (sofia/default/9225270812@65.0.92.207:5060) Locked, Waiting on external entities
2021-12-27 11:36:09.652275 98.90% [NOTICE] switch_core_session.c:1771 Session 31 (sofia/default/9225270812@65.0.92.207:5060) Ended
2021-12-27 11:36:09.652275 98.90% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/9225270812@65.0.92.207:5060 [CS_DESTROY]
2021-12-27 11:36:09.652275 98.90% [DEBUG] switch_core_state_machine.c:735 (sofia/default/9225270812@65.0.92.207:5060) Running State Change CS_DESTROY (Cur 0 Tot 31)
2021-12-27 11:36:09.652275 98.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9225270812@65.0.92.207:5060) State DESTROY
2021-12-27 11:36:09.652275 98.90% [DEBUG] mod_sofia.c:379 sofia/default/9225270812@65.0.92.207:5060 SOFIA DESTROY
2021-12-27 11:36:09.652275 98.90% [DEBUG] switch_core_state_machine.c:175 sofia/default/9225270812@65.0.92.207:5060 Standard DESTROY
2021-12-27 11:36:09.652275 98.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9225270812@65.0.92.207:5060) State DESTROY going to sleep
2021-12-27 11:36:14.872271 98.77% [NOTICE] switch_channel.c:1123 New Channel sofia/default/101.22.56.152@65.0.92.207 [47578839-7535-41be-8798-febe2c945744]
2021-12-27 11:36:14.872271 98.77% [DEBUG] switch_core_state_machine.c:581 (sofia/default/101.22.56.152@65.0.92.207) Running State Change CS_NEW (Cur 1 Tot 32)
2021-12-27 11:36:14.872271 98.77% [INFO] sofia.c:10462 sofia/default/101.22.56.152@65.0.92.207 receiving invite from 91.134.176.96:49785 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 1795583883-1742320530-1748821739
2021-12-27 11:36:14.872271 98.77% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 91.134.176.96:0.
2021-12-27 11:36:14.872271 98.77% [DEBUG] sofia.c:2419 detaching session 47578839-7535-41be-8798-febe2c945744
2021-12-27 11:36:14.872271 98.77% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [0046812400090@65.0.92.207] from ip 91.134.176.96
2021-12-27 11:36:14.872271 98.77% [DEBUG] switch_core_state_machine.c:600 (sofia/default/101.22.56.152@65.0.92.207) State NEW
2021-12-27 11:36:15.032317 98.77% [DEBUG] sofia.c:2532 Re-attaching to session 47578839-7535-41be-8798-febe2c945744
2021-12-27 11:36:15.052272 98.77% [INFO] sofia.c:10462 sofia/default/101.22.56.152@65.0.92.207 receiving invite from 91.134.176.96:49785 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 1795583883-1742320530-1748821739
2021-12-27 11:36:15.052272 98.77% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 91.134.176.96:0.
2021-12-27 11:36:15.052272 98.77% [WARNING] sofia_reg.c:3223 Can't find user [101.22.56.152@65.0.92.207] from 91.134.176.96
You must define a domain called '65.0.92.207' in your directory and add a user with the id="101.22.56.152" attribute
and you must configure your device to use the proper domain in its authentication credentials.
2021-12-27 11:36:15.052272 98.77% [NOTICE] sofia.c:2417 Hangup sofia/default/101.22.56.152@65.0.92.207 [CS_NEW] [CALL_REJECTED]
2021-12-27 11:36:15.052272 98.77% [WARNING] sofia_reg.c:1806 SIP auth failure (INVITE) on sofia profile 'default' for [0046812400090@65.0.92.207] from ip 91.134.176.96
2021-12-27 11:36:15.052272 98.77% [DEBUG] sofia.c:1527 Channel is already hungup.
2021-12-27 11:36:15.052272 98.77% [DEBUG] sofia.c:1527 Channel is already hungup.
2021-12-27 11:36:15.052272 98.77% [DEBUG] switch_core_state_machine.c:581 (sofia/default/101.22.56.152@65.0.92.207) Running State Change CS_HANGUP (Cur 1 Tot 32)
2021-12-27 11:36:15.052272 98.77% [DEBUG] switch_core_state_machine.c:844 (sofia/default/101.22.56.152@65.0.92.207) Callstate Change DOWN -> HANGUP
2021-12-27 11:36:15.052272 98.77% [DEBUG] switch_core_state_machine.c:846 (sofia/default/101.22.56.152@65.0.92.207) State HANGUP
2021-12-27 11:36:15.052272 98.77% [DEBUG] mod_sofia.c:468 Channel sofia/default/101.22.56.152@65.0.92.207 hanging up, cause: CALL_REJECTED
2021-12-27 11:36:15.052272 98.77% [DEBUG] switch_core_state_machine.c:59 sofia/default/101.22.56.152@65.0.92.207 Standard HANGUP, cause: CALL_REJECTED
2021-12-27 11:36:15.052272 98.77% [DEBUG] switch_core_state_machine.c:846 (sofia/default/101.22.56.152@65.0.92.207) State HANGUP going to sleep
2021-12-27 11:36:15.052272 98.77% [DEBUG] switch_core_state_machine.c:616 (sofia/default/101.22.56.152@65.0.92.207) State Change CS_HANGUP -> CS_REPORTING
2021-12-27 11:36:15.052272 98.77% [DEBUG] switch_core_state_machine.c:581 (sofia/default/101.22.56.152@65.0.92.207) Running State Change CS_REPORTING (Cur 1 Tot 32)
2021-12-27 11:36:15.052272 98.77% [DEBUG] switch_core_state_machine.c:932 (sofia/default/101.22.56.152@65.0.92.207) State REPORTING
2021-12-27 11:36:15.052272 98.77% [DEBUG] switch_core_state_machine.c:168 sofia/default/101.22.56.152@65.0.92.207 Standard REPORTING, cause: CALL_REJECTED
2021-12-27 11:36:15.052272 98.77% [DEBUG] switch_core_state_machine.c:932 (sofia/default/101.22.56.152@65.0.92.207) State REPORTING going to sleep
2021-12-27 11:36:15.052272 98.77% [DEBUG] switch_core_state_machine.c:607 (sofia/default/101.22.56.152@65.0.92.207) State Change CS_REPORTING -> CS_DESTROY
2021-12-27 11:36:15.052272 98.77% [DEBUG] switch_core_session.c:1753 Session 32 (sofia/default/101.22.56.152@65.0.92.207) Locked, Waiting on external entities
2021-12-27 11:36:15.052272 98.77% [NOTICE] switch_core_session.c:1771 Session 32 (sofia/default/101.22.56.152@65.0.92.207) Ended
2021-12-27 11:36:15.052272 98.77% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/101.22.56.152@65.0.92.207 [CS_DESTROY]
2021-12-27 11:36:15.052272 98.77% [DEBUG] switch_core_state_machine.c:735 (sofia/default/101.22.56.152@65.0.92.207) Running State Change CS_DESTROY (Cur 0 Tot 32)
2021-12-27 11:36:15.052272 98.77% [DEBUG] switch_core_state_machine.c:745 (sofia/default/101.22.56.152@65.0.92.207) State DESTROY
2021-12-27 11:36:15.052272 98.77% [DEBUG] mod_sofia.c:379 sofia/default/101.22.56.152@65.0.92.207 SOFIA DESTROY
2021-12-27 11:36:15.052272 98.77% [DEBUG] switch_core_state_machine.c:175 sofia/default/101.22.56.152@65.0.92.207 Standard DESTROY
2021-12-27 11:36:15.052272 98.77% [DEBUG] switch_core_state_machine.c:745 (sofia/default/101.22.56.152@65.0.92.207) State DESTROY going to sleep
2021-12-27 11:38:38.252299 99.73% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [9225270812@65.0.92.207] from ip 117.247.91.162
2021-12-27 11:38:38.532279 99.73% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [9225270812@65.0.92.207] from ip 117.247.91.162
freeswitch@ip-172-31-36-75>