From Tarek Kalaji, 2 Years ago, written in Plain Text.
- view diff
Embed
  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.