2023-07-26 09:21:16.243917 88.97% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
2023-07-26 09:21:16.243917 88.97% [NOTICE] switch_channel.c:1123 New Channel sofia/default/100512@13.127.204.47:5060 [5f8b2782-66e4-46fb-ab17-67e2658619b0]
2023-07-26 09:21:16.243917 88.97% [DEBUG] mod_sofia.c:5121 (sofia/default/100512@13.127.204.47:5060) State Change CS_NEW -> CS_INIT
2023-07-26 09:21:16.243917 88.97% [DEBUG] switch_core_state_machine.c:581 (sofia/default/100512@13.127.204.47:5060) Running State Change CS_INIT (Cur 1 Tot 72)
2023-07-26 09:21:16.243917 88.97% [DEBUG] switch_core_state_machine.c:624 (sofia/default/100512@13.127.204.47:5060) State INIT
2023-07-26 09:21:16.243917 88.97% [DEBUG] mod_sofia.c:97 sofia/default/100512@13.127.204.47:5060 SOFIA INIT
2023-07-26 09:21:16.243917 88.97% [INFO] sofia_glue.c:1651 sofia/default/100512@13.127.204.47:5060 sending invite call-id: (null)
2023-07-26 09:21:16.243917 88.97% [DEBUG] sofia_glue.c:1654 sofia/default/100512@13.127.204.47:5060 sending invite version: 1.10.7 -release 64bit
Local SDP:
v=0
o=FreeSWITCH 1690339784 1690339785 IN IP4 13.127.204.47
s=FreeSWITCH
c=IN IP4 13.127.204.47
t=0 0
m=audio 23492 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
a=sendrecv

2023-07-26 09:21:16.243917 88.97% [DEBUG] switch_core_state_machine.c:40 sofia/default/100512@13.127.204.47:5060 Standard INIT
2023-07-26 09:21:16.243917 88.97% [DEBUG] switch_core_state_machine.c:48 (sofia/default/100512@13.127.204.47:5060) State Change CS_INIT -> CS_ROUTING
2023-07-26 09:21:16.243917 88.97% [DEBUG] switch_core_state_machine.c:624 (sofia/default/100512@13.127.204.47:5060) State INIT going to sleep
2023-07-26 09:21:16.243917 88.97% [DEBUG] switch_core_state_machine.c:581 (sofia/default/100512@13.127.204.47:5060) Running State Change CS_ROUTING (Cur 1 Tot 72)
2023-07-26 09:21:16.243917 88.97% [DEBUG] switch_core_state_machine.c:640 (sofia/default/100512@13.127.204.47:5060) State ROUTING
2023-07-26 09:21:16.243917 88.97% [DEBUG] mod_sofia.c:158 sofia/default/100512@13.127.204.47:5060 SOFIA ROUTING
2023-07-26 09:21:16.243917 88.97% [DEBUG] switch_ivr_originate.c:67 (sofia/default/100512@13.127.204.47:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2023-07-26 09:21:16.243917 88.97% [DEBUG] switch_core_state_machine.c:640 (sofia/default/100512@13.127.204.47:5060) State ROUTING going to sleep
2023-07-26 09:21:16.243917 88.97% [DEBUG] switch_core_state_machine.c:581 (sofia/default/100512@13.127.204.47:5060) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 72)
2023-07-26 09:21:16.243917 88.97% [DEBUG] switch_core_state_machine.c:659 (sofia/default/100512@13.127.204.47:5060) State CONSUME_MEDIA
2023-07-26 09:21:16.243917 88.97% [DEBUG] switch_core_state_machine.c:659 (sofia/default/100512@13.127.204.47:5060) State CONSUME_MEDIA going to sleep
2023-07-26 09:21:16.263915 88.97% [NOTICE] switch_channel.c:1123 New Channel sofia/default/100512@13.127.204.47 [a3c925b6-f9f2-4988-81fd-bf016b77b9a1]
2023-07-26 09:21:16.263915 88.97% [DEBUG] switch_core_state_machine.c:581 (sofia/default/100512@13.127.204.47) Running State Change CS_NEW (Cur 2 Tot 73)
2023-07-26 09:21:16.263915 88.97% [DEBUG] sofia.c:7499 Channel sofia/default/100512@13.127.204.47:5060 entering state [calling][0]
2023-07-26 09:21:16.263915 88.97% [INFO] sofia.c:10462 sofia/default/100512@13.127.204.47 receiving invite from 13.127.204.47:5060 version: 1.10.7 -release 64bit call-id: 9d1a9991-a638-123c-c085-02ba0d891d22
2023-07-26 09:21:16.263915 88.97% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 13.127.204.47:0.
2023-07-26 09:21:16.263915 88.97% [DEBUG] sofia.c:2419 detaching session a3c925b6-f9f2-4988-81fd-bf016b77b9a1
2023-07-26 09:21:16.263915 88.97% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [100512@13.127.204.47] from ip 13.127.204.47
2023-07-26 09:21:16.263915 88.97% [DEBUG] switch_core_state_machine.c:600 (sofia/default/100512@13.127.204.47) State NEW
2023-07-26 09:21:16.263915 88.97% [WARNING] sofia_reg.c:2857 Cannot locate any authentication credentials to complete an authentication request for realm '"13.127.204.47"'
2023-07-26 09:21:16.263915 88.97% [NOTICE] sofia_reg.c:2881 Hangup sofia/default/100512@13.127.204.47:5060 [CS_CONSUME_MEDIA] [MANDATORY_IE_MISSING]
2023-07-26 09:21:16.263915 88.97% [DEBUG] switch_core_state_machine.c:581 (sofia/default/100512@13.127.204.47:5060) Running State Change CS_HANGUP (Cur 2 Tot 73)
2023-07-26 09:21:16.263915 88.97% [DEBUG] switch_core_state_machine.c:844 (sofia/default/100512@13.127.204.47:5060) Callstate Change DOWN -> HANGUP
2023-07-26 09:21:16.263915 88.97% [DEBUG] switch_core_state_machine.c:846 (sofia/default/100512@13.127.204.47:5060) State HANGUP
2023-07-26 09:21:16.263915 88.97% [DEBUG] mod_sofia.c:468 Channel sofia/default/100512@13.127.204.47:5060 hanging up, cause: MANDATORY_IE_MISSING
2023-07-26 09:21:16.263915 88.97% [DEBUG] mod_sofia.c:536 Sending CANCEL to sofia/default/100512@13.127.204.47:5060
2023-07-26 09:21:16.263915 88.97% [DEBUG] switch_core_state_machine.c:59 sofia/default/100512@13.127.204.47:5060 Standard HANGUP, cause: MANDATORY_IE_MISSING
2023-07-26 09:21:16.263915 88.97% [DEBUG] switch_core_state_machine.c:846 (sofia/default/100512@13.127.204.47:5060) State HANGUP going to sleep
2023-07-26 09:21:16.263915 88.97% [DEBUG] switch_core_state_machine.c:616 (sofia/default/100512@13.127.204.47:5060) State Change CS_HANGUP -> CS_REPORTING
2023-07-26 09:21:16.263915 88.97% [DEBUG] switch_core_state_machine.c:581 (sofia/default/100512@13.127.204.47:5060) Running State Change CS_REPORTING (Cur 2 Tot 73)
2023-07-26 09:21:16.263915 88.97% [DEBUG] switch_core_state_machine.c:932 (sofia/default/100512@13.127.204.47:5060) State REPORTING
2023-07-26 09:21:16.263915 88.97% [INFO] mod_json_cdr.c:271 Process [5f8b2782-66e4-46fb-ab17-67e2658619b0.cdr.json]
2023-07-26 09:21:16.283923 88.97% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 96 [MANDATORY_IE_MISSING]
2023-07-26 09:21:16.283923 88.97% [DEBUG] switch_core_state_machine.c:168 sofia/default/100512@13.127.204.47:5060 Standard REPORTING, cause: MANDATORY_IE_MISSING
2023-07-26 09:21:16.283923 88.97% [DEBUG] switch_core_state_machine.c:932 (sofia/default/100512@13.127.204.47:5060) State REPORTING going to sleep
2023-07-26 09:21:16.283923 88.97% [DEBUG] switch_core_state_machine.c:607 (sofia/default/100512@13.127.204.47:5060) State Change CS_REPORTING -> CS_DESTROY
2023-07-26 09:21:16.283923 88.97% [DEBUG] switch_core_session.c:1753 Session 72 (sofia/default/100512@13.127.204.47:5060) Locked, Waiting on external entities
2023-07-26 09:21:16.283923 88.97% [NOTICE] switch_core_session.c:1771 Session 72 (sofia/default/100512@13.127.204.47:5060) Ended
2023-07-26 09:21:16.283923 88.97% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/100512@13.127.204.47:5060 [CS_DESTROY]
2023-07-26 09:21:16.283923 88.97% [DEBUG] switch_core_state_machine.c:735 (sofia/default/100512@13.127.204.47:5060) Running State Change CS_DESTROY (Cur 1 Tot 73)
2023-07-26 09:21:16.283923 88.97% [DEBUG] switch_core_state_machine.c:745 (sofia/default/100512@13.127.204.47:5060) State DESTROY
2023-07-26 09:21:16.283923 88.97% [DEBUG] mod_sofia.c:379 sofia/default/100512@13.127.204.47:5060 SOFIA DESTROY
2023-07-26 09:21:16.283923 88.97% [DEBUG] switch_core_state_machine.c:175 sofia/default/100512@13.127.204.47:5060 Standard DESTROY
2023-07-26 09:21:16.283923 88.97% [DEBUG] switch_core_state_machine.c:745 (sofia/default/100512@13.127.204.47:5060) State DESTROY going to sleep
2023-07-26 09:21:26.283956 89.23% [WARNING] switch_core_state_machine.c:684 a3c925b6-f9f2-4988-81fd-bf016b77b9a1 sofia/default/100512@13.127.204.47 Abandoned
2023-07-26 09:21:26.283956 89.23% [NOTICE] switch_core_state_machine.c:687 Hangup sofia/default/100512@13.127.204.47 [CS_NEW] [WRONG_CALL_STATE]
2023-07-26 09:21:26.283956 89.23% [DEBUG] switch_core_state_machine.c:581 (sofia/default/100512@13.127.204.47) Running State Change CS_HANGUP (Cur 1 Tot 73)
2023-07-26 09:21:26.283956 89.23% [DEBUG] switch_core_state_machine.c:844 (sofia/default/100512@13.127.204.47) Callstate Change DOWN -> HANGUP
2023-07-26 09:21:26.283956 89.23% [DEBUG] switch_core_state_machine.c:846 (sofia/default/100512@13.127.204.47) State HANGUP
2023-07-26 09:21:26.283956 89.23% [DEBUG] mod_sofia.c:468 Channel sofia/default/100512@13.127.204.47 hanging up, cause: WRONG_CALL_STATE
2023-07-26 09:21:26.283956 89.23% [DEBUG] switch_core_state_machine.c:59 sofia/default/100512@13.127.204.47 Standard HANGUP, cause: WRONG_CALL_STATE
2023-07-26 09:21:26.283956 89.23% [DEBUG] switch_core_state_machine.c:846 (sofia/default/100512@13.127.204.47) State HANGUP going to sleep
2023-07-26 09:21:26.283956 89.23% [DEBUG] switch_core_state_machine.c:616 (sofia/default/100512@13.127.204.47) State Change CS_HANGUP -> CS_REPORTING
2023-07-26 09:21:26.283956 89.23% [DEBUG] switch_core_state_machine.c:581 (sofia/default/100512@13.127.204.47) Running State Change CS_REPORTING (Cur 1 Tot 73)
2023-07-26 09:21:26.283956 89.23% [DEBUG] switch_core_state_machine.c:932 (sofia/default/100512@13.127.204.47) State REPORTING
2023-07-26 09:21:26.283956 89.23% [DEBUG] switch_core_state_machine.c:168 sofia/default/100512@13.127.204.47 Standard REPORTING, cause: WRONG_CALL_STATE
2023-07-26 09:21:26.283956 89.23% [DEBUG] switch_core_state_machine.c:932 (sofia/default/100512@13.127.204.47) State REPORTING going to sleep
2023-07-26 09:21:26.283956 89.23% [DEBUG] switch_core_state_machine.c:607 (sofia/default/100512@13.127.204.47) State Change CS_REPORTING -> CS_DESTROY
2023-07-26 09:21:26.283956 89.23% [DEBUG] switch_core_session.c:1753 Session 73 (sofia/default/100512@13.127.204.47) Locked, Waiting on external entities
2023-07-26 09:21:26.283956 89.23% [NOTICE] switch_core_session.c:1771 Session 73 (sofia/default/100512@13.127.204.47) Ended
2023-07-26 09:21:26.283956 89.23% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/100512@13.127.204.47 [CS_DESTROY]
2023-07-26 09:21:26.283956 89.23% [DEBUG] switch_core_state_machine.c:735 (sofia/default/100512@13.127.204.47) Running State Change CS_DESTROY (Cur 0 Tot 73)
2023-07-26 09:21:26.283956 89.23% [DEBUG] switch_core_state_machine.c:745 (sofia/default/100512@13.127.204.47) State DESTROY
2023-07-26 09:21:26.283956 89.23% [DEBUG] mod_sofia.c:379 sofia/default/100512@13.127.204.47 SOFIA DESTROY
2023-07-26 09:21:26.283956 89.23% [DEBUG] switch_core_state_machine.c:175 sofia/default/100512@13.127.204.47 Standard DESTROY
2023-07-26 09:21:26.283956 89.23% [DEBUG] switch_core_state_machine.c:745 (sofia/default/100512@13.127.204.47) State DESTROY going to sleep