2021-02-17 10:06:53.104254 [NOTICE] switch_channel.c:1118 New Channel sofia/default/3855458128@65.0.179.216 [81ad0253-bd09-4431-b9be-902fc100875e]
2021-02-17 10:06:53.104254 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3855458128@65.0.179.216) Running State Change CS_NEW (Cur 2 Tot 82326)
2021-02-17 10:06:53.104254 [DEBUG] sofia.c:10280 sofia/default/3855458128@65.0.179.216 receiving invite from 117.247.91.162:4585 version: 1.10.5 -release-17-25569c1631 64bit
2021-02-17 10:06:53.104254 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 117.247.91.162:0.
2021-02-17 10:06:53.104254 [DEBUG] sofia.c:2434 detaching session 81ad0253-bd09-4431-b9be-902fc100875e
2021-02-17 10:06:53.104254 [WARNING] sofia_reg.c:1794 SIP auth challenge (INVITE) on sofia profile 'default' for [180001111@65.0.179.216] from ip 117.247.91.162
2021-02-17 10:06:53.104254 [DEBUG] switch_core_state_machine.c:604 (sofia/default/3855458128@65.0.179.216) State NEW
2021-02-17 10:06:53.129873 [DEBUG] sofia.c:2544 Re-attaching to session 81ad0253-bd09-4431-b9be-902fc100875e
2021-02-17 10:06:53.129873 [DEBUG] sofia.c:10280 sofia/default/3855458128@65.0.179.216 receiving invite from 117.247.91.162:4585 version: 1.10.5 -release-17-25569c1631 64bit
2021-02-17 10:06:53.129873 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 117.247.91.162:0.
2021-02-17 10:06:53.164227 [DEBUG] sofia.c:7326 Channel sofia/default/3855458128@65.0.179.216 entering state [received][100]
2021-02-17 10:06:53.164227 [DEBUG] sofia.c:7336 Remote SDP:
v=0
o=3855458128 3883 1107 IN IP4 117.247.91.162
s=Talk
c=IN IP4 117.247.91.162
t=0 0
m=audio 7078 RTP/AVP 124 111 110 0 8 101
a=rtpmap:124 opus/48000
a=fmtp:124 useinbandfec=1; usedtx=1
a=rtpmap:111 speex/16000
a=fmtp:111 vbr=on
a=rtpmap:110 speex/8000
a=fmtp:110 vbr=on
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-11
m=video 9078 RTP/AVP 103 99
a=rtpmap:103 VP8/90000
a=rtpmap:99 MP4V-ES/90000
a=fmtp:99 profile-level-id=3
2021-02-17 10:06:53.164227 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [opus:124:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
2021-02-17 10:06:53.164227 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [opus:124:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
2021-02-17 10:06:53.164227 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [speex:111:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2021-02-17 10:06:53.164227 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [speex:111:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2021-02-17 10:06:53.164227 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
2021-02-17 10:06:53.164227 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
2021-02-17 10:06:53.164227 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2021-02-17 10:06:53.164227 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2021-02-17 10:06:53.164227 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2021-02-17 10:06:53.164227 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2021-02-17 10:06:53.164227 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2021-02-17 10:06:53.164227 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2021-02-17 10:06:53.164227 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
2021-02-17 10:06:53.164227 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/3855458128@65.0.179.216 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
2021-02-17 10:06:53.164227 [DEBUG] switch_core_codec.c:111 sofia/default/3855458128@65.0.179.216 Original read codec set to PCMU:0
2021-02-17 10:06:53.164227 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
2021-02-17 10:06:53.164227 [DEBUG] switch_core_media.c:5911 sofia/default/3855458128@65.0.179.216 Set 2833 dtmf send payload to 101 recv payload to 101
2021-02-17 10:06:53.164227 [DEBUG] switch_core_media.c:6239 No matches with FTMP, fallback to ignoring FMTP
2021-02-17 10:06:53.164227 [DEBUG] switch_core_media.c:6247 No matches with inherit_codec, fallback to ignoring PT
2021-02-17 10:06:53.164227 [DEBUG] sofia.c:7760 (sofia/default/3855458128@65.0.179.216) State Change CS_NEW -> CS_INIT
2021-02-17 10:06:53.164227 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3855458128@65.0.179.216) Running State Change CS_INIT (Cur 2 Tot 82326)
2021-02-17 10:06:53.164227 [DEBUG] switch_core_state_machine.c:628 (sofia/default/3855458128@65.0.179.216) State INIT
2021-02-17 10:06:53.164227 [DEBUG] mod_sofia.c:93 sofia/default/3855458128@65.0.179.216 SOFIA INIT
2021-02-17 10:06:53.164227 [DEBUG] switch_core_state_machine.c:40 sofia/default/3855458128@65.0.179.216 Standard INIT
2021-02-17 10:06:53.164227 [DEBUG] switch_core_state_machine.c:48 (sofia/default/3855458128@65.0.179.216) State Change CS_INIT -> CS_ROUTING
2021-02-17 10:06:53.164227 [DEBUG] switch_core_state_machine.c:628 (sofia/default/3855458128@65.0.179.216) State INIT going to sleep
2021-02-17 10:06:53.164227 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3855458128@65.0.179.216) Running State Change CS_ROUTING (Cur 2 Tot 82326)
2021-02-17 10:06:53.164227 [DEBUG] switch_channel.c:2332 (sofia/default/3855458128@65.0.179.216) Callstate Change DOWN -> RINGING
2021-02-17 10:06:53.164227 [DEBUG] switch_core_state_machine.c:644 (sofia/default/3855458128@65.0.179.216) State ROUTING
2021-02-17 10:06:53.164227 [DEBUG] mod_sofia.c:154 sofia/default/3855458128@65.0.179.216 SOFIA ROUTING
2021-02-17 10:06:53.164227 [DEBUG] switch_core_state_machine.c:236 sofia/default/3855458128@65.0.179.216 Standard ROUTING
2021-02-17 10:06:53.164227 [INFO] mod_dialplan_xml.c:637 Processing 3855458128 <3855458128>->180001111 in context default
2021-02-17 10:06:53.244229 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
2021-02-17 10:06:53.244229 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
2021-02-17 10:06:53.264226 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Dialed number : 180001111
2021-02-17 10:06:53.264226 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '180001111' AND status=0 limit 1
2021-02-17 10:06:53.264226 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Generated XML:
Dialplan: sofia/default/3855458128@65.0.179.216 parsing [default->180001111] continue=false
Dialplan: sofia/default/3855458128@65.0.179.216 Regex (PASS) [180001111] destination_number(180001111) =~ /180001111/ break=on-false
Dialplan: sofia/default/3855458128@65.0.179.216 Action log(INFO ASTPP - Calling Card Call)
Dialplan: sofia/default/3855458128@65.0.179.216 Action answer()
Dialplan: sofia/default/3855458128@65.0.179.216 Action sleep(2000)
Dialplan: sofia/default/3855458128@65.0.179.216 Action lua(astpp-callingcards.lua)
2021-02-17 10:06:53.264226 [DEBUG] switch_core_state_machine.c:287 (sofia/default/3855458128@65.0.179.216) State Change CS_ROUTING -> CS_EXECUTE
2021-02-17 10:06:53.264226 [DEBUG] switch_core_state_machine.c:644 (sofia/default/3855458128@65.0.179.216) State ROUTING going to sleep
2021-02-17 10:06:53.264226 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3855458128@65.0.179.216) Running State Change CS_EXECUTE (Cur 2 Tot 82326)
2021-02-17 10:06:53.264226 [DEBUG] switch_core_state_machine.c:651 (sofia/default/3855458128@65.0.179.216) State EXECUTE
2021-02-17 10:06:53.264226 [DEBUG] mod_sofia.c:209 sofia/default/3855458128@65.0.179.216 SOFIA EXECUTE
2021-02-17 10:06:53.264226 [DEBUG] switch_core_state_machine.c:329 sofia/default/3855458128@65.0.179.216 Standard EXECUTE
EXECUTE [depth=0] sofia/default/3855458128@65.0.179.216 log(INFO ASTPP - Calling Card Call)
2021-02-17 10:06:53.264226 [INFO] mod_dptools.c:1866 ASTPP - Calling Card Call
EXECUTE [depth=0] sofia/default/3855458128@65.0.179.216 answer()
2021-02-17 10:06:53.264226 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/3855458128@65.0.179.216] 172.31.14.168 port 27924 -> 117.247.91.162 port 7078 codec: 0 ms: 20
2021-02-17 10:06:53.264226 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
2021-02-17 10:06:53.264226 [DEBUG] switch_core_media.c:8977 sofia/default/3855458128@65.0.179.216 Set 2833 dtmf send payload to 101
2021-02-17 10:06:53.264226 [DEBUG] switch_core_media.c:8984 sofia/default/3855458128@65.0.179.216 Set 2833 dtmf receive payload to 101
2021-02-17 10:06:53.264226 [DEBUG] switch_core_media.c:9007 sofia/default/3855458128@65.0.179.216 Set rtp dtmf delay to 40
2021-02-17 10:06:53.264226 [DEBUG] mod_sofia.c:898 Local SDP sofia/default/3855458128@65.0.179.216:
v=0
o=FreeSWITCH 1613528489 1613528490 IN IP4 65.0.179.216
s=FreeSWITCH
c=IN IP4 65.0.179.216
t=0 0
m=audio 27924 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
m=video 0 RTP/AVP 19
2021-02-17 10:06:53.264226 [NOTICE] mod_dptools.c:1406 Channel [sofia/default/3855458128@65.0.179.216] has been answered
2021-02-17 10:06:53.264226 [DEBUG] switch_channel.c:3865 (sofia/default/3855458128@65.0.179.216) Callstate Change RINGING -> ACTIVE
2021-02-17 10:06:53.264226 [DEBUG] sofia.c:7326 Channel sofia/default/3855458128@65.0.179.216 entering state [completed][200]
EXECUTE [depth=0] sofia/default/3855458128@65.0.179.216 sleep(2000)
2021-02-17 10:06:53.284252 [WARNING] switch_core_state_machine.c:688 75353a84-fe7b-49e3-9cfd-a088663d7df8 sofia/default/38013801@65.0.179.216 Abandoned
2021-02-17 10:06:53.284252 [NOTICE] switch_core_state_machine.c:691 Hangup sofia/default/38013801@65.0.179.216 [CS_NEW] [WRONG_CALL_STATE]
2021-02-17 10:06:53.284252 [DEBUG] switch_core_state_machine.c:585 (sofia/default/38013801@65.0.179.216) Running State Change CS_HANGUP (Cur 2 Tot 82326)
2021-02-17 10:06:53.284252 [DEBUG] switch_core_state_machine.c:848 (sofia/default/38013801@65.0.179.216) Callstate Change DOWN -> HANGUP
2021-02-17 10:06:53.284252 [DEBUG] switch_core_state_machine.c:850 (sofia/default/38013801@65.0.179.216) State HANGUP
2021-02-17 10:06:53.284252 [DEBUG] mod_sofia.c:453 Channel sofia/default/38013801@65.0.179.216 hanging up, cause: WRONG_CALL_STATE
2021-02-17 10:06:53.284252 [DEBUG] switch_core_state_machine.c:60 sofia/default/38013801@65.0.179.216 Standard HANGUP, cause: WRONG_CALL_STATE
2021-02-17 10:06:53.284252 [DEBUG] switch_core_state_machine.c:850 (sofia/default/38013801@65.0.179.216) State HANGUP going to sleep
2021-02-17 10:06:53.284252 [DEBUG] switch_core_state_machine.c:620 (sofia/default/38013801@65.0.179.216) State Change CS_HANGUP -> CS_REPORTING
2021-02-17 10:06:53.284252 [DEBUG] switch_core_state_machine.c:585 (sofia/default/38013801@65.0.179.216) Running State Change CS_REPORTING (Cur 2 Tot 82326)
2021-02-17 10:06:53.284252 [DEBUG] switch_core_state_machine.c:936 (sofia/default/38013801@65.0.179.216) State REPORTING
2021-02-17 10:06:53.284252 [DEBUG] switch_core_state_machine.c:174 sofia/default/38013801@65.0.179.216 Standard REPORTING, cause: WRONG_CALL_STATE
2021-02-17 10:06:53.284252 [DEBUG] switch_core_state_machine.c:936 (sofia/default/38013801@65.0.179.216) State REPORTING going to sleep
2021-02-17 10:06:53.284252 [DEBUG] switch_core_state_machine.c:611 (sofia/default/38013801@65.0.179.216) State Change CS_REPORTING -> CS_DESTROY
2021-02-17 10:06:53.284252 [DEBUG] switch_core_session.c:1726 Session 82325 (sofia/default/38013801@65.0.179.216) Locked, Waiting on external entities
2021-02-17 10:06:53.284252 [NOTICE] switch_core_session.c:1744 Session 82325 (sofia/default/38013801@65.0.179.216) Ended
2021-02-17 10:06:53.284252 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/38013801@65.0.179.216 [CS_DESTROY]
2021-02-17 10:06:53.284252 [DEBUG] switch_core_state_machine.c:739 (sofia/default/38013801@65.0.179.216) Running State Change CS_DESTROY (Cur 1 Tot 82326)
2021-02-17 10:06:53.284252 [DEBUG] switch_core_state_machine.c:749 (sofia/default/38013801@65.0.179.216) State DESTROY
2021-02-17 10:06:53.284252 [DEBUG] mod_sofia.c:364 sofia/default/38013801@65.0.179.216 SOFIA DESTROY
2021-02-17 10:06:53.284252 [DEBUG] switch_core_state_machine.c:181 sofia/default/38013801@65.0.179.216 Standard DESTROY
2021-02-17 10:06:53.284252 [DEBUG] switch_core_state_machine.c:749 (sofia/default/38013801@65.0.179.216) State DESTROY going to sleep
2021-02-17 10:06:53.304230 [DEBUG] sofia.c:7326 Channel sofia/default/3855458128@65.0.179.216 entering state [ready][200]
2021-02-17 10:06:53.424255 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
EXECUTE [depth=0] sofia/default/3855458128@65.0.179.216 lua(astpp-callingcards.lua)
2021-02-17 10:06:55.264226 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
2021-02-17 10:06:55.264226 [NOTICE] switch_cpp.cpp:1447 [ASTPP] SECTION
2021-02-17 10:06:55.264226 [DEBUG] switch_core_file.c:405 File /usr/share/freeswitch/sounds/en/us/callie/astpp-welcome.wav sample rate 16000 doesn't match requested rate 8000
2021-02-17 10:06:55.264226 [DEBUG] switch_ivr_play_say.c:1488 Codec Activated L16@8000hz 1 channels 20ms
2021-02-17 10:06:57.064253 [DEBUG] switch_ivr_play_say.c:1933 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-welcome.wav
2021-02-17 10:06:57.064253 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_DID] Query :SELECT * FROM ani_map WHERE number = 3855458128
2021-02-17 10:06:57.064253 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT *,(select currencyrate from currency where id=currency_id) as currencyrate FROM accounts WHERE (number = "14" OR id="14") AND status=0 AND deleted=0 limit 1
2021-02-17 10:06:57.064253 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [validate_card_usage] Query :SELECT DATE_FORMAT('2031-02-14 09:49:05' , '%Y%m%d%H%i%s') AS expiry
2021-02-17 10:06:57.064253 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [validate_card_usage] Query :SELECT DATE_FORMAT('2021-02-17 10:06:57' , '%Y%m%d%H%i%s') AS expiry
2021-02-17 10:06:57.064253 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [Accountcode : 1594415095]
2021-02-17 10:06:57.064253 [DEBUG] switch_core_file.c:405 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-02-17 10:06:57.064253 [DEBUG] switch_ivr_play_say.c:1488 Codec Activated L16@8000hz 1 channels 20ms
2021-02-17 10:06:58.167683 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [1630@65.0.179.216] from ip 185.217.71.236
2021-02-17 10:06:58.384259 [DEBUG] switch_ivr_play_say.c:1933 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-this-card-has-a-balance-of.wav
2021-02-17 10:06:58.384259 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [Play Amount First Part] Query :3
2021-02-17 10:06:58.384259 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [Play Amount Second Part] Query :88
EXECUTE [depth=0] sofia/default/3855458128@65.0.179.216 say(en number pronounced 3)
2021-02-17 10:06:58.384259 [WARNING] sofia_reg.c:2930 Can't find user [1630@172.31.14.168] from 185.217.71.236
You must define a domain called '172.31.14.168' in your directory and add a user with the id="1630" attribute
and you must configure your device to use the proper domain in its authentication credentials.
2021-02-17 10:06:58.384259 [WARNING] sofia_reg.c:1739 SIP auth failure (REGISTER) on sofia profile 'default' for [1630@65.0.179.216] from ip 185.217.71.236
2021-02-17 10:06:58.384259 [DEBUG] switch_ivr_play_say.c:1488 Codec Activated L16@8000hz 1 channels 20ms
2021-02-17 10:06:59.044252 [DEBUG] switch_ivr_play_say.c:1933 done playing file file_string://digits/3.wav
2021-02-17 10:06:59.044252 [DEBUG] switch_core_file.c:405 File /usr/share/freeswitch/sounds/en/us/callie/astpp-point.wav sample rate 16000 doesn't match requested rate 8000
2021-02-17 10:06:59.044252 [DEBUG] switch_ivr_play_say.c:1488 Codec Activated L16@8000hz 1 channels 20ms
2021-02-17 10:06:59.604253 [DEBUG] switch_ivr_play_say.c:1933 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-point.wav
EXECUTE [depth=0] sofia/default/3855458128@65.0.179.216 say(en number pronounced 88)
2021-02-17 10:06:59.604253 [DEBUG] switch_ivr_play_say.c:1488 Codec Activated L16@8000hz 1 channels 20ms
2021-02-17 10:07:00.624231 [DEBUG] switch_ivr_play_say.c:1933 done playing file file_string://digits/80.wav!digits/8.wav
2021-02-17 10:07:00.624231 [DEBUG] switch_core_file.c:405 File /usr/share/freeswitch/sounds/en/us/callie/astpp-phonenum.wav sample rate 16000 doesn't match requested rate 8000
2021-02-17 10:07:00.624231 [DEBUG] switch_ivr_play_say.c:1488 Codec Activated L16@8000hz 1 channels 20ms
2021-02-17 10:07:04.324252 [DEBUG] switch_ivr_play_say.c:1933 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-phonenum.wav
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
2021-02-17 10:07:19.355314 [DEBUG] switch_core_file.c:405 File /usr/share/freeswitch/sounds/en/us/callie/astpp-badphone.wav sample rate 16000 doesn't match requested rate 8000
2021-02-17 10:07:19.355314 [DEBUG] switch_ivr_play_say.c:1488 Codec Activated L16@8000hz 1 channels 20ms
2021-02-17 10:07:19.724232 [NOTICE] switch_channel.c:1118 New Channel sofia/default/38013801@65.0.179.216 [0d1ed6a3-ff94-4904-8d93-ad937bc7d8a7]
2021-02-17 10:07:19.724232 [DEBUG] switch_core_state_machine.c:585 (sofia/default/38013801@65.0.179.216) Running State Change CS_NEW (Cur 2 Tot 82327)
2021-02-17 10:07:19.724232 [DEBUG] sofia.c:10280 sofia/default/38013801@65.0.179.216 receiving invite from 23.146.241.14:52129 version: 1.10.5 -release-17-25569c1631 64bit
2021-02-17 10:07:19.724232 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 23.146.241.14:0.
2021-02-17 10:07:19.724232 [DEBUG] sofia.c:2434 detaching session 0d1ed6a3-ff94-4904-8d93-ad937bc7d8a7
2021-02-17 10:07:19.724232 [WARNING] sofia_reg.c:1794 SIP auth challenge (INVITE) on sofia profile 'default' for [9011442037694911@65.0.179.216] from ip 23.146.241.14
2021-02-17 10:07:19.724232 [DEBUG] switch_core_state_machine.c:604 (sofia/default/38013801@65.0.179.216) State NEW
2021-02-17 10:07:20.784251 [DEBUG] switch_ivr_play_say.c:1933 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-badphone.wav
2021-02-17 10:07:20.784251 [DEBUG] switch_core_file.c:405 File /usr/share/freeswitch/sounds/en/us/callie/astpp-phonenum.wav sample rate 16000 doesn't match requested rate 8000
2021-02-17 10:07:20.784251 [DEBUG] switch_ivr_play_say.c:1488 Codec Activated L16@8000hz 1 channels 20ms
2021-02-17 10:07:24.484245 [DEBUG] switch_ivr_play_say.c:1933 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-phonenum.wav
2021-02-17 10:07:29.244249 [NOTICE] sofia.c:1089 Hangup sofia/default/3855458128@65.0.179.216 [CS_EXECUTE] [NORMAL_CLEARING]
2021-02-17 10:07:29.244249 [INFO] switch_cpp.cpp:1447 [ASTPP] [CHECK_destination] Dialed destination number :
2021-02-17 10:07:29.244249 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Account Information ===================
2021-02-17 10:07:29.244249 [INFO] switch_cpp.cpp:1447 [ASTPP] User id : 14
2021-02-17 10:07:29.244249 [INFO] switch_cpp.cpp:1447 [ASTPP] Account code : 1594415095
2021-02-17 10:07:29.244249 [INFO] switch_cpp.cpp:1447 [ASTPP] Balance : 3.8833
2021-02-17 10:07:29.244249 [INFO] switch_cpp.cpp:1447 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
2021-02-17 10:07:29.244249 [INFO] switch_cpp.cpp:1447 [ASTPP] Ratecard id : 1
2021-02-17 10:07:29.244249 [INFO] switch_cpp.cpp:1447 [ASTPP] ========================================================
2021-02-17 10:07:29.244249 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns ='--') AND accountid = 14 limit 1
2021-02-17 10:07:29.244249 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE (patterns ='--') AND accountid = 14 ORDER BY LENGTH(PKGPTR.patterns) DESC
2021-02-17 10:07:29.244249 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 1 AND status = 0
2021-02-17 10:07:29.244249 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] call_direction :
2021-02-17 10:07:29.244249 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern ='--') AND status = 0 AND (pricelist_id = 1 OR accountid=14) ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC LIMIT 1
2021-02-17 10:07:29.244249 [INFO] switch_cpp.cpp:1447 [ASTPP] call_direction::::::
2021-02-17 10:07:29.244249 [WARNING] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Rates not found!!!
2021-02-17 10:07:29.244249 [DEBUG] switch_cpp.cpp:1447 [ASTPP] post_cdrs:::0
2021-02-17 10:07:29.244249 [DEBUG] switch_core_session.c:2680 sofia/default/3855458128@65.0.179.216 ZOMBIE EXEC set(process_cdr=false)
EXECUTE [depth=0] sofia/default/3855458128@65.0.179.216 set(process_cdr=false)
2021-02-17 10:07:29.244249 [DEBUG] switch_core_session.c:2889 sofia/default/3855458128@65.0.179.216 skip receive message [APPLICATION_EXEC] (channel is hungup already)
2021-02-17 10:07:29.244249 [DEBUG] mod_dptools.c:1672 SET sofia/default/3855458128@65.0.179.216 [process_cdr]=[false]
2021-02-17 10:07:29.244249 [DEBUG] switch_core_session.c:2905 sofia/default/3855458128@65.0.179.216 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2021-02-17 10:07:29.244249 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Got DTMF digits: retries:0
2021-02-17 10:07:29.244249 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Got DTMF digits: retries:1
2021-02-17 10:07:29.244249 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Got DTMF digits: retries:2
2021-02-17 10:07:29.244249 [DEBUG] switch_cpp.cpp:731 CoreSession::hangup
2021-02-17 10:07:29.244249 [DEBUG] switch_cpp.cpp:1191 sofia/default/3855458128@65.0.179.216 destroy/unlink session from object
2021-02-17 10:07:29.244249 [DEBUG] switch_core_session.c:2905 sofia/default/3855458128@65.0.179.216 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2021-02-17 10:07:29.244249 [DEBUG] switch_core_state_machine.c:651 (sofia/default/3855458128@65.0.179.216) State EXECUTE going to sleep
2021-02-17 10:07:29.244249 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3855458128@65.0.179.216) Running State Change CS_HANGUP (Cur 2 Tot 82327)
2021-02-17 10:07:29.244249 [DEBUG] switch_core_state_machine.c:848 (sofia/default/3855458128@65.0.179.216) Callstate Change ACTIVE -> HANGUP
2021-02-17 10:07:29.244249 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3855458128@65.0.179.216) State HANGUP
2021-02-17 10:07:29.244249 [DEBUG] mod_sofia.c:453 Channel sofia/default/3855458128@65.0.179.216 hanging up, cause: NORMAL_CLEARING
2021-02-17 10:07:29.244249 [DEBUG] switch_core_state_machine.c:60 sofia/default/3855458128@65.0.179.216 Standard HANGUP, cause: NORMAL_CLEARING
2021-02-17 10:07:29.244249 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3855458128@65.0.179.216) State HANGUP going to sleep
2021-02-17 10:07:29.244249 [DEBUG] switch_core_state_machine.c:620 (sofia/default/3855458128@65.0.179.216) State Change CS_HANGUP -> CS_REPORTING
2021-02-17 10:07:29.244249 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3855458128@65.0.179.216) Running State Change CS_REPORTING (Cur 2 Tot 82327)
2021-02-17 10:07:29.244249 [DEBUG] switch_core_state_machine.c:936 (sofia/default/3855458128@65.0.179.216) State REPORTING
2021-02-17 10:07:29.244249 [DEBUG] switch_core_state_machine.c:174 sofia/default/3855458128@65.0.179.216 Standard REPORTING, cause: NORMAL_CLEARING
2021-02-17 10:07:29.244249 [DEBUG] switch_core_state_machine.c:936 (sofia/default/3855458128@65.0.179.216) State REPORTING going to sleep
2021-02-17 10:07:29.244249 [DEBUG] switch_core_state_machine.c:611 (sofia/default/3855458128@65.0.179.216) State Change CS_REPORTING -> CS_DESTROY
2021-02-17 10:07:29.244249 [DEBUG] switch_core_session.c:1726 Session 82326 (sofia/default/3855458128@65.0.179.216) Locked, Waiting on external entities
2021-02-17 10:07:29.244249 [NOTICE] switch_core_session.c:1744 Session 82326 (sofia/default/3855458128@65.0.179.216) Ended
2021-02-17 10:07:29.244249 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/3855458128@65.0.179.216 [CS_DESTROY]
2021-02-17 10:07:29.244249 [DEBUG] switch_core_state_machine.c:739 (sofia/default/3855458128@65.0.179.216) Running State Change CS_DESTROY (Cur 1 Tot 82327)
2021-02-17 10:07:29.244249 [DEBUG] switch_core_state_machine.c:749 (sofia/default/3855458128@65.0.179.216) State DESTROY
2021-02-17 10:07:29.244249 [DEBUG] mod_sofia.c:364 sofia/default/3855458128@65.0.179.216 SOFIA DESTROY
2021-02-17 10:07:29.244249 [DEBUG] switch_core_state_machine.c:181 sofia/default/3855458128@65.0.179.216 Standard DESTROY
2021-02-17 10:07:29.244249 [DEBUG] switch_core_state_machine.c:749 (sofia/default/3855458128@65.0.179.216) State DESTROY going to sleep
2021-02-17 10:07:29.784253 [WARNING] switch_core_state_machine.c:688 0d1ed6a3-ff94-4904-8d93-ad937bc7d8a7 sofia/default/38013801@65.0.179.216 Abandoned
2021-02-17 10:07:29.784253 [NOTICE] switch_core_state_machine.c:691 Hangup sofia/default/38013801@65.0.179.216 [CS_NEW] [WRONG_CALL_STATE]
2021-02-17 10:07:29.784253 [DEBUG] switch_core_state_machine.c:585 (sofia/default/38013801@65.0.179.216) Running State Change CS_HANGUP (Cur 1 Tot 82327)
2021-02-17 10:07:29.784253 [DEBUG] switch_core_state_machine.c:848 (sofia/default/38013801@65.0.179.216) Callstate Change DOWN -> HANGUP
2021-02-17 10:07:29.784253 [DEBUG] switch_core_state_machine.c:850 (sofia/default/38013801@65.0.179.216) State HANGUP
2021-02-17 10:07:29.784253 [DEBUG] mod_sofia.c:453 Channel sofia/default/38013801@65.0.179.216 hanging up, cause: WRONG_CALL_STATE
2021-02-17 10:07:29.784253 [DEBUG] switch_core_state_machine.c:60 sofia/default/38013801@65.0.179.216 Standard HANGUP, cause: WRONG_CALL_STATE
2021-02-17 10:07:29.784253 [DEBUG] switch_core_state_machine.c:850 (sofia/default/38013801@65.0.179.216) State HANGUP going to sleep
2021-02-17 10:07:29.784253 [DEBUG] switch_core_state_machine.c:620 (sofia/default/38013801@65.0.179.216) State Change CS_HANGUP -> CS_REPORTING
2021-02-17 10:07:29.784253 [DEBUG] switch_core_state_machine.c:585 (sofia/default/38013801@65.0.179.216) Running State Change CS_REPORTING (Cur 1 Tot 82327)
2021-02-17 10:07:29.784253 [DEBUG] switch_core_state_machine.c:936 (sofia/default/38013801@65.0.179.216) State REPORTING
2021-02-17 10:07:29.784253 [DEBUG] switch_core_state_machine.c:174 sofia/default/38013801@65.0.179.216 Standard REPORTING, cause: WRONG_CALL_STATE
2021-02-17 10:07:29.784253 [DEBUG] switch_core_state_machine.c:936 (sofia/default/38013801@65.0.179.216) State REPORTING going to sleep
2021-02-17 10:07:29.784253 [DEBUG] switch_core_state_machine.c:611 (sofia/default/38013801@65.0.179.216) State Change CS_REPORTING -> CS_DESTROY
2021-02-17 10:07:29.784253 [DEBUG] switch_core_session.c:1726 Session 82327 (sofia/default/38013801@65.0.179.216) Locked, Waiting on external entities
2021-02-17 10:07:29.784253 [NOTICE] switch_core_session.c:1744 Session 82327 (sofia/default/38013801@65.0.179.216) Ended
2021-02-17 10:07:29.784253 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/38013801@65.0.179.216 [CS_DESTROY]
2021-02-17 10:07:29.784253 [DEBUG] switch_core_state_machine.c:739 (sofia/default/38013801@65.0.179.216) Running State Change CS_DESTROY (Cur 0 Tot 82327)
2021-02-17 10:07:29.784253 [DEBUG] switch_core_state_machine.c:749 (sofia/default/38013801@65.0.179.216) State DESTROY
2021-02-17 10:07:29.784253 [DEBUG] mod_sofia.c:364 sofia/default/38013801@65.0.179.216 SOFIA DESTROY
2021-02-17 10:07:29.784253 [DEBUG] switch_core_state_machine.c:181 sofia/default/38013801@65.0.179.216 Standard DESTROY
2021-02-17 10:07:29.784253 [DEBUG] switch_core_state_machine.c:749 (sofia/default/38013801@65.0.179.216) State DESTROY going to sleep
2021-02-17 10:07:37.604245 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [1686@65.0.179.216] from ip 185.217.71.236
2021-02-17 10:07:37.824286 [WARNING] sofia_reg.c:2930 Can't find user [1686@172.31.14.168] from 185.217.71.236
You must define a domain called '172.31.14.168' in your directory and add a user with the id="1686" attribute
and you must configure your device to use the proper domain in its authentication credentials.
2021-02-17 10:07:37.824286 [WARNING] sofia_reg.c:1739 SIP auth failure (REGISTER) on sofia profile 'default' for [1686@65.0.179.216] from ip 185.217.71.236
2021-02-17 10:07:44.344190 [NOTICE] switch_channel.c:1118 New Channel sofia/default/3855458128@65.0.179.216 [d8b386d3-ab72-4baa-ad45-ef333626cd48]
2021-02-17 10:07:44.344190 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3855458128@65.0.179.216) Running State Change CS_NEW (Cur 1 Tot 82328)
2021-02-17 10:07:44.344190 [DEBUG] sofia.c:10280 sofia/default/3855458128@65.0.179.216 receiving invite from 117.247.91.162:4585 version: 1.10.5 -release-17-25569c1631 64bit
2021-02-17 10:07:44.344190 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 117.247.91.162:0.
2021-02-17 10:07:44.344190 [DEBUG] sofia.c:2434 detaching session d8b386d3-ab72-4baa-ad45-ef333626cd48
2021-02-17 10:07:44.344190 [WARNING] sofia_reg.c:1794 SIP auth challenge (INVITE) on sofia profile 'default' for [180001111@65.0.179.216] from ip 117.247.91.162
2021-02-17 10:07:44.344190 [DEBUG] switch_core_state_machine.c:604 (sofia/default/3855458128@65.0.179.216) State NEW
2021-02-17 10:07:44.384245 [DEBUG] sofia.c:2544 Re-attaching to session d8b386d3-ab72-4baa-ad45-ef333626cd48
2021-02-17 10:07:44.384245 [DEBUG] sofia.c:10280 sofia/default/3855458128@65.0.179.216 receiving invite from 117.247.91.162:4585 version: 1.10.5 -release-17-25569c1631 64bit
2021-02-17 10:07:44.384245 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 117.247.91.162:0.
2021-02-17 10:07:44.384245 [DEBUG] sofia.c:7326 Channel sofia/default/3855458128@65.0.179.216 entering state [received][100]
2021-02-17 10:07:44.384245 [DEBUG] sofia.c:7336 Remote SDP:
v=0
o=3855458128 1993 1906 IN IP4 117.247.91.162
s=Talk
c=IN IP4 117.247.91.162
t=0 0
m=audio 7078 RTP/AVP 124 111 110 0 8 101
a=rtpmap:124 opus/48000
a=fmtp:124 useinbandfec=1; usedtx=1
a=rtpmap:111 speex/16000
a=fmtp:111 vbr=on
a=rtpmap:110 speex/8000
a=fmtp:110 vbr=on
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-11
m=video 9078 RTP/AVP 103 99
a=rtpmap:103 VP8/90000
a=rtpmap:99 MP4V-ES/90000
a=fmtp:99 profile-level-id=3
2021-02-17 10:07:44.384245 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [opus:124:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
2021-02-17 10:07:44.384245 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [opus:124:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
2021-02-17 10:07:44.384245 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [speex:111:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2021-02-17 10:07:44.384245 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [speex:111:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2021-02-17 10:07:44.384245 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
2021-02-17 10:07:44.384245 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
2021-02-17 10:07:44.384245 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2021-02-17 10:07:44.384245 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2021-02-17 10:07:44.384245 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2021-02-17 10:07:44.384245 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2021-02-17 10:07:44.384245 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2021-02-17 10:07:44.384245 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2021-02-17 10:07:44.384245 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
2021-02-17 10:07:44.384245 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/3855458128@65.0.179.216 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
2021-02-17 10:07:44.384245 [DEBUG] switch_core_codec.c:111 sofia/default/3855458128@65.0.179.216 Original read codec set to PCMU:0
2021-02-17 10:07:44.384245 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
2021-02-17 10:07:44.384245 [DEBUG] switch_core_media.c:5911 sofia/default/3855458128@65.0.179.216 Set 2833 dtmf send payload to 101 recv payload to 101
2021-02-17 10:07:44.384245 [DEBUG] switch_core_media.c:6239 No matches with FTMP, fallback to ignoring FMTP
2021-02-17 10:07:44.384245 [DEBUG] switch_core_media.c:6247 No matches with inherit_codec, fallback to ignoring PT
2021-02-17 10:07:44.384245 [DEBUG] sofia.c:7760 (sofia/default/3855458128@65.0.179.216) State Change CS_NEW -> CS_INIT
2021-02-17 10:07:44.384245 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3855458128@65.0.179.216) Running State Change CS_INIT (Cur 1 Tot 82328)
2021-02-17 10:07:44.384245 [DEBUG] switch_core_state_machine.c:628 (sofia/default/3855458128@65.0.179.216) State INIT
2021-02-17 10:07:44.384245 [DEBUG] mod_sofia.c:93 sofia/default/3855458128@65.0.179.216 SOFIA INIT
2021-02-17 10:07:44.384245 [DEBUG] switch_core_state_machine.c:40 sofia/default/3855458128@65.0.179.216 Standard INIT
2021-02-17 10:07:44.384245 [DEBUG] switch_core_state_machine.c:48 (sofia/default/3855458128@65.0.179.216) State Change CS_INIT -> CS_ROUTING
2021-02-17 10:07:44.384245 [DEBUG] switch_core_state_machine.c:628 (sofia/default/3855458128@65.0.179.216) State INIT going to sleep
2021-02-17 10:07:44.384245 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3855458128@65.0.179.216) Running State Change CS_ROUTING (Cur 1 Tot 82328)
2021-02-17 10:07:44.384245 [DEBUG] switch_channel.c:2332 (sofia/default/3855458128@65.0.179.216) Callstate Change DOWN -> RINGING
2021-02-17 10:07:44.404228 [DEBUG] switch_core_state_machine.c:644 (sofia/default/3855458128@65.0.179.216) State ROUTING
2021-02-17 10:07:44.404228 [DEBUG] mod_sofia.c:154 sofia/default/3855458128@65.0.179.216 SOFIA ROUTING
2021-02-17 10:07:44.404228 [DEBUG] switch_core_state_machine.c:236 sofia/default/3855458128@65.0.179.216 Standard ROUTING
2021-02-17 10:07:44.404228 [INFO] mod_dialplan_xml.c:637 Processing 3855458128 <3855458128>->180001111 in context default
2021-02-17 10:07:44.404228 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
2021-02-17 10:07:44.404228 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
2021-02-17 10:07:44.404228 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Dialed number : 180001111
2021-02-17 10:07:44.404228 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '180001111' AND status=0 limit 1
2021-02-17 10:07:44.404228 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Generated XML:
Dialplan: sofia/default/3855458128@65.0.179.216 parsing [default->180001111] continue=false
Dialplan: sofia/default/3855458128@65.0.179.216 Regex (PASS) [180001111] destination_number(180001111) =~ /180001111/ break=on-false
Dialplan: sofia/default/3855458128@65.0.179.216 Action log(INFO ASTPP - Calling Card Call)
Dialplan: sofia/default/3855458128@65.0.179.216 Action answer()
Dialplan: sofia/default/3855458128@65.0.179.216 Action sleep(2000)
Dialplan: sofia/default/3855458128@65.0.179.216 Action lua(astpp-callingcards.lua)
2021-02-17 10:07:44.404228 [DEBUG] switch_core_state_machine.c:287 (sofia/default/3855458128@65.0.179.216) State Change CS_ROUTING -> CS_EXECUTE
2021-02-17 10:07:44.404228 [DEBUG] switch_core_state_machine.c:644 (sofia/default/3855458128@65.0.179.216) State ROUTING going to sleep
2021-02-17 10:07:44.404228 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3855458128@65.0.179.216) Running State Change CS_EXECUTE (Cur 1 Tot 82328)
2021-02-17 10:07:44.404228 [DEBUG] switch_core_state_machine.c:651 (sofia/default/3855458128@65.0.179.216) State EXECUTE
2021-02-17 10:07:44.404228 [DEBUG] mod_sofia.c:209 sofia/default/3855458128@65.0.179.216 SOFIA EXECUTE
2021-02-17 10:07:44.404228 [DEBUG] switch_core_state_machine.c:329 sofia/default/3855458128@65.0.179.216 Standard EXECUTE
EXECUTE [depth=0] sofia/default/3855458128@65.0.179.216 log(INFO ASTPP - Calling Card Call)
2021-02-17 10:07:44.404228 [INFO] mod_dptools.c:1866 ASTPP - Calling Card Call
EXECUTE [depth=0] sofia/default/3855458128@65.0.179.216 answer()
2021-02-17 10:07:44.404228 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/3855458128@65.0.179.216] 172.31.14.168 port 25132 -> 117.247.91.162 port 7078 codec: 0 ms: 20
2021-02-17 10:07:44.404228 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
2021-02-17 10:07:44.424278 [DEBUG] switch_core_media.c:8977 sofia/default/3855458128@65.0.179.216 Set 2833 dtmf send payload to 101
2021-02-17 10:07:44.424278 [DEBUG] switch_core_media.c:8984 sofia/default/3855458128@65.0.179.216 Set 2833 dtmf receive payload to 101
2021-02-17 10:07:44.424278 [DEBUG] switch_core_media.c:9007 sofia/default/3855458128@65.0.179.216 Set rtp dtmf delay to 40
2021-02-17 10:07:44.424278 [DEBUG] mod_sofia.c:898 Local SDP sofia/default/3855458128@65.0.179.216:
v=0
o=FreeSWITCH 1613531332 1613531333 IN IP4 65.0.179.216
s=FreeSWITCH
c=IN IP4 65.0.179.216
t=0 0
m=audio 25132 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
m=video 0 RTP/AVP 19
2021-02-17 10:07:44.424278 [DEBUG] sofia.c:7326 Channel sofia/default/3855458128@65.0.179.216 entering state [completed][200]
2021-02-17 10:07:44.424278 [NOTICE] mod_dptools.c:1406 Channel [sofia/default/3855458128@65.0.179.216] has been answered
2021-02-17 10:07:44.424278 [DEBUG] switch_channel.c:3865 (sofia/default/3855458128@65.0.179.216) Callstate Change RINGING -> ACTIVE
EXECUTE [depth=0] sofia/default/3855458128@65.0.179.216 sleep(2000)
2021-02-17 10:07:44.484247 [DEBUG] sofia.c:7326 Channel sofia/default/3855458128@65.0.179.216 entering state [ready][200]
2021-02-17 10:07:44.544223 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
EXECUTE [depth=0] sofia/default/3855458128@65.0.179.216 lua(astpp-callingcards.lua)
2021-02-17 10:07:46.464224 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
2021-02-17 10:07:46.464224 [NOTICE] switch_cpp.cpp:1447 [ASTPP] SECTION
2021-02-17 10:07:46.464224 [DEBUG] switch_core_file.c:405 File /usr/share/freeswitch/sounds/en/us/callie/astpp-welcome.wav sample rate 16000 doesn't match requested rate 8000
2021-02-17 10:07:46.464224 [DEBUG] switch_ivr_play_say.c:1488 Codec Activated L16@8000hz 1 channels 20ms
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
freeswitch@ip-172-31-14-168>
2021-02-17 10:07:48.244244 [DEBUG] switch_ivr_play_say.c:1933 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-welcome.wav
2021-02-17 10:07:48.244244 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_DID] Query :SELECT * FROM ani_map WHERE number = 3855458128
freeswitch@ip-172-31-14-168>
2021-02-17 10:07:48.264224 [DEBUG] switch_core_file.c:405 File /usr/share/freeswitch/sounds/en/us/callie/astpp-accountnum.wav sample rate 16000 doesn't match requested rate 8000
2021-02-17 10:07:48.264224 [DEBUG] switch_ivr_play_say.c:1488 Codec Activated L16@8000hz 1 channels 20ms
freeswitch@ip-172-31-14-168>
2021-02-17 10:07:51.244325 [DEBUG] switch_ivr_play_say.c:1933 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-accountnum.wav
2021-02-17 10:07:55.784241 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF 1:800
2021-02-17 10:07:55.784241 [INFO] switch_channel.c:522 RECV DTMF 1:800
2021-02-17 10:07:56.544245 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF 4:800
2021-02-17 10:07:56.544245 [INFO] switch_channel.c:522 RECV DTMF 4:800
2021-02-17 10:07:56.904245 [NOTICE] switch_channel.c:1118 New Channel sofia/default/38013801@65.0.179.216 [7454dc0b-5b6e-4e8c-9b5c-ec6a9b121fa9]
2021-02-17 10:07:56.904245 [DEBUG] switch_core_state_machine.c:585 (sofia/default/38013801@65.0.179.216) Running State Change CS_NEW (Cur 2 Tot 82329)
2021-02-17 10:07:56.904245 [DEBUG] sofia.c:10280 sofia/default/38013801@65.0.179.216 receiving invite from 23.146.241.14:58121 version: 1.10.5 -release-17-25569c1631 64bit
2021-02-17 10:07:56.904245 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 23.146.241.14:0.
2021-02-17 10:07:56.904245 [DEBUG] sofia.c:2434 detaching session 7454dc0b-5b6e-4e8c-9b5c-ec6a9b121fa9
2021-02-17 10:07:56.904245 [WARNING] sofia_reg.c:1794 SIP auth challenge (INVITE) on sofia profile 'default' for [9442037694911@65.0.179.216] from ip 23.146.241.14
2021-02-17 10:07:56.904245 [DEBUG] switch_core_state_machine.c:604 (sofia/default/38013801@65.0.179.216) State NEW
2021-02-17 10:07:57.664195 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF #:800
2021-02-17 10:07:57.664195 [INFO] switch_channel.c:522 RECV DTMF #:800
2021-02-17 10:07:57.684233 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Got DTMF digits: 14
2021-02-17 10:07:57.684233 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT *,(select currencyrate from currency where id=currency_id) as currencyrate FROM accounts WHERE (number = "14" OR id="14") AND status=0 AND deleted=0 limit 1
2021-02-17 10:07:57.684233 [DEBUG] switch_core_file.c:405 File /usr/share/freeswitch/sounds/en/us/callie/astpp-pleasepin.wav sample rate 16000 doesn't match requested rate 8000
2021-02-17 10:07:57.684233 [DEBUG] switch_ivr_play_say.c:1488 Codec Activated L16@8000hz 1 channels 20ms
2021-02-17 10:08:00.384246 [DEBUG] switch_ivr_play_say.c:1933 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-pleasepin.wav
2021-02-17 10:08:06.984246 [WARNING] switch_core_state_machine.c:688 7454dc0b-5b6e-4e8c-9b5c-ec6a9b121fa9 sofia/default/38013801@65.0.179.216 Abandoned
2021-02-17 10:08:06.984246 [NOTICE] switch_core_state_machine.c:691 Hangup sofia/default/38013801@65.0.179.216 [CS_NEW] [WRONG_CALL_STATE]
2021-02-17 10:08:06.984246 [DEBUG] switch_core_state_machine.c:585 (sofia/default/38013801@65.0.179.216) Running State Change CS_HANGUP (Cur 2 Tot 82329)
2021-02-17 10:08:06.984246 [DEBUG] switch_core_state_machine.c:848 (sofia/default/38013801@65.0.179.216) Callstate Change DOWN -> HANGUP
2021-02-17 10:08:06.984246 [DEBUG] switch_core_state_machine.c:850 (sofia/default/38013801@65.0.179.216) State HANGUP
2021-02-17 10:08:06.984246 [DEBUG] mod_sofia.c:453 Channel sofia/default/38013801@65.0.179.216 hanging up, cause: WRONG_CALL_STATE
2021-02-17 10:08:06.984246 [DEBUG] switch_core_state_machine.c:60 sofia/default/38013801@65.0.179.216 Standard HANGUP, cause: WRONG_CALL_STATE
2021-02-17 10:08:06.984246 [DEBUG] switch_core_state_machine.c:850 (sofia/default/38013801@65.0.179.216) State HANGUP going to sleep
2021-02-17 10:08:06.984246 [DEBUG] switch_core_state_machine.c:620 (sofia/default/38013801@65.0.179.216) State Change CS_HANGUP -> CS_REPORTING
2021-02-17 10:08:06.984246 [DEBUG] switch_core_state_machine.c:585 (sofia/default/38013801@65.0.179.216) Running State Change CS_REPORTING (Cur 2 Tot 82329)
2021-02-17 10:08:06.984246 [DEBUG] switch_core_state_machine.c:936 (sofia/default/38013801@65.0.179.216) State REPORTING
2021-02-17 10:08:06.984246 [DEBUG] switch_core_state_machine.c:174 sofia/default/38013801@65.0.179.216 Standard REPORTING, cause: WRONG_CALL_STATE
2021-02-17 10:08:06.984246 [DEBUG] switch_core_state_machine.c:936 (sofia/default/38013801@65.0.179.216) State REPORTING going to sleep
2021-02-17 10:08:06.984246 [DEBUG] switch_core_state_machine.c:611 (sofia/default/38013801@65.0.179.216) State Change CS_REPORTING -> CS_DESTROY
2021-02-17 10:08:06.984246 [DEBUG] switch_core_session.c:1726 Session 82329 (sofia/default/38013801@65.0.179.216) Locked, Waiting on external entities
2021-02-17 10:08:06.984246 [NOTICE] switch_core_session.c:1744 Session 82329 (sofia/default/38013801@65.0.179.216) Ended
2021-02-17 10:08:06.984246 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/38013801@65.0.179.216 [CS_DESTROY]
2021-02-17 10:08:06.984246 [DEBUG] switch_core_state_machine.c:739 (sofia/default/38013801@65.0.179.216) Running State Change CS_DESTROY (Cur 1 Tot 82329)
2021-02-17 10:08:06.984246 [DEBUG] switch_core_state_machine.c:749 (sofia/default/38013801@65.0.179.216) State DESTROY
2021-02-17 10:08:06.984246 [DEBUG] mod_sofia.c:364 sofia/default/38013801@65.0.179.216 SOFIA DESTROY
2021-02-17 10:08:06.984246 [DEBUG] switch_core_state_machine.c:181 sofia/default/38013801@65.0.179.216 Standard DESTROY
2021-02-17 10:08:06.984246 [DEBUG] switch_core_state_machine.c:749 (sofia/default/38013801@65.0.179.216) State DESTROY going to sleep
2021-02-17 10:08:08.484225 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF 4:800
2021-02-17 10:08:08.484225 [INFO] switch_channel.c:522 RECV DTMF 4:800
2021-02-17 10:08:09.924245 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF 2:800
2021-02-17 10:08:09.924245 [INFO] switch_channel.c:522 RECV DTMF 2:800
2021-02-17 10:08:11.524247 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF 3:800
2021-02-17 10:08:11.524247 [INFO] switch_channel.c:522 RECV DTMF 3:800
2021-02-17 10:08:12.564245 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF 8:800
2021-02-17 10:08:12.564245 [INFO] switch_channel.c:522 RECV DTMF 8:800
2021-02-17 10:08:13.264316 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF 0:800
2021-02-17 10:08:13.264316 [INFO] switch_channel.c:522 RECV DTMF 0:800
2021-02-17 10:08:14.464243 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF 2:800
2021-02-17 10:08:14.464243 [INFO] switch_channel.c:522 RECV DTMF 2:800
2021-02-17 10:08:15.604249 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF #:800
2021-02-17 10:08:15.604249 [INFO] switch_channel.c:522 RECV DTMF #:800
2021-02-17 10:08:15.604249 [DEBUG] switch_cpp.cpp:1447 [ASTPP] We recieved a pin : 423802
2021-02-17 10:08:15.604249 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [validate_card_usage] Query :SELECT DATE_FORMAT('2031-02-14 09:49:05' , '%Y%m%d%H%i%s') AS expiry
2021-02-17 10:08:15.604249 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [validate_card_usage] Query :SELECT DATE_FORMAT('2021-02-17 10:08:15' , '%Y%m%d%H%i%s') AS expiry
2021-02-17 10:08:15.624228 [DEBUG] switch_core_file.c:405 File /usr/share/freeswitch/sounds/en/us/callie/astpp-register.wav sample rate 16000 doesn't match requested rate 8000
2021-02-17 10:08:15.624228 [DEBUG] switch_ivr_play_say.c:1488 Codec Activated L16@8000hz 1 channels 20ms
2021-02-17 10:08:16.384231 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [3085@65.0.179.216] from ip 185.217.71.236
2021-02-17 10:08:16.644249 [WARNING] sofia_reg.c:2930 Can't find user [3085@172.31.14.168] from 185.217.71.236
You must define a domain called '172.31.14.168' in your directory and add a user with the id="3085" attribute
and you must configure your device to use the proper domain in its authentication credentials.
2021-02-17 10:08:16.644249 [WARNING] sofia_reg.c:1739 SIP auth failure (REGISTER) on sofia profile 'default' for [3085@65.0.179.216] from ip 185.217.71.236
2021-02-17 10:08:18.424250 [DEBUG] switch_ivr_play_say.c:1933 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-register.wav
2021-02-17 10:08:23.424250 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [Accountcode : 1594415095]
2021-02-17 10:08:23.424250 [DEBUG] switch_core_file.c:405 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-02-17 10:08:23.424250 [DEBUG] switch_ivr_play_say.c:1488 Codec Activated L16@8000hz 1 channels 20ms