2022-01-05 06:48:45.238230 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [9228553890@192.168.1.114] from ip 192.168.1.59 2022-01-05 06:49:03.758189 [NOTICE] switch_channel.c:1118 New Channel sofia/default/957484@192.168.1.114:5126 [4ccfe5e8-f376-4691-8d88-f5d7164d425b] 2022-01-05 06:49:03.758189 [DEBUG] switch_core_state_machine.c:585 (sofia/default/957484@192.168.1.114:5126) Running State Change CS_NEW (Cur 1 Tot 17) 2022-01-05 06:49:03.758189 [DEBUG] sofia.c:10280 sofia/default/957484@192.168.1.114:5126 receiving invite from 192.168.1.177:45193 version: 1.10.5 -release-17-25569c1631 64bit 2022-01-05 06:49:03.758189 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 192.168.1.177:0. 2022-01-05 06:49:03.758189 [WARNING] sofia_reg.c:1794 SIP auth challenge (INVITE) on sofia profile 'default' for [1800@192.168.1.114] from ip 192.168.1.177 2022-01-05 06:49:03.758189 [DEBUG] switch_core_state_machine.c:604 (sofia/default/957484@192.168.1.114:5126) State NEW 2022-01-05 06:49:03.758189 [DEBUG] sofia.c:2434 detaching session 4ccfe5e8-f376-4691-8d88-f5d7164d425b 2022-01-05 06:49:03.818172 [DEBUG] sofia.c:2544 Re-attaching to session 4ccfe5e8-f376-4691-8d88-f5d7164d425b 2022-01-05 06:49:03.818172 [DEBUG] sofia.c:10280 sofia/default/957484@192.168.1.114:5126 receiving invite from 192.168.1.177:45193 version: 1.10.5 -release-17-25569c1631 64bit 2022-01-05 06:49:03.818172 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 192.168.1.177:0. 2022-01-05 06:49:03.898164 [DEBUG] sofia.c:11477 Setting NAT mode based on via port 2022-01-05 06:49:03.898164 [DEBUG] sofia.c:7326 Channel sofia/default/957484@192.168.1.114:5126 entering state [received][100] 2022-01-05 06:49:03.898164 [DEBUG] sofia.c:7336 Remote SDP: v=0 o=Zoiper 1641365344081 1 IN IP4 192.168.1.177 s=Z c=IN IP4 192.168.1.177 t=0 0 m=audio 33934 RTP/AVP 0 101 8 3 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 2022-01-05 06:49:03.898164 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2022-01-05 06:49:03.898164 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2022-01-05 06:49:03.898164 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2022-01-05 06:49:03.898164 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000 2022-01-05 06:49:03.898164 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2022-01-05 06:49:03.898164 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2022-01-05 06:49:03.898164 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2022-01-05 06:49:03.898164 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1] 2022-01-05 06:49:03.898164 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1] 2022-01-05 06:49:03.898164 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/957484@192.168.1.114:5126 PCMU/8000 20 ms 160 samples 64000 bits 1 channels 2022-01-05 06:49:03.898164 [DEBUG] switch_core_codec.c:111 sofia/default/957484@192.168.1.114:5126 Original read codec set to PCMU:0 2022-01-05 06:49:03.898164 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000 2022-01-05 06:49:03.898164 [DEBUG] switch_core_media.c:5911 sofia/default/957484@192.168.1.114:5126 Set 2833 dtmf send payload to 101 recv payload to 101 2022-01-05 06:49:03.898164 [DEBUG] sofia.c:7760 (sofia/default/957484@192.168.1.114:5126) State Change CS_NEW -> CS_INIT 2022-01-05 06:49:03.898164 [DEBUG] switch_core_state_machine.c:585 (sofia/default/957484@192.168.1.114:5126) Running State Change CS_INIT (Cur 1 Tot 17) 2022-01-05 06:49:03.898164 [DEBUG] switch_core_state_machine.c:628 (sofia/default/957484@192.168.1.114:5126) State INIT 2022-01-05 06:49:03.898164 [DEBUG] mod_sofia.c:93 sofia/default/957484@192.168.1.114:5126 SOFIA INIT 2022-01-05 06:49:03.898164 [DEBUG] switch_core_state_machine.c:40 sofia/default/957484@192.168.1.114:5126 Standard INIT 2022-01-05 06:49:03.898164 [DEBUG] switch_core_state_machine.c:48 (sofia/default/957484@192.168.1.114:5126) State Change CS_INIT -> CS_ROUTING 2022-01-05 06:49:03.898164 [DEBUG] switch_core_state_machine.c:628 (sofia/default/957484@192.168.1.114:5126) State INIT going to sleep 2022-01-05 06:49:03.898164 [DEBUG] switch_core_state_machine.c:585 (sofia/default/957484@192.168.1.114:5126) Running State Change CS_ROUTING (Cur 1 Tot 17) 2022-01-05 06:49:03.898164 [DEBUG] switch_channel.c:2332 (sofia/default/957484@192.168.1.114:5126) Callstate Change DOWN -> RINGING 2022-01-05 06:49:03.898164 [DEBUG] switch_core_state_machine.c:644 (sofia/default/957484@192.168.1.114:5126) State ROUTING 2022-01-05 06:49:03.898164 [DEBUG] mod_sofia.c:154 sofia/default/957484@192.168.1.114:5126 SOFIA ROUTING 2022-01-05 06:49:03.898164 [DEBUG] switch_core_state_machine.c:236 sofia/default/957484@192.168.1.114:5126 Standard ROUTING 2022-01-05 06:49:03.898164 [INFO] mod_dialplan_xml.c:637 Processing 957484 <957484>->1800 in context default 2022-01-05 06:49:03.918176 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes') 2022-01-05 06:49:03.918176 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2022-01-05 06:49:03.938183 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Dialed number : 1800 2022-01-05 06:49:03.938183 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '1800' AND status=0 limit 1 2022-01-05 06:49:03.938183 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Generated XML:
Dialplan: sofia/default/957484@192.168.1.114:5126 parsing [default->1800] continue=false Dialplan: sofia/default/957484@192.168.1.114:5126 Regex (PASS) [1800] destination_number(1800) =~ /1800/ break=on-false Dialplan: sofia/default/957484@192.168.1.114:5126 Action log(INFO ASTPP - Calling Card Call) Dialplan: sofia/default/957484@192.168.1.114:5126 Action answer() Dialplan: sofia/default/957484@192.168.1.114:5126 Action sleep(2000) Dialplan: sofia/default/957484@192.168.1.114:5126 Action lua(astpp-callingcards.lua) 2022-01-05 06:49:03.938183 [DEBUG] switch_core_state_machine.c:287 (sofia/default/957484@192.168.1.114:5126) State Change CS_ROUTING -> CS_EXECUTE 2022-01-05 06:49:03.938183 [DEBUG] switch_core_state_machine.c:644 (sofia/default/957484@192.168.1.114:5126) State ROUTING going to sleep 2022-01-05 06:49:03.938183 [DEBUG] switch_core_state_machine.c:585 (sofia/default/957484@192.168.1.114:5126) Running State Change CS_EXECUTE (Cur 1 Tot 17) 2022-01-05 06:49:03.938183 [DEBUG] switch_core_state_machine.c:651 (sofia/default/957484@192.168.1.114:5126) State EXECUTE 2022-01-05 06:49:03.938183 [DEBUG] mod_sofia.c:209 sofia/default/957484@192.168.1.114:5126 SOFIA EXECUTE 2022-01-05 06:49:03.938183 [DEBUG] switch_core_state_machine.c:329 sofia/default/957484@192.168.1.114:5126 Standard EXECUTE EXECUTE [depth=0] sofia/default/957484@192.168.1.114:5126 log(INFO ASTPP - Calling Card Call) 2022-01-05 06:49:03.938183 [INFO] mod_dptools.c:1866 ASTPP - Calling Card Call EXECUTE [depth=0] sofia/default/957484@192.168.1.114:5126 answer() 2022-01-05 06:49:03.938183 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/957484@192.168.1.114:5126] 192.168.1.114 port 27988 -> 192.168.1.177 port 33934 codec: 0 ms: 20 2022-01-05 06:49:03.938183 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms 2022-01-05 06:49:03.938183 [DEBUG] switch_core_media.c:8977 sofia/default/957484@192.168.1.114:5126 Set 2833 dtmf send payload to 101 2022-01-05 06:49:03.938183 [DEBUG] switch_core_media.c:8984 sofia/default/957484@192.168.1.114:5126 Set 2833 dtmf receive payload to 101 2022-01-05 06:49:03.938183 [DEBUG] switch_core_media.c:9007 sofia/default/957484@192.168.1.114:5126 Set rtp dtmf delay to 40 2022-01-05 06:49:03.938183 [DEBUG] mod_sofia.c:898 Local SDP sofia/default/957484@192.168.1.114:5126: v=0 o=FreeSWITCH 1641337355 1641337356 IN IP4 192.168.1.114 s=FreeSWITCH c=IN IP4 192.168.1.114 t=0 0 m=audio 27988 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 2022-01-05 06:49:03.938183 [NOTICE] mod_dptools.c:1406 Channel [sofia/default/957484@192.168.1.114:5126] has been answered 2022-01-05 06:49:03.938183 [DEBUG] switch_channel.c:3865 (sofia/default/957484@192.168.1.114:5126) Callstate Change RINGING -> ACTIVE 2022-01-05 06:49:03.938183 [DEBUG] sofia.c:7326 Channel sofia/default/957484@192.168.1.114:5126 entering state [completed][200] EXECUTE [depth=0] sofia/default/957484@192.168.1.114:5126 sleep(2000) 2022-01-05 06:49:03.958178 [DEBUG] sofia.c:7326 Channel sofia/default/957484@192.168.1.114:5126 entering state [ready][200] 2022-01-05 06:49:04.338214 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [9228553890@192.168.1.114] from ip 192.168.1.59 2022-01-05 06:49:04.978176 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed. EXECUTE [depth=0] sofia/default/957484@192.168.1.114:5126 lua(astpp-callingcards.lua) 2022-01-05 06:49:05.938226 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes') 2022-01-05 06:49:05.958225 [NOTICE] switch_cpp.cpp:1447 [ASTPP] SECTION 2022-01-05 06:49:05.958225 [DEBUG] switch_core_file.c:405 File /var/www/html/astpp/upload/astpp-welcome.wav sample rate 16000 doesn't match requested rate 8000 2022-01-05 06:49:05.958225 [DEBUG] switch_ivr_play_say.c:1488 Codec Activated L16@8000hz 1 channels 20ms 2022-01-05 06:49:06.838162 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [9228553890@192.168.1.114] from ip 192.168.1.59 2022-01-05 06:49:07.738179 [DEBUG] switch_ivr_play_say.c:1933 done playing file /var/www/html/astpp/upload/astpp-welcome.wav 2022-01-05 06:49:07.738179 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [get_ani] Query :SELECT * FROM ani_map WHERE number = 957484 2022-01-05 06:49:07.738179 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT *,(select currencyrate from currency where id=currency_id) as currencyrate FROM accounts WHERE (number = "36" OR id="36") AND status=0 AND deleted=0 limit 1 2022-01-05 06:49:07.738179 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [validate_card_usage] Query :SELECT DATE_FORMAT('2032-01-03 06:28:50' , '%Y%m%d%H%i%s') AS expiry 2022-01-05 06:49:07.738179 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [validate_card_usage] Query :SELECT DATE_FORMAT('2022-01-05 06:49:07' , '%Y%m%d%H%i%s') AS expiry 2022-01-05 06:49:07.738179 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [Accountcode : 3470347082] 2022-01-05 06:49:07.738179 [ERR] mod_lua.cpp:202 /usr/share/freeswitch/scripts/astpp-callingcards.lua:103: attempt to concatenate field 'access_number_call_flow' (a nil value) stack traceback: /usr/share/freeswitch/scripts/astpp-callingcards.lua:103: in main chunk 2022-01-05 06:49:07.738179 [DEBUG] switch_cpp.cpp:1191 sofia/default/957484@192.168.1.114:5126 destroy/unlink session from object 2022-01-05 06:49:07.738179 [NOTICE] switch_core_state_machine.c:386 sofia/default/957484@192.168.1.114:5126 has executed the last dialplan instruction, hanging up. 2022-01-05 06:49:07.738179 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/default/957484@192.168.1.114:5126 [CS_EXECUTE] [NORMAL_CLEARING] 2022-01-05 06:49:07.738179 [DEBUG] switch_core_state_machine.c:651 (sofia/default/957484@192.168.1.114:5126) State EXECUTE going to sleep 2022-01-05 06:49:07.738179 [DEBUG] switch_core_state_machine.c:585 (sofia/default/957484@192.168.1.114:5126) Running State Change CS_HANGUP (Cur 1 Tot 17) 2022-01-05 06:49:07.738179 [DEBUG] switch_core_state_machine.c:848 (sofia/default/957484@192.168.1.114:5126) Callstate Change ACTIVE -> HANGUP 2022-01-05 06:49:07.738179 [DEBUG] switch_core_state_machine.c:850 (sofia/default/957484@192.168.1.114:5126) State HANGUP 2022-01-05 06:49:07.738179 [DEBUG] mod_sofia.c:453 Channel sofia/default/957484@192.168.1.114:5126 hanging up, cause: NORMAL_CLEARING 2022-01-05 06:49:07.758188 [DEBUG] mod_sofia.c:507 Sending BYE to sofia/default/957484@192.168.1.114:5126 2022-01-05 06:49:07.758188 [DEBUG] switch_core_state_machine.c:60 sofia/default/957484@192.168.1.114:5126 Standard HANGUP, cause: NORMAL_CLEARING 2022-01-05 06:49:07.758188 [DEBUG] switch_core_state_machine.c:850 (sofia/default/957484@192.168.1.114:5126) State HANGUP going to sleep 2022-01-05 06:49:07.758188 [DEBUG] switch_core_state_machine.c:620 (sofia/default/957484@192.168.1.114:5126) State Change CS_HANGUP -> CS_REPORTING 2022-01-05 06:49:07.758188 [DEBUG] switch_core_state_machine.c:585 (sofia/default/957484@192.168.1.114:5126) Running State Change CS_REPORTING (Cur 1 Tot 17) 2022-01-05 06:49:07.758188 [DEBUG] switch_core_state_machine.c:936 (sofia/default/957484@192.168.1.114:5126) State REPORTING 2022-01-05 06:49:07.758188 [INFO] mod_json_cdr.c:271 Process [4ccfe5e8-f376-4691-8d88-f5d7164d425b.cdr.json] 2022-01-05 06:49:07.778199 [DEBUG] switch_core_state_machine.c:174 sofia/default/957484@192.168.1.114:5126 Standard REPORTING, cause: NORMAL_CLEARING 2022-01-05 06:49:07.778199 [DEBUG] switch_core_state_machine.c:936 (sofia/default/957484@192.168.1.114:5126) State REPORTING going to sleep 2022-01-05 06:49:07.778199 [DEBUG] switch_core_state_machine.c:611 (sofia/default/957484@192.168.1.114:5126) State Change CS_REPORTING -> CS_DESTROY 2022-01-05 06:49:07.778199 [DEBUG] switch_core_session.c:1726 Session 17 (sofia/default/957484@192.168.1.114:5126) Locked, Waiting on external entities 2022-01-05 06:49:07.778199 [NOTICE] switch_core_session.c:1744 Session 17 (sofia/default/957484@192.168.1.114:5126) Ended 2022-01-05 06:49:07.778199 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/957484@192.168.1.114:5126 [CS_DESTROY] 2022-01-05 06:49:07.778199 [DEBUG] switch_core_state_machine.c:739 (sofia/default/957484@192.168.1.114:5126) Running State Change CS_DESTROY (Cur 0 Tot 17) 2022-01-05 06:49:07.778199 [DEBUG] switch_core_state_machine.c:749 (sofia/default/957484@192.168.1.114:5126) State DESTROY 2022-01-05 06:49:07.778199 [DEBUG] mod_sofia.c:364 sofia/default/957484@192.168.1.114:5126 SOFIA DESTROY 2022-01-05 06:49:07.778199 [DEBUG] switch_core_state_machine.c:181 sofia/default/957484@192.168.1.114:5126 Standard DESTROY 2022-01-05 06:49:07.778199 [DEBUG] switch_core_state_machine.c:749 (sofia/default/957484@192.168.1.114:5126) State DESTROY going to sleep freeswitch@Devops-jenkins> /exit root@inex-GDhaval:/home/inextrix/Downloads#