freeswitch@beta> freeswitch@beta> freeswitch@beta> freeswitch@beta> 2023-04-28 10:16:51.059182 87.03% [NOTICE] switch_channel.c:1123 New Channel sofia/default/69464@astppqa603.astppbilling.org:5071 [3428156e-8f0d-4f08-960c-50d4312394f8] 2023-04-28 10:16:51.059182 87.03% [DEBUG] switch_core_state_machine.c:581 (sofia/default/69464@astppqa603.astppbilling.org:5071) Running State Change CS_NEW (Cur 1 Tot 334) 2023-04-28 10:16:51.059182 87.03% [INFO] sofia.c:10462 sofia/default/69464@astppqa603.astppbilling.org:5071 receiving invite from 103.240.35.46:60684 version: 1.10.7 -release 64bit call-id: OGU4Y2FiYTZlZGY3YWMyNGQ0ZDQ5M2QwMThhYzQxMDE. 2023-04-28 10:16:51.059182 87.03% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 103.240.35.46:0. 2023-04-28 10:16:51.059182 87.03% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [112233@astppqa603.astppbilling.org] from ip 103.240.35.46 2023-04-28 10:16:51.059182 87.03% [DEBUG] switch_core_state_machine.c:600 (sofia/default/69464@astppqa603.astppbilling.org:5071) State NEW 2023-04-28 10:16:51.059182 87.03% [DEBUG] sofia.c:2419 detaching session 3428156e-8f0d-4f08-960c-50d4312394f8 2023-04-28 10:16:51.659157 87.03% [DEBUG] sofia.c:2532 Re-attaching to session 3428156e-8f0d-4f08-960c-50d4312394f8 2023-04-28 10:16:51.679145 87.03% [INFO] sofia.c:10462 sofia/default/69464@astppqa603.astppbilling.org:5071 receiving invite from 103.240.35.46:60684 version: 1.10.7 -release 64bit call-id: OGU4Y2FiYTZlZGY3YWMyNGQ0ZDQ5M2QwMThhYzQxMDE. 2023-04-28 10:16:51.679145 87.03% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 103.240.35.46:0. 2023-04-28 10:16:51.699155 87.03% [DEBUG] sofia.c:11672 Setting NAT mode based on via received 2023-04-28 10:16:51.699155 87.03% [DEBUG] sofia.c:7499 Channel sofia/default/69464@astppqa603.astppbilling.org:5071 entering state [received][100] 2023-04-28 10:16:51.699155 87.03% [DEBUG] sofia.c:7509 Remote SDP: v=0 o=Z 0 0 IN IP4 103.240.35.46 s=Z c=IN IP4 103.240.35.46 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 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1] 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1] 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1] 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1] 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMA:8:8000:20:64000:1] 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMU:0:8000:20:64000:1] 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/69464@astppqa603.astppbilling.org:5071 PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_codec.c:111 sofia/default/69464@astppqa603.astppbilling.org:5071 Original read codec set to PCMA:8 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_media.c:5973 sofia/default/69464@astppqa603.astppbilling.org:5071 Set 2833 dtmf send payload to 101 recv payload to 101 2023-04-28 10:16:51.699155 87.03% [DEBUG] sofia.c:7933 (sofia/default/69464@astppqa603.astppbilling.org:5071) State Change CS_NEW -> CS_INIT 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:581 (sofia/default/69464@astppqa603.astppbilling.org:5071) Running State Change CS_INIT (Cur 1 Tot 334) 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:624 (sofia/default/69464@astppqa603.astppbilling.org:5071) State INIT 2023-04-28 10:16:51.699155 87.03% [DEBUG] mod_sofia.c:97 sofia/default/69464@astppqa603.astppbilling.org:5071 SOFIA INIT 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:40 sofia/default/69464@astppqa603.astppbilling.org:5071 Standard INIT 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:48 (sofia/default/69464@astppqa603.astppbilling.org:5071) State Change CS_INIT -> CS_ROUTING 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:624 (sofia/default/69464@astppqa603.astppbilling.org:5071) State INIT going to sleep 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:581 (sofia/default/69464@astppqa603.astppbilling.org:5071) Running State Change CS_ROUTING (Cur 1 Tot 334) 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_channel.c:2380 (sofia/default/69464@astppqa603.astppbilling.org:5071) Callstate Change DOWN -> RINGING 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:640 (sofia/default/69464@astppqa603.astppbilling.org:5071) State ROUTING 2023-04-28 10:16:51.699155 87.03% [DEBUG] mod_sofia.c:158 sofia/default/69464@astppqa603.astppbilling.org:5071 SOFIA ROUTING 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:230 sofia/default/69464@astppqa603.astppbilling.org:5071 Standard ROUTING 2023-04-28 10:16:51.699155 87.03% [INFO] mod_dialplan_xml.c:639 Processing 69464 <69464>->112233 in context default 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','international_prefixes','interconnect_code') 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2023-04-28 10:16:51.699155 87.03% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0 2023-04-28 10:16:51.699155 87.03% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 112233 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '112233' limit 1 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '112233' AND status=0 limit 1 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Generated XML:
Dialplan: sofia/default/69464@astppqa603.astppbilling.org:5071 parsing [default->112233] continue=false Dialplan: sofia/default/69464@astppqa603.astppbilling.org:5071 Regex (PASS) [112233] destination_number(112233) =~ /112233/ break=on-false Dialplan: sofia/default/69464@astppqa603.astppbilling.org:5071 Action log(INFO ASTPP - Calling Card Call) Dialplan: sofia/default/69464@astppqa603.astppbilling.org:5071 Action answer() Dialplan: sofia/default/69464@astppqa603.astppbilling.org:5071 Action sleep(2000) Dialplan: sofia/default/69464@astppqa603.astppbilling.org:5071 Action lua(astpp-callingcards.lua) 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:281 (sofia/default/69464@astppqa603.astppbilling.org:5071) State Change CS_ROUTING -> CS_EXECUTE 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:640 (sofia/default/69464@astppqa603.astppbilling.org:5071) State ROUTING going to sleep 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:581 (sofia/default/69464@astppqa603.astppbilling.org:5071) Running State Change CS_EXECUTE (Cur 1 Tot 334) 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:647 (sofia/default/69464@astppqa603.astppbilling.org:5071) State EXECUTE 2023-04-28 10:16:51.699155 87.03% [DEBUG] mod_sofia.c:213 sofia/default/69464@astppqa603.astppbilling.org:5071 SOFIA EXECUTE 2023-04-28 10:16:51.699155 87.03% [DEBUG] switch_core_state_machine.c:323 sofia/default/69464@astppqa603.astppbilling.org:5071 Standard EXECUTE EXECUTE [depth=0] sofia/default/69464@astppqa603.astppbilling.org:5071 log(INFO ASTPP - Calling Card Call) 2023-04-28 10:16:51.719149 87.03% [INFO] mod_dptools.c:1879 ASTPP - Calling Card Call EXECUTE [depth=0] sofia/default/69464@astppqa603.astppbilling.org:5071 answer() 2023-04-28 10:16:51.719149 87.03% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/69464@astppqa603.astppbilling.org:5071] 65.109.231.66 port 30096 -> 103.240.35.46 port 8000 codec: 8 ms: 20 2023-04-28 10:16:51.719149 87.03% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms 2023-04-28 10:16:51.719149 87.03% [DEBUG] switch_core_media.c:9089 sofia/default/69464@astppqa603.astppbilling.org:5071 Set 2833 dtmf send payload to 101 2023-04-28 10:16:51.719149 87.03% [DEBUG] switch_core_media.c:9096 sofia/default/69464@astppqa603.astppbilling.org:5071 Set 2833 dtmf receive payload to 101 2023-04-28 10:16:51.719149 87.03% [DEBUG] switch_core_media.c:9119 sofia/default/69464@astppqa603.astppbilling.org:5071 Set rtp dtmf delay to 40 2023-04-28 10:16:51.719149 87.03% [DEBUG] mod_sofia.c:913 Local SDP sofia/default/69464@astppqa603.astppbilling.org:5071: v=0 o=FreeSWITCH 1682646915 1682646916 IN IP4 65.109.231.66 s=FreeSWITCH c=IN IP4 65.109.231.66 t=0 0 m=audio 30096 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 2023-04-28 10:16:51.719149 87.03% [NOTICE] mod_dptools.c:1419 Channel [sofia/default/69464@astppqa603.astppbilling.org:5071] has been answered 2023-04-28 10:16:51.719149 87.03% [DEBUG] switch_channel.c:3950 (sofia/default/69464@astppqa603.astppbilling.org:5071) Callstate Change RINGING -> ACTIVE 2023-04-28 10:16:51.719149 87.03% [DEBUG] sofia.c:7499 Channel sofia/default/69464@astppqa603.astppbilling.org:5071 entering state [completed][200] EXECUTE [depth=0] sofia/default/69464@astppqa603.astppbilling.org:5071 sleep(2000) 2023-04-28 10:16:52.119158 86.97% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed. 2023-04-28 10:16:52.119158 86.97% [DEBUG] sofia.c:7499 Channel sofia/default/69464@astppqa603.astppbilling.org:5071 entering state [ready][200] EXECUTE [depth=0] sofia/default/69464@astppqa603.astppbilling.org:5071 lua(astpp-callingcards.lua) 2023-04-28 10:16:53.719206 86.93% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0 2023-04-28 10:16:53.719206 86.93% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','international_prefixes','interconnect_code') 2023-04-28 10:16:53.719206 86.93% [NOTICE] switch_cpp.cpp:1465 [ASTPP] SECTION 2023-04-28 10:16:53.719206 86.93% [DEBUG] switch_core_file.c:444 File /var/www/html/astpp/upload/astpp-welcome.wav sample rate 16000 doesn't match requested rate 8000 2023-04-28 10:16:53.719206 86.93% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms 2023-04-28 10:16:55.519166 86.70% [DEBUG] switch_ivr_play_say.c:2001 done playing file /var/www/html/astpp/upload/astpp-welcome.wav 2023-04-28 10:16:55.519166 86.70% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_ani] Query :SELECT * FROM ani_map WHERE number = 69464 2023-04-28 10:16:55.519166 86.70% [DEBUG] switch_core_file.c:444 File /usr/local/freeswitch/sounds/en/us/callie/astpp-accountnum.wav sample rate 16000 doesn't match requested rate 8000 2023-04-28 10:16:55.519166 86.70% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms 2023-04-28 10:16:58.519201 86.57% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-accountnum.wav 2023-04-28 10:17:01.339165 86.57% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [7046557419@astppqa605.astppbilling.org] from ip 103.240.35.46 2023-04-28 10:17:01.879136 86.57% [WARNING] sofia_reg.c:3223 Can't find user [7046557419@astppqa605.astppbilling.org] from 103.240.35.46 You must define a domain called 'astppqa605.astppbilling.org' in your directory and add a user with the id="7046557419" attribute and you must configure your device to use the proper domain in its authentication credentials. 2023-04-28 10:17:02.079143 86.53% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [7046557419@astppqa605.astppbilling.org] from ip 103.240.35.46 2023-04-28 10:17:10.659174 82.67% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 9:960 2023-04-28 10:17:10.659174 82.67% [INFO] switch_channel.c:527 RECV DTMF 9:960 2023-04-28 10:17:10.979149 82.67% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 7:960 2023-04-28 10:17:10.979149 82.67% [INFO] switch_channel.c:527 RECV DTMF 7:960 2023-04-28 10:17:11.379191 82.40% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 1:960 2023-04-28 10:17:11.379191 82.40% [INFO] switch_channel.c:527 RECV DTMF 1:960 2023-04-28 10:17:11.579158 82.40% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 2:960 2023-04-28 10:17:11.579158 82.40% [INFO] switch_channel.c:527 RECV DTMF 2:960 2023-04-28 10:17:13.539186 82.37% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 8:960 2023-04-28 10:17:13.539186 82.37% [INFO] switch_channel.c:527 RECV DTMF 8:960 2023-04-28 10:17:14.039187 82.60% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 0:960 2023-04-28 10:17:14.039187 82.60% [INFO] switch_channel.c:527 RECV DTMF 0:960 2023-04-28 10:17:15.539195 83.10% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 6:960 2023-04-28 10:17:15.539195 83.10% [INFO] switch_channel.c:527 RECV DTMF 6:960 2023-04-28 10:17:16.379169 83.13% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [7046557419@astppqa605.astppbilling.org] from ip 103.240.35.46 2023-04-28 10:17:16.919155 83.13% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 1:960 2023-04-28 10:17:16.919155 83.13% [INFO] switch_channel.c:527 RECV DTMF 1:960 2023-04-28 10:17:16.919155 83.13% [WARNING] sofia_reg.c:3223 Can't find user [7046557419@astppqa605.astppbilling.org] from 103.240.35.46 You must define a domain called 'astppqa605.astppbilling.org' in your directory and add a user with the id="7046557419" attribute and you must configure your device to use the proper domain in its authentication credentials. 2023-04-28 10:17:17.119184 82.97% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [7046557419@astppqa605.astppbilling.org] from ip 103.240.35.46 2023-04-28 10:17:17.159151 82.97% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 1:960 2023-04-28 10:17:17.159151 82.97% [INFO] switch_channel.c:527 RECV DTMF 1:960 2023-04-28 10:17:17.599158 82.97% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 9:960 2023-04-28 10:17:17.599158 82.97% [INFO] switch_channel.c:527 RECV DTMF 9:960 2023-04-28 10:17:19.019183 82.17% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF #:960 2023-04-28 10:17:19.019183 82.17% [INFO] switch_channel.c:527 RECV DTMF #:960 2023-04-28 10:17:19.019183 82.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Got DTMF digits: 9712806119 2023-04-28 10:17:19.019183 82.17% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT *,(select currencyrate from currency where id=currency_id) as currencyrate FROM accounts WHERE number = "9712806119" AND status=0 AND deleted=0 limit 1 2023-04-28 10:17:19.019183 82.17% [DEBUG] switch_core_file.c:444 File /usr/local/freeswitch/sounds/en/us/callie/astpp-pleasepin.wav sample rate 16000 doesn't match requested rate 8000 2023-04-28 10:17:19.019183 82.17% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms 2023-04-28 10:17:21.739160 82.60% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-pleasepin.wav 2023-04-28 10:17:24.039181 82.50% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 5:960 2023-04-28 10:17:24.039181 82.50% [INFO] switch_channel.c:527 RECV DTMF 5:960 2023-04-28 10:17:24.259161 82.50% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 6:960 2023-04-28 10:17:24.259161 82.50% [INFO] switch_channel.c:527 RECV DTMF 6:960 2023-04-28 10:17:26.359151 83.40% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 9:960 2023-04-28 10:17:26.359151 83.40% [INFO] switch_channel.c:527 RECV DTMF 9:960 2023-04-28 10:17:26.719149 83.40% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 8:960 2023-04-28 10:17:26.719149 83.40% [INFO] switch_channel.c:527 RECV DTMF 8:960 2023-04-28 10:17:28.139211 83.00% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 9:960 2023-04-28 10:17:28.139211 83.00% [INFO] switch_channel.c:527 RECV DTMF 9:960 2023-04-28 10:17:28.859192 83.00% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 6:960 2023-04-28 10:17:28.859192 83.00% [INFO] switch_channel.c:527 RECV DTMF 6:960 2023-04-28 10:17:30.299173 83.53% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF #:960 2023-04-28 10:17:30.299173 83.53% [INFO] switch_channel.c:527 RECV DTMF #:960 2023-04-28 10:17:30.299173 83.53% [DEBUG] switch_cpp.cpp:1465 [ASTPP] We Recieved A Pin Number : 569896 2023-04-28 10:17:30.299173 83.53% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [validate_card_usage] Query :SELECT DATE_FORMAT('2033-04-21 05:19:22' , '%Y%m%d%H%i%s') AS expiry 2023-04-28 10:17:30.299173 83.53% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [validate_card_usage] Query :SELECT DATE_FORMAT('2023-04-28 10:17:30' , '%Y%m%d%H%i%s') AS expiry 2023-04-28 10:17:30.299173 83.53% [DEBUG] switch_core_file.c:444 File /usr/local/freeswitch/sounds/en/us/callie/astpp-register.wav sample rate 16000 doesn't match requested rate 8000 2023-04-28 10:17:30.299173 83.53% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms 2023-04-28 10:17:33.119148 84.73% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-register.wav 2023-04-28 10:17:33.659177 84.73% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [7046557419@astppqa605.astppbilling.org] from ip 103.240.35.46 2023-04-28 10:17:34.259140 85.60% [WARNING] sofia_reg.c:3223 Can't find user [7046557419@astppqa605.astppbilling.org] from 103.240.35.46 You must define a domain called 'astppqa605.astppbilling.org' in your directory and add a user with the id="7046557419" attribute and you must configure your device to use the proper domain in its authentication credentials. 2023-04-28 10:17:34.379150 85.60% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [7046557419@astppqa605.astppbilling.org] from ip 103.240.35.46 2023-04-28 10:17:38.119169 87.37% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 9712806119] 2023-04-28 10:17:38.119169 87.37% [DEBUG] switch_core_file.c:444 File /usr/local/freeswitch/sounds/en/us/callie/astpp-this-card-has-a-balance-of.wav sample rate 16000 doesn't match requested rate 8000 2023-04-28 10:17:38.119169 87.37% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms 2023-04-28 10:17:39.439154 87.30% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-this-card-has-a-balance-of.wav 2023-04-28 10:17:39.439154 87.30% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Play Amount First Part] Query :188 2023-04-28 10:17:39.439154 87.30% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Play Amount Second Part] Query :89 EXECUTE [depth=0] sofia/default/69464@astppqa603.astppbilling.org:5071 say(en number pronounced 188) 2023-04-28 10:17:39.439154 87.30% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms 2023-04-28 10:17:41.379258 87.23% [DEBUG] switch_ivr_play_say.c:2001 done playing file file_string://digits/1.wav!digits/hundred.wav!digits/80.wav!digits/8.wav 2023-04-28 10:17:41.379258 87.23% [DEBUG] switch_core_file.c:444 File /usr/local/freeswitch/sounds/en/us/callie/astpp-point.wav sample rate 16000 doesn't match requested rate 8000 2023-04-28 10:17:41.379258 87.23% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms 2023-04-28 10:17:41.939172 87.23% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-point.wav EXECUTE [depth=0] sofia/default/69464@astppqa603.astppbilling.org:5071 say(en number pronounced 89) 2023-04-28 10:17:41.939172 87.23% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms 2023-04-28 10:17:43.039164 86.27% [DEBUG] switch_ivr_play_say.c:2001 done playing file file_string://digits/80.wav!digits/9.wav 2023-04-28 10:17:43.039164 86.27% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [HP:access_number_call_flow[0:Go Direct Dial,1:Go IVR] : 1] 2023-04-28 10:17:43.039164 86.27% [INFO] switch_cpp.cpp:1465 [ASTPP] Press 1 for make call, Press 2 for Recharge using Coupon 2023-04-28 10:17:43.039164 86.27% [WARNING] mod_sndfile.c:281 Error Opening File [/usr/local/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.] 2023-04-28 10:17:43.039164 86.27% [WARNING] mod_sndfile.c:281 Error Opening File [/usr/local/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.] 2023-04-28 10:17:43.039164 86.27% [WARNING] mod_sndfile.c:281 Error Opening File [/usr/local/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.] 2023-04-28 10:17:43.039164 86.27% [INFO] switch_cpp.cpp:1465 [ASTPP] ivr_custom_dtmf:: 2023-04-28 10:17:43.039164 86.27% [INFO] switch_cpp.cpp:1465 [ASTPP] Press 1 for make call, Press 2 for Recharge using Coupon 2023-04-28 10:17:43.039164 86.27% [WARNING] mod_sndfile.c:281 Error Opening File [/usr/local/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.] 2023-04-28 10:17:43.039164 86.27% [WARNING] mod_sndfile.c:281 Error Opening File [/usr/local/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.] 2023-04-28 10:17:43.039164 86.27% [WARNING] mod_sndfile.c:281 Error Opening File [/usr/local/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.] 2023-04-28 10:17:43.039164 86.27% [INFO] switch_cpp.cpp:1465 [ASTPP] ivr_custom_dtmf:: 2023-04-28 10:17:43.039164 86.27% [INFO] switch_cpp.cpp:1465 [ASTPP] Press 1 for make call, Press 2 for Recharge using Coupon 2023-04-28 10:17:43.039164 86.27% [WARNING] mod_sndfile.c:281 Error Opening File [/usr/local/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.] 2023-04-28 10:17:43.039164 86.27% [WARNING] mod_sndfile.c:281 Error Opening File [/usr/local/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.] 2023-04-28 10:17:43.039164 86.27% [WARNING] mod_sndfile.c:281 Error Opening File [/usr/local/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.] 2023-04-28 10:17:43.039164 86.27% [INFO] switch_cpp.cpp:1465 [ASTPP] ivr_custom_dtmf:: 2023-04-28 10:17:43.039164 86.27% [DEBUG] switch_core_file.c:444 File /usr/local/freeswitch/sounds/en/us/callie/astpp-goodbye.wav sample rate 16000 doesn't match requested rate 8000 2023-04-28 10:17:43.039164 86.27% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-goodbye.wav 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_cpp.cpp:749 CoreSession::hangup 2023-04-28 10:17:43.839200 86.27% [NOTICE] switch_cpp.cpp:751 Hangup sofia/default/69464@astppqa603.astppbilling.org:5071 [CS_EXECUTE] [NORMAL_CLEARING] 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Got DTMF Digits: retries:0 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Got DTMF Digits: retries:1 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Got DTMF Digits: retries:2 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_cpp.cpp:749 CoreSession::hangup 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_cpp.cpp:1209 sofia/default/69464@astppqa603.astppbilling.org:5071 destroy/unlink session from object 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_core_session.c:2983 sofia/default/69464@astppqa603.astppbilling.org:5071 skip receive message [PHONE_EVENT] (channel is hungup already) 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_core_state_machine.c:647 (sofia/default/69464@astppqa603.astppbilling.org:5071) State EXECUTE going to sleep 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/69464@astppqa603.astppbilling.org:5071) Running State Change CS_HANGUP (Cur 1 Tot 334) 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_core_state_machine.c:844 (sofia/default/69464@astppqa603.astppbilling.org:5071) Callstate Change ACTIVE -> HANGUP 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_core_state_machine.c:846 (sofia/default/69464@astppqa603.astppbilling.org:5071) State HANGUP 2023-04-28 10:17:43.839200 86.27% [DEBUG] mod_sofia.c:468 Channel sofia/default/69464@astppqa603.astppbilling.org:5071 hanging up, cause: NORMAL_CLEARING 2023-04-28 10:17:43.839200 86.27% [DEBUG] mod_sofia.c:522 Sending BYE to sofia/default/69464@astppqa603.astppbilling.org:5071 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_core_state_machine.c:59 sofia/default/69464@astppqa603.astppbilling.org:5071 Standard HANGUP, cause: NORMAL_CLEARING 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_core_state_machine.c:846 (sofia/default/69464@astppqa603.astppbilling.org:5071) State HANGUP going to sleep 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_core_state_machine.c:616 (sofia/default/69464@astppqa603.astppbilling.org:5071) State Change CS_HANGUP -> CS_REPORTING 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/69464@astppqa603.astppbilling.org:5071) Running State Change CS_REPORTING (Cur 1 Tot 334) 2023-04-28 10:17:43.839200 86.27% [DEBUG] switch_core_state_machine.c:932 (sofia/default/69464@astppqa603.astppbilling.org:5071) State REPORTING 2023-04-28 10:17:43.839200 86.27% [INFO] mod_json_cdr.c:271 Process [3428156e-8f0d-4f08-960c-50d4312394f8.cdr.json] 2023-04-28 10:17:43.859159 86.27% [DEBUG] switch_core_state_machine.c:168 sofia/default/69464@astppqa603.astppbilling.org:5071 Standard REPORTING, cause: NORMAL_CLEARING 2023-04-28 10:17:43.859159 86.27% [DEBUG] switch_core_state_machine.c:932 (sofia/default/69464@astppqa603.astppbilling.org:5071) State REPORTING going to sleep 2023-04-28 10:17:43.859159 86.27% [DEBUG] switch_core_state_machine.c:607 (sofia/default/69464@astppqa603.astppbilling.org:5071) State Change CS_REPORTING -> CS_DESTROY 2023-04-28 10:17:43.859159 86.27% [DEBUG] switch_core_session.c:1753 Session 334 (sofia/default/69464@astppqa603.astppbilling.org:5071) Locked, Waiting on external entities 2023-04-28 10:17:43.859159 86.27% [NOTICE] switch_core_session.c:1771 Session 334 (sofia/default/69464@astppqa603.astppbilling.org:5071) Ended 2023-04-28 10:17:43.859159 86.27% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/69464@astppqa603.astppbilling.org:5071 [CS_DESTROY] 2023-04-28 10:17:43.859159 86.27% [DEBUG] switch_core_state_machine.c:735 (sofia/default/69464@astppqa603.astppbilling.org:5071) Running State Change CS_DESTROY (Cur 0 Tot 334) 2023-04-28 10:17:43.859159 86.27% [DEBUG] switch_core_state_machine.c:745 (sofia/default/69464@astppqa603.astppbilling.org:5071) State DESTROY 2023-04-28 10:17:43.859159 86.27% [DEBUG] mod_sofia.c:379 sofia/default/69464@astppqa603.astppbilling.org:5071 SOFIA DESTROY 2023-04-28 10:17:43.859159 86.27% [DEBUG] switch_core_state_machine.c:175 sofia/default/69464@astppqa603.astppbilling.org:5071 Standard DESTROY 2023-04-28 10:17:43.859159 86.27% [DEBUG] switch_core_state_machine.c:745 (sofia/default/69464@astppqa603.astppbilling.org:5071) State DESTROY going to sleep 2023-04-28 10:18:05.959184 80.47% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [7046557419@astppqa605.astppbilling.org] from ip 103.240.35.46