2020-10-08 16:06:15.207841 [NOTICE] sofia.c:7339 Pre-Answer sofia/external/101!
2020-10-08 16:06:15.207841 [DEBUG] switch_channel.c:3565 (sofia/external/101) Callstate Change DOWN -> EARLY
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:3839 Set Codec sofia/external/101 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
2020-10-08 16:06:15.207841 [DEBUG] switch_core_codec.c:111 sofia/external/101 Original read codec set to PCMU:0
2020-10-08 16:06:15.207841 [WARNING] switch_core_media.c:4254 NO candidate ACL defined, Defaulting to wan.auto
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:4284 Save audio Candidate cid: 1 proto: UDP type: host addr: 192.168.1.100:10112
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:4284 Save audio Candidate cid: 1 proto: UDP type: host addr: 172.16.1.221:10112
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:4284 Save audio Candidate cid: 2 proto: UDP type: host addr: 192.168.1.100:10113
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:4284 Save audio Candidate cid: 2 proto: UDP type: host addr: 172.16.1.221:10113
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:4329 Searching for rtp candidate.
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:4329 Searching for rtcp candidate.
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:4376 Look for Relay Candidates as last resort
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:4329 Searching for rtp candidate.
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:4329 Searching for rtcp candidate.
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:4382 sofia/external/101 no suitable candidates found.
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:5911 sofia/external/101 Set 2833 dtmf send payload to 101 recv payload to 101
2020-10-08 16:06:15.207841 [NOTICE] sofia.c:7520 Hangup sofia/external/101 [CS_CONSUME_MEDIA] [INCOMPATIBLE_DESTINATION]
2020-10-08 16:06:15.207841 [DEBUG] switch_core_state_machine.c:585 (sofia/external/101) Running State Change CS_HANGUP (Cur 2 Tot 199)
2020-10-08 16:06:15.207841 [DEBUG] switch_core_state_machine.c:848 (sofia/external/101) Callstate Change EARLY -> HANGUP
2020-10-08 16:06:15.207841 [DEBUG] switch_core_state_machine.c:850 (sofia/external/101) State HANGUP
2020-10-08 16:06:15.207841 [DEBUG] mod_sofia.c:453 Channel sofia/external/101 hanging up, cause: INCOMPATIBLE_DESTINATION
2020-10-08 16:06:15.207841 [DEBUG] mod_sofia.c:521 Sending CANCEL to sofia/external/101
2020-10-08 16:06:15.207841 [DEBUG] switch_core_state_machine.c:60 sofia/external/101 Standard HANGUP, cause: INCOMPATIBLE_DESTINATION
2020-10-08 16:06:15.207841 [DEBUG] switch_core_state_machine.c:850 (sofia/external/101) State HANGUP going to sleep
2020-10-08 16:06:15.207841 [DEBUG] switch_core_state_machine.c:620 (sofia/external/101) State Change CS_HANGUP -> CS_REPORTING
2020-10-08 16:06:15.207841 [DEBUG] switch_core_state_machine.c:585 (sofia/external/101) Running State Change CS_REPORTING (Cur 2 Tot 199)
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:936 (sofia/external/101) State REPORTING
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:174 sofia/external/101 Standard REPORTING, cause: INCOMPATIBLE_DESTINATION
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:936 (sofia/external/101) State REPORTING going to sleep
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:611 (sofia/external/101) State Change CS_REPORTING -> CS_DESTROY
2020-10-08 16:06:15.227837 [DEBUG] switch_core_session.c:1726 Session 199 (sofia/external/101) Locked, Waiting on external entities
2020-10-08 16:06:15.227837 [DEBUG] switch_ivr_originate.c:3995 Originate Resulted in Error Cause: 88 [INCOMPATIBLE_DESTINATION]
2020-10-08 16:06:15.227837 [NOTICE] switch_core_session.c:1744 Session 199 (sofia/external/101) Ended
2020-10-08 16:06:15.227837 [NOTICE] switch_core_session.c:1748 Close Channel sofia/external/101 [CS_DESTROY]
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:739 (sofia/external/101) Running State Change CS_DESTROY (Cur 1 Tot 199)
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:749 (sofia/external/101) State DESTROY
2020-10-08 16:06:15.227837 [DEBUG] mod_sofia.c:364 sofia/external/101 SOFIA DESTROY
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:181 sofia/external/101 Standard DESTROY
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:749 (sofia/external/101) State DESTROY going to sleep
2020-10-08 16:06:15.227837 [INFO] mod_dptools.c:3631 Originate Failed. Cause: INCOMPATIBLE_DESTINATION
2020-10-08 16:06:15.227837 [NOTICE] switch_core_state_machine.c:386 sofia/internal/9296@testucs01.fti-nbil.com has executed the last dialplan instruction, hanging up.
2020-10-08 16:06:15.227837 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/internal/9296@testucs01.fti-nbil.com [CS_EXECUTE] [NORMAL_CLEARING]
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/9296@testucs01.fti-nbil.com) State EXECUTE going to sleep
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/9296@testucs01.fti-nbil.com) Running State Change CS_HANGUP (Cur 1 Tot 199)
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/9296@testucs01.fti-nbil.com) Callstate Change RINGING -> HANGUP
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/9296@testucs01.fti-nbil.com) State HANGUP
2020-10-08 16:06:15.227837 [DEBUG] mod_sofia.c:453 Channel sofia/internal/9296@testucs01.fti-nbil.com hanging up, cause: NORMAL_CLEARING
2020-10-08 16:06:15.227837 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 480
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:60 sofia/internal/9296@testucs01.fti-nbil.com Standard HANGUP, cause: NORMAL_CLEARING
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/9296@testucs01.fti-nbil.com) State HANGUP going to sleep
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/9296@testucs01.fti-nbil.com) State Change CS_HANGUP -> CS_REPORTING
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/9296@testucs01.fti-nbil.com) Running State Change CS_REPORTING (Cur 1 Tot 199)
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/9296@testucs01.fti-nbil.com) State REPORTING
2020-10-08 16:06:15.387845 [DEBUG] switch_core_state_machine.c:174 sofia/internal/9296@testucs01.fti-nbil.com Standard REPORTING, cause: NORMAL_CLEARING
2020-10-08 16:06:15.387845 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/9296@testucs01.fti-nbil.com) State REPORTING going to sleep
2020-10-08 16:06:15.387845 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/9296@testucs01.fti-nbil.com) State Change CS_REPORTING -> CS_DESTROY
2020-10-08 16:06:15.387845 [DEBUG] switch_core_session.c:1726 Session 198 (sofia/internal/9296@testucs01.fti-nbil.com) Locked, Waiting on external entities
2020-10-08 16:06:15.387845 [NOTICE] switch_core_session.c:1744 Session 198 (sofia/internal/9296@testucs01.fti-nbil.com) Ended
2020-10-08 16:06:15.387845 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/9296@testucs01.fti-nbil.com [CS_DESTROY]
2020-10-08 16:06:15.387845 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/9296@testucs01.fti-nbil.com) Running State Change CS_DESTROY (Cur 0 Tot 199)
2020-10-08 16:06:15.387845 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/9296@testucs01.fti-nbil.com) State DESTROY
2020-10-08 16:06:15.387845 [DEBUG] mod_sofia.c:364 sofia/internal/9296@testucs01.fti-nbil.com SOFIA DESTROY
2020-10-08 16:06:15.387845 [DEBUG] switch_core_state_machine.c:181 sofia/internal/9296@testucs01.fti-nbil.com Standard DESTROY
2020-10-08 16:06:15.387845 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/9296@testucs01.fti-nbil.com) State DESTROY going to sleep
2020-10-08 16:06:26.767876 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'internal' for [9296@testucs01.fti-nbil.com] from ip 172.16.1.45
2020-10-08 16:06:15.207841 [DEBUG] switch_channel.c:3565 (sofia/external/101) Callstate Change DOWN -> EARLY
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:3839 Set Codec sofia/external/101 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
2020-10-08 16:06:15.207841 [DEBUG] switch_core_codec.c:111 sofia/external/101 Original read codec set to PCMU:0
2020-10-08 16:06:15.207841 [WARNING] switch_core_media.c:4254 NO candidate ACL defined, Defaulting to wan.auto
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:4284 Save audio Candidate cid: 1 proto: UDP type: host addr: 192.168.1.100:10112
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:4284 Save audio Candidate cid: 1 proto: UDP type: host addr: 172.16.1.221:10112
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:4284 Save audio Candidate cid: 2 proto: UDP type: host addr: 192.168.1.100:10113
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:4284 Save audio Candidate cid: 2 proto: UDP type: host addr: 172.16.1.221:10113
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:4329 Searching for rtp candidate.
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:4329 Searching for rtcp candidate.
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:4376 Look for Relay Candidates as last resort
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:4329 Searching for rtp candidate.
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:4329 Searching for rtcp candidate.
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:4382 sofia/external/101 no suitable candidates found.
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
2020-10-08 16:06:15.207841 [DEBUG] switch_core_media.c:5911 sofia/external/101 Set 2833 dtmf send payload to 101 recv payload to 101
2020-10-08 16:06:15.207841 [NOTICE] sofia.c:7520 Hangup sofia/external/101 [CS_CONSUME_MEDIA] [INCOMPATIBLE_DESTINATION]
2020-10-08 16:06:15.207841 [DEBUG] switch_core_state_machine.c:585 (sofia/external/101) Running State Change CS_HANGUP (Cur 2 Tot 199)
2020-10-08 16:06:15.207841 [DEBUG] switch_core_state_machine.c:848 (sofia/external/101) Callstate Change EARLY -> HANGUP
2020-10-08 16:06:15.207841 [DEBUG] switch_core_state_machine.c:850 (sofia/external/101) State HANGUP
2020-10-08 16:06:15.207841 [DEBUG] mod_sofia.c:453 Channel sofia/external/101 hanging up, cause: INCOMPATIBLE_DESTINATION
2020-10-08 16:06:15.207841 [DEBUG] mod_sofia.c:521 Sending CANCEL to sofia/external/101
2020-10-08 16:06:15.207841 [DEBUG] switch_core_state_machine.c:60 sofia/external/101 Standard HANGUP, cause: INCOMPATIBLE_DESTINATION
2020-10-08 16:06:15.207841 [DEBUG] switch_core_state_machine.c:850 (sofia/external/101) State HANGUP going to sleep
2020-10-08 16:06:15.207841 [DEBUG] switch_core_state_machine.c:620 (sofia/external/101) State Change CS_HANGUP -> CS_REPORTING
2020-10-08 16:06:15.207841 [DEBUG] switch_core_state_machine.c:585 (sofia/external/101) Running State Change CS_REPORTING (Cur 2 Tot 199)
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:936 (sofia/external/101) State REPORTING
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:174 sofia/external/101 Standard REPORTING, cause: INCOMPATIBLE_DESTINATION
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:936 (sofia/external/101) State REPORTING going to sleep
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:611 (sofia/external/101) State Change CS_REPORTING -> CS_DESTROY
2020-10-08 16:06:15.227837 [DEBUG] switch_core_session.c:1726 Session 199 (sofia/external/101) Locked, Waiting on external entities
2020-10-08 16:06:15.227837 [DEBUG] switch_ivr_originate.c:3995 Originate Resulted in Error Cause: 88 [INCOMPATIBLE_DESTINATION]
2020-10-08 16:06:15.227837 [NOTICE] switch_core_session.c:1744 Session 199 (sofia/external/101) Ended
2020-10-08 16:06:15.227837 [NOTICE] switch_core_session.c:1748 Close Channel sofia/external/101 [CS_DESTROY]
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:739 (sofia/external/101) Running State Change CS_DESTROY (Cur 1 Tot 199)
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:749 (sofia/external/101) State DESTROY
2020-10-08 16:06:15.227837 [DEBUG] mod_sofia.c:364 sofia/external/101 SOFIA DESTROY
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:181 sofia/external/101 Standard DESTROY
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:749 (sofia/external/101) State DESTROY going to sleep
2020-10-08 16:06:15.227837 [INFO] mod_dptools.c:3631 Originate Failed. Cause: INCOMPATIBLE_DESTINATION
2020-10-08 16:06:15.227837 [NOTICE] switch_core_state_machine.c:386 sofia/internal/9296@testucs01.fti-nbil.com has executed the last dialplan instruction, hanging up.
2020-10-08 16:06:15.227837 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/internal/9296@testucs01.fti-nbil.com [CS_EXECUTE] [NORMAL_CLEARING]
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/9296@testucs01.fti-nbil.com) State EXECUTE going to sleep
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/9296@testucs01.fti-nbil.com) Running State Change CS_HANGUP (Cur 1 Tot 199)
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/9296@testucs01.fti-nbil.com) Callstate Change RINGING -> HANGUP
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/9296@testucs01.fti-nbil.com) State HANGUP
2020-10-08 16:06:15.227837 [DEBUG] mod_sofia.c:453 Channel sofia/internal/9296@testucs01.fti-nbil.com hanging up, cause: NORMAL_CLEARING
2020-10-08 16:06:15.227837 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 480
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:60 sofia/internal/9296@testucs01.fti-nbil.com Standard HANGUP, cause: NORMAL_CLEARING
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/9296@testucs01.fti-nbil.com) State HANGUP going to sleep
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/9296@testucs01.fti-nbil.com) State Change CS_HANGUP -> CS_REPORTING
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/9296@testucs01.fti-nbil.com) Running State Change CS_REPORTING (Cur 1 Tot 199)
2020-10-08 16:06:15.227837 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/9296@testucs01.fti-nbil.com) State REPORTING
2020-10-08 16:06:15.387845 [DEBUG] switch_core_state_machine.c:174 sofia/internal/9296@testucs01.fti-nbil.com Standard REPORTING, cause: NORMAL_CLEARING
2020-10-08 16:06:15.387845 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/9296@testucs01.fti-nbil.com) State REPORTING going to sleep
2020-10-08 16:06:15.387845 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/9296@testucs01.fti-nbil.com) State Change CS_REPORTING -> CS_DESTROY
2020-10-08 16:06:15.387845 [DEBUG] switch_core_session.c:1726 Session 198 (sofia/internal/9296@testucs01.fti-nbil.com) Locked, Waiting on external entities
2020-10-08 16:06:15.387845 [NOTICE] switch_core_session.c:1744 Session 198 (sofia/internal/9296@testucs01.fti-nbil.com) Ended
2020-10-08 16:06:15.387845 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/9296@testucs01.fti-nbil.com [CS_DESTROY]
2020-10-08 16:06:15.387845 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/9296@testucs01.fti-nbil.com) Running State Change CS_DESTROY (Cur 0 Tot 199)
2020-10-08 16:06:15.387845 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/9296@testucs01.fti-nbil.com) State DESTROY
2020-10-08 16:06:15.387845 [DEBUG] mod_sofia.c:364 sofia/internal/9296@testucs01.fti-nbil.com SOFIA DESTROY
2020-10-08 16:06:15.387845 [DEBUG] switch_core_state_machine.c:181 sofia/internal/9296@testucs01.fti-nbil.com Standard DESTROY
2020-10-08 16:06:15.387845 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/9296@testucs01.fti-nbil.com) State DESTROY going to sleep
2020-10-08 16:06:26.767876 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'internal' for [9296@testucs01.fti-nbil.com] from ip 172.16.1.45
