1. 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]
2. 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
3. 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
4. 2016-09-20 10:01:15.975938 [DEBUG] sofia.c:6962 Channel sofia/external/08504300004@91.244.227.228 entering state [received][100]
5. 2016-09-20 10:01:15.975938 [DEBUG] sofia.c:6972 Remote SDP:
6. v=0
7. o=- 127968 127968 IN IP4 91.244.227.228
8. s=FreeSWITCH
9. c=IN IP4 91.244.227.228
10. t=0 0
11. m=audio 49826 RTP/AVP 102 9 0 8 104 101
12. a=rtpmap:102 opus/48000/2
13. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
14. a=rtpmap:9 G722/8000
15. a=rtpmap:0 PCMU/8000
16. a=rtpmap:8 PCMA/8000
17. a=rtpmap:104 telephone-event/48000
18. a=fmtp:104 0-16
19. a=rtpmap:101 telephone-event/8000
20. a=fmtp:101 0-16
21. a=ptime:20
22. m=video 44524 RTP/AVP 103
23. b=AS:1024
24. a=rtpmap:103 VP8/90000
25. a=rtcp-fb:103 ccm fir
26. a=rtcp-fb:103 ccm tmmbr
27. a=rtcp-fb:103 nack
28. a=rtcp-fb:103 nack pli
29. a=direction:active
30. a=nortpproxy:yes
31.  
32. 2016-09-20 10:01:15.975938 [DEBUG] sofia.c:7340 (sofia/external/08504300004@91.244.227.228) State Change CS_NEW -> CS_INIT
33. 2016-09-20 10:01:15.975938 [DEBUG] switch_core_state_machine.c:562 (sofia/external/08504300004@91.244.227.228) State NEW
34. 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
35. 2016-09-20 10:01:15.975938 [DEBUG] switch_core_state_machine.c:586 (sofia/external/08504300004@91.244.227.228) State INIT
36. 2016-09-20 10:01:15.975938 [DEBUG] mod_sofia.c:90 sofia/external/08504300004@91.244.227.228 SOFIA INIT
37. 2016-09-20 10:01:15.975938 [DEBUG] switch_core_state_machine.c:40 sofia/external/08504300004@91.244.227.228 Standard INIT
38. 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
39. 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
40. 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
41. 2016-09-20 10:01:15.975938 [DEBUG] switch_channel.c:2249 (sofia/external/08504300004@91.244.227.228) Callstate Change DOWN -> RINGING
42. 2016-09-20 10:01:15.975938 [DEBUG] switch_core_state_machine.c:602 (sofia/external/08504300004@91.244.227.228) State ROUTING
43. 2016-09-20 10:01:15.975938 [DEBUG] mod_sofia.c:143 sofia/external/08504300004@91.244.227.228 SOFIA ROUTING
44. 2016-09-20 10:01:15.975938 [DEBUG] switch_core_state_machine.c:236 sofia/external/08504300004@91.244.227.228 Standard ROUTING
45. 2016-09-20 10:01:15.975938 [INFO] mod_dialplan_xml.c:637 Processing 08504300004 <08504300004>->908504300003 in context public
46. Dialplan: sofia/external/08504300004@91.244.227.228 parsing [public->unloop] continue=false
47. Dialplan: sofia/external/08504300004@91.244.227.228 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
48. Dialplan: sofia/external/08504300004@91.244.227.228 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
49. Dialplan: sofia/external/08504300004@91.244.227.228 parsing [public->outside_call] continue=true
50. Dialplan: sofia/external/08504300004@91.244.227.228 Absolute Condition [outside_call]
51. Dialplan: sofia/external/08504300004@91.244.227.228 Action set(outside_call=true)
52. Dialplan: sofia/external/08504300004@91.244.227.228 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
53. Dialplan: sofia/external/08504300004@91.244.227.228 parsing [public->call_debug] continue=true
54. Dialplan: sofia/external/08504300004@91.244.227.228 Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never
55. Dialplan: sofia/external/08504300004@91.244.227.228 parsing [public->public_extensions] continue=false
56. Dialplan: sofia/external/08504300004@91.244.227.228 Regex (FAIL) [public_extensions] destination_number(908504300003) =~ /^(10[01][0-9])$/ break=on-false
57. Dialplan: sofia/external/08504300004@91.244.227.228 parsing [public->public_did] continue=false
58. Dialplan: sofia/external/08504300004@91.244.227.228 Regex (FAIL) [public_did] destination_number(908504300003) =~ /^(5551212)$/ break=on-false
59. Dialplan: sofia/external/08504300004@91.244.227.228 parsing [public->908504300003] continue=false
60. Dialplan: sofia/external/08504300004@91.244.227.228 Regex (PASS) [908504300003] destination_number(908504300003) =~ /^908504300003$/ break=on-false
61. Dialplan: sofia/external/08504300004@91.244.227.228 Action answer()
62. Dialplan: sofia/external/08504300004@91.244.227.228 Action playback(silence_stream://2000)
63. Dialplan: sofia/external/08504300004@91.244.227.228 Action rxfax(//home//user1//fax//rxfax-${uuid}.tiff)
64. Dialplan: sofia/external/08504300004@91.244.227.228 Action hangup()
65. Dialplan: sofia/external/08504300004@91.244.227.228 Action set(session_in_hangup_hook=true)
66. 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
67. 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
68. 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
69. 2016-09-20 10:01:15.985944 [DEBUG] switch_core_state_machine.c:609 (sofia/external/08504300004@91.244.227.228) State EXECUTE
70. 2016-09-20 10:01:15.985944 [DEBUG] mod_sofia.c:198 sofia/external/08504300004@91.244.227.228 SOFIA EXECUTE
71. 2016-09-20 10:01:15.985944 [DEBUG] switch_core_state_machine.c:328 sofia/external/08504300004@91.244.227.228 Standard EXECUTE
72. EXECUTE sofia/external/08504300004@91.244.227.228 set(outside_call=true)
73. 2016-09-20 10:01:15.985944 [DEBUG] mod_dptools.c:1519 SET sofia/external/08504300004@91.244.227.228 [outside_call]=[true]
74. EXECUTE sofia/external/08504300004@91.244.227.228 export(RFC2822_DATE=Tue, 20 Sep 2016 10:01:15 -0400)
75. 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]
76. EXECUTE sofia/external/08504300004@91.244.227.228 answer()
77. 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]
78. 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
79. 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]
80. 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]
81. 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]
82. 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]
83. 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]
84. 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
85. 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]
86. 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]
87. 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]
88. 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]
89. 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]
90. 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
91. 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]
92. 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]
93. 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]
94. 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]
95. 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]
96. 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
97. 2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4197 Set telephone-event payload to 104@48000
98. 2016-09-20 10:01:15.985944 [DEBUG] mod_opus.c:559 Opus encoder: set bitrate to local settings [72000bps]
99. 2016-09-20 10:01:15.985944 [DEBUG] mod_opus.c:559 Opus encoder: set bitrate to local settings [72000bps]
100. 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
101. 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
102. 2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4542 Set telephone-event payload to 104@48000
103. 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
104. 2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4764 Video Codec Compare [VP8:103]/[VP8:99]
105. 2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:4788 Video Codec Compare [VP8:103] +++ is saved as a match
106. 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
107. 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
108. 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
109. 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
110. 2016-09-20 10:01:15.985944 [DEBUG] switch_rtp.c:3824 Starting timer [soft] 960 bytes per 20ms
111. 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
112. 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
113. 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
114. 2016-09-20 10:01:15.985944 [DEBUG] switch_rtp.c:3835 Starting video timer.
115. 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]
116. 2016-09-20 10:01:15.985944 [DEBUG] switch_core_media.c:5581 sofia/external/08504300004@91.244.227.228 Starting Video thread
117. 2016-09-20 10:01:15.985944 [INFO] switch_core_media.c:7035 Activating VIDEO RTCP PORT 0 interval 2000 mux 0
118. 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
119. 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
120. 2016-09-20 10:01:15.985944 [DEBUG] switch_rtp.c:2488 Setting RTCP remote addr to 91.244.227.228:44525 2
121. 2016-09-20 10:01:15.985944 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/08504300004@91.244.227.228!
122. 2016-09-20 10:01:15.985944 [DEBUG] switch_channel.c:3471 (sofia/external/08504300004@91.244.227.228) Callstate Change RINGING -> EARLY
123. 2016-09-20 10:01:15.985944 [DEBUG] mod_sofia.c:822 Local SDP sofia/external/08504300004@91.244.227.228:
124. v=0
125. o=FreeSWITCH 1474348219 1474348220 IN IP4 185.77.91.8
126. s=FreeSWITCH
127. c=IN IP4 185.77.91.8
128. t=0 0
129. m=audio 31856 RTP/AVP 102 104
130. a=rtpmap:102 opus/48000/2
131. a=fmtp:102 useinbandfec=1; ptime=20; minptime=10; maxptime=40
132. a=rtpmap:104 telephone-event/48000
133. a=fmtp:104 0-16
134. a=ptime:20
135. a=sendrecv
136. m=video 32202 RTP/AVP 103
137. b=AS:1024
138. a=rtpmap:103 VP8/90000
139. a=rtcp:32203 IN IP4 185.77.91.8
140. a=rtcp-fb:103 ccm fir
141. a=rtcp-fb:103 ccm tmmbr
142. a=rtcp-fb:103 nack
143. a=rtcp-fb:103 nack pli
144.  
145. 2016-09-20 10:01:15.985944 [NOTICE] mod_dptools.c:1309 Channel [sofia/external/08504300004@91.244.227.228] has been answered
146. 2016-09-20 10:01:15.985944 [DEBUG] switch_channel.c:3770 (sofia/external/08504300004@91.244.227.228) Callstate Change EARLY -> ACTIVE
147. 2016-09-20 10:01:15.985944 [DEBUG] sofia.c:6962 Channel sofia/external/08504300004@91.244.227.228 entering state [completed][200]
148. EXECUTE sofia/external/08504300004@91.244.227.228 playback(silence_stream://2000)
149. 2016-09-20 10:01:15.985944 [DEBUG] switch_ivr_play_say.c:1467 Codec Activated L16@48000hz 1 channels 20ms
150. 2016-09-20 10:01:16.005923 [DEBUG] sofia.c:6962 Channel sofia/external/08504300004@91.244.227.228 entering state [ready][200]
151. 2016-09-20 10:01:16.195959 [NOTICE] switch_vpx.c:402 VPX reset encoder picture from 320x240 to 352x288 1 BW
152. 2016-09-20 10:01:16.265945 [DEBUG] switch_rtp.c:1847 rtcp_stats_init: video ssrc[200605565] base_seq[22703]
153. 2016-09-20 10:01:16.265945 [DEBUG] switch_rtp.c:6721 Correct video ip/port confirmed.
154. 2016-09-20 10:01:16.835930 [DEBUG] switch_rtp.c:6721 Correct audio ip/port confirmed.
155. 2016-09-20 10:01:18.325940 [DEBUG] switch_ivr_play_say.c:1910 done playing file silence_stream://2000
156. EXECUTE sofia/external/08504300004@91.244.227.228 rxfax(//home//user1//fax//rxfax-b26f3a16-7f3a-11e6-aeed-e7f8278a30bd.tiff)
157. 2016-09-20 10:01:18.325940 [DEBUG] mod_spandsp_fax.c:1440 Raw read codec activation Success L16 20000
158. 2016-09-20 10:01:18.325940 [DEBUG] switch_core_codec.c:221 sofia/external/08504300004@91.244.227.228 Push codec L16:100
159. 2016-09-20 10:01:18.325940 [DEBUG] mod_spandsp_fax.c:1458 Raw write codec activation Success L16
160. 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)
161. 2016-09-20 10:01:21.225927 [DEBUG] mod_spandsp_fax.c:496 ==============================================================================
162. 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.
163. 2016-09-20 10:01:21.225927 [DEBUG] mod_spandsp_fax.c:513 Remote station id:
164. 2016-09-20 10:01:21.225927 [DEBUG] mod_spandsp_fax.c:514 Local station id:  SpanDSP Fax Ident
165. 2016-09-20 10:01:21.225927 [DEBUG] mod_spandsp_fax.c:515 Pages transferred: 0
166. 2016-09-20 10:01:21.225927 [DEBUG] mod_spandsp_fax.c:517 Total fax pages:   0
167. 2016-09-20 10:01:21.225927 [DEBUG] mod_spandsp_fax.c:518 Image resolution:  0x0
168. 2016-09-20 10:01:21.225927 [DEBUG] mod_spandsp_fax.c:519 Transfer Rate:     14400
169. 2016-09-20 10:01:21.225927 [DEBUG] mod_spandsp_fax.c:521 ECM status         off
170. 2016-09-20 10:01:21.225927 [DEBUG] mod_spandsp_fax.c:522 remote country:  
171. 2016-09-20 10:01:21.225927 [DEBUG] mod_spandsp_fax.c:523 remote vendor:    
172. 2016-09-20 10:01:21.225927 [DEBUG] mod_spandsp_fax.c:524 remote model:    
173. 2016-09-20 10:01:21.225927 [DEBUG] mod_spandsp_fax.c:526 ==============================================================================
174. 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.
175. EXECUTE sofia/external/08504300004@91.244.227.228 hangup()
176. 2016-09-20 10:01:21.245931 [NOTICE] mod_dptools.c:1283 Hangup sofia/external/08504300004@91.244.227.228 [CS_EXECUTE] [NORMAL_CLEARING]
177. 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)
178. 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
179. 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
180. 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
181. 2016-09-20 10:01:21.245931 [DEBUG] switch_core_state_machine.c:811 (sofia/external/08504300004@91.244.227.228) State HANGUP
182. 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
183. 2016-09-20 10:01:21.245931 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/external/08504300004@91.244.227.228
184. 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
185. 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
186. 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
187. 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
188. 2016-09-20 10:01:21.245931 [DEBUG] switch_core_state_machine.c:897 (sofia/external/08504300004@91.244.227.228) State REPORTING
189. 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
190. 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
191. 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
192. 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
193. 2016-09-20 10:01:21.245931 [DEBUG] switch_core_media.c:5548 sofia/external/08504300004@91.244.227.228 Video thread ended
194. 2016-09-20 10:01:21.245931 [NOTICE] switch_core_session.c:1665 Session 154 (sofia/external/08504300004@91.244.227.228) Ended
195. 2016-09-20 10:01:21.245931 [NOTICE] switch_core_session.c:1669 Close Channel sofia/external/08504300004@91.244.227.228 [CS_DESTROY]
196. 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
197. 2016-09-20 10:01:21.245931 [DEBUG] switch_core_state_machine.c:710 (sofia/external/08504300004@91.244.227.228) State DESTROY
198. 2016-09-20 10:01:21.245931 [DEBUG] mod_sofia.c:343 sofia/external/08504300004@91.244.227.228 SOFIA DESTROY
199. 2016-09-20 10:01:21.245931 [DEBUG] mod_opus.c:661 Opus decoder stats: Frames[58] PLC[0] FEC[0]
200. 2016-09-20 10:01:21.245931 [DEBUG] mod_opus.c:661 Opus decoder stats: Frames[0] PLC[0] FEC[0]
201. 2016-09-20 10:01:21.245931 [DEBUG] switch_core_state_machine.c:181 sofia/external/08504300004@91.244.227.228 Standard DESTROY
202. 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
203.