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