From josh, 6 Years ago, written in Plain Text.
- view diff
Embed
  1. 2017-06-21 17:45:04.747766 [DEBUG] mod_rtc.c:392 () State Change CS_NEW -> CS_INIT
  2. 2017-06-21 17:45:04.747766 [DEBUG] switch_core_session.c:640 N/A set UUID=71071c71-0351-b67c-2165-9bff65ccc4e4
  3. 2017-06-21 17:45:04.747766 [NOTICE] switch_channel.c:1104 New Channel verto.rtc/1005 [71071c71-0351-b67c-2165-9bff65ccc4e4]
  4. 2017-06-21 17:45:04.747766 [DEBUG] mod_verto.c:3728 Remote SDP verto.rtc/1005:
  5. v=0
  6. o=mozilla...THIS_IS_SDPARTA-54.0 1285636339620956612 0 IN IP4 0.0.0.0
  7. s=-
  8. t=0 0
  9. a=sendrecv
  10. a=fingerprint:sha-256 63:F0:7B:49:87:65:43:E3:9E:83:10:A3:FA:B8:89:E5:A9:9B:CF:DB:8E:F6:EE:26:17:8A:26:71:9F:99:DF:DB
  11. a=group:BUNDLE sdparta_0 sdparta_1
  12. a=ice-options:trickle
  13. a=msid-semantic:WMS *
  14. m=audio 51494 UDP/TLS/RTP/SAVPF 109 9 0 8 101
  15. c=IN IP4 65.15.69.32
  16. a=candidate:0 1 UDP 2122252543 192.168.150.12 51494 typ host
  17. a=candidate:2 1 TCP 2105524479 192.168.150.12 9 typ host tcptype active
  18. a=candidate:3 1 TCP 2105458943 2001:0:9d38:6ab8:ced:bee:3f57:69f3 9 typ host tcptype active
  19. a=candidate:0 2 UDP 2122252542 192.168.150.12 51495 typ host
  20. a=candidate:2 2 TCP 2105524478 192.168.150.12 9 typ host tcptype active
  21. a=candidate:3 2 TCP 2105458942 2001:0:9d38:6ab8:ced:bee:3f57:69f3 9 typ host tcptype active
  22. a=candidate:1 1 UDP 1686052863 65.15.69.32 51494 typ srflx raddr 192.168.150.12 rport 51494
  23. a=candidate:1 2 UDP 1686052862 65.15.69.32 51495 typ srflx raddr 192.168.150.12 rport 51495
  24. a=recvonly
  25. a=end-of-candidates
  26. a=extmap:1/sendonly urn:ietf:params:rtp-hdrext:ssrc-audio-level
  27. a=fmtp:109 maxplaybackrate=48000;stereo=1;useinbandfec=1; stereo=1; sprop-stereo=1
  28. a=fmtp:101 0-15
  29. a=ice-pwd:b7efc0bc9593d5b0b7d4bf501c141d09
  30. a=ice-ufrag:9c79d98b
  31. a=mid:sdparta_0
  32. a=rtcp:51495 IN IP4 65.15.69.32
  33. a=rtcp-mux
  34. a=rtpmap:109 opus/48000/2
  35. a=rtpmap:9 G722/8000/1
  36. a=rtpmap:0 PCMU/8000
  37. a=rtpmap:8 PCMA/8000
  38. a=rtpmap:101 telephone-event/8000
  39. a=setup:actpass
  40. a=ssrc:775356116 cname:{76cc3af4-3034-4190-a6d2-916dace30a89}
  41. m=video 51496 UDP/TLS/RTP/SAVPF 120 121 126 97
  42. c=IN IP4 65.15.69.32
  43. a=candidate:0 1 UDP 2122252543 192.168.150.12 51496 typ host
  44. a=candidate:2 1 TCP 2105524479 192.168.150.12 9 typ host tcptype active
  45. a=candidate:3 1 TCP 2105458943 2001:0:9d38:6ab8:ced:bee:3f57:69f3 9 typ host tcptype active
  46. a=candidate:0 2 UDP 2122252542 192.168.150.12 51497 typ host
  47. a=candidate:2 2 TCP 2105524478 192.168.150.12 9 typ host tcptype active
  48. a=candidate:3 2 TCP 2105458942 2001:0:9d38:6ab8:ced:bee:3f57:69f3 9 typ host tcptype active
  49. a=candidate:1 1 UDP 1686052863 65.15.69.32 51496 typ srflx raddr 192.168.150.12 rport 51496
  50. a=candidate:1 2 UDP 1686052862 65.15.69.32 51497 typ srflx raddr 192.168.150.12 rport 51497
  51. a=sendrecv
  52. a=end-of-candidates
  53. a=extmap:1 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
  54. a=extmap:2 urn:ietf:params:rtp-hdrext:toffset
  55. a=fmtp:126 profile-level-id=42e01f;level-asymmetry-allowed=1;packetization-mode=1
  56. a=fmtp:97 profile-level-id=42e01f;level-asymmetry-allowed=1
  57. a=fmtp:120 max-fs=12288;max-fr=60
  58. a=fmtp:121 max-fs=12288;max-fr=60
  59. a=ice-pwd:b7efc0bc9593d5b0b7d4bf501c141d09
  60. a=ice-ufrag:9c79d98b
  61. a=mid:sdparta_1
  62. a=msid:{a4fe56d7-6791-49bd-89d0-7ba5d5a93803} {d8d495a6-98b2-4210-bace-1d3870ddb6a9}
  63. a=rtcp:51497 IN IP4 65.15.69.32
  64. a=rtcp-fb:120 nack
  65. a=rtcp-fb:120 nack pli
  66. a=rtcp-fb:120 ccm fir
  67. a=rtcp-fb:120 goog-remb
  68. a=rtcp-fb:121 nack
  69. a=rtcp-fb:121 nack pli
  70. a=rtcp-fb:121 ccm fir
  71. a=rtcp-fb:121 goog-remb
  72. a=rtcp-fb:126 nack
  73. a=rtcp-fb:126 nack pli
  74. a=rtcp-fb:126 ccm fir
  75. a=rtcp-fb:126 goog-remb
  76. a=rtcp-fb:97 nack
  77. a=rtcp-fb:97 nack pli
  78. a=rtcp-fb:97 ccm fir
  79. a=rtcp-fb:97 goog-remb
  80. a=rtcp-mux
  81. a=rtpmap:120 VP8/90000
  82. a=rtpmap:121 VP9/90000
  83. a=rtpmap:126 H264/90000
  84. a=rtpmap:97 H264/90000
  85. a=setup:actpass
  86. a=ssrc:2761950371 cname:{76cc3af4-3034-4190-a6d2-916dace30a89}
  87.  
  88. 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/1005) Running State Change CS_INIT (Cur 1 Tot 56)
  89. 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/1005) State INIT
  90. 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:40 verto.rtc/1005 Standard INIT
  91. 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:48 (verto.rtc/1005) State Change CS_INIT -> CS_ROUTING
  92. 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/1005) State INIT going to sleep
  93. 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/1005) Running State Change CS_ROUTING (Cur 1 Tot 56)
  94. 2017-06-21 17:45:04.747766 [DEBUG] switch_channel.c:2249 (verto.rtc/1005) Callstate Change DOWN -> RINGING
  95. 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/1005) State ROUTING
  96. 2017-06-21 17:45:04.747766 [DEBUG] mod_rtc.c:89 verto.rtc/1005 RTC ROUTING
  97. 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:236 verto.rtc/1005 Standard ROUTING
  98. 2017-06-21 17:45:04.747766 [INFO] mod_dialplan_xml.c:637 Processing eboshxff <eboshxff@joshebosh.com>->1005 in context default
  99. Dialplan: verto.rtc/1005 parsing [default->unloop] continue=false
  100. Dialplan: verto.rtc/1005 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  101. Dialplan: verto.rtc/1005 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  102. Dialplan: verto.rtc/1005 parsing [default->tod_example] continue=true
  103. Dialplan: verto.rtc/1005 Date/Time Match (PASS) [tod_example] break=on-false
  104. Dialplan: verto.rtc/1005 Action set(open=true)
  105. Dialplan: verto.rtc/1005 parsing [default->holiday_example] continue=true
  106. Dialplan: verto.rtc/1005 Date/TimeMatch (FAIL) [holiday_example] break=on-false
  107. Dialplan: verto.rtc/1005 parsing [default->global-intercept] continue=false
  108. Dialplan: verto.rtc/1005 Regex (FAIL) [global-intercept] destination_number(1005) =~ /^886$/ break=on-false
  109. Dialplan: verto.rtc/1005 parsing [default->group-intercept] continue=false
  110. Dialplan: verto.rtc/1005 Regex (FAIL) [group-intercept] destination_number(1005) =~ /^\*8$/ break=on-false
  111. Dialplan: verto.rtc/1005 parsing [default->intercept-ext] continue=false
  112. Dialplan: verto.rtc/1005 Regex (FAIL) [intercept-ext] destination_number(1005) =~ /^\*\*(\d+)$/ break=on-false
  113. Dialplan: verto.rtc/1005 parsing [default->redial] continue=false
  114. Dialplan: verto.rtc/1005 Regex (FAIL) [redial] destination_number(1005) =~ /^(redial|870)$/ break=on-false
  115. Dialplan: verto.rtc/1005 parsing [default->global] continue=true
  116. Dialplan: verto.rtc/1005 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
  117. Dialplan: verto.rtc/1005 Regex (FAIL) [global] ${default_password}(j0sh3b0sh) =~ /^1234$/ break=never
  118. Dialplan: verto.rtc/1005 Regex (FAIL) [global] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|AES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=never
  119. Dialplan: verto.rtc/1005 Regex (FAIL) [global] ${endpoint_disposition}() =~ /^(DELAYED NEGOTIATION)/ break=on-false
  120. Dialplan: verto.rtc/1005 parsing [default->snom-demo-2] continue=false
  121. Dialplan: verto.rtc/1005 Regex (FAIL) [snom-demo-2] destination_number(1005) =~ /^9001$/ break=on-false
  122. Dialplan: verto.rtc/1005 parsing [default->snom-demo-1] continue=false
  123. Dialplan: verto.rtc/1005 Regex (FAIL) [snom-demo-1] destination_number(1005) =~ /^9000$/ break=on-false
  124. Dialplan: verto.rtc/1005 parsing [default->eavesdrop] continue=false
  125. Dialplan: verto.rtc/1005 Regex (FAIL) [eavesdrop] destination_number(1005) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
  126. Dialplan: verto.rtc/1005 parsing [default->eavesdrop] continue=false
  127. Dialplan: verto.rtc/1005 Regex (FAIL) [eavesdrop] destination_number(1005) =~ /^779$/ break=on-false
  128. Dialplan: verto.rtc/1005 parsing [default->call_return] continue=false
  129. Dialplan: verto.rtc/1005 Regex (FAIL) [call_return] destination_number(1005) =~ /^\*69$|^869$|^lcr$/ break=on-false
  130. Dialplan: verto.rtc/1005 parsing [default->del-group] continue=false
  131. Dialplan: verto.rtc/1005 Regex (FAIL) [del-group] destination_number(1005) =~ /^80(\d{2})$/ break=on-false
  132. Dialplan: verto.rtc/1005 parsing [default->add-group] continue=false
  133. Dialplan: verto.rtc/1005 Regex (FAIL) [add-group] destination_number(1005) =~ /^81(\d{2})$/ break=on-false
  134. Dialplan: verto.rtc/1005 parsing [default->call-group-simo] continue=false
  135. Dialplan: verto.rtc/1005 Regex (FAIL) [call-group-simo] destination_number(1005) =~ /^82(\d{2})$/ break=on-false
  136. Dialplan: verto.rtc/1005 parsing [default->call-group-order] continue=false
  137. Dialplan: verto.rtc/1005 Regex (FAIL) [call-group-order] destination_number(1005) =~ /^83(\d{2})$/ break=on-false
  138. Dialplan: verto.rtc/1005 parsing [default->extension-intercom] continue=false
  139. Dialplan: verto.rtc/1005 Regex (FAIL) [extension-intercom] destination_number(1005) =~ /^8(10[01][0-9])$/ break=on-false
  140. Dialplan: verto.rtc/1005 parsing [default->Local_Extension] continue=false
  141. Dialplan: verto.rtc/1005 Regex (PASS) [Local_Extension] destination_number(1005) =~ /^(10[01][0-9])$/ break=on-false
  142. Dialplan: verto.rtc/1005 Action export(dialed_extension=1005)
  143. Dialplan: verto.rtc/1005 Action bind_meta_app(1 b s execute_extension::dx XML features)
  144. Dialplan: verto.rtc/1005 Action bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/${caller_id_number}.${strftime(%Y-%m-%d-%H-%M-%S)}.wav)
  145. Dialplan: verto.rtc/1005 Action bind_meta_app(3 b s execute_extension::cf XML features)
  146. Dialplan: verto.rtc/1005 Action bind_meta_app(4 b s execute_extension::att_xfer XML features)
  147. Dialplan: verto.rtc/1005 Action set(ringback=${us-ring})
  148. Dialplan: verto.rtc/1005 Action set(transfer_ringback=local_stream://moh)
  149. Dialplan: verto.rtc/1005 Action set(call_timeout=30)
  150. Dialplan: verto.rtc/1005 Action set(hangup_after_bridge=true)
  151. Dialplan: verto.rtc/1005 Action set(continue_on_fail=true)
  152. Dialplan: verto.rtc/1005 Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number})
  153. Dialplan: verto.rtc/1005 Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid})
  154. Dialplan: verto.rtc/1005 Action set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name} var callgroup)})
  155. Dialplan: verto.rtc/1005 Action hash(insert/${domain_name}-last_dial_ext/${called_party_callgroup}/${uuid})
  156. Dialplan: verto.rtc/1005 Action hash(insert/${domain_name}-last_dial_ext/global/${uuid})
  157. Dialplan: verto.rtc/1005 Action hash(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid})
  158. Dialplan: verto.rtc/1005 Action bridge(user/${dialed_extension}@${domain_name})
  159. Dialplan: verto.rtc/1005 Action answer()
  160. Dialplan: verto.rtc/1005 Action sleep(1000)
  161. Dialplan: verto.rtc/1005 Action bridge(loopback/app=voicemail:default ${domain_name} ${dialed_extension})
  162. 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:286 (verto.rtc/1005) State Change CS_ROUTING -> CS_EXECUTE
  163. 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/1005) State ROUTING going to sleep
  164. 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/1005) Running State Change CS_EXECUTE (Cur 1 Tot 56)
  165. 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:650 (verto.rtc/1005) State EXECUTE
  166. 2017-06-21 17:45:04.747766 [DEBUG] mod_rtc.c:120 verto.rtc/1005 RTC EXECUTE
  167. 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:328 verto.rtc/1005 Standard EXECUTE
  168. EXECUTE verto.rtc/1005 set(open=true)
  169. 2017-06-21 17:45:04.747766 [DEBUG] mod_dptools.c:1570 SET verto.rtc/1005 [open]=[true]
  170. EXECUTE verto.rtc/1005 export(dialed_extension=1005)
  171. 2017-06-21 17:45:04.747766 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dialed_extension]=[1005]
  172. EXECUTE verto.rtc/1005 bind_meta_app(1 b s execute_extension::dx XML features)
  173. 2017-06-21 17:45:04.747766 [INFO] switch_ivr_async.c:4260 Bound B-Leg: *1 execute_extension::dx XML features
  174. EXECUTE verto.rtc/1005 bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/eboshxff@joshebosh.com.2017-06-21-17-45-04.wav)
  175. 2017-06-21 17:45:04.747766 [INFO] switch_ivr_async.c:4260 Bound B-Leg: *2 record_session::/usr/local/freeswitch/recordings/eboshxff@joshebosh.com.2017-06-21-17-45-04.wav
  176. EXECUTE verto.rtc/1005 bind_meta_app(3 b s execute_extension::cf XML features)
  177. 2017-06-21 17:45:04.747766 [INFO] switch_ivr_async.c:4260 Bound B-Leg: *3 execute_extension::cf XML features
  178. EXECUTE verto.rtc/1005 bind_meta_app(4 b s execute_extension::att_xfer XML features)
  179. 2017-06-21 17:45:04.747766 [INFO] switch_ivr_async.c:4260 Bound B-Leg: *4 execute_extension::att_xfer XML features
  180. EXECUTE verto.rtc/1005 set(ringback=%(2000,4000,440,480))
  181. 2017-06-21 17:45:04.747766 [DEBUG] mod_dptools.c:1570 SET verto.rtc/1005 [ringback]=[%(2000,4000,440,480)]
  182. EXECUTE verto.rtc/1005 set(transfer_ringback=local_stream://moh)
  183. 2017-06-21 17:45:04.747766 [DEBUG] mod_dptools.c:1570 SET verto.rtc/1005 [transfer_ringback]=[local_stream://moh]
  184. EXECUTE verto.rtc/1005 set(call_timeout=30)
  185. 2017-06-21 17:45:04.747766 [DEBUG] mod_dptools.c:1570 SET verto.rtc/1005 [call_timeout]=[30]
  186. EXECUTE verto.rtc/1005 set(hangup_after_bridge=true)
  187. 2017-06-21 17:45:04.747766 [DEBUG] mod_dptools.c:1570 SET verto.rtc/1005 [hangup_after_bridge]=[true]
  188. EXECUTE verto.rtc/1005 set(continue_on_fail=true)
  189. 2017-06-21 17:45:04.747766 [DEBUG] mod_dptools.c:1570 SET verto.rtc/1005 [continue_on_fail]=[true]
  190. EXECUTE verto.rtc/1005 hash(insert/192.168.150.3-call_return/1005/eboshxff@joshebosh.com)
  191. EXECUTE verto.rtc/1005 hash(insert/192.168.150.3-last_dial_ext/1005/71071c71-0351-b67c-2165-9bff65ccc4e4)
  192. EXECUTE verto.rtc/1005 set(called_party_callgroup=techsupport)
  193. 2017-06-21 17:45:04.747766 [DEBUG] mod_dptools.c:1570 SET verto.rtc/1005 [called_party_callgroup]=[techsupport]
  194. EXECUTE verto.rtc/1005 hash(insert/192.168.150.3-last_dial_ext/techsupport/71071c71-0351-b67c-2165-9bff65ccc4e4)
  195. EXECUTE verto.rtc/1005 hash(insert/192.168.150.3-last_dial_ext/global/71071c71-0351-b67c-2165-9bff65ccc4e4)
  196. EXECUTE verto.rtc/1005 hash(insert/192.168.150.3-last_dial/techsupport/71071c71-0351-b67c-2165-9bff65ccc4e4)
  197. EXECUTE verto.rtc/1005 bridge(user/1005@192.168.150.3)
  198. 2017-06-21 17:45:04.747766 [DEBUG] switch_channel.c:1250 verto.rtc/1005 EXPORTING[export_vars] [dialed_extension]=[1005] to event
  199. 2017-06-21 17:45:04.747766 [DEBUG] switch_ivr_originate.c:2147 Parsing global variables
  200. 2017-06-21 17:45:04.747766 [DEBUG] switch_channel.c:1250 verto.rtc/1005 EXPORTING[export_vars] [dialed_extension]=[1005] to event
  201. 2017-06-21 17:45:04.747766 [DEBUG] switch_ivr_originate.c:2147 Parsing global variables
  202. 2017-06-21 17:45:04.747766 [NOTICE] switch_ivr_originate.c:2856 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
  203. 2017-06-21 17:45:04.747766 [DEBUG] mod_rtc.c:392 () State Change CS_NEW -> CS_INIT
  204. 2017-06-21 17:45:04.747766 [NOTICE] switch_channel.c:1104 New Channel verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d [07f50d5d-defd-4d0b-bec6-51bcebb24a3d]
  205. 2017-06-21 17:45:04.747766 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) Running State Change CS_INIT (Cur 2 Tot 57)
  206. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State INIT
  207. 2017-06-21 17:45:04.757737 [DEBUG] mod_verto.c:2245 Local verto.invite SDP verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d:
  208. v=0
  209. o=FreeSWITCH 1498056132 1498056133 IN IP4 192.168.150.3
  210. s=FreeSWITCH
  211. c=IN IP4 192.168.150.3
  212. t=0 0
  213. a=msid-semantic: WMS tA7iD69oTjc5eSeFAdyV3woEj3pT1LNl
  214. m=audio 25372 RTP/SAVPF 102 9 0 8
  215. a=rtpmap:102 opus/48000/2
  216. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
  217. a=rtpmap:9 G722/8000
  218. a=rtpmap:0 PCMU/8000
  219. a=rtpmap:8 PCMA/8000
  220. a=fingerprint:sha-256 A8:37:47:E9:6B:02:93:D6:06:AF:E4:10:72:A8:44:32:A2:EB:3D:03:E9:48:BC:9B:28:CA:59:4A:A6:B6:02:90
  221. a=setup:actpass
  222. a=rtcp-mux
  223. a=rtcp:25372 IN IP4 192.168.150.3
  224. a=ssrc:693004200 cname:bLuEHuTqEVmIDre3
  225. a=ssrc:693004200 msid:tA7iD69oTjc5eSeFAdyV3woEj3pT1LNl a0
  226. a=ssrc:693004200 mslabel:tA7iD69oTjc5eSeFAdyV3woEj3pT1LNl
  227. a=ssrc:693004200 label:tA7iD69oTjc5eSeFAdyV3woEj3pT1LNla0
  228. a=ice-ufrag:vCPoY4T7Y8RPJv1L
  229. a=ice-pwd:7lfFFYWaK9IdqM7LfM03HJ1v
  230. a=candidate:4561521124 1 udp 659136 192.168.150.3 25372 typ host generation 0
  231. a=candidate:4561521124 2 udp 659136 192.168.150.3 25372 typ host generation 0
  232. a=silenceSupp:off - - - -
  233. a=ptime:20
  234. a=sendrecv
  235. m=video 25374 RTP/SAVPF 120 121
  236. b=AS:1024
  237. a=rtpmap:120 VP8/90000
  238. a=fmtp:120 max-fs=12288;max-fr=60
  239. a=rtpmap:121 VP9/90000
  240. a=fmtp:121 max-fs=12288;max-fr=60
  241. a=sendrecv
  242. a=fingerprint:sha-256 A8:37:47:E9:6B:02:93:D6:06:AF:E4:10:72:A8:44:32:A2:EB:3D:03:E9:48:BC:9B:28:CA:59:4A:A6:B6:02:90
  243. a=setup:actpass
  244. a=rtcp-mux
  245. a=rtcp:25374 IN IP4 192.168.150.3
  246. a=rtcp-fb:120 ccm fir
  247. a=rtcp-fb:120 ccm tmmbr
  248. a=rtcp-fb:120 nack
  249. a=rtcp-fb:120 nack pli
  250. a=rtcp-fb:121 ccm fir
  251. a=rtcp-fb:121 ccm tmmbr
  252. a=rtcp-fb:121 nack
  253. a=rtcp-fb:121 nack pli
  254. a=ssrc:4238951720 cname:bLuEHuTqEVmIDre3
  255. a=ssrc:4238951720 msid:tA7iD69oTjc5eSeFAdyV3woEj3pT1LNl v0
  256. a=ssrc:4238951720 mslabel:tA7iD69oTjc5eSeFAdyV3woEj3pT1LNl
  257. a=ssrc:4238951720 label:tA7iD69oTjc5eSeFAdyV3woEj3pT1LNlv0
  258. a=ice-ufrag:PAutM4IWe0n0gpR8
  259. a=ice-pwd:hDyJgkJt6QCMHEwm5QGHLeuP
  260. a=candidate:4750157512 1 udp 659136 192.168.150.3 25374 typ host generation 0
  261. a=candidate:4750157512 2 udp 659135 192.168.150.3 25374 typ host generation 0
  262. a=end-of-candidates
  263.  
  264. 2017-06-21 17:45:04.757737 [NOTICE] mod_verto.c:2345 Ring-Ready verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d!
  265. 2017-06-21 17:45:04.757737 [DEBUG] switch_channel.c:3354 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) Callstate Change DOWN -> RINGING
  266. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_state_machine.c:40 verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d Standard INIT
  267. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_state_machine.c:48 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State Change CS_INIT -> CS_ROUTING
  268. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State INIT going to sleep
  269. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) Running State Change CS_ROUTING (Cur 2 Tot 57)
  270. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State ROUTING
  271. 2017-06-21 17:45:04.757737 [DEBUG] mod_rtc.c:89 verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d RTC ROUTING
  272. 2017-06-21 17:45:04.757737 [DEBUG] switch_ivr_originate.c:67 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  273. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State ROUTING going to sleep
  274. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 57)
  275. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_state_machine.c:662 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State CONSUME_MEDIA
  276. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_state_machine.c:662 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State CONSUME_MEDIA going to sleep
  277. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [opus:109:48000:20:0:2]/[opus:116:48000:20:0:1]
  278. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:5168 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
  279. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
  280. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  281. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  282. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:5029 Set telephone-event payload to 101@8000
  283. 2017-06-21 17:45:04.757737 [DEBUG] mod_opus.c:603 Opus encoder: set bitrate to local settings [120000bps]
  284. 2017-06-21 17:45:04.757737 [DEBUG] mod_opus.c:603 Opus encoder: set bitrate to local settings [120000bps]
  285. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3428 Set Codec verto.rtc/1005 opus/48000 20 ms 960 samples 0 bits 2 channels
  286. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_codec.c:111 verto.rtc/1005 Original read codec set to opus:116
  287. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3858 Save audio Candidate cid: 1 proto: UDP type: host addr: 192.168.150.12:51494
  288. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3858 Save audio Candidate cid: 2 proto: UDP type: host addr: 192.168.150.12:51495
  289. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3858 Save audio Candidate cid: 1 proto: UDP type: srflx addr: 65.15.69.32:51494
  290. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3858 Save audio Candidate cid: 2 proto: UDP type: srflx addr: 65.15.69.32:51495
  291. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3900 Searching for rtp candidate.
  292. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3905 Choose rtp candidate, index 0, 192.168.150.12:51494
  293. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3678 verto.rtc/1005 choosing family v4
  294. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3916 Choose same candidate, index 2, for rtcp based on rtcp-mux attribute 192.168.150.12:51494
  295. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3963 setting remote audio ice addr to index 0 192.168.150.12:51494 based on candidate
  296. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3998 Setting remote rtcp audio addr to 192.168.150.12:51494 based on candidate
  297. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:5430 verto.rtc/1005 Set 2833 dtmf send payload to 101 recv payload to 101
  298. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:5713 Video Codec Compare [VP8:120]/[VP8:99]
  299. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:5745 Video Codec Compare [VP8:120] +++ is saved as a match
  300. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:5713 Video Codec Compare [VP9:121]/[VP8:99]
  301. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:5713 Video Codec Compare [H264:126]/[VP8:99]
  302. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:5713 Video Codec Compare [H264:97]/[VP8:99]
  303. 2017-06-21 17:45:04.757737 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
  304. 2017-06-21 17:45:04.757737 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
  305. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3238 Set VIDEO Codec verto.rtc/1005 VP8/90000 0 ms
  306. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3858 Save video Candidate cid: 1 proto: UDP type: host addr: 192.168.150.12:51496
  307. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3858 Save video Candidate cid: 2 proto: UDP type: host addr: 192.168.150.12:51497
  308. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3858 Save video Candidate cid: 1 proto: UDP type: srflx addr: 65.15.69.32:51496
  309. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3858 Save video Candidate cid: 2 proto: UDP type: srflx addr: 65.15.69.32:51497
  310. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3900 Searching for rtp candidate.
  311. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3905 Choose rtp candidate, index 0, 192.168.150.12:51496
  312. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3678 verto.rtc/1005 choosing family v4
  313. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3916 Choose same candidate, index 2, for rtcp based on rtcp-mux attribute 192.168.150.12:51496
  314. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3963 setting remote video ice addr to index 0 192.168.150.12:51496 based on candidate
  315. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:3998 Setting remote rtcp video addr to 192.168.150.12:51496 based on candidate
  316. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:8147 AUDIO RTP [verto.rtc/1005] 192.168.150.3 port 24600 -> 192.168.150.12 port 51494 codec: 109 ms: 20
  317. 2017-06-21 17:45:04.757737 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 960 bytes per 20ms
  318. 2017-06-21 17:45:04.757737 [INFO] switch_core_media.c:8326 Activating Audio ICE
  319. 2017-06-21 17:45:04.757737 [NOTICE] switch_rtp.c:4610 Activating RTP audio ICE: 9c79d98b:6S63hfj6seFahKmN 192.168.150.12:51494
  320. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:8369 Activating RTCP PORT 51494
  321. 2017-06-21 17:45:04.757737 [DEBUG] switch_rtp.c:4507 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 51494
  322. 2017-06-21 17:45:04.757737 [INFO] switch_core_media.c:8377 Skipping RTCP ICE (Same as RTP)
  323. 2017-06-21 17:45:04.757737 [INFO] switch_rtp.c:3581 Activate RTP/RTCP audio DTLS client
  324. 2017-06-21 17:45:04.757737 [INFO] switch_rtp.c:3730 Changing audio DTLS state from OFF to HANDSHAKE
  325. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:2190 Setting Jitterbuffer to 20ms (1 frames) (50 max frames)
  326. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:8450 verto.rtc/1005 Set 2833 dtmf send payload to 101
  327. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:8457 verto.rtc/1005 Set 2833 dtmf receive payload to 101
  328. 2017-06-21 17:45:04.757737 [DEBUG] switch_rtp.c:4126 Starting video timer.
  329. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:8975 VIDEO RTP [verto.rtc/1005] 192.168.150.3:29308->192.168.150.12:51496 codec: 120 ms: 0 [SUCCESS]
  330. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:7139 verto.rtc/1005 Starting Video thread
  331. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:7005 verto.rtc/1005 Video thread started. Echo is off
  332. 2017-06-21 17:45:04.757737 [INFO] switch_core_media.c:9017 Activating Video ICE
  333. 2017-06-21 17:45:04.757737 [NOTICE] switch_rtp.c:4610 Activating RTP video ICE: 9c79d98b:MOuVMxLyYGUb3veX 192.168.150.12:51496
  334. 2017-06-21 17:45:04.757737 [INFO] switch_core_media.c:9055 Activating VIDEO RTCP PORT 51496 interval 1000 mux 1
  335. 2017-06-21 17:45:04.757737 [DEBUG] switch_rtp.c:4507 RTCP send rate is: 1000 and packet rate is: 90000 Remote Port: 51496
  336. 2017-06-21 17:45:04.757737 [INFO] switch_core_media.c:9066 Skipping VIDEO RTCP ICE (Same as VIDEO RTP)
  337. 2017-06-21 17:45:04.757737 [INFO] switch_rtp.c:3581 Activate RTP/RTCP video DTLS client
  338. 2017-06-21 17:45:04.757737 [INFO] switch_rtp.c:3730 Changing video DTLS state from OFF to HANDSHAKE
  339. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:8130 Audio params are unchanged for verto.rtc/1005.
  340. 2017-06-21 17:45:04.757737 [DEBUG] switch_core_media.c:8833 Video params are unchanged for verto.rtc/1005.
  341. 2017-06-21 17:45:04.757737 [DEBUG] mod_verto.c:2480 Local SDP verto.rtc/1005:
  342. v=0
  343. o=FreeSWITCH 1498056904 1498056905 IN IP4 192.168.150.3
  344. s=FreeSWITCH
  345. c=IN IP4 192.168.150.3
  346. t=0 0
  347. a=msid-semantic: WMS EBIGD7cdXgAKtazIII947yipZn5JRdem
  348. m=audio 24600 UDP/TLS/RTP/SAVPF 109 101
  349. a=rtpmap:109 opus/48000/2
  350. a=fmtp:109 useinbandfec=1; stereo=1; sprop-stereo=1
  351. a=rtpmap:101 telephone-event/8000
  352. a=silenceSupp:off - - - -
  353. a=ptime:20
  354. a=sendrecv
  355. a=fingerprint:sha-256 A8:37:47:E9:6B:02:93:D6:06:AF:E4:10:72:A8:44:32:A2:EB:3D:03:E9:48:BC:9B:28:CA:59:4A:A6:B6:02:90
  356. a=setup:active
  357. a=rtcp-mux
  358. a=rtcp:24600 IN IP4 192.168.150.3
  359. a=ice-ufrag:6S63hfj6seFahKmN
  360. a=ice-pwd:n5LYQNlHgjSKHBMDkJxrOGo6
  361. a=candidate:1477249693 1 udp 659136 192.168.150.3 24600 typ host generation 0
  362. a=end-of-candidates
  363. a=ssrc:89116584 cname:FNS8KUcx1C7kDxSc
  364. a=ssrc:89116584 msid:EBIGD7cdXgAKtazIII947yipZn5JRdem a0
  365. a=ssrc:89116584 mslabel:EBIGD7cdXgAKtazIII947yipZn5JRdem
  366. a=ssrc:89116584 label:EBIGD7cdXgAKtazIII947yipZn5JRdema0
  367. m=video 29308 UDP/TLS/RTP/SAVPF 120
  368. b=AS:1024
  369. a=rtpmap:120 VP8/90000
  370. a=fmtp:120 max-fs=12288;max-fr=60
  371. a=sendrecv
  372. a=fingerprint:sha-256 A8:37:47:E9:6B:02:93:D6:06:AF:E4:10:72:A8:44:32:A2:EB:3D:03:E9:48:BC:9B:28:CA:59:4A:A6:B6:02:90
  373. a=setup:active
  374. a=rtcp-mux
  375. a=rtcp:29308 IN IP4 192.168.150.3
  376. a=rtcp-fb:120 ccm fir
  377. a=rtcp-fb:120 nack
  378. a=rtcp-fb:120 nack pli
  379. a=ssrc:3635064104 cname:FNS8KUcx1C7kDxSc
  380. a=ssrc:3635064104 msid:EBIGD7cdXgAKtazIII947yipZn5JRdem v0
  381. a=ssrc:3635064104 mslabel:EBIGD7cdXgAKtazIII947yipZn5JRdem
  382. a=ssrc:3635064104 label:EBIGD7cdXgAKtazIII947yipZn5JRdemv0
  383. a=ice-ufrag:MOuVMxLyYGUb3veX
  384. a=ice-pwd:5Ew4sXKZMmuIAJdcnyXZVzoK
  385. a=candidate:0268092962 1 udp 659136 192.168.150.3 29308 typ host generation 0
  386. a=end-of-candidates
  387.  
  388. 2017-06-21 17:45:04.757737 [NOTICE] switch_ivr_originate.c:1220 Pre-Answer verto.rtc/1005!
  389. 2017-06-21 17:45:04.767737 [DEBUG] switch_channel.c:3482 (verto.rtc/1005) Callstate Change RINGING -> EARLY
  390. 2017-06-21 17:45:04.767737 [DEBUG] switch_ivr_originate.c:1278 Raw Codec Activation Success L16@48000hz 2 channel 20ms
  391. 2017-06-21 17:45:04.767737 [DEBUG] switch_core_codec.c:223 verto.rtc/1005 Push codec L16:100
  392. 2017-06-21 17:45:04.767737 [DEBUG] switch_ivr_originate.c:1347 Play Ringback Tone [%(2000,4000,440,480)]
  393. 2017-06-21 17:45:04.817767 [NOTICE] mod_verto.c:2807 Hangup verto.rtc/1005 [CS_EXECUTE] [NORMAL_CLEARING]
  394. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_codec.c:248 verto.rtc/1005 Restore previous codec opus:116.
  395. 2017-06-21 17:45:04.817767 [NOTICE] switch_ivr_originate.c:3617 Hangup verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL]
  396. 2017-06-21 17:45:04.817767 [DEBUG] switch_ivr_originate.c:3842 Originate Cancelled by originator termination Cause: 487 [ORIGINATOR_CANCEL]
  397. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) Running State Change CS_HANGUP (Cur 2 Tot 57)
  398. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:850 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) Callstate Change RINGING -> HANGUP
  399. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:852 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State HANGUP
  400. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:60 verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d Standard HANGUP, cause: ORIGINATOR_CANCEL
  401. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:852 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State HANGUP going to sleep
  402. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:619 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State Change CS_HANGUP -> CS_REPORTING
  403. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) Running State Change CS_REPORTING (Cur 2 Tot 57)
  404. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:938 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State REPORTING
  405. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:174 verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d Standard REPORTING, cause: ORIGINATOR_CANCEL
  406. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:938 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State REPORTING going to sleep
  407. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:610 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State Change CS_REPORTING -> CS_DESTROY
  408. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_session.c:1712 Session 57 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) Locked, Waiting on external entities
  409. 2017-06-21 17:45:04.817767 [NOTICE] switch_core_session.c:1730 Session 57 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) Ended
  410. 2017-06-21 17:45:04.817767 [NOTICE] switch_ivr_originate.c:2856 Cannot create outgoing channel of type [user] cause: [ORIGINATOR_CANCEL]
  411. 2017-06-21 17:45:04.817767 [NOTICE] switch_core_session.c:1734 Close Channel verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d [CS_DESTROY]
  412. 2017-06-21 17:45:04.817767 [DEBUG] switch_ivr_originate.c:3853 Originate Resulted in Error Cause: 487 [ORIGINATOR_CANCEL]
  413. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:741 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) Running State Change CS_DESTROY (Cur 1 Tot 57)
  414. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:751 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State DESTROY
  415. 2017-06-21 17:45:04.817767 [DEBUG] mod_rtc.c:132 verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d RTC DESTROY
  416. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:181 verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d Standard DESTROY
  417. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:751 (verto.rtc/13856a35-c60d-5fef-1f4e-9b49d589375d) State DESTROY going to sleep
  418. 2017-06-21 17:45:04.817767 [INFO] mod_dptools.c:3490 Originate Failed.  Cause: ORIGINATOR_CANCEL
  419. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_session.c:2884 verto.rtc/1005 skip receive message [PHONE_EVENT] (channel is hungup already)
  420. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:650 (verto.rtc/1005) State EXECUTE going to sleep
  421. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/1005) Running State Change CS_HANGUP (Cur 1 Tot 57)
  422. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:850 (verto.rtc/1005) Callstate Change EARLY -> HANGUP
  423. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:852 (verto.rtc/1005) State HANGUP
  424. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_media.c:13746 verto.rtc/1005 skip receive message [DISPLAY] (channel is hungup already)
  425. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:60 verto.rtc/1005 Standard HANGUP, cause: NORMAL_CLEARING
  426. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:852 (verto.rtc/1005) State HANGUP going to sleep
  427. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:619 (verto.rtc/1005) State Change CS_HANGUP -> CS_REPORTING
  428. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/1005) Running State Change CS_REPORTING (Cur 1 Tot 57)
  429. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:938 (verto.rtc/1005) State REPORTING
  430. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:174 verto.rtc/1005 Standard REPORTING, cause: NORMAL_CLEARING
  431. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:938 (verto.rtc/1005) State REPORTING going to sleep
  432. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_state_machine.c:610 (verto.rtc/1005) State Change CS_REPORTING -> CS_DESTROY
  433. 2017-06-21 17:45:04.817767 [DEBUG] switch_core_session.c:1712 Session 56 (verto.rtc/1005) Locked, Waiting on external entities
  434. 2017-06-21 17:45:04.827747 [DEBUG] switch_core_media.c:7106 verto.rtc/1005 Video thread ended
  435. 2017-06-21 17:45:04.827747 [NOTICE] switch_core_session.c:1730 Session 56 (verto.rtc/1005) Ended
  436. 2017-06-21 17:45:04.827747 [NOTICE] switch_core_session.c:1734 Close Channel verto.rtc/1005 [CS_DESTROY]
  437. 2017-06-21 17:45:04.827747 [DEBUG] switch_core_state_machine.c:741 (verto.rtc/1005) Running State Change CS_DESTROY (Cur 0 Tot 57)
  438. 2017-06-21 17:45:04.827747 [DEBUG] switch_core_state_machine.c:751 (verto.rtc/1005) State DESTROY
  439. 2017-06-21 17:45:04.827747 [DEBUG] mod_rtc.c:132 verto.rtc/1005 RTC DESTROY
  440. 2017-06-21 17:45:04.827747 [DEBUG] mod_opus.c:711 Opus decoder stats: Frames[0] PLC[0] FEC[0]
  441. 2017-06-21 17:45:04.827747 [DEBUG] mod_opus.c:726 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0]
  442. 2017-06-21 17:45:04.827747 [DEBUG] mod_opus.c:711 Opus decoder stats: Frames[0] PLC[0] FEC[0]
  443. 2017-06-21 17:45:04.827747 [DEBUG] mod_opus.c:726 Opus encoder stats: Frames[2] Bytes encoded[427] Encoded length ms[40] Average encoded bitrate bps[0]
  444. 2017-06-21 17:45:04.827747 [DEBUG] switch_core_state_machine.c:181 verto.rtc/1005 Standard DESTROY
  445. 2017-06-21 17:45:04.827747 [DEBUG] switch_core_state_machine.c:751 (verto.rtc/1005) State DESTROY going to sleep