reeswitch@Devops-jenkins> freeswitch@Devops-jenkins> freeswitch@Devops-jenkins> freeswitch@Devops-jenkins> freeswitch@Devops-jenkins> 2022-01-05 13:35:01.049946 [NOTICE] switch_channel.c:1118 New Channel sofia/default/6565@192.168.1.114:5126 [6ccac49b-7a30-427f-9682-b0f4abb253de] 2022-01-05 13:35:01.049946 [DEBUG] switch_core_state_machine.c:585 (sofia/default/6565@192.168.1.114:5126) Running State Change CS_NEW (Cur 1 Tot 149) 2022-01-05 13:35:01.049946 [DEBUG] sofia.c:10280 sofia/default/6565@192.168.1.114:5126 receiving invite from 192.168.1.177:37931 version: 1.10.5 -release-17-25569c1631 64bit 2022-01-05 13:35:01.049946 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 192.168.1.177:0. 2022-01-05 13:35:01.049946 [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 13:35:01.049946 [DEBUG] switch_core_state_machine.c:604 (sofia/default/6565@192.168.1.114:5126) State NEW 2022-01-05 13:35:01.049946 [DEBUG] sofia.c:2434 detaching session 6ccac49b-7a30-427f-9682-b0f4abb253de 2022-01-05 13:35:01.110032 [DEBUG] sofia.c:2544 Re-attaching to session 6ccac49b-7a30-427f-9682-b0f4abb253de 2022-01-05 13:35:01.129961 [DEBUG] sofia.c:10280 sofia/default/6565@192.168.1.114:5126 receiving invite from 192.168.1.177:37931 version: 1.10.5 -release-17-25569c1631 64bit 2022-01-05 13:35:01.129961 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 192.168.1.177:0. 2022-01-05 13:35:01.129961 [DEBUG] sofia.c:11477 Setting NAT mode based on via port 2022-01-05 13:35:01.152849 [DEBUG] sofia.c:7326 Channel sofia/default/6565@192.168.1.114:5126 entering state [received][100] 2022-01-05 13:35:01.152849 [DEBUG] sofia.c:7336 Remote SDP: v=0 o=Zoiper 1641389701348 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 13:35:01.152849 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2022-01-05 13:35:01.152849 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2022-01-05 13:35:01.152849 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2022-01-05 13:35:01.152849 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000 2022-01-05 13:35:01.152849 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2022-01-05 13:35:01.152849 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2022-01-05 13:35:01.152849 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2022-01-05 13:35:01.152849 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1] 2022-01-05 13:35:01.152849 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1] 2022-01-05 13:35:01.152849 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/6565@192.168.1.114:5126 PCMU/8000 20 ms 160 samples 64000 bits 1 channels 2022-01-05 13:35:01.152849 [DEBUG] switch_core_codec.c:111 sofia/default/6565@192.168.1.114:5126 Original read codec set to PCMU:0 2022-01-05 13:35:01.152849 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000 2022-01-05 13:35:01.152849 [DEBUG] switch_core_media.c:5911 sofia/default/6565@192.168.1.114:5126 Set 2833 dtmf send payload to 101 recv payload to 101 2022-01-05 13:35:01.152849 [DEBUG] sofia.c:7760 (sofia/default/6565@192.168.1.114:5126) State Change CS_NEW -> CS_INIT 2022-01-05 13:35:01.152849 [DEBUG] switch_core_state_machine.c:585 (sofia/default/6565@192.168.1.114:5126) Running State Change CS_INIT (Cur 1 Tot 149) 2022-01-05 13:35:01.152849 [DEBUG] switch_core_state_machine.c:628 (sofia/default/6565@192.168.1.114:5126) State INIT 2022-01-05 13:35:01.152849 [DEBUG] mod_sofia.c:93 sofia/default/6565@192.168.1.114:5126 SOFIA INIT 2022-01-05 13:35:01.152849 [DEBUG] switch_core_state_machine.c:40 sofia/default/6565@192.168.1.114:5126 Standard INIT 2022-01-05 13:35:01.152849 [DEBUG] switch_core_state_machine.c:48 (sofia/default/6565@192.168.1.114:5126) State Change CS_INIT -> CS_ROUTING 2022-01-05 13:35:01.152849 [DEBUG] switch_core_state_machine.c:628 (sofia/default/6565@192.168.1.114:5126) State INIT going to sleep 2022-01-05 13:35:01.152849 [DEBUG] switch_core_state_machine.c:585 (sofia/default/6565@192.168.1.114:5126) Running State Change CS_ROUTING (Cur 1 Tot 149) 2022-01-05 13:35:01.152849 [DEBUG] switch_channel.c:2332 (sofia/default/6565@192.168.1.114:5126) Callstate Change DOWN -> RINGING 2022-01-05 13:35:01.152849 [DEBUG] switch_core_state_machine.c:644 (sofia/default/6565@192.168.1.114:5126) State ROUTING 2022-01-05 13:35:01.152849 [DEBUG] mod_sofia.c:154 sofia/default/6565@192.168.1.114:5126 SOFIA ROUTING 2022-01-05 13:35:01.152849 [DEBUG] switch_core_state_machine.c:236 sofia/default/6565@192.168.1.114:5126 Standard ROUTING 2022-01-05 13:35:01.152849 [INFO] mod_dialplan_xml.c:637 Processing 6565 <6565>->1800 in context default 2022-01-05 13:35:01.152849 [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 13:35:01.152849 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2022-01-05 13:35:01.152849 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Dialed number : 1800 2022-01-05 13:35:01.152849 [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 13:35:01.152849 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Generated XML:
Dialplan: sofia/default/6565@192.168.1.114:5126 parsing [default->1800] continue=false Dialplan: sofia/default/6565@192.168.1.114:5126 Regex (PASS) [1800] destination_number(1800) =~ /1800/ break=on-false Dialplan: sofia/default/6565@192.168.1.114:5126 Action log(INFO ASTPP - Calling Card Call) Dialplan: sofia/default/6565@192.168.1.114:5126 Action answer() Dialplan: sofia/default/6565@192.168.1.114:5126 Action sleep(2000) Dialplan: sofia/default/6565@192.168.1.114:5126 Action lua(astpp-callingcards.lua) 2022-01-05 13:35:01.189962 [DEBUG] switch_core_state_machine.c:287 (sofia/default/6565@192.168.1.114:5126) State Change CS_ROUTING -> CS_EXECUTE 2022-01-05 13:35:01.189962 [DEBUG] switch_core_state_machine.c:644 (sofia/default/6565@192.168.1.114:5126) State ROUTING going to sleep 2022-01-05 13:35:01.189962 [DEBUG] switch_core_state_machine.c:585 (sofia/default/6565@192.168.1.114:5126) Running State Change CS_EXECUTE (Cur 1 Tot 149) 2022-01-05 13:35:01.189962 [DEBUG] switch_core_state_machine.c:651 (sofia/default/6565@192.168.1.114:5126) State EXECUTE 2022-01-05 13:35:01.189962 [DEBUG] mod_sofia.c:209 sofia/default/6565@192.168.1.114:5126 SOFIA EXECUTE 2022-01-05 13:35:01.189962 [DEBUG] switch_core_state_machine.c:329 sofia/default/6565@192.168.1.114:5126 Standard EXECUTE EXECUTE [depth=0] sofia/default/6565@192.168.1.114:5126 log(INFO ASTPP - Calling Card Call) 2022-01-05 13:35:01.189962 [INFO] mod_dptools.c:1866 ASTPP - Calling Card Call EXECUTE [depth=0] sofia/default/6565@192.168.1.114:5126 answer() 2022-01-05 13:35:01.229942 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/6565@192.168.1.114:5126] 192.168.1.114 port 27988 -> 192.168.1.177 port 33934 codec: 0 ms: 20 2022-01-05 13:35:01.229942 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms 2022-01-05 13:35:01.229942 [DEBUG] switch_core_media.c:8977 sofia/default/6565@192.168.1.114:5126 Set 2833 dtmf send payload to 101 2022-01-05 13:35:01.229942 [DEBUG] switch_core_media.c:8984 sofia/default/6565@192.168.1.114:5126 Set 2833 dtmf receive payload to 101 2022-01-05 13:35:01.229942 [DEBUG] switch_core_media.c:9007 sofia/default/6565@192.168.1.114:5126 Set rtp dtmf delay to 40 2022-01-05 13:35:01.229942 [DEBUG] mod_sofia.c:898 Local SDP sofia/default/6565@192.168.1.114:5126: v=0 o=FreeSWITCH 1641361713 1641361714 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 13:35:01.229942 [NOTICE] mod_dptools.c:1406 Channel [sofia/default/6565@192.168.1.114:5126] has been answered 2022-01-05 13:35:01.229942 [DEBUG] switch_channel.c:3865 (sofia/default/6565@192.168.1.114:5126) Callstate Change RINGING -> ACTIVE 2022-01-05 13:35:01.229942 [DEBUG] sofia.c:7326 Channel sofia/default/6565@192.168.1.114:5126 entering state [completed][200] EXECUTE [depth=0] sofia/default/6565@192.168.1.114:5126 sleep(2000) 2022-01-05 13:35:01.249968 [DEBUG] sofia.c:7326 Channel sofia/default/6565@192.168.1.114:5126 entering state [ready][200] 2022-01-05 13:35:02.169958 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed. EXECUTE [depth=0] sofia/default/6565@192.168.1.114:5126 lua(astpp-callingcards.lua) 2022-01-05 13:35:03.229945 [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 13:35:03.250004 [NOTICE] switch_cpp.cpp:1447 [ASTPP] SECTION 2022-01-05 13:35:03.250004 [WARNING] switch_core_file.c:424 File has 2 channels, muxing to 1 channel will occur. 2022-01-05 13:35:03.250004 [DEBUG] switch_ivr_play_say.c:1488 Codec Activated L16@8000hz 1 channels 20ms 2022-01-05 13:35:36.769943 [DEBUG] switch_ivr_play_say.c:1933 done playing file /var/www/html/astpp/upload/file_example_WAV_1MG.wav 2022-01-05 13:35:36.769943 [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 2022-01-05 13:35:36.769943 [DEBUG] switch_ivr_play_say.c:1488 Codec Activated L16@8000hz 1 channels 20ms 2022-01-05 13:35:39.769942 [DEBUG] switch_ivr_play_say.c:1933 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-accountnum.wav 2022-01-05 13:35:40.689945 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF 1:960 2022-01-05 13:35:40.689945 [INFO] switch_channel.c:522 RECV DTMF 1:960 2022-01-05 13:35:41.649942 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF 8:960 2022-01-05 13:35:41.649942 [INFO] switch_channel.c:522 RECV DTMF 8:960 2022-01-05 13:35:42.549941 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF 5:960 2022-01-05 13:35:42.549941 [INFO] switch_channel.c:522 RECV DTMF 5:960 2022-01-05 13:35:43.029964 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF 1:960 2022-01-05 13:35:43.029964 [INFO] switch_channel.c:522 RECV DTMF 1:960 2022-01-05 13:35:44.369949 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF 0:960 2022-01-05 13:35:44.369949 [INFO] switch_channel.c:522 RECV DTMF 0:960 2022-01-05 13:35:44.749943 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF 7:960 2022-01-05 13:35:44.749943 [INFO] switch_channel.c:522 RECV DTMF 7:960 2022-01-05 13:35:45.129944 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF 4:960 2022-01-05 13:35:45.129944 [INFO] switch_channel.c:522 RECV DTMF 4:960 2022-01-05 13:35:45.789950 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF 0:960 2022-01-05 13:35:45.789950 [INFO] switch_channel.c:522 RECV DTMF 0:960 2022-01-05 13:35:46.369942 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF 2:960 2022-01-05 13:35:46.369942 [INFO] switch_channel.c:522 RECV DTMF 2:960 2022-01-05 13:35:46.669959 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF 9:960 2022-01-05 13:35:46.669959 [INFO] switch_channel.c:522 RECV DTMF 9:960 2022-01-05 13:35:47.169942 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF #:960 2022-01-05 13:35:47.169942 [INFO] switch_channel.c:522 RECV DTMF #:960 2022-01-05 13:35:47.169942 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Got DTMF digits: 1851074029 2022-01-05 13:35:47.169942 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT *,(select currencyrate from currency where id=currency_id) as currencyrate FROM accounts WHERE number = "1851074029" AND status=0 AND deleted=0 limit 1 2022-01-05 13:35:47.169942 [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 2022-01-05 13:35:47.169942 [DEBUG] switch_ivr_play_say.c:1488 Codec Activated L16@8000hz 1 channels 20ms 2022-01-05 13:35:49.889940 [DEBUG] switch_ivr_play_say.c:1933 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-pleasepin.wav 2022-01-05 13:35:50.669972 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF 5:960 2022-01-05 13:35:50.669972 [INFO] switch_channel.c:522 RECV DTMF 5:960 2022-01-05 13:35:51.109967 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF 0:960 2022-01-05 13:35:51.109967 [INFO] switch_channel.c:522 RECV DTMF 0:960 2022-01-05 13:35:51.450017 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF 7:960 2022-01-05 13:35:51.450017 [INFO] switch_channel.c:522 RECV DTMF 7:960 2022-01-05 13:35:51.709978 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF 9:960 2022-01-05 13:35:51.709978 [INFO] switch_channel.c:522 RECV DTMF 9:960 2022-01-05 13:35:52.189971 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF 2:960 2022-01-05 13:35:52.189971 [INFO] switch_channel.c:522 RECV DTMF 2:960 2022-01-05 13:35:52.470010 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF 1:960 2022-01-05 13:35:52.470010 [INFO] switch_channel.c:522 RECV DTMF 1:960 2022-01-05 13:35:52.909956 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF #:960 2022-01-05 13:35:52.909956 [INFO] switch_channel.c:522 RECV DTMF #:960 2022-01-05 13:35:52.909956 [DEBUG] switch_cpp.cpp:1447 [ASTPP] We Recieved A Pin Number : 507921 2022-01-05 13:35:52.909956 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [validate_card_usage] Query :SELECT DATE_FORMAT('2032-01-03 13:25:40' , '%Y%m%d%H%i%s') AS expiry 2022-01-05 13:35:52.909956 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [validate_card_usage] Query :SELECT DATE_FORMAT('2022-01-05 13:35:52' , '%Y%m%d%H%i%s') AS expiry 2022-01-05 13:35:52.909956 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [Accountcode : 1851074029] 2022-01-05 13:35:52.909956 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [HP:access_number_call_flow[0:Go Direct Dial,1:Go IVR] : 1] 2022-01-05 13:35:52.909956 [INFO] switch_cpp.cpp:1447 [ASTPP] Press 1 for make call, Press 2 for Recharge using Coupon 2022-01-05 13:35:52.909956 [WARNING] mod_sndfile.c:281 Error Opening File [/usr/share/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.] 2022-01-05 13:35:52.909956 [WARNING] mod_sndfile.c:281 Error Opening File [/usr/share/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.] 2022-01-05 13:35:52.909956 [WARNING] mod_sndfile.c:281 Error Opening File [/usr/share/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.] 2022-01-05 13:35:52.909956 [INFO] switch_cpp.cpp:1447 [ASTPP] ivr_custom_dtmf:: 2022-01-05 13:35:52.909956 [INFO] switch_cpp.cpp:1447 [ASTPP] Press 1 for make call, Press 2 for Recharge using Coupon 2022-01-05 13:35:52.909956 [WARNING] mod_sndfile.c:281 Error Opening File [/usr/share/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.] 2022-01-05 13:35:52.909956 [WARNING] mod_sndfile.c:281 Error Opening File [/usr/share/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.] 2022-01-05 13:35:52.909956 [WARNING] mod_sndfile.c:281 Error Opening File [/usr/share/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.] 2022-01-05 13:35:52.909956 [INFO] switch_cpp.cpp:1447 [ASTPP] ivr_custom_dtmf:: 2022-01-05 13:35:52.909956 [INFO] switch_cpp.cpp:1447 [ASTPP] Press 1 for make call, Press 2 for Recharge using Coupon 2022-01-05 13:35:52.909956 [WARNING] mod_sndfile.c:281 Error Opening File [/usr/share/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.] 2022-01-05 13:35:52.909956 [WARNING] mod_sndfile.c:281 Error Opening File [/usr/share/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.] 2022-01-05 13:35:52.909956 [WARNING] mod_sndfile.c:281 Error Opening File [/usr/share/freeswitch/sounds/en/us/callie/astpp-dailnumber-or-refill.wav] [System error : No such file or directory.] 2022-01-05 13:35:52.909956 [INFO] switch_cpp.cpp:1447 [ASTPP] ivr_custom_dtmf:: 2022-01-05 13:35:52.909956 [DEBUG] switch_core_file.c:405 File /usr/share/freeswitch/sounds/en/us/callie/astpp-goodbye.wav sample rate 16000 doesn't match requested rate 8000 2022-01-05 13:35:52.909956 [DEBUG] switch_ivr_play_say.c:1488 Codec Activated L16@8000hz 1 channels 20ms 2022-01-05 13:35:53.709994 [DEBUG] switch_ivr_play_say.c:1933 done playing file /usr/share/freeswitch/sounds/en/us/callie/astpp-goodbye.wav 2022-01-05 13:35:53.709994 [DEBUG] switch_cpp.cpp:731 CoreSession::hangup 2022-01-05 13:35:53.709994 [NOTICE] switch_cpp.cpp:733 Hangup sofia/default/6565@192.168.1.114:5126 [CS_EXECUTE] [NORMAL_CLEARING] 2022-01-05 13:35:53.709994 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Got DTMF Digits: retries:0 2022-01-05 13:35:53.709994 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Got DTMF Digits: retries:1 2022-01-05 13:35:53.709994 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Got DTMF Digits: retries:2 2022-01-05 13:35:53.709994 [DEBUG] switch_cpp.cpp:731 CoreSession::hangup 2022-01-05 13:35:53.709994 [DEBUG] switch_cpp.cpp:1191 sofia/default/6565@192.168.1.114:5126 destroy/unlink session from object 2022-01-05 13:35:53.709994 [DEBUG] switch_core_session.c:2905 sofia/default/6565@192.168.1.114:5126 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2022-01-05 13:35:53.709994 [DEBUG] switch_core_state_machine.c:651 (sofia/default/6565@192.168.1.114:5126) State EXECUTE going to sleep 2022-01-05 13:35:53.709994 [DEBUG] switch_core_state_machine.c:585 (sofia/default/6565@192.168.1.114:5126) Running State Change CS_HANGUP (Cur 1 Tot 149) 2022-01-05 13:35:53.709994 [DEBUG] switch_core_state_machine.c:848 (sofia/default/6565@192.168.1.114:5126) Callstate Change ACTIVE -> HANGUP 2022-01-05 13:35:53.709994 [DEBUG] switch_core_state_machine.c:850 (sofia/default/6565@192.168.1.114:5126) State HANGUP 2022-01-05 13:35:53.709994 [DEBUG] mod_sofia.c:453 Channel sofia/default/6565@192.168.1.114:5126 hanging up, cause: NORMAL_CLEARING 2022-01-05 13:35:53.709994 [DEBUG] mod_sofia.c:507 Sending BYE to sofia/default/6565@192.168.1.114:5126 2022-01-05 13:35:53.709994 [DEBUG] switch_core_state_machine.c:60 sofia/default/6565@192.168.1.114:5126 Standard HANGUP, cause: NORMAL_CLEARING 2022-01-05 13:35:53.709994 [DEBUG] switch_core_state_machine.c:850 (sofia/default/6565@192.168.1.114:5126) State HANGUP going to sleep 2022-01-05 13:35:53.709994 [DEBUG] switch_core_state_machine.c:620 (sofia/default/6565@192.168.1.114:5126) State Change CS_HANGUP -> CS_REPORTING 2022-01-05 13:35:53.709994 [DEBUG] switch_core_state_machine.c:585 (sofia/default/6565@192.168.1.114:5126) Running State Change CS_REPORTING (Cur 1 Tot 149) 2022-01-05 13:35:53.709994 [DEBUG] switch_core_state_machine.c:936 (sofia/default/6565@192.168.1.114:5126) State REPORTING 2022-01-05 13:35:53.709994 [INFO] mod_json_cdr.c:271 Process [6ccac49b-7a30-427f-9682-b0f4abb253de.cdr.json] 2022-01-05 13:35:53.730011 [DEBUG] switch_core_state_machine.c:174 sofia/default/6565@192.168.1.114:5126 Standard REPORTING, cause: NORMAL_CLEARING 2022-01-05 13:35:53.730011 [DEBUG] switch_core_state_machine.c:936 (sofia/default/6565@192.168.1.114:5126) State REPORTING going to sleep 2022-01-05 13:35:53.730011 [DEBUG] switch_core_state_machine.c:611 (sofia/default/6565@192.168.1.114:5126) State Change CS_REPORTING -> CS_DESTROY 2022-01-05 13:35:53.730011 [DEBUG] switch_core_session.c:1726 Session 149 (sofia/default/6565@192.168.1.114:5126) Locked, Waiting on external entities 2022-01-05 13:35:53.730011 [NOTICE] switch_core_session.c:1744 Session 149 (sofia/default/6565@192.168.1.114:5126) Ended 2022-01-05 13:35:53.730011 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/6565@192.168.1.114:5126 [CS_DESTROY] 2022-01-05 13:35:53.730011 [DEBUG] switch_core_state_machine.c:739 (sofia/default/6565@192.168.1.114:5126) Running State Change CS_DESTROY (Cur 0 Tot 149) 2022-01-05 13:35:53.730011 [DEBUG] switch_core_state_machine.c:749 (sofia/default/6565@192.168.1.114:5126) State DESTROY 2022-01-05 13:35:53.730011 [DEBUG] mod_sofia.c:364 sofia/default/6565@192.168.1.114:5126 SOFIA DESTROY 2022-01-05 13:35:53.730011 [DEBUG] switch_core_state_machine.c:181 sofia/default/6565@192.168.1.114:5126 Standard DESTROY 2022-01-05 13:35:53.730011 [DEBUG] switch_core_state_machine.c:749 (sofia/default/6565@192.168.1.114:5126) State DESTROY going to sleep freeswitch@Devops-jenkins> /exit root@inex-GDhaval:/home/inextrix/Downloads# root@inex-GDhaval:/home/inextrix/Downloads#