Type /help to see a list of commands +OK log level [7] freeswitch@ip-172-31-40-49> freeswitch@ip-172-31-40-49> freeswitch@ip-172-31-40-49> freeswitch@ip-172-31-40-49> freeswitch@ip-172-31-40-49> freeswitch@ip-172-31-40-49> freeswitch@ip-172-31-40-49> 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables 2022-09-22 12:18:03.069963 97.80% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1515121325@13.127.15.161:1 [2ce42887-20ab-48eb-8e56-8faac22ba62b] 2022-09-22 12:18:03.069963 97.80% [DEBUG] mod_sofia.c:5121 (sofia/default/1515121325@13.127.15.161:1) State Change CS_NEW -> CS_INIT 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@13.127.15.161:1) Running State Change CS_INIT (Cur 1 Tot 118) 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1515121325@13.127.15.161:1) State INIT 2022-09-22 12:18:03.069963 97.80% [DEBUG] mod_sofia.c:97 sofia/default/1515121325@13.127.15.161:1 SOFIA INIT 2022-09-22 12:18:03.069963 97.80% [INFO] sofia_glue.c:1651 sofia/default/1515121325@13.127.15.161:1 sending invite call-id: (null) 2022-09-22 12:18:03.069963 97.80% [DEBUG] sofia_glue.c:1654 sofia/default/1515121325@13.127.15.161:1 sending invite version: 1.10.7 -release-19-883d2cb662 64bit Local SDP: v=0 o=FreeSWITCH 1663817287 1663817288 IN IP4 13.127.15.161 s=FreeSWITCH c=IN IP4 13.127.15.161 t=0 0 m=audio 31796 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:40 sofia/default/1515121325@13.127.15.161:1 Standard INIT 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1515121325@13.127.15.161:1) State Change CS_INIT -> CS_ROUTING 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1515121325@13.127.15.161:1) State INIT going to sleep 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@13.127.15.161:1) Running State Change CS_ROUTING (Cur 1 Tot 118) 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1515121325@13.127.15.161:1) State ROUTING 2022-09-22 12:18:03.069963 97.80% [DEBUG] mod_sofia.c:158 sofia/default/1515121325@13.127.15.161:1 SOFIA ROUTING 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_ivr_originate.c:67 (sofia/default/1515121325@13.127.15.161:1) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1515121325@13.127.15.161:1) State ROUTING going to sleep 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@13.127.15.161:1) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 118) 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1515121325@13.127.15.161:1) State CONSUME_MEDIA 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1515121325@13.127.15.161:1) State CONSUME_MEDIA going to sleep 2022-09-22 12:18:03.069963 97.80% [DEBUG] sofia.c:7499 Channel sofia/default/1515121325@13.127.15.161:1 entering state [calling][0] 2022-09-22 12:18:03.069963 97.80% [DEBUG] sofia.c:7499 Channel sofia/default/1515121325@13.127.15.161:1 entering state [terminated][503] 2022-09-22 12:18:03.069963 97.80% [NOTICE] sofia.c:8738 Hangup sofia/default/1515121325@13.127.15.161:1 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE] 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@13.127.15.161:1) Running State Change CS_HANGUP (Cur 1 Tot 118) 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1515121325@13.127.15.161:1) Callstate Change DOWN -> HANGUP 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1515121325@13.127.15.161:1) State HANGUP 2022-09-22 12:18:03.069963 97.80% [DEBUG] mod_sofia.c:468 Channel sofia/default/1515121325@13.127.15.161:1 hanging up, cause: NORMAL_TEMPORARY_FAILURE 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:59 sofia/default/1515121325@13.127.15.161:1 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1515121325@13.127.15.161:1) State HANGUP going to sleep 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1515121325@13.127.15.161:1) State Change CS_HANGUP -> CS_REPORTING 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1515121325@13.127.15.161:1) Running State Change CS_REPORTING (Cur 1 Tot 118) 2022-09-22 12:18:03.069963 97.80% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1515121325@13.127.15.161:1) State REPORTING 2022-09-22 12:18:03.069963 97.80% [INFO] mod_json_cdr.c:271 Process [2ce42887-20ab-48eb-8e56-8faac22ba62b.cdr.json] 2022-09-22 12:18:03.089923 97.80% [DEBUG] switch_core_state_machine.c:168 sofia/default/1515121325@13.127.15.161:1 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE 2022-09-22 12:18:03.089923 97.80% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1515121325@13.127.15.161:1) State REPORTING going to sleep 2022-09-22 12:18:03.089923 97.80% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1515121325@13.127.15.161:1) State Change CS_REPORTING -> CS_DESTROY 2022-09-22 12:18:03.089923 97.80% [DEBUG] switch_core_session.c:1753 Session 118 (sofia/default/1515121325@13.127.15.161:1) Locked, Waiting on external entities 2022-09-22 12:18:03.089923 97.80% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE] 2022-09-22 12:18:03.089923 97.80% [NOTICE] switch_core_session.c:1771 Session 118 (sofia/default/1515121325@13.127.15.161:1) Ended 2022-09-22 12:18:03.089923 97.80% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1515121325@13.127.15.161:1 [CS_DESTROY] 2022-09-22 12:18:03.089923 97.80% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1515121325@13.127.15.161:1) Running State Change CS_DESTROY (Cur 0 Tot 118) 2022-09-22 12:18:03.089923 97.80% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1515121325@13.127.15.161:1) State DESTROY 2022-09-22 12:18:03.089923 97.80% [DEBUG] mod_sofia.c:379 sofia/default/1515121325@13.127.15.161:1 SOFIA DESTROY 2022-09-22 12:18:03.089923 97.80% [DEBUG] switch_core_state_machine.c:175 sofia/default/1515121325@13.127.15.161:1 Standard DESTROY 2022-09-22 12:18:03.089923 97.80% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1515121325@13.127.15.161:1) State DESTROY going to sleep 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables 2022-09-22 12:18:04.109928 97.73% [NOTICE] switch_channel.c:1123 New Channel sofia/default/2536854752@13.127.15.161:1 [54f5988c-c359-4c9e-9997-71e7d7daf73e] 2022-09-22 12:18:04.109928 97.73% [DEBUG] mod_sofia.c:5121 (sofia/default/2536854752@13.127.15.161:1) State Change CS_NEW -> CS_INIT 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@13.127.15.161:1) Running State Change CS_INIT (Cur 1 Tot 119) 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:624 (sofia/default/2536854752@13.127.15.161:1) State INIT 2022-09-22 12:18:04.109928 97.73% [DEBUG] mod_sofia.c:97 sofia/default/2536854752@13.127.15.161:1 SOFIA INIT 2022-09-22 12:18:04.109928 97.73% [INFO] sofia_glue.c:1651 sofia/default/2536854752@13.127.15.161:1 sending invite call-id: (null) 2022-09-22 12:18:04.109928 97.73% [DEBUG] sofia_glue.c:1654 sofia/default/2536854752@13.127.15.161:1 sending invite version: 1.10.7 -release-19-883d2cb662 64bit Local SDP: v=0 o=FreeSWITCH 1663823858 1663823859 IN IP4 13.127.15.161 s=FreeSWITCH c=IN IP4 13.127.15.161 t=0 0 m=audio 25226 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:40 sofia/default/2536854752@13.127.15.161:1 Standard INIT 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:48 (sofia/default/2536854752@13.127.15.161:1) State Change CS_INIT -> CS_ROUTING 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:624 (sofia/default/2536854752@13.127.15.161:1) State INIT going to sleep 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@13.127.15.161:1) Running State Change CS_ROUTING (Cur 1 Tot 119) 2022-09-22 12:18:04.109928 97.73% [DEBUG] sofia.c:7499 Channel sofia/default/2536854752@13.127.15.161:1 entering state [calling][0] 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:640 (sofia/default/2536854752@13.127.15.161:1) State ROUTING 2022-09-22 12:18:04.109928 97.73% [DEBUG] mod_sofia.c:158 sofia/default/2536854752@13.127.15.161:1 SOFIA ROUTING 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_ivr_originate.c:67 (sofia/default/2536854752@13.127.15.161:1) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:640 (sofia/default/2536854752@13.127.15.161:1) State ROUTING going to sleep 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@13.127.15.161:1) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 119) 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:659 (sofia/default/2536854752@13.127.15.161:1) State CONSUME_MEDIA 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:659 (sofia/default/2536854752@13.127.15.161:1) State CONSUME_MEDIA going to sleep 2022-09-22 12:18:04.109928 97.73% [DEBUG] sofia.c:7499 Channel sofia/default/2536854752@13.127.15.161:1 entering state [terminated][503] 2022-09-22 12:18:04.109928 97.73% [NOTICE] sofia.c:8738 Hangup sofia/default/2536854752@13.127.15.161:1 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE] 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@13.127.15.161:1) Running State Change CS_HANGUP (Cur 1 Tot 119) 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:844 (sofia/default/2536854752@13.127.15.161:1) Callstate Change DOWN -> HANGUP 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:846 (sofia/default/2536854752@13.127.15.161:1) State HANGUP 2022-09-22 12:18:04.109928 97.73% [DEBUG] mod_sofia.c:468 Channel sofia/default/2536854752@13.127.15.161:1 hanging up, cause: NORMAL_TEMPORARY_FAILURE 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:59 sofia/default/2536854752@13.127.15.161:1 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:846 (sofia/default/2536854752@13.127.15.161:1) State HANGUP going to sleep 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:616 (sofia/default/2536854752@13.127.15.161:1) State Change CS_HANGUP -> CS_REPORTING 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2536854752@13.127.15.161:1) Running State Change CS_REPORTING (Cur 1 Tot 119) 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:932 (sofia/default/2536854752@13.127.15.161:1) State REPORTING 2022-09-22 12:18:04.109928 97.73% [INFO] mod_json_cdr.c:271 Process [54f5988c-c359-4c9e-9997-71e7d7daf73e.cdr.json] 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:168 sofia/default/2536854752@13.127.15.161:1 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:932 (sofia/default/2536854752@13.127.15.161:1) State REPORTING going to sleep 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_state_machine.c:607 (sofia/default/2536854752@13.127.15.161:1) State Change CS_REPORTING -> CS_DESTROY 2022-09-22 12:18:04.109928 97.73% [DEBUG] switch_core_session.c:1753 Session 119 (sofia/default/2536854752@13.127.15.161:1) Locked, Waiting on external entities 2022-09-22 12:18:04.129921 97.73% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE] 2022-09-22 12:18:04.129921 97.73% [NOTICE] switch_core_session.c:1771 Session 119 (sofia/default/2536854752@13.127.15.161:1) Ended 2022-09-22 12:18:04.129921 97.73% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/2536854752@13.127.15.161:1 [CS_DESTROY] 2022-09-22 12:18:04.129921 97.73% [DEBUG] switch_core_state_machine.c:735 (sofia/default/2536854752@13.127.15.161:1) Running State Change CS_DESTROY (Cur 0 Tot 119) 2022-09-22 12:18:04.129921 97.73% [DEBUG] switch_core_state_machine.c:745 (sofia/default/2536854752@13.127.15.161:1) State DESTROY 2022-09-22 12:18:04.129921 97.73% [DEBUG] mod_sofia.c:379 sofia/default/2536854752@13.127.15.161:1 SOFIA DESTROY 2022-09-22 12:18:04.129921 97.73% [DEBUG] switch_core_state_machine.c:175 sofia/default/2536854752@13.127.15.161:1 Standard DESTROY 2022-09-22 12:18:04.129921 97.73% [DEBUG] switch_core_state_machine.c:745 (sofia/default/2536854752@13.127.15.161:1) State DESTROY going to sleep 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables 2022-09-22 12:18:05.129932 97.67% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1554856322@13.127.15.161:1 [adb812e1-d181-41f2-8a9d-dedfd9d608cd] 2022-09-22 12:18:05.129932 97.67% [DEBUG] mod_sofia.c:5121 (sofia/default/1554856322@13.127.15.161:1) State Change CS_NEW -> CS_INIT 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@13.127.15.161:1) Running State Change CS_INIT (Cur 1 Tot 120) 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1554856322@13.127.15.161:1) State INIT 2022-09-22 12:18:05.129932 97.67% [DEBUG] mod_sofia.c:97 sofia/default/1554856322@13.127.15.161:1 SOFIA INIT 2022-09-22 12:18:05.129932 97.67% [INFO] sofia_glue.c:1651 sofia/default/1554856322@13.127.15.161:1 sending invite call-id: (null) 2022-09-22 12:18:05.129932 97.67% [DEBUG] sofia_glue.c:1654 sofia/default/1554856322@13.127.15.161:1 sending invite version: 1.10.7 -release-19-883d2cb662 64bit Local SDP: v=0 o=FreeSWITCH 1663823961 1663823962 IN IP4 13.127.15.161 s=FreeSWITCH c=IN IP4 13.127.15.161 t=0 0 m=audio 25124 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:40 sofia/default/1554856322@13.127.15.161:1 Standard INIT 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1554856322@13.127.15.161:1) State Change CS_INIT -> CS_ROUTING 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1554856322@13.127.15.161:1) State INIT going to sleep 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@13.127.15.161:1) Running State Change CS_ROUTING (Cur 1 Tot 120) 2022-09-22 12:18:05.129932 97.67% [DEBUG] sofia.c:7499 Channel sofia/default/1554856322@13.127.15.161:1 entering state [calling][0] 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1554856322@13.127.15.161:1) State ROUTING 2022-09-22 12:18:05.129932 97.67% [DEBUG] mod_sofia.c:158 sofia/default/1554856322@13.127.15.161:1 SOFIA ROUTING 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_ivr_originate.c:67 (sofia/default/1554856322@13.127.15.161:1) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1554856322@13.127.15.161:1) State ROUTING going to sleep 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@13.127.15.161:1) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 120) 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1554856322@13.127.15.161:1) State CONSUME_MEDIA 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1554856322@13.127.15.161:1) State CONSUME_MEDIA going to sleep 2022-09-22 12:18:05.129932 97.67% [DEBUG] sofia.c:7499 Channel sofia/default/1554856322@13.127.15.161:1 entering state [terminated][503] 2022-09-22 12:18:05.129932 97.67% [NOTICE] sofia.c:8738 Hangup sofia/default/1554856322@13.127.15.161:1 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE] 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@13.127.15.161:1) Running State Change CS_HANGUP (Cur 1 Tot 120) 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1554856322@13.127.15.161:1) Callstate Change DOWN -> HANGUP 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1554856322@13.127.15.161:1) State HANGUP 2022-09-22 12:18:05.129932 97.67% [DEBUG] mod_sofia.c:468 Channel sofia/default/1554856322@13.127.15.161:1 hanging up, cause: NORMAL_TEMPORARY_FAILURE 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:59 sofia/default/1554856322@13.127.15.161:1 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1554856322@13.127.15.161:1) State HANGUP going to sleep 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1554856322@13.127.15.161:1) State Change CS_HANGUP -> CS_REPORTING 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1554856322@13.127.15.161:1) Running State Change CS_REPORTING (Cur 1 Tot 120) 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1554856322@13.127.15.161:1) State REPORTING 2022-09-22 12:18:05.129932 97.67% [INFO] mod_json_cdr.c:271 Process [adb812e1-d181-41f2-8a9d-dedfd9d608cd.cdr.json] 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:168 sofia/default/1554856322@13.127.15.161:1 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1554856322@13.127.15.161:1) State REPORTING going to sleep 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1554856322@13.127.15.161:1) State Change CS_REPORTING -> CS_DESTROY 2022-09-22 12:18:05.129932 97.67% [DEBUG] switch_core_session.c:1753 Session 120 (sofia/default/1554856322@13.127.15.161:1) Locked, Waiting on external entities 2022-09-22 12:18:05.149948 97.67% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE] 2022-09-22 12:18:05.149948 97.67% [NOTICE] switch_core_session.c:1771 Session 120 (sofia/default/1554856322@13.127.15.161:1) Ended 2022-09-22 12:18:05.149948 97.67% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1554856322@13.127.15.161:1 [CS_DESTROY] 2022-09-22 12:18:05.149948 97.67% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1554856322@13.127.15.161:1) Running State Change CS_DESTROY (Cur 0 Tot 120) 2022-09-22 12:18:05.149948 97.67% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1554856322@13.127.15.161:1) State DESTROY 2022-09-22 12:18:05.149948 97.67% [DEBUG] mod_sofia.c:379 sofia/default/1554856322@13.127.15.161:1 SOFIA DESTROY 2022-09-22 12:18:05.149948 97.67% [DEBUG] switch_core_state_machine.c:175 sofia/default/1554856322@13.127.15.161:1 Standard DESTROY 2022-09-22 12:18:05.149948 97.67% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1554856322@13.127.15.161:1) State DESTROY going to sleep 2022-09-22 12:18:06.149931 97.60% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables 2022-09-22 12:18:06.169918 97.60% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1547964325@13.127.15.161:1 [8d0bba73-f95b-481f-a9a7-83486c6740da] 2022-09-22 12:18:06.169918 97.60% [DEBUG] mod_sofia.c:5121 (sofia/default/1547964325@13.127.15.161:1) State Change CS_NEW -> CS_INIT 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@13.127.15.161:1) Running State Change CS_INIT (Cur 1 Tot 121) 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1547964325@13.127.15.161:1) State INIT 2022-09-22 12:18:06.169918 97.60% [DEBUG] mod_sofia.c:97 sofia/default/1547964325@13.127.15.161:1 SOFIA INIT 2022-09-22 12:18:06.169918 97.60% [INFO] sofia_glue.c:1651 sofia/default/1547964325@13.127.15.161:1 sending invite call-id: (null) 2022-09-22 12:18:06.169918 97.60% [DEBUG] sofia_glue.c:1654 sofia/default/1547964325@13.127.15.161:1 sending invite version: 1.10.7 -release-19-883d2cb662 64bit Local SDP: v=0 o=FreeSWITCH 1663825622 1663825623 IN IP4 13.127.15.161 s=FreeSWITCH c=IN IP4 13.127.15.161 t=0 0 m=audio 23464 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:40 sofia/default/1547964325@13.127.15.161:1 Standard INIT 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1547964325@13.127.15.161:1) State Change CS_INIT -> CS_ROUTING 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1547964325@13.127.15.161:1) State INIT going to sleep 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@13.127.15.161:1) Running State Change CS_ROUTING (Cur 1 Tot 121) 2022-09-22 12:18:06.169918 97.60% [DEBUG] sofia.c:7499 Channel sofia/default/1547964325@13.127.15.161:1 entering state [calling][0] 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1547964325@13.127.15.161:1) State ROUTING 2022-09-22 12:18:06.169918 97.60% [DEBUG] mod_sofia.c:158 sofia/default/1547964325@13.127.15.161:1 SOFIA ROUTING 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_ivr_originate.c:67 (sofia/default/1547964325@13.127.15.161:1) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1547964325@13.127.15.161:1) State ROUTING going to sleep 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@13.127.15.161:1) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 121) 2022-09-22 12:18:06.169918 97.60% [DEBUG] sofia.c:7499 Channel sofia/default/1547964325@13.127.15.161:1 entering state [terminated][503] 2022-09-22 12:18:06.169918 97.60% [NOTICE] sofia.c:8738 Hangup sofia/default/1547964325@13.127.15.161:1 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE] 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1547964325@13.127.15.161:1) State CONSUME_MEDIA 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1547964325@13.127.15.161:1) State CONSUME_MEDIA going to sleep 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@13.127.15.161:1) Running State Change CS_HANGUP (Cur 1 Tot 121) 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1547964325@13.127.15.161:1) Callstate Change DOWN -> HANGUP 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1547964325@13.127.15.161:1) State HANGUP 2022-09-22 12:18:06.169918 97.60% [DEBUG] mod_sofia.c:468 Channel sofia/default/1547964325@13.127.15.161:1 hanging up, cause: NORMAL_TEMPORARY_FAILURE 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:59 sofia/default/1547964325@13.127.15.161:1 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1547964325@13.127.15.161:1) State HANGUP going to sleep 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1547964325@13.127.15.161:1) State Change CS_HANGUP -> CS_REPORTING 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1547964325@13.127.15.161:1) Running State Change CS_REPORTING (Cur 1 Tot 121) 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1547964325@13.127.15.161:1) State REPORTING 2022-09-22 12:18:06.169918 97.60% [INFO] mod_json_cdr.c:271 Process [8d0bba73-f95b-481f-a9a7-83486c6740da.cdr.json] 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE] 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:168 sofia/default/1547964325@13.127.15.161:1 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1547964325@13.127.15.161:1) State REPORTING going to sleep 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1547964325@13.127.15.161:1) State Change CS_REPORTING -> CS_DESTROY 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_session.c:1753 Session 121 (sofia/default/1547964325@13.127.15.161:1) Locked, Waiting on external entities 2022-09-22 12:18:06.169918 97.60% [NOTICE] switch_core_session.c:1771 Session 121 (sofia/default/1547964325@13.127.15.161:1) Ended 2022-09-22 12:18:06.169918 97.60% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1547964325@13.127.15.161:1 [CS_DESTROY] 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1547964325@13.127.15.161:1) Running State Change CS_DESTROY (Cur 0 Tot 121) 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1547964325@13.127.15.161:1) State DESTROY 2022-09-22 12:18:06.169918 97.60% [DEBUG] mod_sofia.c:379 sofia/default/1547964325@13.127.15.161:1 SOFIA DESTROY 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:175 sofia/default/1547964325@13.127.15.161:1 Standard DESTROY 2022-09-22 12:18:06.169918 97.60% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1547964325@13.127.15.161:1) State DESTROY going to sleep 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables 2022-09-22 12:18:07.169965 97.37% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1030303044@13.127.15.161:1 [250a01f7-1f9c-4bd5-b4df-a0c16f552a37] 2022-09-22 12:18:07.169965 97.37% [DEBUG] mod_sofia.c:5121 (sofia/default/1030303044@13.127.15.161:1) State Change CS_NEW -> CS_INIT 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1030303044@13.127.15.161:1) Running State Change CS_INIT (Cur 1 Tot 122) 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1030303044@13.127.15.161:1) State INIT 2022-09-22 12:18:07.169965 97.37% [DEBUG] mod_sofia.c:97 sofia/default/1030303044@13.127.15.161:1 SOFIA INIT 2022-09-22 12:18:07.169965 97.37% [INFO] sofia_glue.c:1651 sofia/default/1030303044@13.127.15.161:1 sending invite call-id: (null) 2022-09-22 12:18:07.169965 97.37% [DEBUG] sofia_glue.c:1654 sofia/default/1030303044@13.127.15.161:1 sending invite version: 1.10.7 -release-19-883d2cb662 64bit Local SDP: v=0 o=FreeSWITCH 1663822861 1663822862 IN IP4 13.127.15.161 s=FreeSWITCH c=IN IP4 13.127.15.161 t=0 0 m=audio 26226 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:40 sofia/default/1030303044@13.127.15.161:1 Standard INIT 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1030303044@13.127.15.161:1) State Change CS_INIT -> CS_ROUTING 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1030303044@13.127.15.161:1) State INIT going to sleep 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1030303044@13.127.15.161:1) Running State Change CS_ROUTING (Cur 1 Tot 122) 2022-09-22 12:18:07.169965 97.37% [DEBUG] sofia.c:7499 Channel sofia/default/1030303044@13.127.15.161:1 entering state [calling][0] 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1030303044@13.127.15.161:1) State ROUTING 2022-09-22 12:18:07.169965 97.37% [DEBUG] mod_sofia.c:158 sofia/default/1030303044@13.127.15.161:1 SOFIA ROUTING 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_ivr_originate.c:67 (sofia/default/1030303044@13.127.15.161:1) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1030303044@13.127.15.161:1) State ROUTING going to sleep 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1030303044@13.127.15.161:1) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 122) 2022-09-22 12:18:07.169965 97.37% [DEBUG] sofia.c:7499 Channel sofia/default/1030303044@13.127.15.161:1 entering state [terminated][503] 2022-09-22 12:18:07.169965 97.37% [NOTICE] sofia.c:8738 Hangup sofia/default/1030303044@13.127.15.161:1 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE] 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1030303044@13.127.15.161:1) State CONSUME_MEDIA 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1030303044@13.127.15.161:1) State CONSUME_MEDIA going to sleep 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1030303044@13.127.15.161:1) Running State Change CS_HANGUP (Cur 1 Tot 122) 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1030303044@13.127.15.161:1) Callstate Change DOWN -> HANGUP 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1030303044@13.127.15.161:1) State HANGUP 2022-09-22 12:18:07.169965 97.37% [DEBUG] mod_sofia.c:468 Channel sofia/default/1030303044@13.127.15.161:1 hanging up, cause: NORMAL_TEMPORARY_FAILURE 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:59 sofia/default/1030303044@13.127.15.161:1 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1030303044@13.127.15.161:1) State HANGUP going to sleep 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1030303044@13.127.15.161:1) State Change CS_HANGUP -> CS_REPORTING 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1030303044@13.127.15.161:1) Running State Change CS_REPORTING (Cur 1 Tot 122) 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1030303044@13.127.15.161:1) State REPORTING 2022-09-22 12:18:07.169965 97.37% [INFO] mod_json_cdr.c:271 Process [250a01f7-1f9c-4bd5-b4df-a0c16f552a37.cdr.json] 2022-09-22 12:18:07.169965 97.37% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE] 2022-09-22 12:18:07.189968 97.37% [DEBUG] switch_core_state_machine.c:168 sofia/default/1030303044@13.127.15.161:1 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE 2022-09-22 12:18:07.189968 97.37% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1030303044@13.127.15.161:1) State REPORTING going to sleep 2022-09-22 12:18:07.189968 97.37% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1030303044@13.127.15.161:1) State Change CS_REPORTING -> CS_DESTROY 2022-09-22 12:18:07.189968 97.37% [DEBUG] switch_core_session.c:1753 Session 122 (sofia/default/1030303044@13.127.15.161:1) Locked, Waiting on external entities 2022-09-22 12:18:07.189968 97.37% [NOTICE] switch_core_session.c:1771 Session 122 (sofia/default/1030303044@13.127.15.161:1) Ended 2022-09-22 12:18:07.189968 97.37% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1030303044@13.127.15.161:1 [CS_DESTROY] 2022-09-22 12:18:07.189968 97.37% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1030303044@13.127.15.161:1) Running State Change CS_DESTROY (Cur 0 Tot 122) 2022-09-22 12:18:07.189968 97.37% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1030303044@13.127.15.161:1) State DESTROY 2022-09-22 12:18:07.189968 97.37% [DEBUG] mod_sofia.c:379 sofia/default/1030303044@13.127.15.161:1 SOFIA DESTROY 2022-09-22 12:18:07.189968 97.37% [DEBUG] switch_core_state_machine.c:175 sofia/default/1030303044@13.127.15.161:1 Standard DESTROY 2022-09-22 12:18:07.189968 97.37% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1030303044@13.127.15.161:1) State DESTROY going to sleep 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables 2022-09-22 12:18:08.189955 97.37% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1039999999@13.127.15.161:1 [77c0d747-501a-46f4-b906-334995bca531] 2022-09-22 12:18:08.189955 97.37% [DEBUG] mod_sofia.c:5121 (sofia/default/1039999999@13.127.15.161:1) State Change CS_NEW -> CS_INIT 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1039999999@13.127.15.161:1) Running State Change CS_INIT (Cur 1 Tot 123) 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1039999999@13.127.15.161:1) State INIT 2022-09-22 12:18:08.189955 97.37% [DEBUG] mod_sofia.c:97 sofia/default/1039999999@13.127.15.161:1 SOFIA INIT 2022-09-22 12:18:08.189955 97.37% [INFO] sofia_glue.c:1651 sofia/default/1039999999@13.127.15.161:1 sending invite call-id: (null) 2022-09-22 12:18:08.189955 97.37% [DEBUG] sofia_glue.c:1654 sofia/default/1039999999@13.127.15.161:1 sending invite version: 1.10.7 -release-19-883d2cb662 64bit Local SDP: v=0 o=FreeSWITCH 1663820050 1663820051 IN IP4 13.127.15.161 s=FreeSWITCH c=IN IP4 13.127.15.161 t=0 0 m=audio 29038 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:40 sofia/default/1039999999@13.127.15.161:1 Standard INIT 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1039999999@13.127.15.161:1) State Change CS_INIT -> CS_ROUTING 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1039999999@13.127.15.161:1) State INIT going to sleep 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1039999999@13.127.15.161:1) Running State Change CS_ROUTING (Cur 1 Tot 123) 2022-09-22 12:18:08.189955 97.37% [DEBUG] sofia.c:7499 Channel sofia/default/1039999999@13.127.15.161:1 entering state [calling][0] 2022-09-22 12:18:08.189955 97.37% [DEBUG] sofia.c:7499 Channel sofia/default/1039999999@13.127.15.161:1 entering state [terminated][503] 2022-09-22 12:18:08.189955 97.37% [NOTICE] sofia.c:8738 Hangup sofia/default/1039999999@13.127.15.161:1 [CS_ROUTING] [NORMAL_TEMPORARY_FAILURE] 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1039999999@13.127.15.161:1) State ROUTING 2022-09-22 12:18:08.189955 97.37% [DEBUG] mod_sofia.c:158 sofia/default/1039999999@13.127.15.161:1 SOFIA ROUTING 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1039999999@13.127.15.161:1) State ROUTING going to sleep 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1039999999@13.127.15.161:1) Running State Change CS_HANGUP (Cur 1 Tot 123) 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1039999999@13.127.15.161:1) Callstate Change DOWN -> HANGUP 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1039999999@13.127.15.161:1) State HANGUP 2022-09-22 12:18:08.189955 97.37% [DEBUG] mod_sofia.c:468 Channel sofia/default/1039999999@13.127.15.161:1 hanging up, cause: NORMAL_TEMPORARY_FAILURE 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:59 sofia/default/1039999999@13.127.15.161:1 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1039999999@13.127.15.161:1) State HANGUP going to sleep 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1039999999@13.127.15.161:1) State Change CS_HANGUP -> CS_REPORTING 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1039999999@13.127.15.161:1) Running State Change CS_REPORTING (Cur 1 Tot 123) 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1039999999@13.127.15.161:1) State REPORTING 2022-09-22 12:18:08.189955 97.37% [INFO] mod_json_cdr.c:271 Process [77c0d747-501a-46f4-b906-334995bca531.cdr.json] 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE] 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:168 sofia/default/1039999999@13.127.15.161:1 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1039999999@13.127.15.161:1) State REPORTING going to sleep 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1039999999@13.127.15.161:1) State Change CS_REPORTING -> CS_DESTROY 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_session.c:1753 Session 123 (sofia/default/1039999999@13.127.15.161:1) Locked, Waiting on external entities 2022-09-22 12:18:08.189955 97.37% [NOTICE] switch_core_session.c:1771 Session 123 (sofia/default/1039999999@13.127.15.161:1) Ended 2022-09-22 12:18:08.189955 97.37% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1039999999@13.127.15.161:1 [CS_DESTROY] 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1039999999@13.127.15.161:1) Running State Change CS_DESTROY (Cur 0 Tot 123) 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1039999999@13.127.15.161:1) State DESTROY 2022-09-22 12:18:08.189955 97.37% [DEBUG] mod_sofia.c:379 sofia/default/1039999999@13.127.15.161:1 SOFIA DESTROY 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:175 sofia/default/1039999999@13.127.15.161:1 Standard DESTROY 2022-09-22 12:18:08.189955 97.37% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1039999999@13.127.15.161:1) State DESTROY going to sleep 2022-09-22 12:18:09.189958 97.27% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables 2022-09-22 12:18:09.209952 97.27% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1077777777@13.127.15.161:1 [54836d79-ee18-4cf0-9db0-4768936a4ee1] 2022-09-22 12:18:09.209952 97.27% [DEBUG] mod_sofia.c:5121 (sofia/default/1077777777@13.127.15.161:1) State Change CS_NEW -> CS_INIT 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1077777777@13.127.15.161:1) Running State Change CS_INIT (Cur 1 Tot 124) 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1077777777@13.127.15.161:1) State INIT 2022-09-22 12:18:09.209952 97.27% [DEBUG] mod_sofia.c:97 sofia/default/1077777777@13.127.15.161:1 SOFIA INIT 2022-09-22 12:18:09.209952 97.27% [INFO] sofia_glue.c:1651 sofia/default/1077777777@13.127.15.161:1 sending invite call-id: (null) 2022-09-22 12:18:09.209952 97.27% [DEBUG] sofia_glue.c:1654 sofia/default/1077777777@13.127.15.161:1 sending invite version: 1.10.7 -release-19-883d2cb662 64bit Local SDP: v=0 o=FreeSWITCH 1663822035 1663822036 IN IP4 13.127.15.161 s=FreeSWITCH c=IN IP4 13.127.15.161 t=0 0 m=audio 27054 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:40 sofia/default/1077777777@13.127.15.161:1 Standard INIT 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1077777777@13.127.15.161:1) State Change CS_INIT -> CS_ROUTING 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1077777777@13.127.15.161:1) State INIT going to sleep 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1077777777@13.127.15.161:1) Running State Change CS_ROUTING (Cur 1 Tot 124) 2022-09-22 12:18:09.209952 97.27% [DEBUG] sofia.c:7499 Channel sofia/default/1077777777@13.127.15.161:1 entering state [calling][0] 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1077777777@13.127.15.161:1) State ROUTING 2022-09-22 12:18:09.209952 97.27% [DEBUG] mod_sofia.c:158 sofia/default/1077777777@13.127.15.161:1 SOFIA ROUTING 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_ivr_originate.c:67 (sofia/default/1077777777@13.127.15.161:1) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1077777777@13.127.15.161:1) State ROUTING going to sleep 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1077777777@13.127.15.161:1) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 124) 2022-09-22 12:18:09.209952 97.27% [DEBUG] sofia.c:7499 Channel sofia/default/1077777777@13.127.15.161:1 entering state [terminated][503] 2022-09-22 12:18:09.209952 97.27% [NOTICE] sofia.c:8738 Hangup sofia/default/1077777777@13.127.15.161:1 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE] 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1077777777@13.127.15.161:1) State CONSUME_MEDIA 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1077777777@13.127.15.161:1) State CONSUME_MEDIA going to sleep 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1077777777@13.127.15.161:1) Running State Change CS_HANGUP (Cur 1 Tot 124) 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1077777777@13.127.15.161:1) Callstate Change DOWN -> HANGUP 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1077777777@13.127.15.161:1) State HANGUP 2022-09-22 12:18:09.209952 97.27% [DEBUG] mod_sofia.c:468 Channel sofia/default/1077777777@13.127.15.161:1 hanging up, cause: NORMAL_TEMPORARY_FAILURE 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:59 sofia/default/1077777777@13.127.15.161:1 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1077777777@13.127.15.161:1) State HANGUP going to sleep 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1077777777@13.127.15.161:1) State Change CS_HANGUP -> CS_REPORTING 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1077777777@13.127.15.161:1) Running State Change CS_REPORTING (Cur 1 Tot 124) 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1077777777@13.127.15.161:1) State REPORTING 2022-09-22 12:18:09.209952 97.27% [INFO] mod_json_cdr.c:271 Process [54836d79-ee18-4cf0-9db0-4768936a4ee1.cdr.json] 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE] 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:168 sofia/default/1077777777@13.127.15.161:1 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1077777777@13.127.15.161:1) State REPORTING going to sleep 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1077777777@13.127.15.161:1) State Change CS_REPORTING -> CS_DESTROY 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_session.c:1753 Session 124 (sofia/default/1077777777@13.127.15.161:1) Locked, Waiting on external entities 2022-09-22 12:18:09.209952 97.27% [NOTICE] switch_core_session.c:1771 Session 124 (sofia/default/1077777777@13.127.15.161:1) Ended 2022-09-22 12:18:09.209952 97.27% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1077777777@13.127.15.161:1 [CS_DESTROY] 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1077777777@13.127.15.161:1) Running State Change CS_DESTROY (Cur 0 Tot 124) 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1077777777@13.127.15.161:1) State DESTROY 2022-09-22 12:18:09.209952 97.27% [DEBUG] mod_sofia.c:379 sofia/default/1077777777@13.127.15.161:1 SOFIA DESTROY 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:175 sofia/default/1077777777@13.127.15.161:1 Standard DESTROY 2022-09-22 12:18:09.209952 97.27% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1077777777@13.127.15.161:1) State DESTROY going to sleep freeswitch@ip-172-31-40-49>