2019-05-30 16:25:52.779715 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
2019-05-30 16:25:52.779715 [NOTICE] switch_channel.c:1104 New Channel sofia/default/0212345678@10.239.238.30 [cba40998-82a3-11e9-9a03-7d3b5d4ccf50]
2019-05-30 16:25:52.779715 [DEBUG] mod_sofia.c:4819 (sofia/default/0212345678@10.239.238.30) State Change CS_NEW -> CS_INIT
2019-05-30 16:25:52.779715 [DEBUG] switch_core_state_machine.c:584 (sofia/default/0212345678@10.239.238.30) Running State Change CS_INIT (Cur 1 Tot 1360)
2019-05-30 16:25:52.779715 [DEBUG] switch_core_state_machine.c:627 (sofia/default/0212345678@10.239.238.30) State INIT
2019-05-30 16:25:52.779715 [DEBUG] mod_sofia.c:90 sofia/default/0212345678@10.239.238.30 SOFIA INIT
2019-05-30 16:25:52.779715 [DEBUG] sofia_glue.c:1295 sofia/default/0212345678@10.239.238.30 sending invite version: 1.6.20  64bit
Local SDP:
v=0
o=FreeSWITCH 1559176484 1559176485 IN IP4 59.100.179.100
s=FreeSWITCH
c=IN IP4 59.100.179.100
t=0 0
m=audio 21068 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

2019-05-30 16:25:52.779715 [DEBUG] switch_core_state_machine.c:40 sofia/default/0212345678@10.239.238.30 Standard INIT
2019-05-30 16:25:52.779715 [DEBUG] switch_core_state_machine.c:48 (sofia/default/0212345678@10.239.238.30) State Change CS_INIT -> CS_ROUTING
2019-05-30 16:25:52.779715 [DEBUG] switch_core_state_machine.c:627 (sofia/default/0212345678@10.239.238.30) State INIT going to sleep
2019-05-30 16:25:52.779715 [DEBUG] switch_core_state_machine.c:584 (sofia/default/0212345678@10.239.238.30) Running State Change CS_ROUTING (Cur 1 Tot 1360)
2019-05-30 16:25:52.779715 [DEBUG] switch_core_state_machine.c:643 (sofia/default/0212345678@10.239.238.30) State ROUTING
2019-05-30 16:25:52.779715 [DEBUG] mod_sofia.c:143 sofia/default/0212345678@10.239.238.30 SOFIA ROUTING
2019-05-30 16:25:52.779715 [DEBUG] switch_ivr_originate.c:67 (sofia/default/0212345678@10.239.238.30) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2019-05-30 16:25:52.779715 [DEBUG] switch_core_state_machine.c:643 (sofia/default/0212345678@10.239.238.30) State ROUTING going to sleep
2019-05-30 16:25:52.779715 [DEBUG] switch_core_state_machine.c:584 (sofia/default/0212345678@10.239.238.30) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 1360)
2019-05-30 16:25:52.779715 [DEBUG] switch_core_state_machine.c:662 (sofia/default/0212345678@10.239.238.30) State CONSUME_MEDIA
2019-05-30 16:25:52.779715 [DEBUG] switch_core_state_machine.c:662 (sofia/default/0212345678@10.239.238.30) State CONSUME_MEDIA going to sleep
2019-05-30 16:25:52.779715 [DEBUG] sofia.c:7084 Channel sofia/default/0212345678@10.239.238.30 entering state [calling][0]
2019-05-30 16:25:52.779715 [DEBUG] sofia.c:7084 Channel sofia/default/0212345678@10.239.238.30 entering state [terminated][503]
2019-05-30 16:25:52.779715 [NOTICE] sofia.c:8273 Hangup sofia/default/0212345678@10.239.238.30 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
2019-05-30 16:25:52.779715 [DEBUG] switch_core_state_machine.c:584 (sofia/default/0212345678@10.239.238.30) Running State Change CS_HANGUP (Cur 1 Tot 1360)
2019-05-30 16:25:52.779715 [DEBUG] switch_core_state_machine.c:850 (sofia/default/0212345678@10.239.238.30) Callstate Change DOWN -> HANGUP
2019-05-30 16:25:52.779715 [DEBUG] switch_core_state_machine.c:852 (sofia/default/0212345678@10.239.238.30) State HANGUP
2019-05-30 16:25:52.779715 [DEBUG] mod_sofia.c:438 Channel sofia/default/0212345678@10.239.238.30 hanging up, cause: NORMAL_TEMPORARY_FAILURE
2019-05-30 16:25:52.779715 [DEBUG] switch_core_state_machine.c:60 sofia/default/0212345678@10.239.238.30 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
2019-05-30 16:25:52.779715 [DEBUG] switch_core_state_machine.c:852 (sofia/default/0212345678@10.239.238.30) State HANGUP going to sleep
2019-05-30 16:25:52.779715 [DEBUG] switch_core_state_machine.c:619 (sofia/default/0212345678@10.239.238.30) State Change CS_HANGUP -> CS_REPORTING
2019-05-30 16:25:52.779715 [DEBUG] switch_core_state_machine.c:584 (sofia/default/0212345678@10.239.238.30) Running State Change CS_REPORTING (Cur 1 Tot 1360)
2019-05-30 16:25:52.779715 [DEBUG] switch_core_state_machine.c:938 (sofia/default/0212345678@10.239.238.30) State REPORTING
2019-05-30 16:25:52.779715 [INFO] mod_json_cdr.c:271 Process [cba40998-82a3-11e9-9a03-7d3b5d4ccf50.cdr.json]
2019-05-30 16:25:52.799699 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
2019-05-30 16:25:52.819703 [DEBUG] switch_core_state_machine.c:174 sofia/default/0212345678@10.239.238.30 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
2019-05-30 16:25:52.819703 [DEBUG] switch_core_state_machine.c:938 (sofia/default/0212345678@10.239.238.30) State REPORTING going to sleep
2019-05-30 16:25:52.819703 [DEBUG] switch_core_state_machine.c:610 (sofia/default/0212345678@10.239.238.30) State Change CS_REPORTING -> CS_DESTROY
2019-05-30 16:25:52.819703 [DEBUG] switch_core_session.c:1665 Session 1360 (sofia/default/0212345678@10.239.238.30) Locked, Waiting on external entities
2019-05-30 16:25:52.819703 [NOTICE] switch_core_session.c:1683 Session 1360 (sofia/default/0212345678@10.239.238.30) Ended
2019-05-30 16:25:52.819703 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/0212345678@10.239.238.30 [CS_DESTROY]
2019-05-30 16:25:52.819703 [DEBUG] switch_core_state_machine.c:741 (sofia/default/0212345678@10.239.238.30) Running State Change CS_DESTROY (Cur 0 Tot 1360)
2019-05-30 16:25:52.819703 [DEBUG] switch_core_state_machine.c:751 (sofia/default/0212345678@10.239.238.30) State DESTROY
2019-05-30 16:25:52.819703 [DEBUG] mod_sofia.c:343 sofia/default/0212345678@10.239.238.30 SOFIA DESTROY
2019-05-30 16:25:52.819703 [DEBUG] switch_core_state_machine.c:181 sofia/default/0212345678@10.239.238.30 Standard DESTROY
2019-05-30 16:25:52.819703 [DEBUG] switch_core_state_machine.c:751 (sofia/default/0212345678@10.239.238.30) State DESTROY going to sleep