From josh, 4 Years ago, written in Plain Text.
- view diff
Embed
  1. freeswitch@localhost.localdomain>
  2. freeswitch@localhost.localdomain>
  3. 2017-07-06 14:14:53.623053 [DEBUG] mod_rtc.c:392 () State Change CS_NEW -> CS_INIT
  4. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_session.c:641 N/A set UUID=53ae2cf6-fcd9-3a7f-bb02-91871de7fac5
  5. 2017-07-06 14:14:53.623053 [NOTICE] switch_channel.c:1104 New Channel verto.rtc/3500 [53ae2cf6-fcd9-3a7f-bb02-91871de7fac5]
  6. 2017-07-06 14:14:53.623053 [DEBUG] mod_verto.c:3728 Remote SDP verto.rtc/3500:
  7. v=0
  8. o=- 7154045974886751329 2 IN IP4 127.0.0.1
  9. s=-
  10. t=0 0
  11. a=group:BUNDLE audio video
  12. a=msid-semantic: WMS pOHXuGw4hvimKhVkOkYjd9Od3fRIh6Mwnxw2
  13. m=audio 52043 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126
  14. c=IN IP4 166.137.96.56
  15. a=rtcp:9 IN IP4 0.0.0.0
  16. a=candidate:2129710778 1 udp 2122255103 2001::9d38:6ab8:34aa:1fac:3f57:feb7 51878 typ host generation 0 network-id 2 network-cost 50
  17. a=candidate:3594748867 1 udp 2122194687 192.168.1.72 51879 typ host generation 0 network-id 3 network-cost 10
  18. a=candidate:812855882 1 tcp 1518275327 2001::9d38:6ab8:34aa:1fac:3f57:feb7 9 typ host tcptype active generation 0 network-id 2 network-cost 50
  19. a=candidate:2562752307 1 tcp 1518214911 192.168.1.72 9 typ host tcptype active generation 0 network-id 3 network-cost 10
  20. a=candidate:542374423 1 udp 1685987071 166.137.96.56 52043 typ srflx raddr 192.168.1.72 rport 51879 generation 0 network-id 3 network-cost 10
  21. a=ice-ufrag:LNBR
  22. a=ice-pwd:PjEVXPr5tyIVuUhGmXtVaJYf
  23. a=fingerprint:sha-256 99:51:69:1D:B4:90:26:4D:84:F9:BC:5D:6C:D8:48:E6:9B:08:C5:71:C8:19:78:15:D7:AE:30:F5:6E:70:F9:E4
  24. a=setup:actpass
  25. a=mid:audio
  26. a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
  27. a=sendrecv
  28. a=rtcp-mux
  29. a=rtpmap:111 opus/48000/2
  30. a=rtcp-fb:111 transport-cc
  31. a=fmtp:111 minptime=10;useinbandfec=1; stereo=1; sprop-stereo=1
  32. a=rtpmap:103 ISAC/16000
  33. a=rtpmap:104 ISAC/32000
  34. a=rtpmap:9 G722/8000
  35. a=rtpmap:0 PCMU/8000
  36. a=rtpmap:8 PCMA/8000
  37. a=rtpmap:106 CN/32000
  38. a=rtpmap:105 CN/16000
  39. a=rtpmap:13 CN/8000
  40. a=rtpmap:110 telephone-event/48000
  41. a=rtpmap:112 telephone-event/32000
  42. a=rtpmap:113 telephone-event/16000
  43. a=rtpmap:126 telephone-event/8000
  44. a=ssrc:3115677395 cname:+/Y5FyCr60SqkA4m
  45. a=ssrc:3115677395 msid:pOHXuGw4hvimKhVkOkYjd9Od3fRIh6Mwnxw2 5b76b1b1-5df6-4093-94f6-ade5cbb10243
  46. a=ssrc:3115677395 mslabel:pOHXuGw4hvimKhVkOkYjd9Od3fRIh6Mwnxw2
  47. a=ssrc:3115677395 label:5b76b1b1-5df6-4093-94f6-ade5cbb10243
  48. m=video 20141 UDP/TLS/RTP/SAVPF 96 98 100 102 127 97 99 101 125
  49. c=IN IP4 166.137.96.56
  50. a=rtcp:9 IN IP4 0.0.0.0
  51. a=candidate:2129710778 1 udp 2122255103 2001::9d38:6ab8:34aa:1fac:3f57:feb7 51880 typ host generation 0 network-id 2 network-cost 50
  52. a=candidate:3594748867 1 udp 2122194687 192.168.1.72 51881 typ host generation 0 network-id 3 network-cost 10
  53. a=candidate:812855882 1 tcp 1518275327 2001::9d38:6ab8:34aa:1fac:3f57:feb7 9 typ host tcptype active generation 0 network-id 2 network-cost 50
  54. a=candidate:2562752307 1 tcp 1518214911 192.168.1.72 9 typ host tcptype active generation 0 network-id 3 network-cost 10
  55. a=candidate:542374423 1 udp 1685987071 166.137.96.56 20141 typ srflx raddr 192.168.1.72 rport 51881 generation 0 network-id 3 network-cost 10
  56. a=ice-ufrag:LNBR
  57. a=ice-pwd:PjEVXPr5tyIVuUhGmXtVaJYf
  58. a=fingerprint:sha-256 99:51:69:1D:B4:90:26:4D:84:F9:BC:5D:6C:D8:48:E6:9B:08:C5:71:C8:19:78:15:D7:AE:30:F5:6E:70:F9:E4
  59. a=setup:actpass
  60. a=mid:video
  61. a=extmap:2 urn:ietf:params:rtp-hdrext:toffset
  62. a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
  63. a=extmap:4 urn:3gpp:video-orientation
  64. a=extmap:5 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
  65. a=extmap:6 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay
  66. a=sendrecv
  67. a=rtcp-mux
  68. a=rtcp-rsize
  69. a=rtpmap:96 VP8/90000
  70. a=rtcp-fb:96 ccm fir
  71. a=rtcp-fb:96 nack
  72. a=rtcp-fb:96 nack pli
  73. a=rtcp-fb:96 goog-remb
  74. a=rtcp-fb:96 transport-cc
  75. a=rtpmap:98 VP9/90000
  76. a=rtcp-fb:98 ccm fir
  77. a=rtcp-fb:98 nack
  78. a=rtcp-fb:98 nack pli
  79. a=rtcp-fb:98 goog-remb
  80. a=rtcp-fb:98 transport-cc
  81. a=rtpmap:100 H264/90000
  82. a=rtcp-fb:100 ccm fir
  83. a=rtcp-fb:100 nack
  84. a=rtcp-fb:100 nack pli
  85. a=rtcp-fb:100 goog-remb
  86. a=rtcp-fb:100 transport-cc
  87. a=fmtp:100 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f
  88. a=rtpmap:102 red/90000
  89. a=rtpmap:127 ulpfec/90000
  90. a=rtpmap:97 rtx/90000
  91. a=fmtp:97 apt=96
  92. a=rtpmap:99 rtx/90000
  93. a=fmtp:99 apt=98
  94. a=rtpmap:101 rtx/90000
  95. a=fmtp:101 apt=100
  96. a=rtpmap:125 rtx/90000
  97. a=fmtp:125 apt=102
  98. a=ssrc-group:FID 2631570493 3143608125
  99. a=ssrc:2631570493 cname:+/Y5FyCr60SqkA4m
  100. a=ssrc:2631570493 msid:pOHXuGw4hvimKhVkOkYjd9Od3fRIh6Mwnxw2 0fe76717-39a2-4992-be11-f7ea36c834b9
  101. a=ssrc:2631570493 mslabel:pOHXuGw4hvimKhVkOkYjd9Od3fRIh6Mwnxw2
  102. a=ssrc:2631570493 label:0fe76717-39a2-4992-be11-f7ea36c834b9
  103. a=ssrc:3143608125 cname:+/Y5FyCr60SqkA4m
  104. a=ssrc:3143608125 msid:pOHXuGw4hvimKhVkOkYjd9Od3fRIh6Mwnxw2 0fe76717-39a2-4992-be11-f7ea36c834b9
  105. a=ssrc:3143608125 mslabel:pOHXuGw4hvimKhVkOkYjd9Od3fRIh6Mwnxw2
  106. a=ssrc:3143608125 label:0fe76717-39a2-4992-be11-f7ea36c834b9
  107.  
  108. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/3500) Running State Change CS_INIT (Cur 1 Tot 5)
  109. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/3500) State INIT
  110. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:40 verto.rtc/3500 Standard INIT
  111. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:48 (verto.rtc/3500) State Change CS_INIT -> CS_ROUTING
  112. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/3500) State INIT going to sleep
  113. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/3500) Running State Change CS_ROUTING (Cur 1 Tot 5)
  114. 2017-07-06 14:14:53.623053 [DEBUG] switch_channel.c:2249 (verto.rtc/3500) Callstate Change DOWN -> RINGING
  115. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/3500) State ROUTING
  116. 2017-07-06 14:14:53.623053 [DEBUG] mod_rtc.c:89 verto.rtc/3500 RTC ROUTING
  117. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:236 verto.rtc/3500 Standard ROUTING
  118. 2017-07-06 14:14:53.623053 [INFO] mod_dialplan_xml.c:637 Processing Joshua Young <joshebosh@gmail.com>->3500 in context default
  119. Dialplan: verto.rtc/3500 parsing [default->home-group] continue=false
  120. Dialplan: verto.rtc/3500 Regex (FAIL) [home-group] destination_number(3500) =~ /^(1030)$/ break=on-false
  121. Dialplan: verto.rtc/3500 parsing [default->unloop] continue=false
  122. Dialplan: verto.rtc/3500 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  123. Dialplan: verto.rtc/3500 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  124. Dialplan: verto.rtc/3500 parsing [default->tod_example] continue=true
  125. Dialplan: verto.rtc/3500 Date/Time Match (PASS) [tod_example] break=on-false
  126. Dialplan: verto.rtc/3500 Action set(open=true)
  127. Dialplan: verto.rtc/3500 parsing [default->holiday_example] continue=true
  128. Dialplan: verto.rtc/3500 Date/TimeMatch (FAIL) [holiday_example] break=on-false
  129. Dialplan: verto.rtc/3500 parsing [default->global-intercept] continue=false
  130. Dialplan: verto.rtc/3500 Regex (FAIL) [global-intercept] destination_number(3500) =~ /^886$/ break=on-false
  131. Dialplan: verto.rtc/3500 parsing [default->group-intercept] continue=false
  132. Dialplan: verto.rtc/3500 Regex (FAIL) [group-intercept] destination_number(3500) =~ /^\*8$/ break=on-false
  133. Dialplan: verto.rtc/3500 parsing [default->intercept-ext] continue=false
  134. Dialplan: verto.rtc/3500 Regex (FAIL) [intercept-ext] destination_number(3500) =~ /^\*\*(\d+)$/ break=on-false
  135. Dialplan: verto.rtc/3500 parsing [default->redial] continue=false
  136. Dialplan: verto.rtc/3500 Regex (FAIL) [redial] destination_number(3500) =~ /^(redial|870)$/ break=on-false
  137. Dialplan: verto.rtc/3500 parsing [default->global] continue=true
  138. Dialplan: verto.rtc/3500 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
  139. Dialplan: verto.rtc/3500 Regex (FAIL) [global] ${default_password}(j0sh3b0sh) =~ /^1234$/ break=never
  140. Dialplan: verto.rtc/3500 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
  141. Dialplan: verto.rtc/3500 Regex (FAIL) [global] ${endpoint_disposition}() =~ /^(DELAYED NEGOTIATION)/ break=on-false
  142. Dialplan: verto.rtc/3500 parsing [default->snom-demo-2] continue=false
  143. Dialplan: verto.rtc/3500 Regex (FAIL) [snom-demo-2] destination_number(3500) =~ /^9001$/ break=on-false
  144. Dialplan: verto.rtc/3500 parsing [default->snom-demo-1] continue=false
  145. Dialplan: verto.rtc/3500 Regex (FAIL) [snom-demo-1] destination_number(3500) =~ /^9000$/ break=on-false
  146. Dialplan: verto.rtc/3500 parsing [default->eavesdrop] continue=false
  147. Dialplan: verto.rtc/3500 Regex (FAIL) [eavesdrop] destination_number(3500) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
  148. Dialplan: verto.rtc/3500 parsing [default->eavesdrop] continue=false
  149. Dialplan: verto.rtc/3500 Regex (FAIL) [eavesdrop] destination_number(3500) =~ /^779$/ break=on-false
  150. Dialplan: verto.rtc/3500 parsing [default->call_return] continue=false
  151. Dialplan: verto.rtc/3500 Regex (FAIL) [call_return] destination_number(3500) =~ /^\*69$|^869$|^lcr$/ break=on-false
  152. Dialplan: verto.rtc/3500 parsing [default->del-group] continue=false
  153. Dialplan: verto.rtc/3500 Regex (FAIL) [del-group] destination_number(3500) =~ /^80(\d{2})$/ break=on-false
  154. Dialplan: verto.rtc/3500 parsing [default->add-group] continue=false
  155. Dialplan: verto.rtc/3500 Regex (FAIL) [add-group] destination_number(3500) =~ /^81(\d{2})$/ break=on-false
  156. Dialplan: verto.rtc/3500 parsing [default->call-group-simo] continue=false
  157. Dialplan: verto.rtc/3500 Regex (FAIL) [call-group-simo] destination_number(3500) =~ /^82(\d{2})$/ break=on-false
  158. Dialplan: verto.rtc/3500 parsing [default->call-group-order] continue=false
  159. Dialplan: verto.rtc/3500 Regex (FAIL) [call-group-order] destination_number(3500) =~ /^83(\d{2})$/ break=on-false
  160. Dialplan: verto.rtc/3500 parsing [default->extension-intercom] continue=false
  161. Dialplan: verto.rtc/3500 Regex (FAIL) [extension-intercom] destination_number(3500) =~ /^8(10[01][0-9])$/ break=on-false
  162. Dialplan: verto.rtc/3500 parsing [default->Local_Extension] continue=false
  163. Dialplan: verto.rtc/3500 Regex (FAIL) [Local_Extension] destination_number(3500) =~ /^(10[01][0-9])$/ break=on-false
  164. Dialplan: verto.rtc/3500 parsing [default->Local_Extension_Skinny] continue=false
  165. Dialplan: verto.rtc/3500 Regex (FAIL) [Local_Extension_Skinny] destination_number(3500) =~ /^(11[01][0-9])$/ break=on-false
  166. Dialplan: verto.rtc/3500 parsing [default->group_dial_sales] continue=false
  167. Dialplan: verto.rtc/3500 Regex (FAIL) [group_dial_sales] destination_number(3500) =~ /^2000$/ break=on-false
  168. Dialplan: verto.rtc/3500 parsing [default->group_dial_support] continue=false
  169. Dialplan: verto.rtc/3500 Regex (FAIL) [group_dial_support] destination_number(3500) =~ /^2001$/ break=on-false
  170. Dialplan: verto.rtc/3500 parsing [default->group_dial_billing] continue=false
  171. Dialplan: verto.rtc/3500 Regex (FAIL) [group_dial_billing] destination_number(3500) =~ /^2002$/ break=on-false
  172. Dialplan: verto.rtc/3500 parsing [default->operator] continue=false
  173. Dialplan: verto.rtc/3500 Regex (FAIL) [operator] destination_number(3500) =~ /^(operator|0)$/ break=on-false
  174. Dialplan: verto.rtc/3500 parsing [default->vmain] continue=false
  175. Dialplan: verto.rtc/3500 Regex (FAIL) [vmain] destination_number(3500) =~ /^vmain$|^4000$|^\*98$/ break=on-false
  176. Dialplan: verto.rtc/3500 parsing [default->sip_uri] continue=false
  177. Dialplan: verto.rtc/3500 Regex (FAIL) [sip_uri] destination_number(3500) =~ /^sip:(.*)$/ break=on-false
  178. Dialplan: verto.rtc/3500 parsing [default->nb_conferences] continue=false
  179. Dialplan: verto.rtc/3500 Regex (FAIL) [nb_conferences] destination_number(3500) =~ /^(30\d{2})$/ break=on-false
  180. Dialplan: verto.rtc/3500 parsing [default->wb_conferences] continue=false
  181. Dialplan: verto.rtc/3500 Regex (FAIL) [wb_conferences] destination_number(3500) =~ /^(31\d{2})$/ break=on-false
  182. Dialplan: verto.rtc/3500 parsing [default->uwb_conferences] continue=false
  183. Dialplan: verto.rtc/3500 Regex (FAIL) [uwb_conferences] destination_number(3500) =~ /^(32\d{2})$/ break=on-false
  184. Dialplan: verto.rtc/3500 parsing [default->cdquality_conferences] continue=false
  185. Dialplan: verto.rtc/3500 Regex (FAIL) [cdquality_conferences] destination_number(3500) =~ /^(33\d{2})$/ break=on-false
  186. Dialplan: verto.rtc/3500 parsing [default->cdquality_stereo_conferences] continue=false
  187. Dialplan: verto.rtc/3500 Regex (FAIL) [cdquality_stereo_conferences] destination_number(3500) =~ /^(35\d{2}).*?-screen$/ break=on-false
  188. Dialplan: verto.rtc/3500 parsing [default->conference-canvases] continue=true
  189. Dialplan: verto.rtc/3500 Regex (FAIL) [conference-canvases] destination_number(3500) =~ /(35\d{2})-canvas-(\d+)/ break=on-false
  190. Dialplan: verto.rtc/3500 parsing [default->conf mod] continue=false
  191. Dialplan: verto.rtc/3500 Regex (FAIL) [conf mod] destination_number(3500) =~ /^6070-moderator$/ break=on-false
  192. Dialplan: verto.rtc/3500 parsing [default->cdquality_conferences] continue=false
  193. Dialplan: verto.rtc/3500 Regex (PASS) [cdquality_conferences] destination_number(3500) =~ /^(35\d{2})$/ break=on-false
  194. Dialplan: verto.rtc/3500 Action answer()
  195. Dialplan: verto.rtc/3500 Action conference(3500-${domain_name}@video-mcu-stereo)
  196. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:286 (verto.rtc/3500) State Change CS_ROUTING -> CS_EXECUTE
  197. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/3500) State ROUTING going to sleep
  198. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/3500) Running State Change CS_EXECUTE (Cur 1 Tot 5)
  199. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:650 (verto.rtc/3500) State EXECUTE
  200. 2017-07-06 14:14:53.623053 [DEBUG] mod_rtc.c:120 verto.rtc/3500 RTC EXECUTE
  201. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_state_machine.c:328 verto.rtc/3500 Standard EXECUTE
  202. EXECUTE verto.rtc/3500 set(open=true)
  203. 2017-07-06 14:14:53.623053 [DEBUG] mod_dptools.c:1588 SET verto.rtc/3500 [open]=[true]
  204. EXECUTE verto.rtc/3500 answer()
  205. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [opus:111:48000:20:0:2]/[opus:116:48000:20:0:1]
  206. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5168 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
  207. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[opus:116:48000:20:0:1]
  208. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[opus:116:48000:20:0:1]
  209. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
  210. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  211. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  212. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [CN:106:32000:20:0:1]/[opus:116:48000:20:0:1]
  213. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [CN:105:16000:20:0:1]/[opus:116:48000:20:0:1]
  214. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [CN:13:8000:20:0:1]/[opus:116:48000:20:0:1]
  215. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5029 Set telephone-event payload to 110@48000
  216. 2017-07-06 14:14:53.623053 [DEBUG] mod_opus.c:603 Opus encoder: set bitrate to local settings [120000bps]
  217. 2017-07-06 14:14:53.623053 [DEBUG] mod_opus.c:603 Opus encoder: set bitrate to local settings [120000bps]
  218. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3428 Set Codec verto.rtc/3500 opus/48000 20 ms 960 samples 0 bits 2 channels
  219. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_codec.c:111 verto.rtc/3500 Original read codec set to opus:116
  220. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3852 Drop audio Candidate cid: 1 proto: udp type: host addr: 2001::9d38:6ab8:34aa:1fac:3f57:feb7:51878 (no network path)
  221. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3858 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.1.72:51879
  222. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3858 Save audio Candidate cid: 1 proto: udp type: srflx addr: 166.137.96.56:52043
  223. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3900 Searching for rtp candidate.
  224. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3905 Choose rtp candidate, index 1, 166.137.96.56:52043
  225. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3678 verto.rtc/3500 choosing family v4
  226. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3916 Choose same candidate, index 0, for rtcp based on rtcp-mux attribute 166.137.96.56:52043
  227. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3963 setting remote audio ice addr to index 1 166.137.96.56:52043 based on candidate
  228. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3998 Setting remote rtcp audio addr to 166.137.96.56:52043 based on candidate
  229. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5372 Set telephone-event payload to 110@48000
  230. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5430 verto.rtc/3500 Set 2833 dtmf send payload to 110 recv payload to 110
  231. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5713 Video Codec Compare [VP8:96]/[VP8:99]
  232. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5745 Video Codec Compare [VP8:96] +++ is saved as a match
  233. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5713 Video Codec Compare [VP9:98]/[VP8:99]
  234. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5713 Video Codec Compare [H264:100]/[VP8:99]
  235. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5713 Video Codec Compare [red:102]/[VP8:99]
  236. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5713 Video Codec Compare [ulpfec:127]/[VP8:99]
  237. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5713 Video Codec Compare [rtx:97]/[VP8:99]
  238. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5713 Video Codec Compare [rtx:99]/[VP8:99]
  239. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5713 Video Codec Compare [rtx:101]/[VP8:99]
  240. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:5713 Video Codec Compare [rtx:125]/[VP8:99]
  241. 2017-07-06 14:14:53.623053 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
  242. 2017-07-06 14:14:53.623053 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
  243. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3238 Set VIDEO Codec verto.rtc/3500 VP8/90000 0 ms
  244. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3852 Drop video Candidate cid: 1 proto: udp type: host addr: 2001::9d38:6ab8:34aa:1fac:3f57:feb7:51880 (no network path)
  245. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3858 Save video Candidate cid: 1 proto: udp type: host addr: 192.168.1.72:51881
  246. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3858 Save video Candidate cid: 1 proto: udp type: srflx addr: 166.137.96.56:20141
  247. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3900 Searching for rtp candidate.
  248. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3905 Choose rtp candidate, index 1, 166.137.96.56:20141
  249. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3678 verto.rtc/3500 choosing family v4
  250. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3916 Choose same candidate, index 0, for rtcp based on rtcp-mux attribute 166.137.96.56:20141
  251. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3963 setting remote video ice addr to index 1 166.137.96.56:20141 based on candidate
  252. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:3998 Setting remote rtcp video addr to 166.137.96.56:20141 based on candidate
  253. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:8147 AUDIO RTP [verto.rtc/3500] 74.208.165.83 port 18730 -> 166.137.96.56 port 52043 codec: 111 ms: 20
  254. 2017-07-06 14:14:53.623053 [DEBUG] switch_rtp.c:4164 Starting timer [soft] 960 bytes per 20ms
  255. 2017-07-06 14:14:53.623053 [INFO] switch_core_media.c:8326 Activating Audio ICE
  256. 2017-07-06 14:14:53.623053 [NOTICE] switch_rtp.c:4663 Activating RTP audio ICE: LNBR:HsCUKJ0kd6teAxf1 166.137.96.56:52043
  257. 2017-07-06 14:14:53.623053 [DEBUG] switch_core_media.c:8369 Activating RTCP PORT 52043
  258. 2017-07-06 14:14:53.623053 [DEBUG] switch_rtp.c:4560 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 52043
  259. 2017-07-06 14:14:53.623053 [INFO] switch_core_media.c:8377 Skipping RTCP ICE (Same as RTP)
  260. 2017-07-06 14:14:53.623053 [INFO] switch_rtp.c:3620 Activate RTP/RTCP audio DTLS client
  261. 2017-07-06 14:14:53.633057 [INFO] switch_rtp.c:3783 Changing audio DTLS state from OFF to HANDSHAKE
  262. 2017-07-06 14:14:53.633057 [DEBUG] switch_core_media.c:2190 Setting Jitterbuffer to 20ms (1 frames) (50 max frames)
  263. 2017-07-06 14:14:53.633057 [DEBUG] switch_core_media.c:8450 verto.rtc/3500 Set 2833 dtmf send payload to 110
  264. 2017-07-06 14:14:53.633057 [DEBUG] switch_core_media.c:8457 verto.rtc/3500 Set 2833 dtmf receive payload to 110
  265. 2017-07-06 14:14:53.633057 [DEBUG] switch_rtp.c:4179 Starting video timer.
  266. 2017-07-06 14:14:53.633057 [DEBUG] switch_core_media.c:8975 VIDEO RTP [verto.rtc/3500] 74.208.165.83:21586->166.137.96.56:20141 codec: 96 ms: 0 [SUCCESS]
  267. 2017-07-06 14:14:53.633057 [DEBUG] switch_core_media.c:7139 verto.rtc/3500 Starting Video thread
  268. 2017-07-06 14:14:53.633057 [DEBUG] switch_core_media.c:7005 verto.rtc/3500 Video thread started. Echo is off
  269. 2017-07-06 14:14:53.633057 [INFO] switch_core_media.c:9017 Activating Video ICE
  270. 2017-07-06 14:14:53.633057 [NOTICE] switch_rtp.c:4663 Activating RTP video ICE: LNBR:YFcf4rxz0T1O6nye 166.137.96.56:20141
  271. 2017-07-06 14:14:53.633057 [INFO] switch_core_media.c:9055 Activating VIDEO RTCP PORT 20141 interval 1000 mux 1
  272. 2017-07-06 14:14:53.633057 [DEBUG] switch_rtp.c:4560 RTCP send rate is: 1000 and packet rate is: 90000 Remote Port: 20141
  273. 2017-07-06 14:14:53.633057 [INFO] switch_core_media.c:9066 Skipping VIDEO RTCP ICE (Same as VIDEO RTP)
  274. 2017-07-06 14:14:53.633057 [INFO] switch_rtp.c:3620 Activate RTP/RTCP video DTLS client
  275. 2017-07-06 14:14:53.633057 [INFO] switch_rtp.c:3783 Changing video DTLS state from OFF to HANDSHAKE
  276. 2017-07-06 14:14:53.633057 [DEBUG] switch_core_media.c:8130 Audio params are unchanged for verto.rtc/3500.
  277. 2017-07-06 14:14:53.633057 [DEBUG] switch_core_media.c:8833 Video params are unchanged for verto.rtc/3500.
  278. 2017-07-06 14:14:53.633057 [DEBUG] mod_verto.c:2480 Local SDP verto.rtc/3500:
  279. v=0
  280. o=FreeSWITCH 1499331763 1499331764 IN IP4 74.208.165.83
  281. s=FreeSWITCH
  282. c=IN IP4 74.208.165.83
  283. t=0 0
  284. a=msid-semantic: WMS 96oSh5M9KKWmPGTvaXuMmsggKEfiaEda
  285. m=audio 18730 UDP/TLS/RTP/SAVPF 111 110
  286. a=rtpmap:111 opus/48000/2
  287. a=fmtp:111 useinbandfec=1; minptime=10; stereo=1; sprop-stereo=1
  288. a=rtpmap:110 telephone-event/48000
  289. a=silenceSupp:off - - - -
  290. a=ptime:20
  291. a=sendrecv
  292. a=fingerprint:sha-256 35:3E:E4:67:65:B2:1D:93:39:69:06:CE:88:86:A8:DB:D9:06:5D:08:E9:D2:9F:C4:12:C6:28:55:1E:D9:3D:AC
  293. a=setup:active
  294. a=rtcp-mux
  295. a=rtcp:18730 IN IP4 74.208.165.83
  296. a=ice-ufrag:HsCUKJ0kd6teAxf1
  297. a=ice-pwd:PYz3k6RhqSTfCR6a9yUJ8KUc
  298. a=candidate:2182278095 1 udp 659136 74.208.165.83 18730 typ host generation 0
  299. a=end-of-candidates
  300. a=ssrc:1530617941 cname:AsTInvHY6taMZU70
  301. a=ssrc:1530617941 msid:96oSh5M9KKWmPGTvaXuMmsggKEfiaEda a0
  302. a=ssrc:1530617941 mslabel:96oSh5M9KKWmPGTvaXuMmsggKEfiaEda
  303. a=ssrc:1530617941 label:96oSh5M9KKWmPGTvaXuMmsggKEfiaEdaa0
  304. m=video 21586 UDP/TLS/RTP/SAVPF 96
  305. b=AS:1024
  306. a=rtpmap:96 VP8/90000
  307. a=sendrecv
  308. a=fingerprint:sha-256 35:3E:E4:67:65:B2:1D:93:39:69:06:CE:88:86:A8:DB:D9:06:5D:08:E9:D2:9F:C4:12:C6:28:55:1E:D9:3D:AC
  309. a=setup:active
  310. a=rtcp-mux
  311. a=rtcp:21586 IN IP4 74.208.165.83
  312. a=rtcp-fb:96 ccm fir
  313. a=rtcp-fb:96 nack
  314. a=rtcp-fb:96 nack pli
  315. a=ssrc:780963670 cname:AsTInvHY6taMZU70
  316. a=ssrc:780963670 msid:96oSh5M9KKWmPGTvaXuMmsggKEfiaEda v0
  317. a=ssrc:780963670 mslabel:96oSh5M9KKWmPGTvaXuMmsggKEfiaEda
  318. a=ssrc:780963670 label:96oSh5M9KKWmPGTvaXuMmsggKEfiaEdav0
  319. a=ice-ufrag:YFcf4rxz0T1O6nye
  320. a=ice-pwd:9lAUkX8g0P0rMKLAfNGa44zU
  321. a=candidate:3621849758 1 udp 659136 74.208.165.83 21586 typ host generation 0
  322. a=end-of-candidates
  323.  
  324. 2017-07-06 14:14:53.633057 [NOTICE] mod_dptools.c:1352 Channel [verto.rtc/3500] has been answered
  325. 2017-07-06 14:14:53.633057 [DEBUG] switch_channel.c:3781 (verto.rtc/3500) Callstate Change RINGING -> ACTIVE
  326. EXECUTE verto.rtc/3500 conference(3500-74.208.165.83@video-mcu-stereo)
  327. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:303 Adding layout group grid
  328. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 1x1 to layout group grid
  329. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 2x1 to layout group grid
  330. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 1x1+2x1 to layout group grid
  331. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 2x2 to layout group grid
  332. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 3x3 to layout group grid
  333. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 4x4 to layout group grid
  334. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 5x5 to layout group grid
  335. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 6x6 to layout group grid
  336. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 8x8 to layout group grid
  337. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:303 Adding layout group grid-zoom
  338. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 1x1 to layout group grid-zoom
  339. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 2x1-zoom to layout group grid-zoom
  340. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 3x1-zoom to layout group grid-zoom
  341. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 2x2 to layout group grid-zoom
  342. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 5-grid-zoom to layout group grid-zoom
  343. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 3x2-zoom to layout group grid-zoom
  344. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 7-grid-zoom to layout group grid-zoom
  345. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 4x2-zoom to layout group grid-zoom
  346. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 3x3 to layout group grid-zoom
  347. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:303 Adding layout group 1up_top_left_plus
  348. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 1up_top_left+5 to layout group 1up_top_left_plus
  349. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 1up_top_left+7 to layout group 1up_top_left_plus
  350. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 1up_top_left+9 to layout group 1up_top_left_plus
  351. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:303 Adding layout group 3up_plus
  352. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 3up+4 to layout group 3up_plus
  353. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:312 Adding node 3up+9 to layout group 3up_plus
  354. 2017-07-06 14:14:53.633057 [DEBUG] mod_conference.c:3305 using channel sound prefix: /usr/local/freeswitch/sounds/en/us/callie
  355. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:1546 Canvas position 1 applied layout 1x1
  356. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:1667 Layout set to 1x1
  357. 2017-07-06 14:14:53.633057 [DEBUG] conference_video.c:1627 Canvas attached to position 0
  358. 2017-07-06 14:14:53.633057 [DEBUG] conference_member.c:1715 Raw Codec Activation Success L16@48000hz 2 channel 20ms
  359. 2017-07-06 14:14:53.633057 [DEBUG] conference_member.c:1762 Raw Codec Activation Success L16@48000hz 2 channel 20ms
  360. 2017-07-06 14:14:53.633057 [DEBUG] switch_core_codec.c:223 verto.rtc/3500 Push codec L16:100
  361. 2017-07-06 14:14:53.643052 [WARNING] switch_core_file.c:360 File has 1 channels, muxing to 2 channels will occur.
  362. 2017-07-06 14:14:53.643052 [DEBUG] conference_member.c:104 verto.rtc/3500 binding '0' to 'mute'
  363. 2017-07-06 14:14:53.643052 [INFO] switch_ivr_async.c:215 Digit parser mod_conference: Setting realm to 'conf'
  364. 2017-07-06 14:14:53.643052 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 0/conf/0 callback: 0x7f47b569f840 data: 0x7f47ac127460
  365. 2017-07-06 14:14:53.643052 [DEBUG] conference_member.c:104 verto.rtc/3500 binding '*' to 'deaf mute'
  366. 2017-07-06 14:14:53.643052 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding */conf/0 callback: 0x7f47b569f840 data: 0x7f47ac127490
  367. 2017-07-06 14:14:53.643052 [DEBUG] conference_member.c:104 verto.rtc/3500 binding '9' to 'energy up'
  368. 2017-07-06 14:14:53.643052 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 9/conf/0 callback: 0x7f47b569f840 data: 0x7f47ac1274c0
  369. 2017-07-06 14:14:53.643052 [DEBUG] conference_member.c:104 verto.rtc/3500 binding '8' to 'energy equ'
  370. 2017-07-06 14:14:53.643052 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 8/conf/0 callback: 0x7f47b569f840 data: 0x7f47ac1274f0
  371. 2017-07-06 14:14:53.643052 [DEBUG] conference_member.c:104 verto.rtc/3500 binding '7' to 'energy dn'
  372. 2017-07-06 14:14:53.643052 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 7/conf/0 callback: 0x7f47b569f840 data: 0x7f47ac127520
  373. 2017-07-06 14:14:53.643052 [DEBUG] conference_member.c:104 verto.rtc/3500 binding '3' to 'vol talk up'
  374. 2017-07-06 14:14:53.643052 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 3/conf/0 callback: 0x7f47b569f840 data: 0x7f47ac127550
  375. 2017-07-06 14:14:53.643052 [DEBUG] conference_member.c:104 verto.rtc/3500 binding '2' to 'vol talk zero'
  376. 2017-07-06 14:14:53.643052 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 2/conf/0 callback: 0x7f47b569f840 data: 0x7f47ac127580
  377. 2017-07-06 14:14:53.643052 [DEBUG] conference_member.c:104 verto.rtc/3500 binding '1' to 'vol talk dn'
  378. 2017-07-06 14:14:53.643052 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 1/conf/0 callback: 0x7f47b569f840 data: 0x7f47ac1275b0
  379. 2017-07-06 14:14:53.643052 [DEBUG] conference_member.c:104 verto.rtc/3500 binding '6' to 'vol listen up'
  380. 2017-07-06 14:14:53.643052 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 6/conf/0 callback: 0x7f47b569f840 data: 0x7f47ac1275e0
  381. 2017-07-06 14:14:53.643052 [DEBUG] conference_member.c:104 verto.rtc/3500 binding '5' to 'vol listen zero'
  382. 2017-07-06 14:14:53.643052 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 5/conf/0 callback: 0x7f47b569f840 data: 0x7f47ac127610
  383. 2017-07-06 14:14:53.643052 [DEBUG] conference_member.c:104 verto.rtc/3500 binding '4' to 'vol listen dn'
  384. 2017-07-06 14:14:53.643052 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding 4/conf/0 callback: 0x7f47b569f840 data: 0x7f47ac127640
  385. 2017-07-06 14:14:53.643052 [DEBUG] conference_member.c:104 verto.rtc/3500 binding '#' to 'hangup'
  386. 2017-07-06 14:14:53.643052 [DEBUG] switch_ivr_async.c:326 Digit parser mod_conference: binding #/conf/0 callback: 0x7f47b569f840 data: 0x7f47ac127670
  387. 2017-07-06 14:14:53.643052 [DEBUG] conference_loop.c:1306 Setup timer soft success interval: 20  samples: 960 from codec opus
  388. 2017-07-06 14:14:53.643052 [DEBUG] mod_conference.c:227 Setup timer success interval: 20  samples: 960
  389. 2017-07-06 14:14:53.713052 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
  390. 2017-07-06 14:14:53.713052 [DEBUG] conference_video.c:3138 Setting up video write codec VP8 at slot 0 group _none_
  391. 2017-07-06 14:14:53.713052 [NOTICE] switch_vpx.c:406 VPX reset encoder picture from 320x240 to 1920x1080 1024 BW
  392. 2017-07-06 14:14:53.803058 [ERR] mod_verto.c:3989 MCAST Data Sent
  393. 2017-07-06 14:14:56.403054 [DEBUG] mod_local_stream.c:901 Opening Stream [moh/48000] 48000hz
  394. 2017-07-06 14:14:56.403054 [WARNING] switch_core_file.c:360 File has 1 channels, muxing to 2 channels will occur.