+OK log level [7] freeswitch@betaAlpesh> freeswitch@betaAlpesh> freeswitch@betaAlpesh> 2023-12-15 09:56:41.547523 92.67% [DEBUG] switch_ivr_originate.c:2297 Parsing global variables 2023-12-15 09:56:41.547523 92.67% [NOTICE] switch_channel.c:1142 New Channel sofia/default/9291468687@automation2betav601.astppbilling.org: [f5b8ecf2-18bf-463d-85d1-a56ed1bced0d] 2023-12-15 09:56:41.547523 92.67% [DEBUG] mod_sofia.c:5110 (sofia/default/9291468687@automation2betav601.astppbilling.org:) State Change CS_NEW -> CS_INIT 2023-12-15 09:56:41.547523 92.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9291468687@automation2betav601.astppbilling.org:) Running State Change CS_INIT (Cur 3 Tot 164) 2023-12-15 09:56:41.547523 92.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9291468687@automation2betav601.astppbilling.org:) State INIT 2023-12-15 09:56:41.547523 92.67% [DEBUG] mod_sofia.c:97 sofia/default/9291468687@automation2betav601.astppbilling.org: SOFIA INIT 2023-12-15 09:56:41.547523 92.67% [INFO] sofia_glue.c:1659 sofia/default/9291468687@automation2betav601.astppbilling.org: sending invite call-id: (null) 2023-12-15 09:56:41.547523 92.67% [DEBUG] sofia_glue.c:1662 sofia/default/9291468687@automation2betav601.astppbilling.org: sending invite version: 1.10.10-release 64bit Local SDP: v=0 o=FreeSWITCH 1702615161 1702615162 IN IP4 65.109.231.66 s=FreeSWITCH c=IN IP4 65.109.231.66 t=0 0 m=audio 19040 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 2023-12-15 09:56:41.547523 92.67% [DEBUG] switch_core_state_machine.c:40 sofia/default/9291468687@automation2betav601.astppbilling.org: Standard INIT 2023-12-15 09:56:41.547523 92.67% [DEBUG] switch_core_state_machine.c:48 (sofia/default/9291468687@automation2betav601.astppbilling.org:) State Change CS_INIT -> CS_ROUTING 2023-12-15 09:56:41.547523 92.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9291468687@automation2betav601.astppbilling.org:) State INIT going to sleep 2023-12-15 09:56:41.547523 92.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9291468687@automation2betav601.astppbilling.org:) Running State Change CS_ROUTING (Cur 3 Tot 164) 2023-12-15 09:56:41.547523 92.67% [DEBUG] sofia.c:7493 Channel sofia/default/9291468687@automation2betav601.astppbilling.org: entering state [calling][0] 2023-12-15 09:56:41.547523 92.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9291468687@automation2betav601.astppbilling.org:) State ROUTING 2023-12-15 09:56:41.547523 92.67% [DEBUG] mod_sofia.c:158 sofia/default/9291468687@automation2betav601.astppbilling.org: SOFIA ROUTING 2023-12-15 09:56:41.547523 92.67% [DEBUG] switch_ivr_originate.c:67 (sofia/default/9291468687@automation2betav601.astppbilling.org:) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2023-12-15 09:56:41.547523 92.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9291468687@automation2betav601.astppbilling.org:) State ROUTING going to sleep 2023-12-15 09:56:41.547523 92.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9291468687@automation2betav601.astppbilling.org:) Running State Change CS_CONSUME_MEDIA (Cur 3 Tot 164) 2023-12-15 09:56:41.547523 92.67% [DEBUG] switch_core_state_machine.c:659 (sofia/default/9291468687@automation2betav601.astppbilling.org:) State CONSUME_MEDIA 2023-12-15 09:56:41.547523 92.67% [DEBUG] switch_core_state_machine.c:659 (sofia/default/9291468687@automation2betav601.astppbilling.org:) State CONSUME_MEDIA going to sleep 2023-12-15 09:56:42.087502 92.67% [WARNING] sofia_reg.c:2843 Cannot locate any authentication credentials to complete an authentication request for realm '"65.109.231.66"' 2023-12-15 09:56:42.087502 92.67% [NOTICE] sofia_reg.c:2867 Hangup sofia/default/9291468687@automation2betav601.astppbilling.org: [CS_CONSUME_MEDIA] [MANDATORY_IE_MISSING] 2023-12-15 09:56:42.087502 92.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9291468687@automation2betav601.astppbilling.org:) Running State Change CS_HANGUP (Cur 3 Tot 164) 2023-12-15 09:56:42.087502 92.67% [DEBUG] switch_core_state_machine.c:844 (sofia/default/9291468687@automation2betav601.astppbilling.org:) Callstate Change DOWN -> HANGUP 2023-12-15 09:56:42.087502 92.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9291468687@automation2betav601.astppbilling.org:) State HANGUP 2023-12-15 09:56:42.087502 92.67% [DEBUG] mod_sofia.c:469 Channel sofia/default/9291468687@automation2betav601.astppbilling.org: hanging up, cause: MANDATORY_IE_MISSING 2023-12-15 09:56:42.087502 92.67% [DEBUG] mod_sofia.c:537 Sending CANCEL to sofia/default/9291468687@automation2betav601.astppbilling.org: 2023-12-15 09:56:42.087502 92.67% [DEBUG] switch_core_state_machine.c:59 sofia/default/9291468687@automation2betav601.astppbilling.org: Standard HANGUP, cause: MANDATORY_IE_MISSING 2023-12-15 09:56:42.087502 92.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9291468687@automation2betav601.astppbilling.org:) State HANGUP going to sleep 2023-12-15 09:56:42.087502 92.67% [DEBUG] switch_core_state_machine.c:616 (sofia/default/9291468687@automation2betav601.astppbilling.org:) State Change CS_HANGUP -> CS_REPORTING 2023-12-15 09:56:42.087502 92.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9291468687@automation2betav601.astppbilling.org:) Running State Change CS_REPORTING (Cur 3 Tot 164) 2023-12-15 09:56:42.087502 92.67% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9291468687@automation2betav601.astppbilling.org:) State REPORTING 2023-12-15 09:56:42.087502 92.67% [INFO] mod_json_cdr.c:272 Process [f5b8ecf2-18bf-463d-85d1-a56ed1bced0d.cdr.json] 2023-12-15 09:56:42.107491 92.67% [DEBUG] switch_ivr_originate.c:4052 Originate Resulted in Error Cause: 96 [MANDATORY_IE_MISSING] 2023-12-15 09:56:42.107491 92.67% [DEBUG] switch_core_state_machine.c:168 sofia/default/9291468687@automation2betav601.astppbilling.org: Standard REPORTING, cause: MANDATORY_IE_MISSING 2023-12-15 09:56:42.107491 92.67% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9291468687@automation2betav601.astppbilling.org:) State REPORTING going to sleep 2023-12-15 09:56:42.107491 92.67% [DEBUG] switch_core_state_machine.c:607 (sofia/default/9291468687@automation2betav601.astppbilling.org:) State Change CS_REPORTING -> CS_DESTROY 2023-12-15 09:56:42.107491 92.67% [DEBUG] switch_core_session.c:1744 Session 164 (sofia/default/9291468687@automation2betav601.astppbilling.org:) Locked, Waiting on external entities 2023-12-15 09:56:42.107491 92.67% [NOTICE] switch_core_session.c:1762 Session 164 (sofia/default/9291468687@automation2betav601.astppbilling.org:) Ended 2023-12-15 09:56:42.107491 92.67% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/9291468687@automation2betav601.astppbilling.org: [CS_DESTROY] 2023-12-15 09:56:42.107491 92.67% [DEBUG] switch_core_state_machine.c:735 (sofia/default/9291468687@automation2betav601.astppbilling.org:) Running State Change CS_DESTROY (Cur 2 Tot 164) 2023-12-15 09:56:42.107491 92.67% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9291468687@automation2betav601.astppbilling.org:) State DESTROY 2023-12-15 09:56:42.107491 92.67% [DEBUG] mod_sofia.c:380 sofia/default/9291468687@automation2betav601.astppbilling.org: SOFIA DESTROY 2023-12-15 09:56:42.107491 92.67% [DEBUG] switch_core_state_machine.c:175 sofia/default/9291468687@automation2betav601.astppbilling.org: Standard DESTROY 2023-12-15 09:56:42.107491 92.67% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9291468687@automation2betav601.astppbilling.org:) State DESTROY going to sleep freeswitch@betaAlpesh> freeswitch@betaAlpesh>