2017-06-20 22:42:53.947939 [DEBUG] switch_core_state_machine.c:40 sofia/external/5034705047 Standard INIT
2017-06-20 22:42:53.947939 [DEBUG] switch_core_state_machine.c:48 (sofia/external/5034705047) State Change CS_INIT -> CS_ROUTING
2017-06-20 22:42:53.947939 [DEBUG] switch_core_state_machine.c:516 (sofia/external/5034705047) State INIT going to sleep
2017-06-20 22:42:53.947939 [DEBUG] switch_core_state_machine.c:473 (sofia/external/5034705047) Running State Change CS_ROUTING
2017-06-20 22:42:53.947939 [DEBUG] sofia.c:6760 Channel sofia/external/5034705047 entering state [calling][0]
2017-06-20 22:42:53.947939 [DEBUG] switch_core_state_machine.c:532 (sofia/external/5034705047) State ROUTING
2017-06-20 22:42:53.947939 [DEBUG] mod_sofia.c:141 sofia/external/5034705047 SOFIA ROUTING
2017-06-20 22:42:53.947939 [DEBUG] switch_ivr_originate.c:67 (sofia/external/5034705047) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2017-06-20 22:42:53.947939 [DEBUG] switch_core_state_machine.c:532 (sofia/external/5034705047) State ROUTING going to sleep
2017-06-20 22:42:53.947939 [DEBUG] switch_core_state_machine.c:473 (sofia/external/5034705047) Running State Change CS_CONSUME_MEDIA
2017-06-20 22:42:53.947939 [DEBUG] switch_core_state_machine.c:551 (sofia/external/5034705047) State CONSUME_MEDIA
2017-06-20 22:42:53.947939 [DEBUG] switch_core_state_machine.c:551 (sofia/external/5034705047) State CONSUME_MEDIA going to sleep
2017-06-20 22:42:54.087940 [DEBUG] sofia.c:6760 Channel sofia/external/5034705047 entering state [terminated][403]
2017-06-20 22:42:54.087940 [NOTICE] sofia.c:7779 Hangup sofia/external/5034705047 [CS_CONSUME_MEDIA] [CALL_REJECTED]
2017-06-20 22:42:54.087940 [DEBUG] switch_core_state_machine.c:473 (sofia/external/5034705047) Running State Change CS_HANGUP
2017-06-20 22:42:54.087940 [DEBUG] switch_core_state_machine.c:739 (sofia/external/5034705047) Callstate Change DOWN -> HANGUP
2017-06-20 22:42:54.087940 [DEBUG] switch_core_state_machine.c:741 (sofia/external/5034705047) State HANGUP
2017-06-20 22:42:54.087940 [DEBUG] mod_sofia.c:431 Channel sofia/external/5034705047 hanging up, cause: CALL_REJECTED
2017-06-20 22:42:54.087940 [DEBUG] switch_core_state_machine.c:60 sofia/external/5034705047 Standard HANGUP, cause: CALL_REJECTED
2017-06-20 22:42:54.087940 [DEBUG] switch_core_state_machine.c:741 (sofia/external/5034705047) State HANGUP going to sleep
2017-06-20 22:42:54.087940 [DEBUG] switch_core_state_machine.c:508 (sofia/external/5034705047) State Change CS_HANGUP -> CS_REPORTING
2017-06-20 22:42:54.087940 [DEBUG] switch_core_state_machine.c:473 (sofia/external/5034705047) Running State Change CS_REPORTING
2017-06-20 22:42:54.087940 [DEBUG] switch_core_state_machine.c:827 (sofia/external/5034705047) State REPORTING
2017-06-20 22:42:54.087940 [DEBUG] switch_core_state_machine.c:104 sofia/external/5034705047 Standard REPORTING, cause: CALL_REJECTED
2017-06-20 22:42:54.087940 [DEBUG] switch_core_state_machine.c:827 (sofia/external/5034705047) State REPORTING going to sleep
2017-06-20 22:42:54.087940 [DEBUG] switch_core_state_machine.c:499 (sofia/external/5034705047) State Change CS_REPORTING -> CS_DESTROY
2017-06-20 22:42:54.087940 [DEBUG] switch_core_session.c:1646 Session 14 (sofia/external/5034705047) Locked, Waiting on external entities
2017-06-20 22:42:54.107935 [DEBUG] switch_ivr_originate.c:3751 Originate Resulted in Error Cause: 21 [CALL_REJECTED]
2017-06-20 22:42:54.107935 [NOTICE] switch_core_session.c:1664 Session 14 (sofia/external/5034705047) Ended
2017-06-20 22:42:54.107935 [NOTICE] switch_core_session.c:1668 Close Channel sofia/external/5034705047 [CS_DESTROY]
2017-06-20 22:42:54.107935 [DEBUG] switch_core_state_machine.c:630 (sofia/external/5034705047) Running State Change CS_DESTROY
2017-06-20 22:42:54.107935 [INFO] mod_dptools.c:3379 Originate Failed.  Cause: CALL_REJECTED
2017-06-20 22:42:54.107935 [DEBUG] switch_core_state_machine.c:640 (sofia/external/5034705047) State DESTROY
2017-06-20 22:42:54.107935 [DEBUG] mod_sofia.c:341 sofia/external/5034705047 SOFIA DESTROY
2017-06-20 22:42:54.107935 [DEBUG] switch_core_state_machine.c:111 sofia/external/5034705047 Standard DESTROY
2017-06-20 22:42:54.107935 [DEBUG] switch_core_state_machine.c:640 (sofia/external/5034705047) State DESTROY going to sleep
EXECUTE sofia/external/12146846400@162.208.16.2 bridge(sofia/gateway/3c820e49-450b-4626-8bbe-561188fc09f2/5034705047)
2017-06-20 22:42:54.107935 [DEBUG] switch_channel.c:1247 sofia/external/12146846400@162.208.16.2 EXPORTING[export_vars] [origination_callee_id_name]=[5034705047] to event
2017-06-20 22:42:54.107935 [DEBUG] switch_channel.c:1247 sofia/external/12146846400@162.208.16.2 EXPORTING[export_vars] [dialed_extension]=[1502] to event
2017-06-20 22:42:54.107935 [DEBUG] switch_channel.c:1247 sofia/external/12146846400@162.208.16.2 EXPORTING[export_vars] [origination_callee_id_name]=[5034705047] to event
2017-06-20 22:42:54.107935 [DEBUG] switch_channel.c:1247 sofia/external/12146846400@162.208.16.2 EXPORTING[export_vars] [origination_callee_id_name]=[5034705047] to event
2017-06-20 22:42:54.107935 [DEBUG] switch_ivr_originate.c:2128 Parsing global variables
2017-06-20 22:42:54.107935 [NOTICE] switch_channel.c:1101 New Channel sofia/external/5034705047 [b52f5008-5633-11e7-8041-4d076d508355]
2017-06-20 22:42:54.107935 [DEBUG] mod_sofia.c:4776 (sofia/external/5034705047) State Change CS_NEW -> CS_INIT
2017-06-20 22:42:54.107935 [DEBUG] switch_core_state_machine.c:473 (sofia/external/5034705047) Running State Change CS_INIT
2017-06-20 22:42:54.107935 [DEBUG] switch_core_state_machine.c:516 (sofia/external/5034705047) State INIT
2017-06-20 22:42:54.107935 [DEBUG] mod_sofia.c:88 sofia/external/5034705047 SOFIA INIT
2017-06-20 22:42:54.107935 [DEBUG] sofia_glue.c:1257 sofia/external/5034705047 sending invite version: 1.6.6  64bit
Local SDP:
v=0
o=FreeSWITCH 1497991928 1497991929 IN IP4 162.208.16.3
s=FreeSWITCH
c=IN IP4 162.208.16.3
t=0 0
m=audio 24646 RTP/AVP 0 101 13
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
a=sendrecv

2017-06-20 22:42:54.107935 [DEBUG] switch_core_state_machine.c:40 sofia/external/5034705047 Standard INIT
2017-06-20 22:42:54.107935 [DEBUG] switch_core_state_machine.c:48 (sofia/external/5034705047) State Change CS_INIT -> CS_ROUTING
2017-06-20 22:42:54.107935 [DEBUG] switch_core_state_machine.c:516 (sofia/external/5034705047) State INIT going to sleep
2017-06-20 22:42:54.107935 [DEBUG] switch_core_state_machine.c:473 (sofia/external/5034705047) Running State Change CS_ROUTING
2017-06-20 22:42:54.107935 [DEBUG] sofia.c:6760 Channel sofia/external/5034705047 entering state [calling][0]
2017-06-20 22:42:54.107935 [DEBUG] switch_core_state_machine.c:532 (sofia/external/5034705047) State ROUTING
2017-06-20 22:42:54.107935 [DEBUG] mod_sofia.c:141 sofia/external/5034705047 SOFIA ROUTING
2017-06-20 22:42:54.107935 [DEBUG] switch_ivr_originate.c:67 (sofia/external/5034705047) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2017-06-20 22:42:54.107935 [DEBUG] switch_core_state_machine.c:532 (sofia/external/5034705047) State ROUTING going to sleep
2017-06-20 22:42:54.107935 [DEBUG] switch_core_state_machine.c:473 (sofia/external/5034705047) Running State Change CS_CONSUME_MEDIA
2017-06-20 22:42:54.107935 [DEBUG] switch_core_state_machine.c:551 (sofia/external/5034705047) State CONSUME_MEDIA
2017-06-20 22:42:54.107935 [DEBUG] switch_core_state_machine.c:551 (sofia/external/5034705047) State CONSUME_MEDIA going to sleep