2016-11-26 12:56:37.368968 [DEBUG] sofia.c:7107 Channel sofia/external/91000@10.211.55.12 entering state [terminated][487]
2016-11-26 12:56:37.368968 [NOTICE] sofia.c:8234 Hangup sofia/external/91000@10.211.55.12 [CS_EXECUTE] [ORIGINATOR_CANCEL]
2016-11-26 12:56:37.368968 [DEBUG] hiredis_profile.c:79 hiredis: waiting for [localhost, 6379]
2016-11-26 12:56:37.368968 [DEBUG] hiredis_profile.c:105 hiredis: recycled from pool[localhost, 6379]
2016-11-26 12:56:37.368968 [DEBUG] hiredis_profile.c:227 hiredis: decr 5.9.50.206:5060
2016-11-26 12:56:37.368968 [DEBUG] hiredis_profile.c:68 hiredis: release back to pool [localhost, 6379]
2016-11-26 12:56:37.388747 [NOTICE] switch_ivr_originate.c:3606 Hangup sofia/external/92301@5.9.50.206:5060 [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL]
2016-11-26 12:56:37.388747 [DEBUG] switch_ivr_originate.c:3823 Originate Cancelled by originator termination Cause: 487 [ORIGINATOR_CANCEL]
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:584 (sofia/external/92301@5.9.50.206:5060) Running State Change CS_HANGUP (Cur 2 Tot 136)
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:850 (sofia/external/92301@5.9.50.206:5060) Callstate Change DOWN -> HANGUP
2016-11-26 12:56:37.388747 [INFO] mod_dptools.c:3444 Originate Failed.  Cause: ORIGINATOR_CANCEL
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:852 (sofia/external/92301@5.9.50.206:5060) State HANGUP
2016-11-26 12:56:37.388747 [DEBUG] mod_sofia.c:434 sofia/external/92301@5.9.50.206:5060 Overriding SIP cause 487 with 487 from the other leg
2016-11-26 12:56:37.388747 [DEBUG] mod_sofia.c:440 Channel sofia/external/92301@5.9.50.206:5060 hanging up, cause: ORIGINATOR_CANCEL
2016-11-26 12:56:37.388747 [DEBUG] mod_sofia.c:504 Sending CANCEL to sofia/external/92301@5.9.50.206:5060
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:60 sofia/external/92301@5.9.50.206:5060 Standard HANGUP, cause: ORIGINATOR_CANCEL
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:852 (sofia/external/92301@5.9.50.206:5060) State HANGUP going to sleep
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:619 (sofia/external/92301@5.9.50.206:5060) State Change CS_HANGUP -> CS_REPORTING
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:584 (sofia/external/92301@5.9.50.206:5060) Running State Change CS_REPORTING (Cur 2 Tot 136)
2016-11-26 12:56:37.388747 [DEBUG] switch_core_session.c:2867 sofia/external/91000@10.211.55.12 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:938 (sofia/external/92301@5.9.50.206:5060) State REPORTING
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:174 sofia/external/92301@5.9.50.206:5060 Standard REPORTING, cause: ORIGINATOR_CANCEL
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:938 (sofia/external/92301@5.9.50.206:5060) State REPORTING going to sleep
2016-11-26 12:56:37.388747 [DEBUG] switch_core_session.c:2867 sofia/external/91000@10.211.55.12 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:650 (sofia/external/91000@10.211.55.12) State EXECUTE going to sleep
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:584 (sofia/external/91000@10.211.55.12) Running State Change CS_HANGUP (Cur 2 Tot 136)
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:850 (sofia/external/91000@10.211.55.12) Callstate Change RINGING -> HANGUP
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:610 (sofia/external/92301@5.9.50.206:5060) State Change CS_REPORTING -> CS_DESTROY
2016-11-26 12:56:37.388747 [DEBUG] switch_core_session.c:1695 Session 136 (sofia/external/92301@5.9.50.206:5060) Locked, Waiting on external entities
2016-11-26 12:56:37.388747 [NOTICE] switch_core_session.c:1713 Session 136 (sofia/external/92301@5.9.50.206:5060) Ended
2016-11-26 12:56:37.388747 [NOTICE] switch_core_session.c:1717 Close Channel sofia/external/92301@5.9.50.206:5060 [CS_DESTROY]
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:852 (sofia/external/91000@10.211.55.12) State HANGUP
2016-11-26 12:56:37.388747 [DEBUG] mod_sofia.c:440 Channel sofia/external/91000@10.211.55.12 hanging up, cause: ORIGINATOR_CANCEL
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:60 sofia/external/91000@10.211.55.12 Standard HANGUP, cause: ORIGINATOR_CANCEL
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:852 (sofia/external/91000@10.211.55.12) State HANGUP going to sleep
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:619 (sofia/external/91000@10.211.55.12) State Change CS_HANGUP -> CS_REPORTING
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:584 (sofia/external/91000@10.211.55.12) Running State Change CS_REPORTING (Cur 1 Tot 136)
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:938 (sofia/external/91000@10.211.55.12) State REPORTING
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:741 (sofia/external/92301@5.9.50.206:5060) Running State Change CS_DESTROY (Cur 1 Tot 136)
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:174 sofia/external/91000@10.211.55.12 Standard REPORTING, cause: ORIGINATOR_CANCEL
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:938 (sofia/external/91000@10.211.55.12) State REPORTING going to sleep
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:751 (sofia/external/92301@5.9.50.206:5060) State DESTROY
2016-11-26 12:56:37.388747 [DEBUG] mod_sofia.c:345 sofia/external/92301@5.9.50.206:5060 SOFIA DESTROY
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:181 sofia/external/92301@5.9.50.206:5060 Standard DESTROY
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:751 (sofia/external/92301@5.9.50.206:5060) State DESTROY going to sleep
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:610 (sofia/external/91000@10.211.55.12) State Change CS_REPORTING -> CS_DESTROY
2016-11-26 12:56:37.388747 [DEBUG] switch_core_session.c:1695 Session 135 (sofia/external/91000@10.211.55.12) Locked, Waiting on external entities
2016-11-26 12:56:37.388747 [NOTICE] switch_core_session.c:1713 Session 135 (sofia/external/91000@10.211.55.12) Ended
2016-11-26 12:56:37.388747 [NOTICE] switch_core_session.c:1717 Close Channel sofia/external/91000@10.211.55.12 [CS_DESTROY]
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:741 (sofia/external/91000@10.211.55.12) Running State Change CS_DESTROY (Cur 0 Tot 136)
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:751 (sofia/external/91000@10.211.55.12) State DESTROY
2016-11-26 12:56:37.388747 [DEBUG] mod_sofia.c:345 sofia/external/91000@10.211.55.12 SOFIA DESTROY
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:181 sofia/external/91000@10.211.55.12 Standard DESTROY
2016-11-26 12:56:37.388747 [DEBUG] switch_core_state_machine.c:751 (sofia/external/91000@10.211.55.12) State DESTROY going to sleep