2016-09-20 10:01:15.975938 [NOTICE] switch_channel.c:1104 New Channel sofia/external/08504300004@91.244.227.228 [b26f3a16-7f3a-11e6-aeed-e7f8278a30bd]
2016-09-20 10:01:15.975938 [DEBUG] switch_core_state_machine.c:543 (sofia/external/08504300004@91.244.227.228) Running State Change CS_NEW
2016-09-20 10:01:15.975938 [DEBUG] sofia.c:9573 sofia/external/08504300004@91.244.227.228 receiving invite from 91.244.227.228:5060 version: 1.7.0 git 7bee958 2016-09-07 14:27:03Z 64bit
2016-09-20 10:01:15.975938 [DEBUG] sofia.c:6962 Channel sofia/external/08504300004@91.244.227.228 entering state [received][100]
2016-09-20 10:01:15.975938 [DEBUG] sofia.c:6972 Remote SDP:
v=0
o=- 127968 127968 IN IP4 91.244.227.228
s=FreeSWITCH
c=IN IP4 91.244.227.228
t=0 0
m=audio 49826 RTP/AVP 102 9 0 8 104 101
a=rtpmap:102 opus/48000/2
a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:104 telephone-event/48000
a=fmtp:104 0-16
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
m=video 44524 RTP/AVP 103
b=AS:1024
a=rtpmap:103 VP8/90000
a=rtcp-fb:103 ccm fir
a=rtcp-fb:103 ccm tmmbr
a=rtcp-fb:103 nack
a=rtcp-fb:103 nack pli
a=direction:active
a=nortpproxy:yes

2016-09-20 10:01:15.975938 [DEBUG] sofia.c:7340 (sofia/external/08504300004@91.244.227.228) State Change CS_NEW -> CS_INIT
2016-09-20 10:01:15.975938 [DEBUG] switch_core_state_machine.c:562 (sofia/external/08504300004@91.244.227.228) State NEW
2016-09-20 10:01:15.975938 [DEBUG] switch_core_state_machine.c:543 (sofia/external/08504300004@91.244.227.228) Running State Change CS_INIT
2016-09-20 10:01:15.975938 [DEBUG] switch_core_state_machine.c:586 (sofia/external/08504300004@91.244.227.228) State INIT
2016-09-20 10:01:15.975938 [DEBUG] mod_sofia.c:90 sofia/external/08504300004@91.244.227.228 SOFIA INIT
2016-09-20 10:01:15.975938 [DEBUG] switch_core_state_machine.c:40 sofia/external/08504300004@91.244.227.228 Standard INIT
2016-09-20 10:01:15.975938 [DEBUG] switch_core_state_machine.c:48 (sofia/external/08504300004@91.244.227.228) State Change CS_INIT -> CS_ROUTING
2016-09-20 10:01:15.975938 [DEBUG] switch_core_state_machine.c:586 (sofia/external/08504300004@91.244.227.228) State INIT going to sleep
2016-09-20 10:01:15.975938 [DEBUG] switch_core_state_machine.c:543 (sofia/external/08504300004@91.244.227.228) Running State Change CS_ROUTING
2016-09-20 10:01:15.975938 [DEBUG] switch_channel.c:2249 (sofia/external/08504300004@91.244.227.228) Callstate Change DOWN -> RINGING
2016-09-20 10:01:15.975938 [DEBUG] switch_core_state_machine.c:602 (sofia/external/08504300004@91.244.227.228) State ROUTING
2016-09-20 10:01:15.975938 [DEBUG] mod_sofia.c:143 sofia/external/08504300004@91.244.227.228 SOFIA ROUTING
2016-09-20 10:01:15.975938 [DEBUG] switch_core_state_machine.c:236 sofia/external/08504300004@91.244.227.228 Standard ROUTING
2016-09-20 10:01:15.975938 [INFO] mod_dialplan_xml.c:637 Processing 08504300004 <08504300004>->908504300003 in context public
Dialplan: sofia/external/08504300004@91.244.227.228 parsing [public->unloop] continue=false
Dialplan: sofia/external/08504300004@91.244.227.228 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/external/08504300004@91.244.227.228 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/external/08504300004@91.244.227.228 parsing [public->outside_call] continue=true
Dialplan: sofia/external/08504300004@91.244.227.228 Absolute Condition [outside_call]
Dialplan: sofia/external/08504300004@91.244.227.228 Action set(outside_call=true) 
Dialplan: sofia/external/08504300004@91.244.227.228 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) 
Dialplan: sofia/external/08504300004@91.244.227.228 parsing [public->call_debug] continue=true
Dialplan: sofia/external/08504300004@91.244.227.228 Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never
Dialplan: sofia/external/08504300004@91.244.227.228 parsing [public->public_extensions] continue=false
Dialplan: sofia/external/08504300004@91.244.227.228 Regex (FAIL) [public_extensions] destination_number(908504300003) =~ /^(10[01][0-9])$/ break=on-false
Dialplan: sofia/external/08504300004@91.244.227.228 parsing [public->public_did] continue=false
Dialplan: sofia/external/08504300004@91.244.227.228 Regex (FAIL) [public_did] destination_number(908504300003) =~ /^(5551212)$/ break=on-false
Dialplan: sofia/external/08504300004@91.244.227.228 parsing [public->908504300003] continue=false
Dialplan: sofia/external/08504300004@91.244.227.228 Regex (PASS) [908504300003] destination_number(908504300003) =~ /^908504300003$/ break=on-false
Dialplan: sofia/external/08504300004@91.244.227.228 Action answer() 
Dialplan: sofia/external/08504300004@91.244.227.228 Action playback(silence_stream://2000) 
Dialplan: sofia/external/08504300004@91.244.227.228 Action rxfax(//home//user1//fax//rxfax-${uuid}.tiff) 
Dialplan: sofia/external/08504300004@91.244.227.228 Action hangup() 
Dialplan: sofia/external/08504300004@91.244.227.228 Action set(session_in_hangup_hook=true) 
2016-09-20 10:01:15.985944 [DEBUG] switch_core_state_machine.c:286 (sofia/external/08504300004@91.244.227.228) State Change CS_ROUTING -> CS_EXECUTE
2016-09-20 10:01:15.985944 [DEBUG] switch_core_state_machine.c:602 (sofia/external/08504300004@91.244.227.228) State ROUTING going to sleep
2016-09-20 10:01:15.985944 [DEBUG] switch_core_state_machine.c:543 (sofia/external/08504300004@91.244.227.228) Running State Change CS_EXECUTE
2016-09-20 10:01:15.985944 [DEBUG] switch_core_state_machine.c:609 (sofia/external/08504300004@91.244.227.228) State EXECUTE
2016-09-20 10:01:15.985944 [DEBUG] mod_sofia.c:198 sofia/external/08504300004@91.244.227.228 SOFIA EXECUTE
2016-09-20 10:01:15.985944 [DEBUG] switch_core_state_machine.c:328 sofia/external/08504300004@91.244.227.228 Standard EXECUTE
EXECUTE sofia/external/08504300004@91.244.227.228 set(outside_call=true)
2016-09-20 10:01:15.985944 [DEBUG] mod_dptools.c:1519 SET sofia/external/08504300004@91.244.227.228 [outside_call]=[true]
EXECUTE sofia/external/08504300004@91.244.227.228 export(RFC2822_DATE=Tue, 20 Sep 2016 10:01:15 -0400)
2016-09-20 10:01:15.985944 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Tue, 20 Sep 2016 10:01:15 -0400]
EXECUTE sofia/external/08504300004@91.244.227.228 answer()
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4281 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:1]
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4336 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4281 Audio Codec Compare [opus:102:48000:20:0:1]/[G722:9:8000:20:64000:1]
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4281 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4281 Audio Codec Compare [opus:102:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4281 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4281 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4336 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4281 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4281 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4281 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4281 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4281 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4336 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4281 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4281 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4281 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4281 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4281 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4336 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4197 Set telephone-event payload to 104@48000
2016-09-20 10:01:15.985944 [DEBUG] mod_opus.c:559 Opus encoder: set bitrate to local settings [72000bps]
2016-09-20 10:01:15.985944 [DEBUG] mod_opus.c:559 Opus encoder: set bitrate to local settings [72000bps]
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:3017 Set Codec sofia/external/08504300004@91.244.227.228 opus/48000 20 ms 960 samples 0 bits 1 channels
2016-09-20 10:01:15.985944 [DEBUG] switch_core_codec.c:111 sofia/external/08504300004@91.244.227.228 Original read codec set to opus:116
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4542 Set telephone-event payload to 104@48000
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4601 sofia/external/08504300004@91.244.227.228 Set 2833 dtmf send payload to 104 recv payload to 104
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4764 Video Codec Compare [VP8:103]/[VP8:99]
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4788 Video Codec Compare [VP8:103] +++ is saved as a match
2016-09-20 10:01:15.985944 [DEBUG] switch_vpx.c:627 VPX VER:v1.5.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
2016-09-20 10:01:15.985944 [DEBUG] switch_vpx.c:627 VPX VER:v1.5.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:2827 Set VIDEO Codec sofia/external/08504300004@91.244.227.228 VP8/90000 0 ms
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:6438 AUDIO RTP [sofia/external/08504300004@91.244.227.228] 185.77.91.8 port 31856 -> 91.244.227.228 port 49826 codec: 102 ms: 20
2016-09-20 10:01:15.985944 [DEBUG] switch_rtp.c:3824 Starting timer [soft] 960 bytes per 20ms
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:6737 sofia/external/08504300004@91.244.227.228 Set 2833 dtmf send payload to 104
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:6744 sofia/external/08504300004@91.244.227.228 Set 2833 dtmf receive payload to 104
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:6767 sofia/external/08504300004@91.244.227.228 Set rtp dtmf delay to 40
2016-09-20 10:01:15.985944 [DEBUG] switch_rtp.c:3835 Starting video timer.
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:6956 VIDEO RTP [sofia/external/08504300004@91.244.227.228] 185.77.91.8:32202->91.244.227.228:44524 codec: 103 ms: 0 [SUCCESS]
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:5581 sofia/external/08504300004@91.244.227.228 Starting Video thread
2016-09-20 10:01:15.985944 [INFO] switch_core_media.c:7035 Activating VIDEO RTCP PORT 0 interval 2000 mux 0
2016-09-20 10:01:15.985944 [DEBUG] switch_rtp.c:4210 RTCP send rate is: 2000 and packet rate is: 90000 Remote Port: 44525
2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:5447 sofia/external/08504300004@91.244.227.228 Video thread started. Echo is off
2016-09-20 10:01:15.985944 [DEBUG] switch_rtp.c:2488 Setting RTCP remote addr to 91.244.227.228:44525 2
2016-09-20 10:01:15.985944 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/08504300004@91.244.227.228!
2016-09-20 10:01:15.985944 [DEBUG] switch_channel.c:3471 (sofia/external/08504300004@91.244.227.228) Callstate Change RINGING -> EARLY
2016-09-20 10:01:15.985944 [DEBUG] mod_sofia.c:822 Local SDP sofia/external/08504300004@91.244.227.228:
v=0
o=FreeSWITCH 1474348219 1474348220 IN IP4 185.77.91.8
s=FreeSWITCH
c=IN IP4 185.77.91.8
t=0 0
m=audio 31856 RTP/AVP 102 104
a=rtpmap:102 opus/48000/2
a=fmtp:102 useinbandfec=1; ptime=20; minptime=10; maxptime=40
a=rtpmap:104 telephone-event/48000
a=fmtp:104 0-16
a=ptime:20
a=sendrecv
m=video 32202 RTP/AVP 103
b=AS:1024
a=rtpmap:103 VP8/90000
a=rtcp:32203 IN IP4 185.77.91.8
a=rtcp-fb:103 ccm fir
a=rtcp-fb:103 ccm tmmbr
a=rtcp-fb:103 nack
a=rtcp-fb:103 nack pli

2016-09-20 10:01:15.985944 [NOTICE] mod_dptools.c:1309 Channel [sofia/external/08504300004@91.244.227.228] has been answered
2016-09-20 10:01:15.985944 [DEBUG] switch_channel.c:3770 (sofia/external/08504300004@91.244.227.228) Callstate Change EARLY -> ACTIVE
2016-09-20 10:01:15.985944 [DEBUG] sofia.c:6962 Channel sofia/external/08504300004@91.244.227.228 entering state [completed][200]
EXECUTE sofia/external/08504300004@91.244.227.228 playback(silence_stream://2000)
2016-09-20 10:01:15.985944 [DEBUG] switch_ivr_play_say.c:1467 Codec Activated L16@48000hz 1 channels 20ms
2016-09-20 10:01:16.005923 [DEBUG] sofia.c:6962 Channel sofia/external/08504300004@91.244.227.228 entering state [ready][200]
2016-09-20 10:01:16.195959 [NOTICE] switch_vpx.c:402 VPX reset encoder picture from 320x240 to 352x288 1 BW
2016-09-20 10:01:16.265945 [DEBUG] switch_rtp.c:1847 rtcp_stats_init: video ssrc[200605565] base_seq[22703]
2016-09-20 10:01:16.265945 [DEBUG] switch_rtp.c:6721 Correct video ip/port confirmed.
2016-09-20 10:01:16.835930 [DEBUG] switch_rtp.c:6721 Correct audio ip/port confirmed.
2016-09-20 10:01:18.325940 [DEBUG] switch_ivr_play_say.c:1910 done playing file silence_stream://2000
EXECUTE sofia/external/08504300004@91.244.227.228 rxfax(//home//user1//fax//rxfax-b26f3a16-7f3a-11e6-aeed-e7f8278a30bd.tiff)
2016-09-20 10:01:18.325940 [DEBUG] mod_spandsp_fax.c:1440 Raw read codec activation Success L16 20000
2016-09-20 10:01:18.325940 [DEBUG] switch_core_codec.c:221 sofia/external/08504300004@91.244.227.228 Push codec L16:100
2016-09-20 10:01:18.325940 [DEBUG] mod_spandsp_fax.c:1458 Raw write codec activation Success L16
2016-09-20 10:01:19.235960 [DEBUG] switch_core_media.c:2473 [VBR]: Correcting calculated ptime value from [60] to [20] to compensate for [2] lost packet(s)
2016-09-20 10:01:21.225927 [DEBUG] mod_spandsp_fax.c:496 ==============================================================================
2016-09-20 10:01:21.225927 [DEBUG] mod_spandsp_fax.c:508 Fax processing not successful - result (5) The HDLC carrier did not stop in a timely manner.
2016-09-20 10:01:21.225927 [DEBUG] mod_spandsp_fax.c:513 Remote station id: 
2016-09-20 10:01:21.225927 [DEBUG] mod_spandsp_fax.c:514 Local station id:  SpanDSP Fax Ident
2016-09-20 10:01:21.225927 [DEBUG] mod_spandsp_fax.c:515 Pages transferred: 0
2016-09-20 10:01:21.225927 [DEBUG] mod_spandsp_fax.c:517 Total fax pages:   0
2016-09-20 10:01:21.225927 [DEBUG] mod_spandsp_fax.c:518 Image resolution:  0x0
2016-09-20 10:01:21.225927 [DEBUG] mod_spandsp_fax.c:519 Transfer Rate:     14400
2016-09-20 10:01:21.225927 [DEBUG] mod_spandsp_fax.c:521 ECM status         off
2016-09-20 10:01:21.225927 [DEBUG] mod_spandsp_fax.c:522 remote country:   
2016-09-20 10:01:21.225927 [DEBUG] mod_spandsp_fax.c:523 remote vendor:    
2016-09-20 10:01:21.225927 [DEBUG] mod_spandsp_fax.c:524 remote model:     
2016-09-20 10:01:21.225927 [DEBUG] mod_spandsp_fax.c:526 ==============================================================================
2016-09-20 10:01:21.245931 [DEBUG] switch_core_codec.c:246 sofia/external/08504300004@91.244.227.228 Restore previous codec opus:116.
EXECUTE sofia/external/08504300004@91.244.227.228 hangup()
2016-09-20 10:01:21.245931 [NOTICE] mod_dptools.c:1283 Hangup sofia/external/08504300004@91.244.227.228 [CS_EXECUTE] [NORMAL_CLEARING]
2016-09-20 10:01:21.245931 [DEBUG] switch_core_session.c:2797 sofia/external/08504300004@91.244.227.228 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2016-09-20 10:01:21.245931 [DEBUG] switch_core_state_machine.c:609 (sofia/external/08504300004@91.244.227.228) State EXECUTE going to sleep
2016-09-20 10:01:21.245931 [DEBUG] switch_core_state_machine.c:543 (sofia/external/08504300004@91.244.227.228) Running State Change CS_HANGUP
2016-09-20 10:01:21.245931 [DEBUG] switch_core_state_machine.c:809 (sofia/external/08504300004@91.244.227.228) Callstate Change ACTIVE -> HANGUP
2016-09-20 10:01:21.245931 [DEBUG] switch_core_state_machine.c:811 (sofia/external/08504300004@91.244.227.228) State HANGUP
2016-09-20 10:01:21.245931 [DEBUG] mod_sofia.c:438 Channel sofia/external/08504300004@91.244.227.228 hanging up, cause: NORMAL_CLEARING
2016-09-20 10:01:21.245931 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/external/08504300004@91.244.227.228
2016-09-20 10:01:21.245931 [DEBUG] switch_core_state_machine.c:60 sofia/external/08504300004@91.244.227.228 Standard HANGUP, cause: NORMAL_CLEARING
2016-09-20 10:01:21.245931 [DEBUG] switch_core_state_machine.c:811 (sofia/external/08504300004@91.244.227.228) State HANGUP going to sleep
2016-09-20 10:01:21.245931 [DEBUG] switch_core_state_machine.c:578 (sofia/external/08504300004@91.244.227.228) State Change CS_HANGUP -> CS_REPORTING
2016-09-20 10:01:21.245931 [DEBUG] switch_core_state_machine.c:543 (sofia/external/08504300004@91.244.227.228) Running State Change CS_REPORTING
2016-09-20 10:01:21.245931 [DEBUG] switch_core_state_machine.c:897 (sofia/external/08504300004@91.244.227.228) State REPORTING
2016-09-20 10:01:21.245931 [DEBUG] switch_core_state_machine.c:174 sofia/external/08504300004@91.244.227.228 Standard REPORTING, cause: NORMAL_CLEARING
2016-09-20 10:01:21.245931 [DEBUG] switch_core_state_machine.c:897 (sofia/external/08504300004@91.244.227.228) State REPORTING going to sleep
2016-09-20 10:01:21.245931 [DEBUG] switch_core_state_machine.c:569 (sofia/external/08504300004@91.244.227.228) State Change CS_REPORTING -> CS_DESTROY
2016-09-20 10:01:21.245931 [DEBUG] switch_core_session.c:1647 Session 154 (sofia/external/08504300004@91.244.227.228) Locked, Waiting on external entities
2016-09-20 10:01:21.245931 [DEBUG] switch_core_media.c:5548 sofia/external/08504300004@91.244.227.228 Video thread ended
2016-09-20 10:01:21.245931 [NOTICE] switch_core_session.c:1665 Session 154 (sofia/external/08504300004@91.244.227.228) Ended
2016-09-20 10:01:21.245931 [NOTICE] switch_core_session.c:1669 Close Channel sofia/external/08504300004@91.244.227.228 [CS_DESTROY]
2016-09-20 10:01:21.245931 [DEBUG] switch_core_state_machine.c:700 (sofia/external/08504300004@91.244.227.228) Running State Change CS_DESTROY
2016-09-20 10:01:21.245931 [DEBUG] switch_core_state_machine.c:710 (sofia/external/08504300004@91.244.227.228) State DESTROY
2016-09-20 10:01:21.245931 [DEBUG] mod_sofia.c:343 sofia/external/08504300004@91.244.227.228 SOFIA DESTROY
2016-09-20 10:01:21.245931 [DEBUG] mod_opus.c:661 Opus decoder stats: Frames[58] PLC[0] FEC[0]
2016-09-20 10:01:21.245931 [DEBUG] mod_opus.c:661 Opus decoder stats: Frames[0] PLC[0] FEC[0]
2016-09-20 10:01:21.245931 [DEBUG] switch_core_state_machine.c:181 sofia/external/08504300004@91.244.227.228 Standard DESTROY
2016-09-20 10:01:21.245931 [DEBUG] switch_core_state_machine.c:710 (sofia/external/08504300004@91.244.227.228) State DESTROY going to sleep