+OK log level [7] freeswitch@ip-172-31-0-164> 2024-01-18 03:55:32.812162 98.43% [NOTICE] switch_channel.c:1142 New Channel sofia/default/9707800844@pk1betav602.astppbilling.org:5078 [1b6dccce-436b-4f93-a0ec-9959b0b45b7f] 2024-01-18 03:55:32.812162 98.43% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) Running State Change CS_NEW (Cur 1 Tot 63) 2024-01-18 03:55:32.812162 98.43% [INFO] sofia.c:10459 sofia/default/9707800844@pk1betav602.astppbilling.org:5078 receiving invite from 152.58.188.26:32948 version: 1.10.10-release 64bit call-id: ZWMwMmE0OWVmY2E1Yzg4NzBkYzg1OWVlZDRhYzczYWQ. 2024-01-18 03:55:32.812162 98.43% [DEBUG] sofia.c:10553 verifying acl "default" for ip/port 152.58.188.26:0. 2024-01-18 03:55:32.812162 98.43% [DEBUG] sofia.c:2419 detaching session 1b6dccce-436b-4f93-a0ec-9959b0b45b7f 2024-01-18 03:55:32.812162 98.43% [WARNING] sofia_reg.c:1842 SIP auth challenge (INVITE) on sofia profile 'default' for [91077@pk1betav602.astppbilling.org] from ip 152.58.188.26 2024-01-18 03:55:32.812162 98.43% [DEBUG] switch_core_state_machine.c:600 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) State NEW 2024-01-18 03:55:32.952164 98.43% [DEBUG] sofia.c:2532 Re-attaching to session 1b6dccce-436b-4f93-a0ec-9959b0b45b7f 2024-01-18 03:55:32.972146 98.43% [INFO] sofia.c:10459 sofia/default/9707800844@pk1betav602.astppbilling.org:5078 receiving invite from 152.58.188.26:32948 version: 1.10.10-release 64bit call-id: ZWMwMmE0OWVmY2E1Yzg4NzBkYzg1OWVlZDRhYzczYWQ. 2024-01-18 03:55:32.972146 98.43% [DEBUG] sofia.c:10553 verifying acl "default" for ip/port 152.58.188.26:0. 2024-01-18 03:55:32.972146 98.43% [DEBUG] sofia.c:11674 Setting NAT mode based on via received 2024-01-18 03:55:32.972146 98.43% [DEBUG] sofia.c:7493 Channel sofia/default/9707800844@pk1betav602.astppbilling.org:5078 entering state [received][100] 2024-01-18 03:55:32.972146 98.43% [DEBUG] sofia.c:7503 Remote SDP: v=0 o=Z 0 0 IN IP4 192.168.172.40 s=Z c=IN IP4 192.168.172.40 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-18 03:55:32.972146 98.43% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1] 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1] 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1] 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1] 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMA:8:8000:20:64000:1] 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMU:0:8000:20:64000:1] 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_core_media.c:3734 Set Codec sofia/default/9707800844@pk1betav602.astppbilling.org:5078 PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_core_codec.c:111 sofia/default/9707800844@pk1betav602.astppbilling.org:5078 Original read codec set to PCMA:8 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_core_media.c:5856 sofia/default/9707800844@pk1betav602.astppbilling.org:5078 Set 2833 dtmf send payload to 101 recv payload to 101 2024-01-18 03:55:32.972146 98.43% [DEBUG] sofia.c:7927 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) State Change CS_NEW -> CS_INIT 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) Running State Change CS_INIT (Cur 1 Tot 63) 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) State INIT 2024-01-18 03:55:32.972146 98.43% [DEBUG] mod_sofia.c:97 sofia/default/9707800844@pk1betav602.astppbilling.org:5078 SOFIA INIT 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_core_state_machine.c:40 sofia/default/9707800844@pk1betav602.astppbilling.org:5078 Standard INIT 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_core_state_machine.c:48 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) State Change CS_INIT -> CS_ROUTING 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) State INIT going to sleep 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) Running State Change CS_ROUTING (Cur 1 Tot 63) 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_channel.c:2399 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) Callstate Change DOWN -> RINGING 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) State ROUTING 2024-01-18 03:55:32.972146 98.43% [DEBUG] mod_sofia.c:158 sofia/default/9707800844@pk1betav602.astppbilling.org:5078 SOFIA ROUTING 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_core_state_machine.c:230 sofia/default/9707800844@pk1betav602.astppbilling.org:5078 Standard ROUTING 2024-01-18 03:55:32.972146 98.43% [INFO] mod_dialplan_xml.c:639 Processing 9707800844 <9707800844>->91077 in context default 2024-01-18 03:55:32.972146 98.43% [INFO] switch_cpp.cpp:1465 [ASTPP] USING CACHE 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0 2024-01-18 03:55:32.972146 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2024-01-18 03:55:32.992113 98.43% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 91077 2024-01-18 03:55:32.992113 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_ACCESS_NUMBER]1.0 2024-01-18 03:55:32.992113 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_ACCESS_NUMBER]1.1 2024-01-18 03:55:32.992113 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_ACCESS_NUMBER]1.2 :: CALL IS ACCESS NUMBER 2024-01-18 03:55:32.992113 98.43% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Generated XML:
Dialplan: sofia/default/9707800844@pk1betav602.astppbilling.org:5078 parsing [default->91077] continue=false Dialplan: sofia/default/9707800844@pk1betav602.astppbilling.org:5078 Regex (PASS) [91077] destination_number(91077) =~ /91077/ break=on-false Dialplan: sofia/default/9707800844@pk1betav602.astppbilling.org:5078 Action log(INFO ASTPP - Calling Card Call) Dialplan: sofia/default/9707800844@pk1betav602.astppbilling.org:5078 Action answer() Dialplan: sofia/default/9707800844@pk1betav602.astppbilling.org:5078 Action sleep(2000) Dialplan: sofia/default/9707800844@pk1betav602.astppbilling.org:5078 Action set(variable_sip_to_port=5078) Dialplan: sofia/default/9707800844@pk1betav602.astppbilling.org:5078 Action set(variable_sip_to_host=pk1betav602.astppbilling.org) Dialplan: sofia/default/9707800844@pk1betav602.astppbilling.org:5078 Action lua(astpp-callingcards.lua) 2024-01-18 03:55:32.992113 98.43% [DEBUG] switch_core_state_machine.c:281 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) State Change CS_ROUTING -> CS_EXECUTE 2024-01-18 03:55:32.992113 98.43% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) State ROUTING going to sleep 2024-01-18 03:55:32.992113 98.43% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) Running State Change CS_EXECUTE (Cur 1 Tot 63) 2024-01-18 03:55:32.992113 98.43% [DEBUG] switch_core_state_machine.c:647 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) State EXECUTE 2024-01-18 03:55:32.992113 98.43% [DEBUG] mod_sofia.c:213 sofia/default/9707800844@pk1betav602.astppbilling.org:5078 SOFIA EXECUTE 2024-01-18 03:55:32.992113 98.43% [DEBUG] switch_core_state_machine.c:323 sofia/default/9707800844@pk1betav602.astppbilling.org:5078 Standard EXECUTE EXECUTE [depth=0] sofia/default/9707800844@pk1betav602.astppbilling.org:5078 log(INFO ASTPP - Calling Card Call) 2024-01-18 03:55:32.992113 98.43% [INFO] mod_dptools.c:1865 ASTPP - Calling Card Call EXECUTE [depth=0] sofia/default/9707800844@pk1betav602.astppbilling.org:5078 answer() 2024-01-18 03:55:32.992113 98.43% [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/9707800844@pk1betav602.astppbilling.org:5078] 172.31.0.164 port 16586 -> 192.168.172.40 port 8000 codec: 8 ms: 20 2024-01-18 03:55:32.992113 98.43% [DEBUG] switch_rtp.c:4373 Starting timer [soft] 160 bytes per 20ms 2024-01-18 03:55:32.992113 98.43% [DEBUG] switch_core_media.c:8972 sofia/default/9707800844@pk1betav602.astppbilling.org:5078 Set 2833 dtmf send payload to 101 2024-01-18 03:55:32.992113 98.43% [DEBUG] switch_core_media.c:8979 sofia/default/9707800844@pk1betav602.astppbilling.org:5078 Set 2833 dtmf receive payload to 101 2024-01-18 03:55:32.992113 98.43% [DEBUG] switch_core_media.c:9002 sofia/default/9707800844@pk1betav602.astppbilling.org:5078 Set rtp dtmf delay to 40 2024-01-18 03:55:32.992113 98.43% [DEBUG] mod_sofia.c:914 Local SDP sofia/default/9707800844@pk1betav602.astppbilling.org:5078: v=0 o=FreeSWITCH 1705533546 1705533547 IN IP4 15.206.212.57 s=FreeSWITCH c=IN IP4 15.206.212.57 t=0 0 m=audio 16586 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-18 03:55:32.992113 98.43% [DEBUG] sofia.c:7493 Channel sofia/default/9707800844@pk1betav602.astppbilling.org:5078 entering state [completed][200] 2024-01-18 03:55:32.992113 98.43% [NOTICE] mod_dptools.c:1406 Channel [sofia/default/9707800844@pk1betav602.astppbilling.org:5078] has been answered 2024-01-18 03:55:32.992113 98.43% [DEBUG] switch_channel.c:3912 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) Callstate Change RINGING -> ACTIVE EXECUTE [depth=0] sofia/default/9707800844@pk1betav602.astppbilling.org:5078 sleep(2000) 2024-01-18 03:55:33.152134 98.43% [DEBUG] sofia.c:7493 Channel sofia/default/9707800844@pk1betav602.astppbilling.org:5078 entering state [ready][200] 2024-01-18 03:55:33.412135 98.43% [INFO] switch_rtp.c:7463 Auto Changing audio port from 192.168.172.40:8000 to 152.58.188.26:13505 EXECUTE [depth=0] sofia/default/9707800844@pk1betav602.astppbilling.org:5078 set(variable_sip_to_port=5078) 2024-01-18 03:55:34.992139 98.37% [DEBUG] mod_dptools.c:1671 SET sofia/default/9707800844@pk1betav602.astppbilling.org:5078 [variable_sip_to_port]=[5078] EXECUTE [depth=0] sofia/default/9707800844@pk1betav602.astppbilling.org:5078 set(variable_sip_to_host=pk1betav602.astppbilling.org) 2024-01-18 03:55:34.992139 98.37% [DEBUG] mod_dptools.c:1671 SET sofia/default/9707800844@pk1betav602.astppbilling.org:5078 [variable_sip_to_host]=[pk1betav602.astppbilling.org] EXECUTE [depth=0] sofia/default/9707800844@pk1betav602.astppbilling.org:5078 lua(astpp-callingcards.lua) 2024-01-18 03:55:34.992139 98.37% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0 2024-01-18 03:55:34.992139 98.37% [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-18 03:55:34.992139 98.37% [NOTICE] switch_cpp.cpp:1465 [ASTPP] SECTION 2024-01-18 03:55:34.992139 98.37% [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-18 03:55:34.992139 98.37% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms 2024-01-18 03:55:36.792137 98.33% [DEBUG] switch_ivr_play_say.c:2010 done playing file /var/www/html/astpp/upload/astpp-welcome.wav 2024-01-18 03:55:36.792137 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_ani] Query :SELECT * FROM ani_map WHERE number = 9707800844 2024-01-18 03:55:36.792137 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT *,(select currencyrate from currency where id=currency_id) as currencyrate FROM accounts WHERE (number = "5" OR id="5") AND status=0 AND deleted=0 limit 1 2024-01-18 03:55:36.792137 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [validate_card_usage] Query :SELECT DATE_FORMAT('2034-01-15 03:39:41' , '%Y%m%d%H%i%s') AS expiry 2024-01-18 03:55:36.792137 98.33% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [validate_card_usage] Query :SELECT DATE_FORMAT('2024-01-18 03:55:36' , '%Y%m%d%H%i%s') AS expiry 2024-01-18 03:55:36.792137 98.33% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 9707800844] 2024-01-18 03:55:36.792137 98.33% [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-18 03:55:36.792137 98.33% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms 2024-01-18 03:55:38.112159 98.27% [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-18 03:55:38.112159 98.27% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Play Amount First Part] Query :49998 2024-01-18 03:55:38.112159 98.27% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Play Amount Second Part] Query :2 EXECUTE [depth=0] sofia/default/9707800844@pk1betav602.astppbilling.org:5078 say(en number pronounced 49998) 2024-01-18 03:55:38.112159 98.27% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms 2024-01-18 03:55:41.452116 98.47% [DEBUG] switch_ivr_play_say.c:2010 done playing file file_string://digits/40.wav!digits/9.wav!digits/thousand.wav!digits/9.wav!digits/hundred.wav!digits/90.wav!digits/8.wav 2024-01-18 03:55:41.452116 98.47% [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-18 03:55:41.452116 98.47% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms 2024-01-18 03:55:42.012139 98.40% [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/9707800844@pk1betav602.astppbilling.org:5078 say(en number pronounced 2) 2024-01-18 03:55:42.012139 98.40% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms 2024-01-18 03:55:42.652119 98.40% [DEBUG] switch_ivr_play_say.c:2010 done playing file file_string://digits/2.wav 2024-01-18 03:55:42.652119 98.40% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [HP:access_number_call_flow[0:Go Direct Dial,1:Go IVR] : 0] 2024-01-18 03:55:42.652119 98.40% [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-18 03:55:42.652119 98.40% [DEBUG] switch_ivr_play_say.c:1561 Codec Activated L16@8000hz 1 channels 20ms 2024-01-18 03:55:46.352128 98.37% [DEBUG] switch_ivr_play_say.c:2010 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-phonenum.wav 2024-01-18 03:55:46.612142 98.37% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 1:960 2024-01-18 03:55:46.612142 98.37% [INFO] switch_channel.c:528 RECV DTMF 1:960 2024-01-18 03:55:46.972134 98.40% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 0:960 2024-01-18 03:55:46.972134 98.40% [INFO] switch_channel.c:528 RECV DTMF 0:960 2024-01-18 03:55:47.132139 98.40% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 4:960 2024-01-18 03:55:47.132139 98.40% [INFO] switch_channel.c:528 RECV DTMF 4:960 2024-01-18 03:55:47.312115 98.40% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 0:960 2024-01-18 03:55:47.312115 98.40% [INFO] switch_channel.c:528 RECV DTMF 0:960 2024-01-18 03:55:47.452137 98.40% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 5:960 2024-01-18 03:55:47.452137 98.40% [INFO] switch_channel.c:528 RECV DTMF 5:960 2024-01-18 03:55:47.632176 98.40% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 0:960 2024-01-18 03:55:47.632176 98.40% [INFO] switch_channel.c:528 RECV DTMF 0:960 2024-01-18 03:55:47.792115 98.40% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 5:960 2024-01-18 03:55:47.792115 98.40% [INFO] switch_channel.c:528 RECV DTMF 5:960 2024-01-18 03:55:47.972162 98.33% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 0:960 2024-01-18 03:55:47.972162 98.33% [INFO] switch_channel.c:528 RECV DTMF 0:960 2024-01-18 03:55:48.132160 98.33% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 5:960 2024-01-18 03:55:48.132160 98.33% [INFO] switch_channel.c:528 RECV DTMF 5:960 2024-01-18 03:55:48.292125 98.33% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 0:960 2024-01-18 03:55:48.292125 98.33% [INFO] switch_channel.c:528 RECV DTMF 0:960 2024-01-18 03:55:48.492136 98.33% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 5:960 2024-01-18 03:55:48.492136 98.33% [INFO] switch_channel.c:528 RECV DTMF 5:960 2024-01-18 03:55:48.652149 98.33% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF 0:960 2024-01-18 03:55:48.652149 98.33% [INFO] switch_channel.c:528 RECV DTMF 0:960 2024-01-18 03:55:48.932142 98.30% [DEBUG] switch_rtp.c:7748 RTP RECV DTMF #:960 2024-01-18 03:55:48.932142 98.30% [INFO] switch_channel.c:528 RECV DTMF #:960 2024-01-18 03:55:48.932142 98.30% [INFO] switch_cpp.cpp:1465 [ASTPP] [CHECK Destination] Dialed Destination Number :104050505050 2024-01-18 03:55:48.932142 98.30% [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 = '104050505050' OR number = '10405050505' OR number = '1040505050' OR number = '104050505' OR number = '10405050' OR number = '1040505' OR number = '104050' OR number = '10405' OR number = '1040' OR number = '104' OR number = '10' OR number = '1' OR number ='--') OR (number = '104050505050*' OR number = '10405050505*' OR number = '1040505050*' OR number = '104050505*' OR number = '10405050*' OR number = '1040505*' OR number = '104050*' OR number = '10405*' OR number = '1040*' OR number = '104*' OR number = '10*' OR number = '1*' OR number ='--') OR (number = '9707800844' OR number = '970780084' OR number = '97078008' OR number = '9707800' OR number = '970780' OR number = '97078' OR number = '9707' OR number = '970' OR number = '97' OR number = '9' OR number ='--') OR (number = '9707800844*' OR number = '970780084*' OR number = '97078008*' OR number = '9707800*' OR number = '970780*' OR number = '97078*' OR number = '9707*' OR number = '970*' OR number = '97*' OR number = '9*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC 2024-01-18 03:55:48.932142 98.30% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1 EXECUTE [depth=0] sofia/default/9707800844@pk1betav602.astppbilling.org:5078 set(daily_call_credit_limit=1.00000) 2024-01-18 03:55:48.932142 98.30% [DEBUG] mod_dptools.c:1671 SET sofia/default/9707800844@pk1betav602.astppbilling.org:5078 [daily_call_credit_limit]=[1.00000] EXECUTE [depth=0] sofia/default/9707800844@pk1betav602.astppbilling.org:5078 export(daily_call_credit_limit=1.00000) 2024-01-18 03:55:48.932142 98.30% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [daily_call_credit_limit]=[1.00000] EXECUTE [depth=0] sofia/default/9707800844@pk1betav602.astppbilling.org:5078 set(daily_call_used_limit=1.80000) 2024-01-18 03:55:48.932142 98.30% [DEBUG] mod_dptools.c:1671 SET sofia/default/9707800844@pk1betav602.astppbilling.org:5078 [daily_call_used_limit]=[1.80000] EXECUTE [depth=0] sofia/default/9707800844@pk1betav602.astppbilling.org:5078 export(daily_call_used_limit=1.80000) 2024-01-18 03:55:48.932142 98.30% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [daily_call_used_limit]=[1.80000] EXECUTE [depth=0] sofia/default/9707800844@pk1betav602.astppbilling.org:5078 set(monthly_call_credit_limit=5.00000) 2024-01-18 03:55:48.932142 98.30% [DEBUG] mod_dptools.c:1671 SET sofia/default/9707800844@pk1betav602.astppbilling.org:5078 [monthly_call_credit_limit]=[5.00000] EXECUTE [depth=0] sofia/default/9707800844@pk1betav602.astppbilling.org:5078 export(monthly_call_credit_limit=5.00000) 2024-01-18 03:55:48.932142 98.30% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [monthly_call_credit_limit]=[5.00000] EXECUTE [depth=0] sofia/default/9707800844@pk1betav602.astppbilling.org:5078 set(monthly_call_used_limit=1.80000) 2024-01-18 03:55:48.932142 98.30% [DEBUG] mod_dptools.c:1671 SET sofia/default/9707800844@pk1betav602.astppbilling.org:5078 [monthly_call_used_limit]=[1.80000] EXECUTE [depth=0] sofia/default/9707800844@pk1betav602.astppbilling.org:5078 export(monthly_call_used_limit=1.80000) 2024-01-18 03:55:48.932142 98.30% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [monthly_call_used_limit]=[1.80000] 2024-01-18 03:55:48.932142 98.30% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Post Cdrs : 0 2024-01-18 03:55:48.932142 98.30% [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' ./astpp/lib/astpp.callingcard.functions.lua:1003: in function 'process_destination' ./astpp-callingcards.lua:122: in main chunk 2024-01-18 03:55:48.932142 98.30% [DEBUG] switch_cpp.cpp:1209 sofia/default/9707800844@pk1betav602.astppbilling.org:5078 destroy/unlink session from object 2024-01-18 03:55:48.932142 98.30% [NOTICE] switch_core_state_machine.c:382 sofia/default/9707800844@pk1betav602.astppbilling.org:5078 has executed the last dialplan instruction, hanging up. 2024-01-18 03:55:48.932142 98.30% [NOTICE] switch_core_state_machine.c:384 Hangup sofia/default/9707800844@pk1betav602.astppbilling.org:5078 [CS_EXECUTE] [NORMAL_CLEARING] 2024-01-18 03:55:48.932142 98.30% [DEBUG] switch_core_state_machine.c:647 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) State EXECUTE going to sleep 2024-01-18 03:55:48.932142 98.30% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) Running State Change CS_HANGUP (Cur 1 Tot 63) 2024-01-18 03:55:48.932142 98.30% [DEBUG] switch_core_state_machine.c:844 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) Callstate Change ACTIVE -> HANGUP 2024-01-18 03:55:48.932142 98.30% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) State HANGUP 2024-01-18 03:55:48.932142 98.30% [DEBUG] mod_sofia.c:469 Channel sofia/default/9707800844@pk1betav602.astppbilling.org:5078 hanging up, cause: NORMAL_CLEARING 2024-01-18 03:55:48.932142 98.30% [DEBUG] mod_sofia.c:523 Sending BYE to sofia/default/9707800844@pk1betav602.astppbilling.org:5078 2024-01-18 03:55:48.932142 98.30% [DEBUG] switch_core_state_machine.c:59 sofia/default/9707800844@pk1betav602.astppbilling.org:5078 Standard HANGUP, cause: NORMAL_CLEARING 2024-01-18 03:55:48.932142 98.30% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) State HANGUP going to sleep 2024-01-18 03:55:48.932142 98.30% [DEBUG] switch_core_state_machine.c:616 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) State Change CS_HANGUP -> CS_REPORTING 2024-01-18 03:55:48.932142 98.30% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) Running State Change CS_REPORTING (Cur 1 Tot 63) 2024-01-18 03:55:48.932142 98.30% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) State REPORTING 2024-01-18 03:55:48.932142 98.30% [INFO] mod_json_cdr.c:272 Process [1b6dccce-436b-4f93-a0ec-9959b0b45b7f.cdr.json] 2024-01-18 03:55:48.952116 98.30% [DEBUG] switch_core_state_machine.c:168 sofia/default/9707800844@pk1betav602.astppbilling.org:5078 Standard REPORTING, cause: NORMAL_CLEARING 2024-01-18 03:55:48.952116 98.30% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) State REPORTING going to sleep 2024-01-18 03:55:48.952116 98.30% [DEBUG] switch_core_state_machine.c:607 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) State Change CS_REPORTING -> CS_DESTROY 2024-01-18 03:55:48.952116 98.30% [DEBUG] switch_core_session.c:1744 Session 63 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) Locked, Waiting on external entities 2024-01-18 03:55:48.952116 98.30% [NOTICE] switch_core_session.c:1762 Session 63 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) Ended 2024-01-18 03:55:48.952116 98.30% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/9707800844@pk1betav602.astppbilling.org:5078 [CS_DESTROY] 2024-01-18 03:55:48.952116 98.30% [DEBUG] switch_core_state_machine.c:735 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) Running State Change CS_DESTROY (Cur 0 Tot 63) 2024-01-18 03:55:48.952116 98.30% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) State DESTROY 2024-01-18 03:55:48.952116 98.30% [DEBUG] mod_sofia.c:380 sofia/default/9707800844@pk1betav602.astppbilling.org:5078 SOFIA DESTROY 2024-01-18 03:55:48.952116 98.30% [DEBUG] switch_core_state_machine.c:175 sofia/default/9707800844@pk1betav602.astppbilling.org:5078 Standard DESTROY 2024-01-18 03:55:48.952116 98.30% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9707800844@pk1betav602.astppbilling.org:5078) State DESTROY going to sleep freeswitch@ip-172-31-0-164> freeswitch@ip-172-31-0-164> freeswitch@ip-172-31-0-164> /exit You have new mail in /var/mail/root root@ip-172-31-0-164:/opt/ASTPP/freeswitch/scripts_deb9/astpp/scripts# root@ip-172-31-0-164:/opt/ASTPP/freeswitch/scripts_deb9/astpp/scripts# root@ip-172-31-0-164:/opt/ASTPP/freeswitch/scripts_deb9/astpp/scripts#