0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:39:58.498789 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/40016@86.153.193.35 [0c49131e-266e-48e6-80fb-93024fb7d60c]
0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:39:58.498789 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/40016@86.153.193.35) Running State Change CS_NEW (Cur 1 Tot 23)
0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:39:58.498789 [DEBUG] sofia.c:10070 sofia/internal/40016@86.153.193.35 receiving invite from 185.40.4.130:5071 version: 1.9.0 git be07fb8 2017-11-20 22:23:48Z 64bit
2017-11-21 16:39:58.498789 [DEBUG] sofia.c:10241 IP 185.40.4.130 Rejected by acl "domains". Falling back to Digest auth.
0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:39:58.498789 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/40016@86.153.193.35) State NEW
2017-11-21 16:39:58.498789 [DEBUG] sofia.c:2405 detaching session 0c49131e-266e-48e6-80fb-93024fb7d60c
0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [WARNING] switch_core_state_machine.c:687 0c49131e-266e-48e6-80fb-93024fb7d60c sofia/internal/40016@86.153.193.35 Abandoned
0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [NOTICE] switch_core_state_machine.c:690 Hangup sofia/internal/40016@86.153.193.35 [CS_NEW] [WRONG_CALL_STATE]
0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/40016@86.153.193.35) Running State Change CS_HANGUP (Cur 1 Tot 23)
0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/40016@86.153.193.35) Callstate Change DOWN -> HANGUP
0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/40016@86.153.193.35) State HANGUP
0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] mod_sofia.c:449 Channel sofia/internal/40016@86.153.193.35 hanging up, cause: WRONG_CALL_STATE
0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:60 sofia/internal/40016@86.153.193.35 Standard HANGUP, cause: WRONG_CALL_STATE
0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/40016@86.153.193.35) State HANGUP going to sleep
0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/40016@86.153.193.35) State Change CS_HANGUP -> CS_REPORTING
0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/40016@86.153.193.35) Running State Change CS_REPORTING (Cur 1 Tot 23)
0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/40016@86.153.193.35) State REPORTING
0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:174 sofia/internal/40016@86.153.193.35 Standard REPORTING, cause: WRONG_CALL_STATE
0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/40016@86.153.193.35) State REPORTING going to sleep
0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/40016@86.153.193.35) State Change CS_REPORTING -> CS_DESTROY
0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_session.c:1713 Session 23 (sofia/internal/40016@86.153.193.35) Locked, Waiting on external entities
0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [NOTICE] switch_core_session.c:1731 Session 23 (sofia/internal/40016@86.153.193.35) Ended
0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [NOTICE] switch_core_session.c:1735 Close Channel sofia/internal/40016@86.153.193.35 [CS_DESTROY]
0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/40016@86.153.193.35) Running State Change CS_DESTROY (Cur 0 Tot 23)
0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/40016@86.153.193.35) State DESTROY
0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] mod_sofia.c:354 sofia/internal/40016@86.153.193.35 SOFIA DESTROY
0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:181 sofia/internal/40016@86.153.193.35 Standard DESTROY
0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/40016@86.153.193.35) State DESTROY going to sleep
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [NOTICE] switch_channel.c:1104 New Channel sofia/external/+12404373728@telnyxlab.com [dee0fafa-4763-4e3b-8422-e8bf8303fcdd]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+12404373728@telnyxlab.com) Running State Change CS_NEW (Cur 1 Tot 24)
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] sofia.c:10070 sofia/external/+12404373728@telnyxlab.com receiving invite from 169.55.36.24:63922 version: 1.9.0 git be07fb8 2017-11-20 22:23:48Z 64bit
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] sofia.c:7283 Channel sofia/external/+12404373728@telnyxlab.com entering state [received][100]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] sofia.c:7293 Remote SDP:
dee0fafa-4763-4e3b-8422-e8bf8303fcdd v=0
dee0fafa-4763-4e3b-8422-e8bf8303fcdd o=- 115 1 IN IP4 169.55.36.24
dee0fafa-4763-4e3b-8422-e8bf8303fcdd s=session
dee0fafa-4763-4e3b-8422-e8bf8303fcdd c=IN IP4 169.55.36.24
dee0fafa-4763-4e3b-8422-e8bf8303fcdd b=CT:1000
dee0fafa-4763-4e3b-8422-e8bf8303fcdd t=0 0
dee0fafa-4763-4e3b-8422-e8bf8303fcdd m=audio 53854 RTP/SAVP 97 101 13 0 8
dee0fafa-4763-4e3b-8422-e8bf8303fcdd c=IN IP4 169.55.36.24
dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=rtpmap:97 RED/8000
dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=rtpmap:101 telephone-event/8000
dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=fmtp:101 0-16
dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=rtpmap:13 CN/8000
dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=rtpmap:0 PCMU/8000
dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=rtpmap:8 PCMA/8000
dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=rtcp:53855
dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=label:Audio
dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:IYb6guP9NH+u8UazavmIg+rKTpdn8Ag+RubWvYyQ|2^31|1:1
dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=crypto:2 AES_CM_128_HMAC_SHA1_80 inline:RTCvYtfPAtfTOm8rhx1+p0KXK8ecrP73ozE6r/lV|2^31
dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=ptime:20
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] sofia.c:7685 (sofia/external/+12404373728@telnyxlab.com) State Change CS_NEW -> CS_INIT
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:603 (sofia/external/+12404373728@telnyxlab.com) State NEW
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+12404373728@telnyxlab.com) Running State Change CS_INIT (Cur 1 Tot 24)
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:627 (sofia/external/+12404373728@telnyxlab.com) State INIT
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] mod_sofia.c:93 sofia/external/+12404373728@telnyxlab.com SOFIA INIT
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:40 sofia/external/+12404373728@telnyxlab.com Standard INIT
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:48 (sofia/external/+12404373728@telnyxlab.com) State Change CS_INIT -> CS_ROUTING
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:627 (sofia/external/+12404373728@telnyxlab.com) State INIT going to sleep
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+12404373728@telnyxlab.com) Running State Change CS_ROUTING (Cur 1 Tot 24)
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_channel.c:2249 (sofia/external/+12404373728@telnyxlab.com) Callstate Change DOWN -> RINGING
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+12404373728@telnyxlab.com) State ROUTING
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] mod_sofia.c:154 sofia/external/+12404373728@telnyxlab.com SOFIA ROUTING
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:236 sofia/external/+12404373728@telnyxlab.com Standard ROUTING
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [INFO] mod_dialplan_xml.c:637 Processing Test02 <+12404373728>->0012404373253 in context public
dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com parsing [public->unloop] continue=false
dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com parsing [public->outside_call] continue=true
dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com Absolute Condition [outside_call]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com Action set(outside_call=true)
dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com parsing [public->call_debug] continue=true
dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never
dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com parsing [public->public_extensions] continue=false
dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com Regex (FAIL) [public_extensions] destination_number(0012404373253) =~ /^(10[01][0-9])$/ break=on-false
dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com parsing [public->telnyx_test_1] continue=false
dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com Regex (FAIL) [telnyx_test_1] destination_number(0012404373253) =~ /^\+(\d{0,2})(0012025550104|12025550104|0012404373255|12404373255)$/ break=on-false
dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com parsing [public->telnyx_test_1_2] continue=false
dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com Regex (PASS) [telnyx_test_1_2] destination_number(0012404373253) =~ /^(0012404373253)$/ break=on-false
dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com Action answer()
dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com Action bridge(user/1004@${domain_name})
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:286 (sofia/external/+12404373728@telnyxlab.com) State Change CS_ROUTING -> CS_EXECUTE
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+12404373728@telnyxlab.com) State ROUTING going to sleep
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+12404373728@telnyxlab.com) Running State Change CS_EXECUTE (Cur 1 Tot 24)
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+12404373728@telnyxlab.com) State EXECUTE
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] mod_sofia.c:209 sofia/external/+12404373728@telnyxlab.com SOFIA EXECUTE
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:328 sofia/external/+12404373728@telnyxlab.com Standard EXECUTE
dee0fafa-4763-4e3b-8422-e8bf8303fcdd EXECUTE sofia/external/+12404373728@telnyxlab.com set(outside_call=true)
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] mod_dptools.c:1588 SET sofia/external/+12404373728@telnyxlab.com [outside_call]=[true]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd EXECUTE sofia/external/+12404373728@telnyxlab.com export(RFC2822_DATE=Tue, 21 Nov 2017 16:40:24 +0000)
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Tue, 21 Nov 2017 16:40:24 +0000]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd EXECUTE sofia/external/+12404373728@telnyxlab.com answer()
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5313 MKI Core session check incoming crypto (audio) 1 AES_CM_128_HMAC_SHA1_80 inline:IYb6guP9NH+u8UazavmIg+rKTpdn8Ag+RubWvYyQ|2^31|1:1
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:1783 looking for crypto suite [AEAD_AES_256_GCM_8] in [1 AES_CM_128_HMAC_SHA1_80 inline:IYb6guP9NH+u8UazavmIg+rKTpdn8Ag+RubWvYyQ|2^31|1:1]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:1783 looking for crypto suite [AEAD_AES_128_GCM_8] in [1 AES_CM_128_HMAC_SHA1_80 inline:IYb6guP9NH+u8UazavmIg+rKTpdn8Ag+RubWvYyQ|2^31|1:1]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:1783 looking for crypto suite [AES_CM_256_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_80 inline:IYb6guP9NH+u8UazavmIg+rKTpdn8Ag+RubWvYyQ|2^31|1:1]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:1783 looking for crypto suite [AES_CM_192_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_80 inline:IYb6guP9NH+u8UazavmIg+rKTpdn8Ag+RubWvYyQ|2^31|1:1]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:1783 looking for crypto suite [AES_CM_128_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_80 inline:IYb6guP9NH+u8UazavmIg+rKTpdn8Ag+RubWvYyQ|2^31|1:1]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:1788 Found suite AES_CM_128_HMAC_SHA1_80
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:1863 Set Remote Key [1 AES_CM_128_HMAC_SHA1_80 inline:IYb6guP9NH+u8UazavmIg+rKTpdn8Ag+RubWvYyQ|2^31|1:1]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:1163 MKI Core media build crypto 4 SEND
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:1204 Set Local audio crypto Key [1 AES_CM_128_HMAC_SHA1_80 inline:aXquDSidVDISGHR8AXU2W/NrlS9LzYuti84qI8dd]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [RED:97:8000:20:0:1]/[opus:116:48000:20:0:1]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [RED:97:8000:20:0:1]/[G722:9:8000:20:64000:1]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [RED:97:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [RED:97:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5356 Set telephone-event payload to 101@8000
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5495 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5495 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:3752 Set Codec sofia/external/+12404373728@telnyxlab.com PCMU/8000 20 ms 160 samples 64000 bits 1 channels
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_codec.c:111 sofia/external/+12404373728@telnyxlab.com Original read codec set to PCMU:0
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5699 Set telephone-event payload to 101@8000
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5757 sofia/external/+12404373728@telnyxlab.com Set 2833 dtmf send payload to 101 recv payload to 101
2017-11-21 16:40:27.738789 [DEBUG] switch_nat.c:508 mapped public port 28606 protocol UDP to localport 28606
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.738789 [DEBUG] switch_core_media.c:8478 AUDIO RTP [sofia/external/+12404373728@telnyxlab.com] 192.168.1.104 port 28606 -> 169.55.36.24 port 53854 codec: 0 ms: 20
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.738789 [DEBUG] switch_rtp.c:4301 Starting timer [soft] 160 bytes per 20ms
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_core_media.c:8698 Activating RTCP PORT 53855
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_rtp.c:4697 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 53855
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_rtp.c:2577 Setting RTCP remote addr to 169.55.36.24:53855 2
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_core_media.c:8782 sofia/external/+12404373728@telnyxlab.com Set 2833 dtmf send payload to 101
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_core_media.c:8789 sofia/external/+12404373728@telnyxlab.com Set 2833 dtmf receive payload to 101
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_core_media.c:8812 sofia/external/+12404373728@telnyxlab.com Set rtp dtmf delay to 40
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_core_media.c:8823 MKI Core session apply crypto (audio)
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_core_media.c:1648 MKI Core media add crypto 1 AES_CM_128_HMAC_SHA1_80 inline:IYb6guP9NH+u8UazavmIg+rKTpdn8Ag+RubWvYyQ|2^31|1:1 (RECV)
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_core_media.c:1414 MKI Core media add crypto RECV
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_core_media.c:1509 LIFETIME found in |2^31|1:1, base 2 exp 31
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_core_media.c:1515 MKI found in |2^31|1:1, id 1 size 1
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [INFO] switch_rtp.c:4105 Activating audio Secure RTP SEND
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [INFO] switch_rtp.c:4083 Activating audio Secure RTP RECV
2017-11-21 16:40:27.758762 [DEBUG] switch_core_sqldb.c:2617 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80
2017-11-21 16:40:27.758762 [DEBUG] switch_core_sqldb.c:2617 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/+12404373728@telnyxlab.com!
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_channel.c:3482 (sofia/external/+12404373728@telnyxlab.com) Callstate Change RINGING -> EARLY
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_core_media.c:8461 Audio params are unchanged for sofia/external/+12404373728@telnyxlab.com.
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] mod_sofia.c:881 Local SDP sofia/external/+12404373728@telnyxlab.com:
dee0fafa-4763-4e3b-8422-e8bf8303fcdd v=0
dee0fafa-4763-4e3b-8422-e8bf8303fcdd o=FreeSWITCH 1511253821 1511253822 IN IP4 86.153.193.35
dee0fafa-4763-4e3b-8422-e8bf8303fcdd s=FreeSWITCH
dee0fafa-4763-4e3b-8422-e8bf8303fcdd c=IN IP4 86.153.193.35
dee0fafa-4763-4e3b-8422-e8bf8303fcdd t=0 0
dee0fafa-4763-4e3b-8422-e8bf8303fcdd m=audio 28606 RTP/SAVP 0 101
dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=rtpmap:0 PCMU/8000
dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=rtpmap:101 telephone-event/8000
dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=fmtp:101 0-16
dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=ptime:20
dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=sendrecv
dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=rtcp:28607 IN IP4 86.153.193.35
dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:aXquDSidVDISGHR8AXU2W/NrlS9LzYuti84qI8dd
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [NOTICE] mod_dptools.c:1352 Channel [sofia/external/+12404373728@telnyxlab.com] has been answered
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_channel.c:3781 (sofia/external/+12404373728@telnyxlab.com) Callstate Change EARLY -> ACTIVE
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] sofia.c:7283 Channel sofia/external/+12404373728@telnyxlab.com entering state [completed][200]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd EXECUTE sofia/external/+12404373728@telnyxlab.com bridge(user/1004@192.168.1.104)
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_channel.c:1823 (sofia/external/+12404373728@telnyxlab.com) Callstate Change ACTIVE -> RING_WAIT
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_channel.c:1250 sofia/external/+12404373728@telnyxlab.com EXPORTING[export_vars] [RFC2822_DATE]=[Tue, 21 Nov 2017 16:40:24 +0000] to event
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_ivr_originate.c:2159 Parsing global variables
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_channel.c:1250 sofia/external/+12404373728@telnyxlab.com EXPORTING[export_vars] [RFC2822_DATE]=[Tue, 21 Nov 2017 16:40:24 +0000] to event
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_ivr_originate.c:2159 Parsing global variables
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1004@192.168.1.103:56406 [7f4c7f2a-67a0-4e6e-a159-4768924bdb10]
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] mod_sofia.c:5026 (sofia/internal/1004@192.168.1.103:56406) State Change CS_NEW -> CS_INIT
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1004@192.168.1.103:56406) Running State Change CS_INIT (Cur 2 Tot 25)
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [NOTICE] switch_ivr_originate.c:2868 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1004@192.168.1.103:56406) State INIT
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] mod_sofia.c:93 sofia/internal/1004@192.168.1.103:56406 SOFIA INIT
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] sofia_glue.c:1299 sofia/internal/1004@192.168.1.103:56406 sending invite version: 1.9.0 git be07fb8 2017-11-20 22:23:48Z 64bit
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 Local SDP:
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 v=0
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 o=FreeSWITCH 1511264847 1511264848 IN IP4 192.168.1.104
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 s=FreeSWITCH
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 c=IN IP4 192.168.1.104
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 t=0 0
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 m=audio 17580 RTP/AVP 0 101
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 a=rtpmap:0 PCMU/8000
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 a=rtpmap:101 telephone-event/8000
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 a=fmtp:101 0-16
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 a=ptime:20
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 a=sendrecv
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1004@192.168.1.103:56406 Standard INIT
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1004@192.168.1.103:56406) State Change CS_INIT -> CS_ROUTING
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1004@192.168.1.103:56406) State INIT going to sleep
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1004@192.168.1.103:56406) Running State Change CS_ROUTING (Cur 2 Tot 25)
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] sofia.c:7283 Channel sofia/internal/1004@192.168.1.103:56406 entering state [calling][0]
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1004@192.168.1.103:56406) State ROUTING
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] mod_sofia.c:154 sofia/internal/1004@192.168.1.103:56406 SOFIA ROUTING
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/1004@192.168.1.103:56406) State Change CS_ROUTING -> CS_CONSUME_MEDIA
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1004@192.168.1.103:56406) State ROUTING going to sleep
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1004@192.168.1.103:56406) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 25)
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1004@192.168.1.103:56406) State CONSUME_MEDIA
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1004@192.168.1.103:56406) State CONSUME_MEDIA going to sleep
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.838773 [DEBUG] sofia.c:7283 Channel sofia/internal/1004@192.168.1.103:56406 entering state [proceeding][180]
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.838773 [NOTICE] sofia.c:7391 Ring-Ready sofia/internal/1004@192.168.1.103:56406!
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.838773 [DEBUG] switch_channel.c:3354 (sofia/internal/1004@192.168.1.103:56406) Callstate Change DOWN -> RINGING
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.898774 [DEBUG] sofia.c:7283 Channel sofia/external/+12404373728@telnyxlab.com entering state [ready][200]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:30.118776 [DEBUG] switch_rtp.c:1892 rtcp_stats_init: audio ssrc[704027278] base_seq[29978]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:30.118776 [DEBUG] switch_rtp.c:7529 Correct audio ip/port confirmed.
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:32.678784 [DEBUG] switch_rtp.c:6970 Correct audio RTCP ip/port confirmed.
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] sofia.c:7283 Channel sofia/internal/1004@192.168.1.103:56406 entering state [completing][200]
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] sofia.c:7293 Remote SDP:
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 v=0
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 o=- 632470344 3 IN IP4 192.168.1.103
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 s=X-Lite release 5.0.3 stamp 88254
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 c=IN IP4 192.168.1.103
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 t=0 0
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 m=audio 55410 RTP/AVP 0 101
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 a=rtpmap:101 telephone-event/8000
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 a=fmtp:101 0-15
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] sofia.c:7283 Channel sofia/internal/1004@192.168.1.103:56406 entering state [ready][200]
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_core_media.c:5495 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_core_media.c:5356 Set telephone-event payload to 101@8000
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_core_media.c:3752 Set Codec sofia/internal/1004@192.168.1.103:56406 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_core_codec.c:111 sofia/internal/1004@192.168.1.103:56406 Original read codec set to PCMU:0
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_core_media.c:5699 Set telephone-event payload to 101@8000
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_core_media.c:5757 sofia/internal/1004@192.168.1.103:56406 Set 2833 dtmf send payload to 101 recv payload to 101
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_core_media.c:8478 AUDIO RTP [sofia/internal/1004@192.168.1.103:56406] 192.168.1.104 port 17580 -> 192.168.1.103 port 55410 codec: 0 ms: 20
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_rtp.c:4301 Starting timer [soft] 160 bytes per 20ms
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_core_media.c:8782 sofia/internal/1004@192.168.1.103:56406 Set 2833 dtmf send payload to 101
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_core_media.c:8789 sofia/internal/1004@192.168.1.103:56406 Set 2833 dtmf receive payload to 101
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_core_media.c:8812 sofia/internal/1004@192.168.1.103:56406 Set rtp dtmf delay to 40
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_core_media.c:8823 MKI Core session apply crypto (audio)
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [NOTICE] sofia.c:8419 Channel [sofia/internal/1004@192.168.1.103:56406] has been answered
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_channel.c:3781 (sofia/internal/1004@192.168.1.103:56406) Callstate Change RINGING -> ACTIVE
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:39.958777 [DEBUG] switch_ivr_originate.c:3722 Originate Resulted in Success: [sofia/internal/1004@192.168.1.103:56406]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:39.978763 [DEBUG] switch_channel.c:2047 (sofia/external/+12404373728@telnyxlab.com) Callstate Change RING_WAIT -> ACTIVE
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:39.978763 [DEBUG] switch_ivr_originate.c:3722 Originate Resulted in Success: [sofia/internal/1004@192.168.1.103:56406]
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.978763 [DEBUG] switch_ivr_bridge.c:1744 (sofia/internal/1004@192.168.1.103:56406) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.978763 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1004@192.168.1.103:56406) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 25)
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.978763 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/1004@192.168.1.103:56406) State EXCHANGE_MEDIA
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.978763 [DEBUG] mod_sofia.c:645 SOFIA EXCHANGE_MEDIA
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:40.018790 [DEBUG] switch_rtp.c:7529 Correct audio ip/port confirmed.
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [NOTICE] sofia.c:1079 Hangup sofia/external/+12404373728@telnyxlab.com [CS_EXECUTE] [NORMAL_CLEARING]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_ivr_bridge.c:917 BRIDGE THREAD DONE [sofia/external/+12404373728@telnyxlab.com]
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_ivr_bridge.c:825 sofia/external/+12404373728@telnyxlab.com ending bridge by request from write function
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_ivr_bridge.c:917 BRIDGE THREAD DONE [sofia/internal/1004@192.168.1.103:56406]
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [NOTICE] switch_ivr_bridge.c:1034 Hangup sofia/internal/1004@192.168.1.103:56406 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/1004@192.168.1.103:56406) State EXCHANGE_MEDIA going to sleep
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1004@192.168.1.103:56406) Running State Change CS_HANGUP (Cur 2 Tot 25)
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1004@192.168.1.103:56406) Callstate Change ACTIVE -> HANGUP
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1004@192.168.1.103:56406) State HANGUP
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] mod_sofia.c:443 sofia/internal/1004@192.168.1.103:56406 Overriding SIP cause 480 with 200 from the other leg
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] mod_sofia.c:449 Channel sofia/internal/1004@192.168.1.103:56406 hanging up, cause: NORMAL_CLEARING
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] mod_sofia.c:502 Sending BYE to sofia/internal/1004@192.168.1.103:56406
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1004@192.168.1.103:56406 Standard HANGUP, cause: NORMAL_CLEARING
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1004@192.168.1.103:56406) State HANGUP going to sleep
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_ivr_bridge.c:1843 sofia/internal/1004@192.168.1.103:56406 skip receive message [TRANSFER] (channel is hungup already)
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_ivr_bridge.c:1846 sofia/external/+12404373728@telnyxlab.com skip receive message [TRANSFER] (channel is hungup already)
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1004@192.168.1.103:56406) State Change CS_HANGUP -> CS_REPORTING
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1004@192.168.1.103:56406) Running State Change CS_REPORTING (Cur 2 Tot 25)
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_session.c:2885 sofia/external/+12404373728@telnyxlab.com skip receive message [PHONE_EVENT] (channel is hungup already)
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+12404373728@telnyxlab.com) State EXECUTE going to sleep
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+12404373728@telnyxlab.com) Running State Change CS_HANGUP (Cur 2 Tot 25)
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1004@192.168.1.103:56406) State REPORTING
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1004@192.168.1.103:56406 Standard REPORTING, cause: NORMAL_CLEARING
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1004@192.168.1.103:56406) State REPORTING going to sleep
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:850 (sofia/external/+12404373728@telnyxlab.com) Callstate Change ACTIVE -> HANGUP
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:852 (sofia/external/+12404373728@telnyxlab.com) State HANGUP
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1004@192.168.1.103:56406) State Change CS_REPORTING -> CS_DESTROY
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_session.c:1713 Session 25 (sofia/internal/1004@192.168.1.103:56406) Locked, Waiting on external entities
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] mod_sofia.c:449 Channel sofia/external/+12404373728@telnyxlab.com hanging up, cause: NORMAL_CLEARING
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:60 sofia/external/+12404373728@telnyxlab.com Standard HANGUP, cause: NORMAL_CLEARING
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [NOTICE] switch_core_session.c:1731 Session 25 (sofia/internal/1004@192.168.1.103:56406) Ended
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:852 (sofia/external/+12404373728@telnyxlab.com) State HANGUP going to sleep
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [NOTICE] switch_core_session.c:1735 Close Channel sofia/internal/1004@192.168.1.103:56406 [CS_DESTROY]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:619 (sofia/external/+12404373728@telnyxlab.com) State Change CS_HANGUP -> CS_REPORTING
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+12404373728@telnyxlab.com) Running State Change CS_REPORTING (Cur 1 Tot 25)
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:938 (sofia/external/+12404373728@telnyxlab.com) State REPORTING
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1004@192.168.1.103:56406) Running State Change CS_DESTROY (Cur 1 Tot 25)
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:174 sofia/external/+12404373728@telnyxlab.com Standard REPORTING, cause: NORMAL_CLEARING
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:938 (sofia/external/+12404373728@telnyxlab.com) State REPORTING going to sleep
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1004@192.168.1.103:56406) State DESTROY
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] mod_sofia.c:354 sofia/internal/1004@192.168.1.103:56406 SOFIA DESTROY
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1004@192.168.1.103:56406 Standard DESTROY
7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1004@192.168.1.103:56406) State DESTROY going to sleep
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:610 (sofia/external/+12404373728@telnyxlab.com) State Change CS_REPORTING -> CS_DESTROY
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_session.c:1713 Session 24 (sofia/external/+12404373728@telnyxlab.com) Locked, Waiting on external entities
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [NOTICE] switch_core_session.c:1731 Session 24 (sofia/external/+12404373728@telnyxlab.com) Ended
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [NOTICE] switch_core_session.c:1735 Close Channel sofia/external/+12404373728@telnyxlab.com [CS_DESTROY]
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:741 (sofia/external/+12404373728@telnyxlab.com) Running State Change CS_DESTROY (Cur 0 Tot 25)
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:751 (sofia/external/+12404373728@telnyxlab.com) State DESTROY
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] mod_sofia.c:354 sofia/external/+12404373728@telnyxlab.com SOFIA DESTROY
2017-11-21 16:41:01.378787 [DEBUG] switch_nat.c:568 unmapped public port 28606 protocol UDP to localport 28606
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:41:02.158789 [DEBUG] switch_core_state_machine.c:181 sofia/external/+12404373728@telnyxlab.com Standard DESTROY
dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:41:02.158789 [DEBUG] switch_core_state_machine.c:751 (sofia/external/+12404373728@telnyxlab.com) State DESTROY going to sleep
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:06.498789 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/146@86.153.193.35 [3d6885a4-8259-44b3-b143-09145e77edb6]
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:06.498789 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/146@86.153.193.35) Running State Change CS_NEW (Cur 1 Tot 26)
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:06.498789 [DEBUG] sofia.c:10070 sofia/internal/146@86.153.193.35 receiving invite from 185.40.4.13:5070 version: 1.9.0 git be07fb8 2017-11-20 22:23:48Z 64bit
2017-11-21 16:44:06.498789 [DEBUG] sofia.c:10241 IP 185.40.4.13 Rejected by acl "domains". Falling back to Digest auth.
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:06.498789 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/146@86.153.193.35) State NEW
2017-11-21 16:44:06.498789 [DEBUG] sofia.c:2405 detaching session 3d6885a4-8259-44b3-b143-09145e77edb6
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [WARNING] switch_core_state_machine.c:687 3d6885a4-8259-44b3-b143-09145e77edb6 sofia/internal/146@86.153.193.35 Abandoned
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [NOTICE] switch_core_state_machine.c:690 Hangup sofia/internal/146@86.153.193.35 [CS_NEW] [WRONG_CALL_STATE]
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/146@86.153.193.35) Running State Change CS_HANGUP (Cur 1 Tot 26)
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/146@86.153.193.35) Callstate Change DOWN -> HANGUP
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/146@86.153.193.35) State HANGUP
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] mod_sofia.c:449 Channel sofia/internal/146@86.153.193.35 hanging up, cause: WRONG_CALL_STATE
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:60 sofia/internal/146@86.153.193.35 Standard HANGUP, cause: WRONG_CALL_STATE
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/146@86.153.193.35) State HANGUP going to sleep
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/146@86.153.193.35) State Change CS_HANGUP -> CS_REPORTING
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/146@86.153.193.35) Running State Change CS_REPORTING (Cur 1 Tot 26)
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/146@86.153.193.35) State REPORTING
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:174 sofia/internal/146@86.153.193.35 Standard REPORTING, cause: WRONG_CALL_STATE
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/146@86.153.193.35) State REPORTING going to sleep
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/146@86.153.193.35) State Change CS_REPORTING -> CS_DESTROY
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_session.c:1713 Session 26 (sofia/internal/146@86.153.193.35) Locked, Waiting on external entities
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [NOTICE] switch_core_session.c:1731 Session 26 (sofia/internal/146@86.153.193.35) Ended
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [NOTICE] switch_core_session.c:1735 Close Channel sofia/internal/146@86.153.193.35 [CS_DESTROY]
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/146@86.153.193.35) Running State Change CS_DESTROY (Cur 0 Tot 26)
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/146@86.153.193.35) State DESTROY
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] mod_sofia.c:354 sofia/internal/146@86.153.193.35 SOFIA DESTROY
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:181 sofia/internal/146@86.153.193.35 Standard DESTROY
3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/146@86.153.193.35) State DESTROY going to sleep
2017-11-21 16:47:31.858787 [DEBUG] switch_nat.c:508 mapped public port 5060 protocol TCP to localport 5060
2017-11-21 16:47:32.418791 [DEBUG] switch_nat.c:508 mapped public port 5060 protocol UDP to localport 5060
2017-11-21 16:47:33.138789 [DEBUG] switch_nat.c:508 mapped public port 5061 protocol TCP to localport 5061
2017-11-21 16:47:34.018802 [DEBUG] switch_nat.c:508 mapped public port 5080 protocol TCP to localport 5080
2017-11-21 16:47:34.598764 [DEBUG] switch_nat.c:508 mapped public port 5080 protocol UDP to localport 5080
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:48:53.198790 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/40016@86.153.193.35 [74e1c9e3-b32c-4973-bc1d-08916829d480]
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:48:53.198790 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/40016@86.153.193.35) Running State Change CS_NEW (Cur 1 Tot 27)
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:48:53.198790 [DEBUG] sofia.c:10070 sofia/internal/40016@86.153.193.35 receiving invite from 185.40.4.130:5070 version: 1.9.0 git be07fb8 2017-11-20 22:23:48Z 64bit
2017-11-21 16:48:53.198790 [DEBUG] sofia.c:10241 IP 185.40.4.130 Rejected by acl "domains". Falling back to Digest auth.
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:48:53.198790 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/40016@86.153.193.35) State NEW
2017-11-21 16:48:53.198790 [DEBUG] sofia.c:2405 detaching session 74e1c9e3-b32c-4973-bc1d-08916829d480
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [WARNING] switch_core_state_machine.c:687 74e1c9e3-b32c-4973-bc1d-08916829d480 sofia/internal/40016@86.153.193.35 Abandoned
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [NOTICE] switch_core_state_machine.c:690 Hangup sofia/internal/40016@86.153.193.35 [CS_NEW] [WRONG_CALL_STATE]
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/40016@86.153.193.35) Running State Change CS_HANGUP (Cur 1 Tot 27)
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/40016@86.153.193.35) Callstate Change DOWN -> HANGUP
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/40016@86.153.193.35) State HANGUP
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] mod_sofia.c:449 Channel sofia/internal/40016@86.153.193.35 hanging up, cause: WRONG_CALL_STATE
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:60 sofia/internal/40016@86.153.193.35 Standard HANGUP, cause: WRONG_CALL_STATE
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/40016@86.153.193.35) State HANGUP going to sleep
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/40016@86.153.193.35) State Change CS_HANGUP -> CS_REPORTING
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/40016@86.153.193.35) Running State Change CS_REPORTING (Cur 1 Tot 27)
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/40016@86.153.193.35) State REPORTING
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:174 sofia/internal/40016@86.153.193.35 Standard REPORTING, cause: WRONG_CALL_STATE
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/40016@86.153.193.35) State REPORTING going to sleep
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/40016@86.153.193.35) State Change CS_REPORTING -> CS_DESTROY
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_session.c:1713 Session 27 (sofia/internal/40016@86.153.193.35) Locked, Waiting on external entities
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [NOTICE] switch_core_session.c:1731 Session 27 (sofia/internal/40016@86.153.193.35) Ended
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [NOTICE] switch_core_session.c:1735 Close Channel sofia/internal/40016@86.153.193.35 [CS_DESTROY]
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/40016@86.153.193.35) Running State Change CS_DESTROY (Cur 0 Tot 27)
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/40016@86.153.193.35) State DESTROY
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] mod_sofia.c:354 sofia/internal/40016@86.153.193.35 SOFIA DESTROY
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:181 sofia/internal/40016@86.153.193.35 Standard DESTROY
74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/40016@86.153.193.35) State DESTROY going to sleep