2024-01-21 18:12:20.970716 95.70% [NOTICE] switch_channel.c:1142 New Channel sofia/default/4727985745@pk1betav602.astppbilling.org:5078 [0a41f4c0-85cd-4d42-9918-f85d60f90de5] 2024-01-21 18:12:20.970716 95.70% [DEBUG] switch_core_state_machine.c:581 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) Running State Change CS_NEW (Cur 1 Tot 16) 2024-01-21 18:12:20.970716 95.70% [INFO] sofia.c:10459 sofia/default/4727985745@pk1betav602.astppbilling.org:5078 receiving invite from 106.206.200.54:56643 version: 1.10.10-release 64bit call-id: OWZhYTdmZDRiNzM2YjEzNjczMDFmZGUxYTJjYzZkZGQ. 2024-01-21 18:12:20.970716 95.70% [DEBUG] sofia.c:10553 verifying acl "default" for ip/port 106.206.200.54:0. 2024-01-21 18:12:20.970716 95.70% [DEBUG] sofia.c:2419 detaching session 0a41f4c0-85cd-4d42-9918-f85d60f90de5 2024-01-21 18:12:20.970716 95.70% [WARNING] sofia_reg.c:1842 SIP auth challenge (INVITE) on sofia profile 'default' for [90770@pk1betav602.astppbilling.org] from ip 106.206.200.54 2024-01-21 18:12:20.970716 95.70% [DEBUG] switch_core_state_machine.c:600 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) State NEW 2024-01-21 18:12:21.510739 95.73% [DEBUG] sofia.c:2532 Re-attaching to session 0a41f4c0-85cd-4d42-9918-f85d60f90de5 2024-01-21 18:12:21.510739 95.73% [INFO] sofia.c:10459 sofia/default/4727985745@pk1betav602.astppbilling.org:5078 receiving invite from 106.206.200.54:56643 version: 1.10.10-release 64bit call-id: OWZhYTdmZDRiNzM2YjEzNjczMDFmZGUxYTJjYzZkZGQ. 2024-01-21 18:12:21.510739 95.73% [DEBUG] sofia.c:10553 verifying acl "default" for ip/port 106.206.200.54:0. 2024-01-21 18:12:21.510739 95.73% [DEBUG] sofia.c:11674 Setting NAT mode based on via received 2024-01-21 18:12:21.510739 95.73% [DEBUG] sofia.c:7493 Channel sofia/default/4727985745@pk1betav602.astppbilling.org:5078 entering state [received][100] 2024-01-21 18:12:21.510739 95.73% [DEBUG] sofia.c:7503 Remote SDP: v=0 o=Z 0 0 IN IP4 106.206.200.54 s=Z c=IN IP4 106.206.200.54 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 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1] 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1] 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1] 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1] 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMA:8:8000:20:64000:1] 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMU:0:8000:20:64000:1] 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_media.c:3734 Set Codec sofia/default/4727985745@pk1betav602.astppbilling.org:5078 PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_codec.c:111 sofia/default/4727985745@pk1betav602.astppbilling.org:5078 Original read codec set to PCMA:8 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_media.c:5856 sofia/default/4727985745@pk1betav602.astppbilling.org:5078 Set 2833 dtmf send payload to 101 recv payload to 101 2024-01-21 18:12:21.510739 95.73% [DEBUG] sofia.c:7927 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) State Change CS_NEW -> CS_INIT 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) Running State Change CS_INIT (Cur 1 Tot 16) 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_state_machine.c:624 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) State INIT 2024-01-21 18:12:21.510739 95.73% [DEBUG] mod_sofia.c:97 sofia/default/4727985745@pk1betav602.astppbilling.org:5078 SOFIA INIT 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_state_machine.c:40 sofia/default/4727985745@pk1betav602.astppbilling.org:5078 Standard INIT 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_state_machine.c:48 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) State Change CS_INIT -> CS_ROUTING 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_state_machine.c:624 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) State INIT going to sleep 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) Running State Change CS_ROUTING (Cur 1 Tot 16) 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_channel.c:2399 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) Callstate Change DOWN -> RINGING 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_state_machine.c:640 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) State ROUTING 2024-01-21 18:12:21.510739 95.73% [DEBUG] mod_sofia.c:158 sofia/default/4727985745@pk1betav602.astppbilling.org:5078 SOFIA ROUTING 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_state_machine.c:230 sofia/default/4727985745@pk1betav602.astppbilling.org:5078 Standard ROUTING 2024-01-21 18:12:21.510739 95.73% [INFO] mod_dialplan_xml.c:639 Processing 4727985745 <4727985745>->90770 in context default 2024-01-21 18:12:21.510739 95.73% [INFO] switch_cpp.cpp:1465 [ASTPP] USING CACHE 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2024-01-21 18:12:21.510739 95.73% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 90770 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_ACCESS_NUMBER]1.0 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_ACCESS_NUMBER]1.1 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_ACCESS_NUMBER]1.2 :: CALL IS ACCESS NUMBER 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Generated XML:
Dialplan: sofia/default/4727985745@pk1betav602.astppbilling.org:5078 parsing [default->90770] continue=false Dialplan: sofia/default/4727985745@pk1betav602.astppbilling.org:5078 Regex (PASS) [90770] destination_number(90770) =~ /90770/ break=on-false Dialplan: sofia/default/4727985745@pk1betav602.astppbilling.org:5078 Action log(INFO ASTPP - Calling Card Call) Dialplan: sofia/default/4727985745@pk1betav602.astppbilling.org:5078 Action answer() Dialplan: sofia/default/4727985745@pk1betav602.astppbilling.org:5078 Action sleep(2000) Dialplan: sofia/default/4727985745@pk1betav602.astppbilling.org:5078 Action set(variable_sip_to_port=5078) Dialplan: sofia/default/4727985745@pk1betav602.astppbilling.org:5078 Action set(variable_sip_to_host=pk1betav602.astppbilling.org) Dialplan: sofia/default/4727985745@pk1betav602.astppbilling.org:5078 Action lua(astpp-callingcards.lua) 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_state_machine.c:281 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) State Change CS_ROUTING -> CS_EXECUTE 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_state_machine.c:640 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) State ROUTING going to sleep 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) Running State Change CS_EXECUTE (Cur 1 Tot 16) 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_state_machine.c:647 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) State EXECUTE 2024-01-21 18:12:21.510739 95.73% [DEBUG] mod_sofia.c:213 sofia/default/4727985745@pk1betav602.astppbilling.org:5078 SOFIA EXECUTE 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_state_machine.c:323 sofia/default/4727985745@pk1betav602.astppbilling.org:5078 Standard EXECUTE EXECUTE [depth=0] sofia/default/4727985745@pk1betav602.astppbilling.org:5078 log(INFO ASTPP - Calling Card Call) 2024-01-21 18:12:21.510739 95.73% [INFO] mod_dptools.c:1865 ASTPP - Calling Card Call EXECUTE [depth=0] sofia/default/4727985745@pk1betav602.astppbilling.org:5078 answer() 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/4727985745@pk1betav602.astppbilling.org:5078] 172.31.0.164 port 20908 -> 106.206.200.54 port 8000 codec: 8 ms: 20 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_rtp.c:4373 Starting timer [soft] 160 bytes per 20ms 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_media.c:8972 sofia/default/4727985745@pk1betav602.astppbilling.org:5078 Set 2833 dtmf send payload to 101 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_media.c:8979 sofia/default/4727985745@pk1betav602.astppbilling.org:5078 Set 2833 dtmf receive payload to 101 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_core_media.c:9002 sofia/default/4727985745@pk1betav602.astppbilling.org:5078 Set rtp dtmf delay to 40 2024-01-21 18:12:21.510739 95.73% [DEBUG] mod_sofia.c:914 Local SDP sofia/default/4727985745@pk1betav602.astppbilling.org:5078: v=0 o=FreeSWITCH 1705839833 1705839834 IN IP4 15.206.212.57 s=FreeSWITCH c=IN IP4 15.206.212.57 t=0 0 m=audio 20908 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 2024-01-21 18:12:21.510739 95.73% [DEBUG] sofia.c:7493 Channel sofia/default/4727985745@pk1betav602.astppbilling.org:5078 entering state [completed][200] 2024-01-21 18:12:21.510739 95.73% [NOTICE] mod_dptools.c:1406 Channel [sofia/default/4727985745@pk1betav602.astppbilling.org:5078] has been answered 2024-01-21 18:12:21.510739 95.73% [DEBUG] switch_channel.c:3912 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) Callstate Change RINGING -> ACTIVE EXECUTE [depth=0] sofia/default/4727985745@pk1betav602.astppbilling.org:5078 sleep(2000) 2024-01-21 18:12:21.950715 95.73% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed. 2024-01-21 18:12:21.950715 95.73% [DEBUG] sofia.c:7493 Channel sofia/default/4727985745@pk1betav602.astppbilling.org:5078 entering state [ready][200] EXECUTE [depth=0] sofia/default/4727985745@pk1betav602.astppbilling.org:5078 set(variable_sip_to_port=5078) 2024-01-21 18:12:23.550729 94.83% [DEBUG] mod_dptools.c:1671 SET sofia/default/4727985745@pk1betav602.astppbilling.org:5078 [variable_sip_to_port]=[5078] EXECUTE [depth=0] sofia/default/4727985745@pk1betav602.astppbilling.org:5078 set(variable_sip_to_host=pk1betav602.astppbilling.org) 2024-01-21 18:12:23.550729 94.83% [DEBUG] mod_dptools.c:1671 SET sofia/default/4727985745@pk1betav602.astppbilling.org:5078 [variable_sip_to_host]=[pk1betav602.astppbilling.org] EXECUTE [depth=0] sofia/default/4727985745@pk1betav602.astppbilling.org:5078 lua(astpp-callingcards.lua) 2024-01-21 18:12:23.550729 94.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0 2024-01-21 18:12:23.550729 94.83% [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') 2024-01-21 18:12:23.550729 94.83% [NOTICE] switch_cpp.cpp:1465 [ASTPP] SECTION 2024-01-21 18:12:23.550729 94.83% [DEBUG] switch_core_file.c:444 File /var/www/html/astpp/upload/astpp-welcome.wav sample rate 16000 doesn't match requested rate 8000 2024-01-21 18:12:23.550729 94.83% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms 2024-01-21 18:12:25.350731 94.83% [DEBUG] switch_ivr_play_say.c:2010 done playing file /var/www/html/astpp/upload/astpp-welcome.wav 2024-01-21 18:12:25.350731 94.83% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_ani] Query :SELECT * FROM ani_map WHERE number = 4727985745 2024-01-21 18:12:25.350731 94.83% [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 2024-01-21 18:12:25.350731 94.83% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms 2024-01-21 18:12:28.350728 94.73% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-accountnum.wav 2024-01-21 18:12:29.310725 94.70% [WARNING] sofia_reg.c:1842 SIP auth challenge (REGISTER) on sofia profile 'default' for [4727985745@pk1betav602.astppbilling.org] from ip 106.206.200.54 2024-01-21 18:12:29.670726 94.77% [WARNING] sofia_reg.c:1842 SIP auth challenge (INVITE) on sofia profile 'default' for [4727985745@pk1betav602.astppbilling.org] from ip 106.206.200.54 2024-01-21 18:12:29.770737 94.77% [WARNING] sofia_reg.c:1842 SIP auth challenge (INVITE) on sofia profile 'default' for [90770@pk1betav602.astppbilling.org] from ip 106.206.200.54 2024-01-21 18:12:32.630729 96.87% [WARNING] sofia_reg.c:1842 SIP auth challenge (INVITE) on sofia profile 'default' for [9815982288@pk1betav602.astppbilling.org] from ip 106.206.200.54 2024-01-21 18:12:32.750733 96.87% [WARNING] sofia_reg.c:3210 Can't find user [9815982288@pk1betav602.astppbilling.org] from 106.206.200.54 You must define a domain called 'pk1betav602.astppbilling.org' in your directory and add a user with the id="9815982288" attribute and you must configure your device to use the proper domain in its authentication credentials. 2024-01-21 18:12:32.890734 96.87% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 4:960 2024-01-21 18:12:32.890734 96.87% [INFO] switch_channel.c:528 RECV DTMF 4:960 2024-01-21 18:12:33.470739 96.97% [WARNING] sofia_reg.c:1842 SIP auth challenge (INVITE) on sofia profile 'default' for [9707800844@pk1betav602.astppbilling.org] from ip 106.206.200.54 2024-01-21 18:12:33.630735 96.97% [WARNING] sofia_reg.c:3210 Can't find user [9707800844@pk1betav602.astppbilling.org] from 106.206.200.54 You must define a domain called 'pk1betav602.astppbilling.org' in your directory and add a user with the id="9707800844" attribute and you must configure your device to use the proper domain in its authentication credentials. 2024-01-21 18:12:33.630735 96.97% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 7:960 2024-01-21 18:12:33.630735 96.97% [INFO] switch_channel.c:528 RECV DTMF 7:960 2024-01-21 18:12:33.910736 96.97% [WARNING] sofia_reg.c:1842 SIP auth challenge (INVITE) on sofia profile 'default' for [9707820666@pk1betav602.astppbilling.org] from ip 106.206.200.54 2024-01-21 18:12:34.030736 96.97% [WARNING] sofia_reg.c:3210 Can't find user [9707820666@pk1betav602.astppbilling.org] from 106.206.200.54 You must define a domain called 'pk1betav602.astppbilling.org' in your directory and add a user with the id="9707820666" attribute and you must configure your device to use the proper domain in its authentication credentials. 2024-01-21 18:12:35.070735 96.90% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 2:960 2024-01-21 18:12:35.070735 96.90% [INFO] switch_channel.c:528 RECV DTMF 2:960 2024-01-21 18:12:35.650726 96.83% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 7:960 2024-01-21 18:12:35.650726 96.83% [INFO] switch_channel.c:528 RECV DTMF 7:960 2024-01-21 18:12:37.570729 96.90% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 9:960 2024-01-21 18:12:37.570729 96.90% [INFO] switch_channel.c:528 RECV DTMF 9:960 2024-01-21 18:12:38.950727 96.97% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 8:960 2024-01-21 18:12:38.950727 96.97% [INFO] switch_channel.c:528 RECV DTMF 8:960 2024-01-21 18:12:40.810730 97.23% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 5:960 2024-01-21 18:12:40.810730 97.23% [INFO] switch_channel.c:528 RECV DTMF 5:960 2024-01-21 18:12:41.770728 97.53% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 7:960 2024-01-21 18:12:41.770728 97.53% [INFO] switch_channel.c:528 RECV DTMF 7:960 2024-01-21 18:12:43.370736 97.57% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 4:960 2024-01-21 18:12:43.370736 97.57% [INFO] switch_channel.c:528 RECV DTMF 4:960 2024-01-21 18:12:43.770732 97.53% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 5:960 2024-01-21 18:12:43.770732 97.53% [INFO] switch_channel.c:528 RECV DTMF 5:960 2024-01-21 18:12:44.810716 97.53% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF #:960 2024-01-21 18:12:44.810716 97.53% [INFO] switch_channel.c:528 RECV DTMF #:960 2024-01-21 18:12:44.810716 97.53% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Got DTMF digits: 4727985745 2024-01-21 18:12:44.810716 97.53% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT *,(select currencyrate from currency where id=currency_id) as currencyrate FROM accounts WHERE number = "4727985745" AND status=0 AND deleted=0 limit 1 2024-01-21 18:12:44.830859 97.53% [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 2024-01-21 18:12:44.830859 97.53% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms 2024-01-21 18:12:47.510728 97.50% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-pleasepin.wav 2024-01-21 18:12:48.930735 97.47% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 9:960 2024-01-21 18:12:48.930735 97.47% [INFO] switch_channel.c:528 RECV DTMF 9:960 2024-01-21 18:12:49.370725 97.47% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 0:960 2024-01-21 18:12:49.370725 97.47% [INFO] switch_channel.c:528 RECV DTMF 0:960 2024-01-21 18:12:49.950727 97.53% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 7:960 2024-01-21 18:12:49.950727 97.53% [INFO] switch_channel.c:528 RECV DTMF 7:960 2024-01-21 18:12:50.670768 97.57% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 0:960 2024-01-21 18:12:50.670768 97.57% [INFO] switch_channel.c:528 RECV DTMF 0:960 2024-01-21 18:12:51.050725 97.57% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 0:960 2024-01-21 18:12:51.050725 97.57% [INFO] switch_channel.c:528 RECV DTMF 0:960 2024-01-21 18:12:51.490733 97.57% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 0:960 2024-01-21 18:12:51.490733 97.57% [INFO] switch_channel.c:528 RECV DTMF 0:960 2024-01-21 18:12:52.670731 98.30% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF #:960 2024-01-21 18:12:52.670731 98.30% [INFO] switch_channel.c:528 RECV DTMF #:960 2024-01-21 18:12:52.670731 98.30% [DEBUG] switch_cpp.cpp:1465 [ASTPP] We Recieved A Pin Number : 907000 2024-01-21 18:12:52.670731 98.30% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [validate_card_usage] Query :SELECT DATE_FORMAT('2046-07-25 11:26:24' , '%Y%m%d%H%i%s') AS expiry 2024-01-21 18:12:52.670731 98.30% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [validate_card_usage] Query :SELECT DATE_FORMAT('2024-01-21 18:12:52' , '%Y%m%d%H%i%s') AS expiry 2024-01-21 18:12:52.670731 98.30% [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 2024-01-21 18:12:52.670731 98.30% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms 2024-01-21 18:12:55.490729 98.50% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-register.wav 2024-01-21 18:12:56.010741 98.50% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 1:960 2024-01-21 18:12:56.010741 98.50% [INFO] switch_channel.c:528 RECV DTMF 1:960 2024-01-21 18:12:56.010741 98.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [SAVE_ANI] Query :INSERT INTO ani_map (number,accountid,status,creation_date,last_modified_date) VALUES ('4727985745','2',0,'2024-01-21 18:12:56','2024-01-21 18:12:56') 2024-01-21 18:12:56.010741 98.50% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 4727985745] 2024-01-21 18:12:56.010741 98.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 2024-01-21 18:12:56.010741 98.50% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms 2024-01-21 18:12:57.330727 98.43% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-this-card-has-a-balance-of.wav 2024-01-21 18:12:57.330727 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Play Amount First Part] Query :82958 2024-01-21 18:12:57.330727 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Play Amount Second Part] Query :75 EXECUTE [depth=0] sofia/default/4727985745@pk1betav602.astppbilling.org:5078 say(en number pronounced 82958) 2024-01-21 18:12:57.350752 98.43% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms 2024-01-21 18:13:00.610728 98.43% [DEBUG] switch_ivr_play_say.c:2010 done playing file file_string://digits/80.wav!digits/2.wav!digits/thousand.wav!digits/9.wav!digits/hundred.wav!digits/50.wav!digits/8.wav 2024-01-21 18:13:00.610728 98.43% [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 2024-01-21 18:13:00.610728 98.43% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms 2024-01-21 18:13:01.170730 98.43% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-point.wav EXECUTE [depth=0] sofia/default/4727985745@pk1betav602.astppbilling.org:5078 say(en number pronounced 75) 2024-01-21 18:13:01.170730 98.43% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms 2024-01-21 18:13:02.490734 96.17% [DEBUG] switch_ivr_play_say.c:2010 done playing file file_string://digits/70.wav!digits/5.wav 2024-01-21 18:13:02.490734 96.17% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [HP:access_number_call_flow[0:Go Direct Dial,1:Go IVR] : 0] 2024-01-21 18:13:02.490734 96.17% [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 2024-01-21 18:13:02.490734 96.17% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms 2024-01-21 18:13:06.190729 95.97% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-phonenum.wav 2024-01-21 18:13:07.510754 95.97% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 1:960 2024-01-21 18:13:07.510754 95.97% [INFO] switch_channel.c:528 RECV DTMF 1:960 2024-01-21 18:13:07.990727 95.97% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 2:960 2024-01-21 18:13:07.990727 95.97% [INFO] switch_channel.c:528 RECV DTMF 2:960 2024-01-21 18:13:08.490728 95.90% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 0:960 2024-01-21 18:13:08.490728 95.90% [INFO] switch_channel.c:528 RECV DTMF 0:960 2024-01-21 18:13:08.670731 95.90% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 4:960 2024-01-21 18:13:08.670731 95.90% [INFO] switch_channel.c:528 RECV DTMF 4:960 2024-01-21 18:13:08.830708 95.90% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 0:960 2024-01-21 18:13:08.830708 95.90% [INFO] switch_channel.c:528 RECV DTMF 0:960 2024-01-21 18:13:09.010717 95.90% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 4:960 2024-01-21 18:13:09.010717 95.90% [INFO] switch_channel.c:528 RECV DTMF 4:960 2024-01-21 18:13:09.190741 95.90% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 5:960 2024-01-21 18:13:09.190741 95.90% [INFO] switch_channel.c:528 RECV DTMF 5:960 2024-01-21 18:13:09.310717 95.90% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 0:960 2024-01-21 18:13:09.310717 95.90% [INFO] switch_channel.c:528 RECV DTMF 0:960 2024-01-21 18:13:09.490725 95.90% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 5:960 2024-01-21 18:13:09.490725 95.90% [INFO] switch_channel.c:528 RECV DTMF 5:960 2024-01-21 18:13:09.670734 95.90% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 0:960 2024-01-21 18:13:09.670734 95.90% [INFO] switch_channel.c:528 RECV DTMF 0:960 2024-01-21 18:13:09.810767 95.90% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 5:960 2024-01-21 18:13:09.810767 95.90% [INFO] switch_channel.c:528 RECV DTMF 5:960 2024-01-21 18:13:09.990728 95.90% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 0:960 2024-01-21 18:13:09.990728 95.90% [INFO] switch_channel.c:528 RECV DTMF 0:960 2024-01-21 18:13:10.990727 95.90% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF #:960 2024-01-21 18:13:10.990727 95.90% [INFO] switch_channel.c:528 RECV DTMF #:960 2024-01-21 18:13:10.990727 95.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [CHECK Destination] Dialed Destination Number :120404505050 2024-01-21 18:13:10.990727 95.90% [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 = '120404505050' OR number = '12040450505' OR number = '1204045050' OR number = '120404505' OR number = '12040450' OR number = '1204045' OR number = '120404' OR number = '12040' OR number = '1204' OR number = '120' OR number = '12' OR number = '1' OR number ='--') OR (number = '120404505050*' OR number = '12040450505*' OR number = '1204045050*' OR number = '120404505*' OR number = '12040450*' OR number = '1204045*' OR number = '120404*' OR number = '12040*' OR number = '1204*' OR number = '120*' OR number = '12*' OR number = '1*' OR number ='--') OR (number = '4727985745' OR number = '472798574' OR number = '47279857' OR number = '4727985' OR number = '472798' OR number = '47279' OR number = '4727' OR number = '472' OR number = '47' OR number = '4' OR number ='--') OR (number = '4727985745*' OR number = '472798574*' OR number = '47279857*' OR number = '4727985*' OR number = '472798*' OR number = '47279*' OR number = '4727*' OR number = '472*' OR number = '47*' OR number = '4*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC 2024-01-21 18:13:10.990727 95.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1 EXECUTE [depth=0] sofia/default/4727985745@pk1betav602.astppbilling.org:5078 set(daily_call_credit_limit=0.00000) 2024-01-21 18:13:10.990727 95.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/4727985745@pk1betav602.astppbilling.org:5078 [daily_call_credit_limit]=[0.00000] EXECUTE [depth=0] sofia/default/4727985745@pk1betav602.astppbilling.org:5078 export(daily_call_credit_limit=0.00000) 2024-01-21 18:13:10.990727 95.90% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [daily_call_credit_limit]=[0.00000] EXECUTE [depth=0] sofia/default/4727985745@pk1betav602.astppbilling.org:5078 set(daily_call_used_limit=1.40000) 2024-01-21 18:13:10.990727 95.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/4727985745@pk1betav602.astppbilling.org:5078 [daily_call_used_limit]=[1.40000] EXECUTE [depth=0] sofia/default/4727985745@pk1betav602.astppbilling.org:5078 export(daily_call_used_limit=1.40000) 2024-01-21 18:13:10.990727 95.90% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [daily_call_used_limit]=[1.40000] EXECUTE [depth=0] sofia/default/4727985745@pk1betav602.astppbilling.org:5078 set(monthly_call_credit_limit=1.00000) 2024-01-21 18:13:10.990727 95.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/4727985745@pk1betav602.astppbilling.org:5078 [monthly_call_credit_limit]=[1.00000] EXECUTE [depth=0] sofia/default/4727985745@pk1betav602.astppbilling.org:5078 export(monthly_call_credit_limit=1.00000) 2024-01-21 18:13:10.990727 95.90% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [monthly_call_credit_limit]=[1.00000] EXECUTE [depth=0] sofia/default/4727985745@pk1betav602.astppbilling.org:5078 set(monthly_call_used_limit=1.40000) 2024-01-21 18:13:10.990727 95.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/4727985745@pk1betav602.astppbilling.org:5078 [monthly_call_used_limit]=[1.40000] EXECUTE [depth=0] sofia/default/4727985745@pk1betav602.astppbilling.org:5078 export(monthly_call_used_limit=1.40000) 2024-01-21 18:13:10.990727 95.90% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [monthly_call_used_limit]=[1.40000] 2024-01-21 18:13:10.990727 95.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Post Cdrs : 0 2024-01-21 18:13:10.990727 95.90% [ERR] mod_lua.cpp:202 /usr/local/freeswitch/scripts/astpp/scripts/astpp.xml.lua:930: attempt to concatenate a nil value stack traceback: /usr/local/freeswitch/scripts/astpp/scripts/astpp.xml.lua:930: in function 'xml_header' /usr/local/freeswitch/scripts/astpp/scripts/astpp.xml.lua:1111: in function 'error_xml_without_cdr' ...switch/scripts/astpp/lib/astpp.callingcard.functions.lua:1009: in function 'process_destination' /usr/local/freeswitch/scripts/astpp-callingcards.lua:122: in main chunk 2024-01-21 18:13:10.990727 95.90% [DEBUG] switch_cpp.cpp:1209 sofia/default/4727985745@pk1betav602.astppbilling.org:5078 destroy/unlink session from object 2024-01-21 18:13:10.990727 95.90% [NOTICE] switch_core_state_machine.c:382 sofia/default/4727985745@pk1betav602.astppbilling.org:5078 has executed the last dialplan instruction, hanging up. 2024-01-21 18:13:10.990727 95.90% [NOTICE] switch_core_state_machine.c:384 Hangup sofia/default/4727985745@pk1betav602.astppbilling.org:5078 [CS_EXECUTE] [NORMAL_CLEARING] 2024-01-21 18:13:10.990727 95.90% [DEBUG] switch_core_state_machine.c:647 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) State EXECUTE going to sleep 2024-01-21 18:13:10.990727 95.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) Running State Change CS_HANGUP (Cur 1 Tot 16) 2024-01-21 18:13:10.990727 95.90% [DEBUG] switch_core_state_machine.c:844 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) Callstate Change ACTIVE -> HANGUP 2024-01-21 18:13:10.990727 95.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) State HANGUP 2024-01-21 18:13:10.990727 95.90% [DEBUG] mod_sofia.c:469 Channel sofia/default/4727985745@pk1betav602.astppbilling.org:5078 hanging up, cause: NORMAL_CLEARING 2024-01-21 18:13:10.990727 95.90% [DEBUG] mod_sofia.c:523 Sending BYE to sofia/default/4727985745@pk1betav602.astppbilling.org:5078 2024-01-21 18:13:10.990727 95.90% [DEBUG] switch_core_state_machine.c:59 sofia/default/4727985745@pk1betav602.astppbilling.org:5078 Standard HANGUP, cause: NORMAL_CLEARING 2024-01-21 18:13:10.990727 95.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) State HANGUP going to sleep 2024-01-21 18:13:10.990727 95.90% [DEBUG] switch_core_state_machine.c:616 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) State Change CS_HANGUP -> CS_REPORTING 2024-01-21 18:13:10.990727 95.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) Running State Change CS_REPORTING (Cur 1 Tot 16) 2024-01-21 18:13:10.990727 95.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) State REPORTING 2024-01-21 18:13:10.990727 95.90% [INFO] mod_json_cdr.c:272 Process [0a41f4c0-85cd-4d42-9918-f85d60f90de5.cdr.json] 2024-01-21 18:13:10.990727 95.90% [DEBUG] switch_core_state_machine.c:168 sofia/default/4727985745@pk1betav602.astppbilling.org:5078 Standard REPORTING, cause: NORMAL_CLEARING 2024-01-21 18:13:10.990727 95.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) State REPORTING going to sleep 2024-01-21 18:13:10.990727 95.90% [DEBUG] switch_core_state_machine.c:607 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) State Change CS_REPORTING -> CS_DESTROY 2024-01-21 18:13:10.990727 95.90% [DEBUG] switch_core_session.c:1744 Session 16 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) Locked, Waiting on external entities 2024-01-21 18:13:10.990727 95.90% [NOTICE] switch_core_session.c:1762 Session 16 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) Ended 2024-01-21 18:13:10.990727 95.90% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/4727985745@pk1betav602.astppbilling.org:5078 [CS_DESTROY] 2024-01-21 18:13:10.990727 95.90% [DEBUG] switch_core_state_machine.c:735 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) Running State Change CS_DESTROY (Cur 0 Tot 16) 2024-01-21 18:13:10.990727 95.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) State DESTROY 2024-01-21 18:13:10.990727 95.90% [DEBUG] mod_sofia.c:380 sofia/default/4727985745@pk1betav602.astppbilling.org:5078 SOFIA DESTROY 2024-01-21 18:13:10.990727 95.90% [DEBUG] switch_core_state_machine.c:175 sofia/default/4727985745@pk1betav602.astppbilling.org:5078 Standard DESTROY 2024-01-21 18:13:10.990727 95.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/4727985745@pk1betav602.astppbilling.org:5078) State DESTROY going to sleep