From Mungo Butterfly, 6 Years ago, written in FreeSWITCH.
- go back
Embed
Viewing differences between and Reinvite
  1. +OK Global siptrace off
  2. 2018-05-30 19:23:47.183102 [NOTICE] switch_channel.c:1104 New Channel sofia/external/1000@139.59.19.72 [bda6fc64-b947-42a7-b9b2-3d520a221368]
  3. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_state_machine.c:584 (sofia/external/1000@139.59.19.72) Running State Change CS_NEW (Cur 1 Tot 11)
  4. 2018-05-30 19:23:47.183102 [DEBUG] sofia.c:10078 sofia/external/1000@139.59.19.72 receiving invite from 139.59.19.72:5060 version: 1.9.0 git ed4920e 2018-05-04 14:37:06Z 64bit
  5. 2018-05-30 19:23:47.183102 [DEBUG] sofia.c:7291 Channel sofia/external/1000@139.59.19.72 entering state [received][100]
  6. 2018-05-30 19:23:47.183102 [DEBUG] sofia.c:7301 Remote SDP:
  7. v=0
  8. o=Shashi-switch-sdp 1527691809 1527691810 IN IP4 139.59.19.72
  9. s=Shashi-switch-sdp
  10. c=IN IP4 139.59.19.72
  11. t=0 0
  12. m=audio 16416 RTP/AVP 102 9 0 8 103 101
  13. a=rtpmap:102 opus/48000/2
  14. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
  15. a=rtpmap:9 G722/8000
  16. a=rtpmap:0 PCMU/8000
  17. a=rtpmap:8 PCMA/8000
  18. a=rtpmap:103 telephone-event/48000
  19. a=fmtp:103 0-16
  20. a=rtpmap:101 telephone-event/8000
  21. a=fmtp:101 0-16
  22. a=ptime:20
  23.  
  24. 2018-05-30 19:23:47.183102 [DEBUG] sofia.c:7693 (sofia/external/1000@139.59.19.72) State Change CS_NEW -> CS_INIT
  25. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_state_machine.c:603 (sofia/external/1000@139.59.19.72) State NEW
  26. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_state_machine.c:584 (sofia/external/1000@139.59.19.72) Running State Change CS_INIT (Cur 1 Tot 11)
  27. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_state_machine.c:627 (sofia/external/1000@139.59.19.72) State INIT
  28. 2018-05-30 19:23:47.183102 [DEBUG] mod_sofia.c:93 sofia/external/1000@139.59.19.72 SOFIA INIT
  29. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_state_machine.c:40 sofia/external/1000@139.59.19.72 Standard INIT
  30. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_state_machine.c:48 (sofia/external/1000@139.59.19.72) State Change CS_INIT -> CS_ROUTING
  31. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_state_machine.c:627 (sofia/external/1000@139.59.19.72) State INIT going to sleep
  32. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_state_machine.c:584 (sofia/external/1000@139.59.19.72) Running State Change CS_ROUTING (Cur 1 Tot 11)
  33. 2018-05-30 19:23:47.183102 [DEBUG] switch_channel.c:2249 (sofia/external/1000@139.59.19.72) Callstate Change DOWN -> RINGING
  34. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_state_machine.c:643 (sofia/external/1000@139.59.19.72) State ROUTING
  35. 2018-05-30 19:23:47.183102 [DEBUG] mod_sofia.c:154 sofia/external/1000@139.59.19.72 SOFIA ROUTING
  36. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_state_machine.c:236 sofia/external/1000@139.59.19.72 Standard ROUTING
  37. 2018-05-30 19:23:47.183102 [INFO] mod_dialplan_xml.c:637 Processing Extension 1000 <1000>->1001 in context public
  38. Dialplan: sofia/external/1000@139.59.19.72 parsing [public->record_rest] continue=false
  39. Dialplan: sofia/external/1000@139.59.19.72 Regex (PASS) [record_rest] ${destination_number}(1001) =~ /1001/ break=on-false
  40. Dialplan: sofia/external/1000@139.59.19.72 Action answer()
  41. Dialplan: sofia/external/1000@139.59.19.72 Action set(playback_terminators=#)
  42. Dialplan: sofia/external/1000@139.59.19.72 Action record(/tmp/data.wav)
  43. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_state_machine.c:286 (sofia/external/1000@139.59.19.72) State Change CS_ROUTING -> CS_EXECUTE
  44. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_state_machine.c:643 (sofia/external/1000@139.59.19.72) State ROUTING going to sleep
  45. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_state_machine.c:584 (sofia/external/1000@139.59.19.72) Running State Change CS_EXECUTE (Cur 1 Tot 11)
  46. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_state_machine.c:650 (sofia/external/1000@139.59.19.72) State EXECUTE
  47. 2018-05-30 19:23:47.183102 [DEBUG] mod_sofia.c:209 sofia/external/1000@139.59.19.72 SOFIA EXECUTE
  48. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_state_machine.c:328 sofia/external/1000@139.59.19.72 Standard EXECUTE
  49. EXECUTE sofia/external/1000@139.59.19.72 answer()
  50. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [opus:102:48000:20:0:2]/[opus:116:48000:20:0:1]
  51. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:5186 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
  52. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [opus:102:48000:20:0:2]/[G722:9:8000:20:64000:1]
  53. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [opus:102:48000:20:0:2]/[PCMU:0:8000:20:64000:1]
  54. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [opus:102:48000:20:0:2]/[PCMA:8:8000:20:64000:1]
  55. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
  56. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  57. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:5186 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
  58. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  59. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  60. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  61. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  62. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  63. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:5186 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  64. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  65. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  66. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  67. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  68. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  69. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:5186 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  70. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:5047 Set telephone-event payload to 103@48000
  71. 2018-05-30 19:23:47.183102 [DEBUG] mod_opus.c:617 Opus encoder: set bitrate to local settings [120000bps]
  72. 2018-05-30 19:23:47.183102 [DEBUG] mod_opus.c:617 Opus encoder: set bitrate to local settings [120000bps]
  73. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:3446 Set Codec sofia/external/1000@139.59.19.72 opus/48000 20 ms 960 samples 0 bits 2 channels
  74. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_codec.c:111 sofia/external/1000@139.59.19.72 Original read codec set to opus:116
  75. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:5390 Set telephone-event payload to 103@48000
  76. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:5448 sofia/external/1000@139.59.19.72 Set 2833 dtmf send payload to 103 recv payload to 103
  77. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:8164 AUDIO RTP [sofia/external/1000@139.59.19.72] 159.89.163.195 port 19228 -> 139.59.19.72 port 16416 codec: 102 ms: 20
  78. 2018-05-30 19:23:47.183102 [DEBUG] switch_rtp.c:4189 Starting timer [soft] 960 bytes per 20ms
  79. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:8468 sofia/external/1000@139.59.19.72 Set 2833 dtmf send payload to 103
  80. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:8475 sofia/external/1000@139.59.19.72 Set 2833 dtmf receive payload to 103
  81. 2018-05-30 19:23:47.183102 [DEBUG] switch_core_media.c:8498 sofia/external/1000@139.59.19.72 Set rtp dtmf delay to 40
  82. 2018-05-30 19:23:47.183102 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/1000@139.59.19.72!
  83. 2018-05-30 19:23:47.193063 [DEBUG] switch_channel.c:3482 (sofia/external/1000@139.59.19.72) Callstate Change RINGING -> EARLY
  84. 2018-05-30 19:23:47.193063 [DEBUG] switch_core_media.c:8147 Audio params are unchanged for sofia/external/1000@139.59.19.72.
  85. 2018-05-30 19:23:47.193063 [DEBUG] mod_sofia.c:881 Local SDP sofia/external/1000@139.59.19.72:
  86. v=0
  87. o=FreeSWITCH 1527688999 1527689000 IN IP4 159.89.163.195
  88. s=FreeSWITCH
  89. c=IN IP4 159.89.163.195
  90. t=0 0
  91. m=audio 19228 RTP/AVP 102 103
  92. a=rtpmap:102 opus/48000/2
  93. a=fmtp:102 useinbandfec=1; ptime=20; minptime=10; maxptime=40; stereo=1
  94. a=rtpmap:103 telephone-event/48000
  95. a=fmtp:103 0-16
  96. a=ptime:20
  97. a=sendrecv
  98.  
  99. 2018-05-30 19:23:47.193063 [DEBUG] sofia.c:7291 Channel sofia/external/1000@139.59.19.72 entering state [completed][200]
  100. 2018-05-30 19:23:47.193063 [NOTICE] mod_dptools.c:1357 Channel [sofia/external/1000@139.59.19.72] has been answered
  101. 2018-05-30 19:23:47.193063 [DEBUG] switch_channel.c:3781 (sofia/external/1000@139.59.19.72) Callstate Change EARLY -> ACTIVE
  102. EXECUTE sofia/external/1000@139.59.19.72 set(playback_terminators=#)
  103. span style="color: goldenrod; font-weight: bold;"> 2018-05-30 19:23:47.193063 [DEBUG] mod_dptools.c:1593 SET sofia/external/1000@139.59.19.72 [playback_terminators]=[#]
  104. EXECUTE sofia/external/1000@139.59.19.72 record(/tmp/data.wav)
  105. 2018-05-30 19:23:47.193063 [DEBUG] switch_ivr_play_say.c:560 Raw Codec Activated, ready to waste resources!
  106. 2018-05-30 19:23:47.193063 [DEBUG] switch_ivr_play_say.c:674 Raw Codec Activated
  107. 2018-05-30 19:23:47.193063 [DEBUG] switch_core_codec.c:223 sofia/external/1000@139.59.19.72 Push codec L16:100
  108. 2018-05-30 19:23:47.193063 [DEBUG] sofia.c:7291 Channel sofia/external/1000@139.59.19.72 entering state [ready][200]
  109. 2018-05-30 19:23:47.543096 [DEBUG] switch_rtp.c:7373 Correct audio ip/port confirmed.
  110. freeswitch@debian-s-1vcpu-1gb-blr1-01>
  111. freeswitch@debian-s-1vcpu-1gb-blr1-01>
  112. freeswitch@debian-s-1vcpu-1gb-blr1-01>
  113. freeswitch@debian-s-1vcpu-1gb-blr1-01>
  114. freeswitch@debian-s-1vcpu-1gb-blr1-01>
  115. freeswitch@debian-s-1vcpu-1gb-blr1-01>
  116. freeswitch@debian-s-1vcpu-1gb-blr1-01>
  117. freeswitch@debian-s-1vcpu-1gb-blr1-01>
  118. 2018-05-30 19:24:50.163080 [DEBUG] sofia.c:7291 Channel sofia/external/1000@139.59.19.72 entering state [received][100]
  119. 2018-05-30 19:24:50.163080 [DEBUG] sofia.c:7301 Remote SDP:
  120. v=0
  121. o=Shashi-switch-sdp 1527691809 1527691810 IN IP4 139.59.19.72
  122. s=Shashi-switch-sdp
  123. c=IN IP4 139.59.19.72
  124. t=0 0
  125. m=audio 16416 RTP/AVP 102 103 9 0 8 101
  126. a=rtpmap:102 opus/48000/2
  127. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
  128. a=rtpmap:103 telephone-event/48000
  129. a=fmtp:103 0-16
  130. a=rtpmap:9 G722/8000
  131. a=rtpmap:0 PCMU/8000
  132. a=rtpmap:8 PCMA/8000
  133. a=rtpmap:101 telephone-event/8000
  134. a=fmtp:101 0-16
  135. a=ptime:20
  136.  
  137. 2018-05-30 19:24:50.163080 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [opus:102:48000:20:0:2]/[opus:116:48000:20:0:1]
  138. 2018-05-30 19:24:50.163080 [DEBUG] switch_core_media.c:5186 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
  139. 2018-05-30 19:24:50.163080 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [opus:102:48000:20:0:2]/[G722:9:8000:20:64000:1]
  140. 2018-05-30 19:24:50.163080 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [opus:102:48000:20:0:2]/[PCMU:0:8000:20:64000:1]
  141. 2018-05-30 19:24:50.163080 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [opus:102:48000:20:0:2]/[PCMA:8:8000:20:64000:1]
  142. 2018-05-30 19:24:50.163080 [DEBUG] switch_core_media.c:5047 Set telephone-event payload to 103@48000
  143. 2018-05-30 19:24:50.163080 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
  144. 2018-05-30 19:24:50.163080 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  145. 2018-05-30 19:24:50.163080 [DEBUG] switch_core_media.c:5186 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
  146. 2018-05-30 19:24:50.163080 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  147. 2018-05-30 19:24:50.163080 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  148. 2018-05-30 19:24:50.163080 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  149. 2018-05-30 19:24:50.163080 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  150. 2018-05-30 19:24:50.163080 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  151. 2018-05-30 19:24:50.163080 [DEBUG] switch_core_media.c:5186 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  152. 2018-05-30 19:24:50.163080 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  153. 2018-05-30 19:24:50.163080 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  154. 2018-05-30 19:24:50.163080 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  155. 2018-05-30 19:24:50.163080 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  156. 2018-05-30 19:24:50.163080 [DEBUG] switch_core_media.c:5131 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  157. 2018-05-30 19:24:50.163080 [DEBUG] switch_core_media.c:5186 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  158. 2018-05-30 19:24:50.163080 [DEBUG] switch_core_media.c:5390 Set telephone-event payload to 103@48000
  159. 2018-05-30 19:24:50.163080 [DEBUG] switch_core_media.c:5448 sofia/external/1000@139.59.19.72 Set 2833 dtmf send payload to 103 recv payload to 103
  160. 2018-05-30 19:24:50.163080 [DEBUG] switch_core_media.c:8147 Audio params are unchanged for sofia/external/1000@139.59.19.72.
  161. 2018-05-30 19:24:50.163080 [DEBUG] sofia.c:8206 Processing updated SDP
  162. 2018-05-30 19:24:50.173063 [DEBUG] sofia.c:7291 Channel sofia/external/1000@139.59.19.72 entering state [completed][200]
  163. 2018-05-30 19:24:50.183080 [DEBUG] sofia.c:7291 Channel sofia/external/1000@139.59.19.72 entering state [ready][200]
  164. 2018-05-30 19:25:18.903071 [NOTICE] sofia.c:1079 Hangup sofia/external/1000@139.59.19.72 [CS_EXECUTE] [NORMAL_CLEARING]
  165. 2018-05-30 19:25:18.913064 [DEBUG] switch_core_codec.c:248 sofia/external/1000@139.59.19.72 Restore previous codec opus:116.
  166. 2018-05-30 19:25:18.913064 [DEBUG] switch_core_session.c:2885 sofia/external/1000@139.59.19.72 skip receive message [PHONE_EVENT] (channel is hungup already)
  167. 2018-05-30 19:25:18.913064 [DEBUG] switch_core_state_machine.c:650 (sofia/external/1000@139.59.19.72) State EXECUTE going to sleep
  168. 2018-05-30 19:25:18.913064 [DEBUG] switch_core_state_machine.c:584 (sofia/external/1000@139.59.19.72) Running State Change CS_HANGUP (Cur 1 Tot 11)
  169. 2018-05-30 19:25:18.913064 [DEBUG] switch_core_state_machine.c:850 (sofia/external/1000@139.59.19.72) Callstate Change ACTIVE -> HANGUP
  170. 2018-05-30 19:25:18.913064 [DEBUG] switch_core_state_machine.c:852 (sofia/external/1000@139.59.19.72) State HANGUP
  171. 2018-05-30 19:25:18.913064 [DEBUG] mod_sofia.c:449 Channel sofia/external/1000@139.59.19.72 hanging up, cause: NORMAL_CLEARING
  172. 2018-05-30 19:25:18.913064 [DEBUG] switch_core_state_machine.c:60 sofia/external/1000@139.59.19.72 Standard HANGUP, cause: NORMAL_CLEARING
  173. 2018-05-30 19:25:18.913064 [DEBUG] switch_core_state_machine.c:852 (sofia/external/1000@139.59.19.72) State HANGUP going to sleep
  174. 2018-05-30 19:25:18.913064 [DEBUG] switch_core_state_machine.c:619 (sofia/external/1000@139.59.19.72) State Change CS_HANGUP -> CS_REPORTING
  175. 2018-05-30 19:25:18.913064 [DEBUG] switch_core_state_machine.c:584 (sofia/external/1000@139.59.19.72) Running State Change CS_REPORTING (Cur 1 Tot 11)
  176. 2018-05-30 19:25:18.913064 [DEBUG] switch_core_state_machine.c:938 (sofia/external/1000@139.59.19.72) State REPORTING
  177. 2018-05-30 19:25:18.913064 [DEBUG] switch_core_state_machine.c:174 sofia/external/1000@139.59.19.72 Standard REPORTING, cause: NORMAL_CLEARING
  178. 2018-05-30 19:25:18.913064 [DEBUG] switch_core_state_machine.c:938 (sofia/external/1000@139.59.19.72) State REPORTING going to sleep
  179. 2018-05-30 19:25:18.913064 [DEBUG] switch_core_state_machine.c:610 (sofia/external/1000@139.59.19.72) State Change CS_REPORTING -> CS_DESTROY
  180. 2018-05-30 19:25:18.913064 [DEBUG] switch_core_session.c:1713 Session 11 (sofia/external/1000@139.59.19.72) Locked, Waiting on external entities
  181. 2018-05-30 19:25:18.913064 [NOTICE] switch_core_session.c:1731 Session 11 (sofia/external/1000@139.59.19.72) Ended
  182. 2018-05-30 19:25:18.913064 [NOTICE] switch_core_session.c:1735 Close Channel sofia/external/1000@139.59.19.72 [CS_DESTROY]
  183. 2018-05-30 19:25:18.913064 [DEBUG] switch_core_state_machine.c:741 (sofia/external/1000@139.59.19.72) Running State Change CS_DESTROY (Cur 0 Tot 11)
  184. 2018-05-30 19:25:18.913064 [DEBUG] switch_core_state_machine.c:751 (sofia/external/1000@139.59.19.72) State DESTROY
  185. 2018-05-30 19:25:18.913064 [DEBUG] mod_sofia.c:354 sofia/external/1000@139.59.19.72 SOFIA DESTROY
  186. 2018-05-30 19:25:18.913064 [DEBUG] mod_opus.c:725 Opus decoder stats: Frames[4488] PLC[0] FEC[0]
  187. 2018-05-30 19:25:18.913064 [DEBUG] mod_opus.c:740 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0]
  188. 2018-05-30 19:25:18.913064 [DEBUG] mod_opus.c:725 Opus decoder stats: Frames[0] PLC[0] FEC[0]
  189. 2018-05-30 19:25:18.913064 [DEBUG] mod_opus.c:740 Opus encoder stats: Frames[4585] Bytes encoded[710568] Encoded length ms[91700] Average encoded bitrate bps[62467]
  190. 2018-05-30 19:25:18.913064 [DEBUG] switch_core_state_machine.c:181 sofia/external/1000@139.59.19.72 Standard DESTROY
  191. 2018-05-30 19:25:18.913064 [DEBUG] switch_core_state_machine.c:751 (sofia/external/1000@139.59.19.72) State DESTROY going to sleep
  192.