· 5 years ago · Dec 28, 2020, 10:50 AM
1WebSockets server started (port 8188)...
2Using certificates:
3 /usr/local/share/janus/fullchain.pem
4 /usr/local/share/janus/privkey.pem
5Secure WebSockets server started (port 8989)...
6[WARN] Admin WebSockets server disabled
7[WARN] Secure Admin WebSockets server disabled
8JANUS WebSockets transport plugin initialized!
9 Version: 1 (0.0.1)
10 [janus.transport.websockets] JANUS WebSockets transport plugin
11 This transport plugin adds WebSockets support to the Janus API via libwebsockets.
12 Plugin API version: 8
13 Janus API: enabled
14 Admin API: disabled
15WebSockets thread started
16[WSS-0x7f5142241560] 27 (LWS_CALLBACK_PROTOCOL_INIT)
17[WSS-0x7f5142241560] 27 (LWS_CALLBACK_PROTOCOL_INIT)
18[WSS-0x7f51422411f0] 72 ((null))
19[WSS-0x7f51422411f0] 72 ((null))
20[WSS-0x7f51422411f0] 72 ((null))
21[WSS-0x7f51422411f0] 72 ((null))
22[file-live-sample] Rewind! (/usr/local/share/janus/streams/radio.alaw)
23[WSS-0x7f5154029b10] 77 ((null))
24[WSS-0x7f5154029b10] 20 (LWS_CALLBACK_FILTER_PROTOCOL_CONNECTION)
25[WSS-0x7f5154029b10] 53 ((null))
26[WSS-0x7f5154029b10] WebSocket connection opened from ::ffff:10.0.2.126
27Checking if ::ffff:10.0.2.126 is allowed to contact janus interface
28Yep
29[WSS-0x7f5154029b10] WebSocket connection accepted
30[WSS-0x7f5154029b10] -- Ready to be used!
31[WSS-0x7f5154029b10] Got 47 bytes:
32[WSS-0x7f5154029b10] First fragment: 47 bytes, 0 remaining
33{"janus":"create","transaction":"GqCLewQHd1pi"}
34[WSS-0x7f5154029b10] Done, parsing message: 47 bytes
35Got a Janus API request from janus.transport.websockets (0x7f5154002420)
36Creating new session: 2697598798093630; 0x7f5160002a00
37Sending Janus API response to janus.transport.websockets (0x7f5154002420)
38[WSS-0x7f5154029b10] Sending WebSocket message (107 bytes)...
39[WSS-0x7f5154029b10] Re-allocating to 123 bytes (was 0, response is 107 bytes)
40[WSS-0x7f5154029b10] -- First=1, Last=1, Requested=107 bytes, Sent=107 bytes, Missing=0 bytes
41[WSS-0x7f5154029b10] Got 148 bytes:
42[WSS-0x7f5154029b10] First fragment: 148 bytes, 0 remaining
43{"janus":"attach","plugin":"janus.plugin.videoroom","opaque_id":"videoroom-GKclhH9l9ILX","transaction":"EQrlJUDelgiF","session_id":2697598798093630}
44[WSS-0x7f5154029b10] Done, parsing message: 148 bytes
45Got a Janus API request from janus.transport.websockets (0x7f5154002420)
46Creating new handle in session 2697598798093630: 1864389157180880; 0x7f5160002a00 0x7f5160003110
47Sending Janus API response to janus.transport.websockets (0x7f5154002420)
48[WSS-0x7f5154029b10] Sending WebSocket message (142 bytes)...
49[WSS-0x7f5154029b10] Re-allocating to 158 bytes (was 123, response is 142 bytes)
50[WSS-0x7f5154029b10] -- First=1, Last=1, Requested=142 bytes, Sent=142 bytes, Missing=0 bytes
51[1864389157180880] Handle thread started; 0x7f5160003110
52[ice.c:janus_ice_handle_thread:1164] [1864389157180880] Looping...
53[WSS-0x7f5154029b10] Got 146 bytes:
54[WSS-0x7f5154029b10] First fragment: 146 bytes, 0 remaining
55{"janus":"message","body":{"request":"exists","room":883},"transaction":"c6Vrn0kezPNL","session_id":2697598798093630,"handle_id":1864389157180880}
56[WSS-0x7f5154029b10] Done, parsing message: 146 bytes
57Got a Janus API request from janus.transport.websockets (0x7f5154002420)
58Transport task pool, serving request
59[1864389157180880] There's a message for JANUS VideoRoom plugin
60Creating plugin result...
61Sending Janus API response to janus.transport.websockets (0x7f5154002420)
62[WSS-0x7f5154029b10] Sending WebSocket message (296 bytes)...
63[WSS-0x7f5154029b10] Re-allocating to 312 bytes (was 158, response is 296 bytes)
64[WSS-0x7f5154029b10] -- First=1, Last=1, Requested=296 bytes, Sent=296 bytes, Missing=0 bytes
65Destroying plugin result...
66[WSS-0x7f5154029b10] Got 220 bytes:
67[WSS-0x7f5154029b10] First fragment: 220 bytes, 0 remaining
68{"janus":"message","body":{"request":"create","room":883,"fir_freq":10,"description":"Video room","bitrate":128000,"publishers":10},"transaction":"g93399fupU6s","session_id":2697598798093630,"handle_id":1864389157180880}
69[WSS-0x7f5154029b10] Done, parsing message: 220 bytes
70Got a Janus API request from janus.transport.websockets (0x7f5154002420)
71Transport task pool, serving request
72[1864389157180880] There's a message for JANUS VideoRoom plugin
73Creating a new VideoRoom room
74Created VideoRoom: 883 (Video room, public, opus/vp8 codecs, secret: no secret, pin: no pin, pvtid: optional)
75 ::: [5678][VP9-SVC Demo Room] 512000, max 6 publishers, FIR frequency of 10 seconds
76 ::: [1234][Demo Room] 128000, max 6 publishers, FIR frequency of 10 seconds
77 ::: [883][Video room] 128000, max 10 publishers, FIR frequency of 10 seconds
78Creating plugin result...
79Sending Janus API response to janus.transport.websockets (0x7f5154002420)
80[WSS-0x7f5154029b10] Sending WebSocket message (299 bytes)...
81[WSS-0x7f5154029b10] Re-allocating to 315 bytes (was 312, response is 299 bytes)
82[WSS-0x7f5154029b10] -- First=1, Last=1, Requested=299 bytes, Sent=299 bytes, Missing=0 bytes
83Destroying plugin result...
84[WSS-0x7f5154029b10] Got 230 bytes:
85[WSS-0x7f5154029b10] First fragment: 230 bytes, 0 remaining
86{"janus":"message","body":{"request":"join","room":883,"ptype":"publisher","display":"{\"id\":1609151752593,\"name\":\"User1609151752593\"}"},"transaction":"g1cH4kUptdQY","session_id":2697598798093630,"handle_id":1864389157180880}
87[WSS-0x7f5154029b10] Done, parsing message: 230 bytes
88Got a Janus API request from janus.transport.websockets (0x7f5154002420)
89Transport task pool, serving request
90[1864389157180880] There's a message for JANUS VideoRoom plugin
91Configuring new participant
92Configuring new publisher
93 -- Participant ID: 6949615700183917
94Preparing JSON event as a reply
95[1864389157180880] Sending event to transport...
96Sending event to janus.transport.websockets (0x7f5154002420)
97 >> 0 (Success)
98[WSS-0x7f5154029b10] Sending WebSocket message (400 bytes)...
99[WSS-0x7f5154029b10] Re-allocating to 416 bytes (was 315, response is 400 bytes)
100[WSS-0x7f5154029b10] -- First=1, Last=1, Requested=400 bytes, Sent=400 bytes, Missing=0 bytes
101Creating plugin result...
102Sending Janus API response to janus.transport.websockets (0x7f5154002420)
103[WSS-0x7f5154029b10] Sending WebSocket message (90 bytes)...
104[WSS-0x7f5154029b10] -- First=1, Last=1, Requested=90 bytes, Sent=90 bytes, Missing=0 bytes
105Destroying plugin result...
106[WSS-0x7f5154029b10] 78 ((null))
107[WSS-0x7f5154029b10] WS connection down, closing
108[WSS-0x7f5154029b10] Destroying WebSocket client
109A janus.transport.websockets transport instance has gone away (0x7f5154002420)
110 -- Session 2697598798093630 will be over if not reclaimed
111 -- Marking Session 2697598798093630 as over
112Destroying session 2697598798093630; 0x7f5160002a00
113[1864389157180880] Hanging up PeerConnection because of a Detach
114Detaching handle from JANUS VideoRoom plugin; 0x7f5160003110 0x7f515404cbf0 0x7f5160003110 0x7f5160004340
115[1864389157180880] Handle detached, scheduling destruction
116[WSS-0x7f5154029b10] -- closed
117[1864389157180880] Telling the plugin about the hangup (JANUS VideoRoom plugin)
118[janus.plugin.videoroom-0x7f515404cbf0] No WebRTC media anymore; 0x7f5160003110 0x7f5160004340
119[1864389157180880] Telling the plugin about the handle detach (JANUS VideoRoom plugin)
120[1864389157180880] Sending event to transport...; 0x7f5160003110
121[1864389157180880] Finalizing loop source
122[1864389157180880] Handle thread ended! 0x7f5160003110
123[1864389157180880] Handle and related resources freed; 0x7f5160003110 0x7f5160002a00
124[WSS-0x7f5154029b10] 77 ((null))
125[WSS-0x7f5154029b10] 20 (LWS_CALLBACK_FILTER_PROTOCOL_CONNECTION)
126[WSS-0x7f5154029b10] 53 ((null))
127[WSS-0x7f5154029b10] WebSocket connection opened from ::ffff:10.0.2.126
128Checking if ::ffff:10.0.2.126 is allowed to contact janus interface
129Yep
130[WSS-0x7f5154029b10] WebSocket connection accepted
131[WSS-0x7f5154029b10] -- Ready to be used!
132[WSS-0x7f5154029b10] Got 47 bytes:
133[WSS-0x7f5154029b10] First fragment: 47 bytes, 0 remaining
134{"janus":"create","transaction":"cCzH3hOHqQb1"}
135[WSS-0x7f5154029b10] Done, parsing message: 47 bytes
136Got a Janus API request from janus.transport.websockets (0x7f5154026660)
137Creating new session: 5662156617889035; 0x7f5154015850
138Sending Janus API response to janus.transport.websockets (0x7f5154026660)
139[WSS-0x7f5154029b10] Sending WebSocket message (107 bytes)...
140[WSS-0x7f5154029b10] Re-allocating to 123 bytes (was 0, response is 107 bytes)
141[WSS-0x7f5154029b10] -- First=1, Last=1, Requested=107 bytes, Sent=107 bytes, Missing=0 bytes
142[WSS-0x7f5154029b10] Got 148 bytes:
143[WSS-0x7f5154029b10] First fragment: 148 bytes, 0 remaining
144{"janus":"attach","plugin":"janus.plugin.videoroom","opaque_id":"videoroom-GKclhH9l9ILX","transaction":"DQ92qLkRiG5w","session_id":5662156617889035}
145[WSS-0x7f5154029b10] Done, parsing message: 148 bytes
146Got a Janus API request from janus.transport.websockets (0x7f5154026660)
147Creating new handle in session 5662156617889035: 450447704721074; 0x7f5154015850 0x7f5160003110
148Sending Janus API response to janus.transport.websockets (0x7f5154026660)
149[WSS-0x7f5154029b10] Sending WebSocket message (141 bytes)...
150[WSS-0x7f5154029b10] Re-allocating to 157 bytes (was 123, response is 141 bytes)
151[WSS-0x7f5154029b10] -- First=1, Last=1, Requested=141 bytes, Sent=141 bytes, Missing=0 bytes
152[450447704721074] Handle thread started; 0x7f5160003110
153[ice.c:janus_ice_handle_thread:1164] [450447704721074] Looping...
154[WSS-0x7f5154029b10] Got 145 bytes:
155[WSS-0x7f5154029b10] First fragment: 145 bytes, 0 remaining
156{"janus":"message","body":{"request":"exists","room":883},"transaction":"7fzhZ7Rh0osg","session_id":5662156617889035,"handle_id":450447704721074}
157[WSS-0x7f5154029b10] Done, parsing message: 145 bytes
158Got a Janus API request from janus.transport.websockets (0x7f5154026660)
159Transport task pool, serving request
160[450447704721074] There's a message for JANUS VideoRoom plugin
161Creating plugin result...
162Sending Janus API response to janus.transport.websockets (0x7f5154026660)
163Destroying plugin result...
164[WSS-0x7f5154029b10] Sending WebSocket message (294 bytes)...
165[WSS-0x7f5154029b10] Re-allocating to 310 bytes (was 157, response is 294 bytes)
166[WSS-0x7f5154029b10] -- First=1, Last=1, Requested=294 bytes, Sent=294 bytes, Missing=0 bytes
167[WSS-0x7f5154029b10] Got 229 bytes:
168[WSS-0x7f5154029b10] First fragment: 229 bytes, 0 remaining
169{"janus":"message","body":{"request":"join","room":883,"ptype":"publisher","display":"{\"id\":1609151752593,\"name\":\"User1609151752593\"}"},"transaction":"PClXoOS0krhw","session_id":5662156617889035,"handle_id":450447704721074}
170[WSS-0x7f5154029b10] Done, parsing message: 229 bytes
171Got a Janus API request from janus.transport.websockets (0x7f5154026660)
172Transport task pool, serving request
173[450447704721074] There's a message for JANUS VideoRoom plugin
174Creating plugin result...
175Sending Janus API response to janus.transport.websockets (0x7f5154026660)
176Destroying plugin result...
177Configuring new participant
178Configuring new publisher
179 -- Participant ID: 7025278577529568
180Preparing JSON event as a reply
181[450447704721074] Sending event to transport...
182Sending event to janus.transport.websockets (0x7f5154026660)
183 >> 0 (Success)
184[WSS-0x7f5154029b10] Sending WebSocket message (90 bytes)...
185[WSS-0x7f5154029b10] -- First=1, Last=1, Requested=90 bytes, Sent=90 bytes, Missing=0 bytes
186[WSS-0x7f5154029b10] Sending WebSocket message (399 bytes)...
187[WSS-0x7f5154029b10] Re-allocating to 415 bytes (was 310, response is 399 bytes)
188[WSS-0x7f5154029b10] -- First=1, Last=1, Requested=399 bytes, Sent=399 bytes, Missing=0 bytes
189[WSS-0x7f5154029b10] Got 4088 bytes:
190[WSS-0x7f5154029b10] First fragment: 4088 bytes, 2175 remaining
191{"janus":"message","body":{"request":"configure","audio":true,"video":true},"transaction":"doo0W8ZF6a5Y","jsep":{"type":"offer","sdp":"v=0\r\no=- 6483593611606552955 2 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=group:BUNDLE 0 1\r\na=msid-semantic: WMS qb9yMApbtPLloy36glKpZUQPFP0gAxzJi4XE\r\nm=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:HKb7\r\na=ice-pwd:IJgXA7dszBOylh4aiHl8ucYN\r\na=ice-options:trickle\r\na=fingerprint:sha-256 F2:49:DA:10:DD:C6:CD:F2:02:DE:F0:4D:C1:3C:D7:16:5B:43:DD:9D:98:42:F5:80:8C:CA:99:BA:CF:E2:45:9D\r\na=setup:actpass\r\na=mid:0\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=sendonly\r\na=msid:qb9yMApbtPLloy36glKpZUQPFP0gAxzJi4XE fa1bdc2d-85e5-41e2-b259-205ae6dad6f7\r\na=rtcp-mux\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=rtpmap:103 ISAC/16000\r\na=rtpmap:104 ISAC/32000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:106 CN/32000\r\na=rtpmap:105 CN/16000\r\na=rtpmap:13 CN/8000\r\na=rtpmap:110 telephone-event/48000\r\na=rtpmap:112 telephone-event/32000\r\na=rtpmap:113 telephone-event/16000\r\na=rtpmap:126 telephone-event/8000\r\na=ssrc:161292535 cname:LO/ziDvlW7n2zP4s\r\na=ssrc:161292535 msid:qb9yMApbtPLloy36glKpZUQPFP0gAxzJi4XE fa1bdc2d-85e5-41e2-b259-205ae6dad6f7\r\na=ssrc:161292535 mslabel:qb9yMApbtPLloy36glKpZUQPFP0gAxzJi4XE\r\na=ssrc:161292535 label:fa1bdc2d-85e5-41e2-b259-205ae6dad6f7\r\nm=video 9 UDP/TLS/RTP/SAVPF 96 97 98 99 100 101 102 121 127 120 125 107 108 109 124 119 123 118 114 115 116\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:HKb7\r\na=ice-pwd:IJgXA7dszBOylh4aiHl8ucYN\r\na=ice-options:trickle\r\na=fingerprint:sha-256 F2:49:DA:10:DD:C6:CD:F2:02:DE:F0:4D:C1:3C:D7:16:5B:43:DD:9D:98:42:F5:80:8C:CA:99:BA:CF:E2:45:9D\r\na=setup:actpass\r\na=mid:1\r\na=extmap:14 urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13 urn:3gpp:video-orientation\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=extmap:8 http://www.webrtc.org/experiments/rtp-hdrext/color-space\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=sendonly\r\na=msid:qb9yMApbtPLloy36glKpZUQPFP0gAxzJi4XE e27a0252-80f5-4c8e-9edb-2fcacc22a152\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:96 VP8/90000\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96 transport-cc\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96 nack pli\r\na=rtpmap:97 rtx/90000\r\na=fmtp:97 apt=96\r\na=rtpmap:98 VP9/90000\r\na=rtcp-fb:98 goog-remb\r\na=rtcp-fb:98 transport-cc\r\na=rtcp-fb:98 ccm fir\r\na=rtcp-fb:98 nack\r\na=rtcp-fb:98 nack pli\r\na=fmtp:98 profile-id=0\r\na=rtpmap:99 rtx/90000\r\na=fmtp:99 apt=98\r\na=rtpmap:100 VP9/90000\r\na=rtcp-fb:100 goog-remb\r\na=rtcp-fb:100 transport-cc\r\na=rtcp-fb:100 ccm fir\r\na=rtcp-fb:100 nack\r\na=rtcp-fb:100 nack pli\r\na=fmtp:100 profile-id=2\r\na=rtpmap:101 rtx/90000\r\na=fmtp:101 apt=100\r\na=rtpmap:102 H264/90000\r\na=rtcp-fb:102 goog-remb\r\na=rtcp-fb:102 transport-cc\r\na=rtcp-fb:102 ccm fir\r\na=rtcp-fb:102 nack\r\na=rtcp-fb:102 nack pli\r\na=fmtp:102 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42001f\r\na=rtpmap:121 rtx/90000\r\na=fmtp:121 apt=102\r\na=rtpmap:127 H264/90000\r\na=
192[WSS-0x7f5154029b10] Waiting for more fragments
193[WSS-0x7f5154029b10] Got 2175 bytes:
194[WSS-0x7f5154029b10] Appending fragment: offset 4088, 2175 bytes, 0 remaining
195rtcp-fb:127 goog-remb\r\na=rtcp-fb:127 transport-cc\r\na=rtcp-fb:127 ccm fir\r\na=rtcp-fb:127 nack\r\na=rtcp-fb:127 nack pli\r\na=fmtp:127 level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42001f\r\na=rtpmap:120 rtx/90000\r\na=fmtp:120 apt=127\r\na=rtpmap:125 H264/90000\r\na=rtcp-fb:125 goog-remb\r\na=rtcp-fb:125 transport-cc\r\na=rtcp-fb:125 ccm fir\r\na=rtcp-fb:125 nack\r\na=rtcp-fb:125 nack pli\r\na=fmtp:125 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f\r\na=rtpmap:107 rtx/90000\r\na=fmtp:107 apt=125\r\na=rtpmap:108 H264/90000\r\na=rtcp-fb:108 goog-remb\r\na=rtcp-fb:108 transport-cc\r\na=rtcp-fb:108 ccm fir\r\na=rtcp-fb:108 nack\r\na=rtcp-fb:108 nack pli\r\na=fmtp:108 level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42e01f\r\na=rtpmap:109 rtx/90000\r\na=fmtp:109 apt=108\r\na=rtpmap:124 H264/90000\r\na=rtcp-fb:124 goog-remb\r\na=rtcp-fb:124 transport-cc\r\na=rtcp-fb:124 ccm fir\r\na=rtcp-fb:124 nack\r\na=rtcp-fb:124 nack pli\r\na=fmtp:124 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=4d0032\r\na=rtpmap:119 rtx/90000\r\na=fmtp:119 apt=124\r\na=rtpmap:123 H264/90000\r\na=rtcp-fb:123 goog-remb\r\na=rtcp-fb:123 transport-cc\r\na=rtcp-fb:123 ccm fir\r\na=rtcp-fb:123 nack\r\na=rtcp-fb:123 nack pli\r\na=fmtp:123 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=640032\r\na=rtpmap:118 rtx/90000\r\na=fmtp:118 apt=123\r\na=rtpmap:114 red/90000\r\na=rtpmap:115 rtx/90000\r\na=fmtp:115 apt=114\r\na=rtpmap:116 ulpfec/90000\r\na=ssrc-group:FID 3835463777 2002304286\r\na=ssrc:3835463777 cname:LO/ziDvlW7n2zP4s\r\na=ssrc:3835463777 msid:qb9yMApbtPLloy36glKpZUQPFP0gAxzJi4XE e27a0252-80f5-4c8e-9edb-2fcacc22a152\r\na=ssrc:3835463777 mslabel:qb9yMApbtPLloy36glKpZUQPFP0gAxzJi4XE\r\na=ssrc:3835463777 label:e27a0252-80f5-4c8e-9edb-2fcacc22a152\r\na=ssrc:2002304286 cname:LO/ziDvlW7n2zP4s\r\na=ssrc:2002304286 msid:qb9yMApbtPLloy36glKpZUQPFP0gAxzJi4XE e27a0252-80f5-4c8e-9edb-2fcacc22a152\r\na=ssrc:2002304286 mslabel:qb9yMApbtPLloy36glKpZUQPFP0gAxzJi4XE\r\na=ssrc:2002304286 label:e27a0252-80f5-4c8e-9edb-2fcacc22a152\r\n"},"session_id":5662156617889035,"handle_id":450447704721074}
196[WSS-0x7f5154029b10] Done, parsing message: 6263 bytes
197Got a Janus API request from janus.transport.websockets (0x7f5154026660)
198(process:7): libnice-DEBUG: 10:36:04.837: Agent 0x7f515c0280a0 : Property set, changing role to "controlled".
199(process:7): libnice-DEBUG: 10:36:04.837: Created NiceStream (1 created, 0 destroyed)
200Transport task pool, serving request
201[450447704721074] There's a message for JANUS VideoRoom plugin
202[450447704721074] Remote SDP:
203v=0
204o=- 6483593611606552955 2 IN IP4 127.0.0.1
205s=-
206t=0 0
207a=group:BUNDLE 0 1
208a=msid-semantic: WMS qb9yMApbtPLloy36glKpZUQPFP0gAxzJi4XE
209m=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126
210c=IN IP4 0.0.0.0
211a=rtcp:9 IN IP4 0.0.0.0
212a=ice-ufrag:HKb7
213a=ice-pwd:IJgXA7dszBOylh4aiHl8ucYN
214a=ice-options:trickle
215a=fingerprint:sha-256 F2:49:DA:10:DD:C6:CD:F2:02:DE:F0:4D:C1:3C:D7:16:5B:43:DD:9D:98:42:F5:80:8C:CA:99:BA:CF:E2:45:9D
216a=setup:actpass
217a=mid:0
218a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
219a=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
220a=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
221a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid
222a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id
223a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id
224a=sendonly
225a=msid:qb9yMApbtPLloy36glKpZUQPFP0gAxzJi4XE fa1bdc2d-85e5-41e2-b259-205ae6dad6f7
226a=rtcp-mux
227a=rtpmap:111 opus/48000/2
228a=rtcp-fb:111 transport-cc
229a=fmtp:111 minptime=10;useinbandfec=1
230a=rtpmap:103 ISAC/16000
231a=rtpmap:104 ISAC/32000
232a=rtpmap:9 G722/8000
233a=rtpmap:0 PCMU/8000
234a=rtpmap:8 PCMA/8000
235a=rtpmap:106 CN/32000
236a=rtpmap:105 CN/16000
237a=rtpmap:13 CN/8000
238a=rtpmap:110 telephone-event/48000
239a=rtpmap:112 telephone-event/32000
240a=rtpmap:113 telephone-event/16000
241a=rtpmap:126 telephone-event/8000
242a=ssrc:161292535 cname:LO/ziDvlW7n2zP4s
243a=ssrc:161292535 msid:qb9yMApbtPLloy36glKpZUQPFP0gAxzJi4XE fa1bdc2d-85e5-41e2-b259-205ae6dad6f7
244a=ssrc:161292535 mslabel:qb9yMApbtPLloy36glKpZUQPFP0gAxzJi4XE
245a=ssrc:161292535 label:fa1bdc2d-85e5-41e2-b259-205ae6dad6f7
246m=video 9 UDP/TLS/RTP/SAVPF 96 97 98 99 100 101 102 121 127 120 125 107 108 109 124 119 123 118 114 115 116
247c=IN IP4 0.0.0.0
248a=rtcp:9 IN IP4 0.0.0.0
249a=ice-ufrag:HKb7
250a=ice-pwd:IJgXA7dszBOylh4aiHl8ucYN
251a=ice-options:trickle
252a=fingerprint:sha-256 F2:49:DA:10:DD:C6:CD:F2:02:DE:F0:4D:C1:3C:D7:16:5B:43:DD:9D:98:42:F5:80:8C:CA:99:BA:CF:E2:45:9D
253a=setup:actpass
254a=mid:1
255a=extmap:14 urn:ietf:params:rtp-hdrext:toffset
256a=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
257a=extmap:13 urn:3gpp:video-orientation
258a=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
259a=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay
260a=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type
261a=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing
262a=extmap:8 http://www.webrtc.org/experiments/rtp-hdrext/color-space
263a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid
264a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id
265a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id
266a=sendonly
267a=msid:qb9yMApbtPLloy36glKpZUQPFP0gAxzJi4XE e27a0252-80f5-4c8e-9edb-2fcacc22a152
268a=rtcp-mux
269a=rtcp-rsize
270a=rtpmap:96 VP8/90000
271a=rtcp-fb:96 goog-remb
272a=rtcp-fb:96 transport-cc
273a=rtcp-fb:96 ccm fir
274a=rtcp-fb:96 nack
275a=rtcp-fb:96 nack pli
276a=rtpmap:97 rtx/90000
277a=fmtp:97 apt=96
278a=rtpmap:98 VP9/90000
279a=rtcp-fb:98 goog-remb
280a=rtcp-fb:98 transport-cc
281a=rtcp-fb:98 ccm fir
282a=rtcp-fb:98 nack
283a=rtcp-fb:98 nack pli
284a=fmtp:98 profile-id=0
285a=rtpmap:99 rtx/90000
286a=fmtp:99 apt=98
287a=rtpmap:100 VP9/90000
288a=rtcp-fb:100 goog-remb
289a=rtcp-fb:100 transport-cc
290a=rtcp-fb:100 ccm fir
291a=rtcp-fb:100 nack
292a=rtcp-fb:100 nack pli
293a=fmtp:100 profile-id=2
294a=rtpmap:101 rtx/90000
295a=fmtp:101 apt=100
296a=rtpmap:102 H264/90000
297a=rtcp-fb:102 goog-remb
298a=rtcp-fb:102 transport-cc
299a=rtcp-fb:102 ccm fir
300a=rtcp-fb:102 nack
301a=rtcp-fb:102 nack pli
302a=fmtp:102 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42001f
303a=rtpmap:121 rtx/90000
304a=fmtp:121 apt=102
305a=rtpmap:127 H264/90000
306a=rtcp-fb:127 goog-remb
307a=rtcp-fb:127 transport-cc
308a=rtcp-fb:127 ccm fir
309a=rtcp-fb:127 nack
310a=rtcp-fb:127 nack pli
311a=fmtp:127 level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42001f
312a=rtpmap:120 rtx/90000
313a=fmtp:120 apt=127
314a=rtpmap:125 H264/90000
315a=rtcp-fb:125 goog-remb
316a=rtcp-fb:125 transport-cc
317a=rtcp-fb:125 ccm fir
318a=rtcp-fb:125 nack
319a=rtcp-fb:125 nack pli
320a=fmtp:125 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f
321a=rtpmap:107 rtx/90000
322a=fmtp:107 apt=125
323a=rtpmap:108 H264/90000
324a=rtcp-fb:108 goog-remb
325a=rtcp-fb:108 transport-cc
326a=rtcp-fb:108 ccm fir
327a=rtcp-fb:108 nack
328a=rtcp-fb:108 nack pli
329a=fmtp:108 level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42e01f
330a=rtpmap:109 rtx/90000
331a=fmtp:109 apt=108
332a=rtpmap:124 H264/90000
333a=rtcp-fb:124 goog-remb
334a=rtcp-fb:124 transport-cc
335a=rtcp-fb:124 ccm fir
336a=rtcp-fb:124 nack
337a=rtcp-fb:124 nack pli
338a=fmtp:124 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=4d0032
339a=rtpmap:119 rtx/90000
340a=fmtp:119 apt=124
341a=rtpmap:123 H264/90000
342a=rtcp-fb:123 goog-remb
343a=rtcp-fb:123 transport-cc
344a=rtcp-fb:123 ccm fir
345a=rtcp-fb:123 nack
346a=rtcp-fb:123 nack pli
347a=fmtp:123 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=640032
348a=rtpmap:118 rtx/90000
349a=fmtp:118 apt=123
350a=rtpmap:114 red/90000
351a=rtpmap:115 rtx/90000
352a=fmtp:115 apt=114
353a=rtpmap:116 ulpfec/90000
354a=ssrc-group:FID 3835463777 2002304286
355a=ssrc:3835463777 cname:LO/ziDvlW7n2zP4s
356a=ssrc:3835463777 msid:qb9yMApbtPLloy36glKpZUQPFP0gAxzJi4XE e27a0252-80f5-4c8e-9edb-2fcacc22a152
357a=ssrc:3835463777 mslabel:qb9yMApbtPLloy36glKpZUQPFP0gAxzJi4XE
358a=ssrc:3835463777 label:e27a0252-80f5-4c8e-9edb-2fcacc22a152
359a=ssrc:2002304286 cname:LO/ziDvlW7n2zP4s
360a=ssrc:2002304286 msid:qb9yMApbtPLloy36glKpZUQPFP0gAxzJi4XE e27a0252-80f5-4c8e-9edb-2fcacc22a152
361a=ssrc:2002304286 mslabel:qb9yMApbtPLloy36glKpZUQPFP0gAxzJi4XE
362a=ssrc:2002304286 label:e27a0252-80f5-4c8e-9edb-2fcacc22a152
363[450447704721074] Audio mid: 0
364[450447704721074] Video mid: 1
365[450447704721074] Audio has been negotiated, Video has been negotiated, SCTP/DataChannels have NOT been negotiated
366[450447704721074] Setting ICE locally: got OFFER (1 audios, 1 videos)
367[450447704721074] Creating ICE agent (ICE Full mode, controlled)
368[450447704721074] Adding 10.0.1.218 to the addresses to gather candidates for
369(process:7): libnice-DEBUG: 10:36:04.837: Created NiceComponent (1 created, 0 destroyed)
370(process:7): libnice-DEBUG: 10:36:04.837: Agent 0x7f515c0280a0 : allocating stream id 1 (0x7f515c02a5f0)
371(process:7): libnice-DEBUG: 10:36:04.837: Agent 0x7f515c0280a0 : In ICE-FULL mode, starting candidate gathering.
372(process:7): libnice-DEBUG: 10:36:04.837: Interface: lo
373(process:7): libnice-DEBUG: 10:36:04.837: IP Address: 127.0.0.1
374(process:7): libnice-DEBUG: 10:36:04.837: Interface: eth0
375(process:7): libnice-DEBUG: 10:36:04.837: IP Address: 10.0.1.218
376(process:7): libnice-DEBUG: 10:36:04.838: Agent 0x7f515c0280a0: Could not set IPV6 socket ToS: Protocol not available
377(process:7): libnice-DEBUG: 10:36:04.838: Component 0x7f515c02d400: Attach source (stream 1).
378(process:7): libnice-DEBUG: 10:36:04.838: Attaching source 0x7f515c038850 (socket 0x7f515c024730, FD 47) to context 0x7f515c030830
379(process:7): libnice-DEBUG: 10:36:04.838: Agent 0x7f515c0280a0: s1/c1: creation of host candidate udp:[10.0.1.218]:30600: success
380(process:7): libnice-DEBUG: 10:36:04.838: Agent 0x7f515c0280a0 : Adding new srv-rflx candidate discovery 0x7f515c039880
381(process:7): libnice-DEBUG: 10:36:04.838: Agent 0x7f515c0280a0 : stream 1 component 1 STATE-CHANGE disconnected -> gathering.
382(process:7): libnice-DEBUG: 10:36:04.838: Agent 0x7f515c0280a0 : discovery tick #1 with list 0x7f515c018150 (1)
383(process:7): libnice-DEBUG: 10:36:04.838: Agent 0x7f515c0280a0 : discovery - scheduling cand type 1 addr 18.222.209.43.
384[450447704721074] Component state changed for component 1 in stream 1: 1 (gathering)
385(process:7): libnice-DEBUG: 10:36:04.838: Detach source 0x7f515c038850, socket 0x7f515c024730.
386(process:7): libnice-DEBUG: 10:36:04.838: Detaching source 0x7f515c038850 (socket 0x7f515c024730, FD 47) from context 0x7f515c030830
387(process:7): libnice-DEBUG: 10:36:04.838: Reattach source (nil).
388(process:7): libnice-DEBUG: 10:36:04.838: Detaching source (nil) (socket 0x7f515c024730, FD 47) from context (nil)
389(process:7): libnice-DEBUG: 10:36:04.838: Attaching source 0x7f515c038b50 (socket 0x7f515c024730, FD 47) to context 0x7f51600043a0
390[450447704721074] Parsing audio candidates (stream=1)...
391[450447704721074] ICE ufrag (local): HKb7
392[450447704721074] ICE pwd (local): IJgXA7dszBOylh4aiHl8ucYN
393[450447704721074] Fingerprint (local) : sha-256 F2:49:DA:10:DD:C6:CD:F2:02:DE:F0:4D:C1:3C:D7:16:5B:43:DD:9D:98:42:F5:80:8C:CA:99:BA:CF:E2:45:9D
394[450447704721074] DTLS setup (local): actpass
395[450447704721074] Setting connect state (DTLS client)
396[450447704721074] Audio mid: 0
397[450447704721074] Setting remote credentials...
398(process:7): libnice-DEBUG: 10:36:04.838: Agent 0x7f515c0280a0: set_remote_credentials 1
399(process:7): libnice-DEBUG: 10:36:04.838: Agent 0x7f515c0280a0 : conn_check_remote_candidates_set 1 1
400[450447704721074] Peer audio SSRC: 161292535
401[450447704721074] Parsing video candidates (stream=1)...
402[450447704721074] ICE ufrag (local): HKb7
403[450447704721074] ICE pwd (local): IJgXA7dszBOylh4aiHl8ucYN
404[450447704721074] Fingerprint (local) : sha-256 F2:49:DA:10:DD:C6:CD:F2:02:DE:F0:4D:C1:3C:D7:16:5B:43:DD:9D:98:42:F5:80:8C:CA:99:BA:CF:E2:45:9D
405[450447704721074] DTLS setup (local): actpass
406[450447704721074] Setting connect state (DTLS client)
407[450447704721074] Video mid: 1
408[450447704721074] Peer video SSRC: 3835463777
409[450447704721074] Peer video SSRC (rtx): 2002304286
410Will remove payload type 97 (97 rtx/90000)
411Will remove payload type 99 (99 rtx/90000)
412Will remove payload type 101 (101 rtx/90000)
413Will remove payload type 121 (121 rtx/90000)
414Will remove payload type 120 (120 rtx/90000)
415Will remove payload type 107 (107 rtx/90000)
416Will remove payload type 109 (109 rtx/90000)
417Will remove payload type 119 (119 rtx/90000)
418Will remove payload type 118 (118 rtx/90000)
419Will remove payload type 114 (114 red/90000)
420Will remove payload type 115 (115 rtx/90000)
421Will remove payload type 116 (116 ulpfec/90000)
422 -------------------------------------------
423 >> Anonymized
424 -------------------------------------------
425Setting audio property: true (room 883, user 7025278577529568)
426Setting video property: true (room 883, user 7025278577529568)
427Preparing JSON event as a reply
428This is involving a negotiation (offer) as well:
429v=0
430o=- 6483593611606552955 2 IN IP4 1.1.1.1
431s=-
432t=0 0
433m=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126
434c=IN IP4 1.1.1.1
435a=sendonly
436a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
437a=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
438a=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
439a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid
440a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id
441a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id
442a=rtpmap:111 opus/48000/2
443a=rtcp-fb:111 transport-cc
444a=fmtp:111 minptime=10;useinbandfec=1
445a=rtpmap:103 ISAC/16000
446a=rtpmap:104 ISAC/32000
447a=rtpmap:9 G722/8000
448a=rtpmap:0 PCMU/8000
449a=rtpmap:8 PCMA/8000
450a=rtpmap:106 CN/32000
451a=rtpmap:105 CN/16000
452a=rtpmap:13 CN/8000
453a=rtpmap:110 telephone-event/48000
454a=rtpmap:112 telephone-event/32000
455a=rtpmap:113 telephone-event/16000
456a=rtpmap:126 telephone-event/8000
457m=video 9 UDP/TLS/RTP/SAVPF 96 98 100 102 127 125 108 124 123
458c=IN IP4 1.1.1.1
459a=sendonly
460a=extmap:14 urn:ietf:params:rtp-hdrext:toffset
461a=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
462a=extmap:13 urn:3gpp:video-orientation
463a=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
464a=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay
465a=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type
466a=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing
467a=extmap:8 http://www.webrtc.org/experiments/rtp-hdrext/color-space
468a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid
469a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id
470a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id
471a=rtpmap:96 VP8/90000
472a=rtcp-fb:96 goog-remb
473a=rtcp-fb:96 transport-cc
474a=rtcp-fb:96 ccm fir
475a=rtcp-fb:96 nack
476a=rtcp-fb:96 nack pli
477a=rtpmap:98 VP9/90000
478a=rtcp-fb:98 goog-remb
479a=rtcp-fb:98 transport-cc
480a=rtcp-fb:98 ccm fir
481a=rtcp-fb:98 nack
482a=rtcp-fb:98 nack pli
483a=fmtp:98 profile-id=0
484a=rtpmap:100 VP9/90000
485a=rtcp-fb:100 goog-remb
486a=rtcp-fb:100 transport-cc
487a=rtcp-fb:100 ccm fir
488a=rtcp-fb:100 nack
489a=rtcp-fb:100 nack pli
490a=fmtp:100 profile-id=2
491a=rtpmap:102 H264/90000
492a=rtcp-fb:102 goog-remb
493a=rtcp-fb:102 transport-cc
494a=rtcp-fb:102 ccm fir
495a=rtcp-fb:102 nack
496a=rtcp-fb:102 nack pli
497a=fmtp:102 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42001f
498a=rtpmap:127 H264/90000
499a=rtcp-fb:127 goog-remb
500a=rtcp-fb:127 transport-cc
501a=rtcp-fb:127 ccm fir
502a=rtcp-fb:127 nack
503a=rtcp-fb:127 nack pli
504a=fmtp:127 level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42001f
505a=rtpmap:125 H264/90000
506a=rtcp-fb:125 goog-remb
507a=rtcp-fb:125 transport-cc
508a=rtcp-fb:125 ccm fir
509a=rtcp-fb:125 nack
510a=rtcp-fb:125 nack pli
511a=fmtp:125 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f
512a=rtpmap:108 H264/90000
513a=rtcp-fb:108 goog-remb
514a=rtcp-fb:108 transport-cc
515a=rtcp-fb:108 ccm fir
516a=rtcp-fb:108 nack
517a=rtcp-fb:108 nack pli
518a=fmtp:108 level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42e01f
519a=rtpmap:124 H264/90000
520a=rtcp-fb:124 goog-remb
521a=rtcp-fb:124 transport-cc
522a=rtcp-fb:124 ccm fir
523a=rtcp-fb:124 nack
524a=rtcp-fb:124 nack pli
525a=fmtp:124 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=4d0032
526a=rtpmap:123 H264/90000
527a=rtcp-fb:123 goog-remb
528a=rtcp-fb:123 transport-cc
529a=rtcp-fb:123 ccm fir
530a=rtcp-fb:123 nack
531a=rtcp-fb:123 nack pli
532a=fmtp:123 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=640032
533
534The publisher is going to send an audio stream
535The publisher is going to send a video stream
536The publisher is NOT going to open a data channel
537The publisher is going to use the opus audio codec
538The publisher is going to use the vp8 video codec
539Per the answer, the publisher is going to send an audio stream
540Per the answer, the publisher is going to send a video stream
541Per the answer, the publisher is NOT going to open a data channel
542Handling publisher: turned this into an 'answer':
543v=0
544o=- 6483593611606552955 2 IN IP4 1.1.1.1
545s=VideoRoom 883
546t=0 0
547c=IN IP4 127.0.0.1
548m=audio 9 UDP/TLS/RTP/SAVPF 111
549c=IN IP4 127.0.0.1
550a=recvonly
551a=rtpmap:111 opus/48000/2
552a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
553a=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
554a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid
555a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id
556a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id
557m=video 9 UDP/TLS/RTP/SAVPF 96
558c=IN IP4 127.0.0.1
559a=recvonly
560a=rtpmap:96 VP8/90000
561a=rtcp-fb:96 ccm fir
562a=rtcp-fb:96 nack
563a=rtcp-fb:96 nack pli
564a=rtcp-fb:96 goog-remb
565a=rtcp-fb:96 transport-cc
566a=extmap:13 urn:3gpp:video-orientation
567a=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
568a=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay
569a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid
570a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id
571a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id
572
573[WSS-0x7f5154029b10] Got 285 bytes:
574[WSS-0x7f5154029b10] First fragment: 285 bytes, 0 remaining
575{"janus":"trickle","candidate":{"candidate":"candidate:2222700650 1 udp 2122260223 192.168.1.105 50404 typ host generation 0 ufrag HKb7 network-id 1 network-cost 10","sdpMid":"0","sdpMLineIndex":0},"transaction":"UM42hXwY9K8P","session_id":5662156617889035,"handle_id":450447704721074}
576[WSS-0x7f5154029b10] Done, parsing message: 285 bytes
577Got a Janus API request from janus.transport.websockets (0x7f5154026660)
578[450447704721074] Trickle candidate (0): candidate:2222700650 1 udp 2122260223 192.168.1.105 50404 typ host generation 0 ufrag HKb7 network-id 1 network-cost 10
579[450447704721074] Adding remote candidate component:1 stream:1 type:host 192.168.1.105:50404
580[450447704721074] Transport: UDP
581[450447704721074] Candidate added to the list! (1 elements for 1/1)
582[450447704721074] SDP processed but ICE not started yet for this component, setting candidates we have up to now
583[450447704721074] ## Setting remote candidates: stream 1, component 1 (1 in the list)
584[450447704721074] Queueing candidate 0x7f515c031e40 (startup)
585Sending Janus API response to janus.transport.websockets (0x7f5154026660)
586[WSS-0x7f5154029b10] Sending WebSocket message (90 bytes)...
587[WSS-0x7f5154029b10] -- First=1, Last=1, Requested=90 bytes, Sent=90 bytes, Missing=0 bytes
588Creating plugin result...
589Sending Janus API response to janus.transport.websockets (0x7f5154026660)
590[WSS-0x7f5154029b10] Sending WebSocket message (90 bytes)...
591[WSS-0x7f5154029b10] -- First=1, Last=1, Requested=90 bytes, Sent=90 bytes, Missing=0 bytes
592Destroying plugin result...
593[450447704721074] Processing candidate 0x7f515c031e40
594(process:7): libnice-DEBUG: 10:36:04.839: Agent 0x7f515c0280a0: set_remote_candidates 1 1
595(process:7): libnice-DEBUG: 10:36:04.840: Agent 0x7f515c0280a0 : Adding UDP remote candidate with addr [192.168.1.105]:50404 for s1/c1. U/P '(null)'/'(null)' prio: 7e7f1eff
596(process:7): libnice-DEBUG: 10:36:04.840: Agent 0x7f515c0280a0 : creating a new pair
597(process:7): libnice-DEBUG: 10:36:04.840: Agent 0x7f515c0280a0 : pair 0x7f5158016010 state FROZEN (priv_add_new_check_pair)
598(process:7): libnice-DEBUG: 10:36:04.840: Agent 0x7f515c0280a0 : new pair 0x7f5158016010 : [10.0.1.218]:30600 --> [192.168.1.105]:50404
599(process:7): libnice-DEBUG: 10:36:04.840: Interface: lo
600(process:7): libnice-DEBUG: 10:36:04.840: IP Address: 127.0.0.1
601(process:7): libnice-DEBUG: 10:36:04.840: Interface: eth0
602(process:7): libnice-DEBUG: 10:36:04.840: IP Address: 10.0.1.218
603(process:7): libnice-DEBUG: 10:36:04.840: Agent 0x7f515c0280a0 : added a new pair 0x7f5158016010 with foundation '1:2222700650' and transport udp:udp to stream 1 component 1
604(process:7): libnice-DEBUG: 10:36:04.840: Agent 0x7f515c0280a0 : stream 1 component 1 STATE-CHANGE gathering -> connecting.
605(process:7): libnice-DEBUG: 10:36:04.840: Agent 0x7f515c0280a0 : conn_check_remote_candidates_set 1 1
606[450447704721074] Component state changed for component 1 in stream 1: 2 (connecting)
607[WSS-0x7f5154029b10] Got 285 bytes:
608[WSS-0x7f5154029b10] First fragment: 285 bytes, 0 remaining
609{"janus":"trickle","candidate":{"candidate":"candidate:2222700650 1 udp 2122260223 192.168.1.105 56485 typ host generation 0 ufrag HKb7 network-id 1 network-cost 10","sdpMid":"1","sdpMLineIndex":1},"transaction":"dOCoDvpWFISW","session_id":5662156617889035,"handle_id":450447704721074}
610[WSS-0x7f5154029b10] Done, parsing message: 285 bytes
611Got a Janus API request from janus.transport.websockets (0x7f5154026660)
612[450447704721074] Trickle candidate (1): candidate:2222700650 1 udp 2122260223 192.168.1.105 56485 typ host generation 0 ufrag HKb7 network-id 1 network-cost 10
613[450447704721074] Got a mid='1' candidate (index 1) but we're bundling, ignoring...
614Sending Janus API response to janus.transport.websockets (0x7f5154026660)
615[WSS-0x7f5154029b10] Sending WebSocket message (90 bytes)...
616[WSS-0x7f5154029b10] -- First=1, Last=1, Requested=90 bytes, Sent=90 bytes, Missing=0 bytes
617[450447704721074] 1 remote candidate added
618(process:7): libnice-DEBUG: 10:36:04.841: Agent 0x7f515c0280a0: inbound STUN packet for 1/1 (stream/component) from [18.222.209.43]:3479 (68 octets) :
619(process:7): libnice-stun-DEBUG: 10:36:04.841: STUN demux error: no cookie!
620(process:7): libnice-stun-DEBUG: 10:36:04.841: STUN unknown: 0 mandatory attribute(s)!
621(process:7): libnice-stun-DEBUG: 10:36:04.841: Received 68-bytes STUN message
622(process:7): libnice-stun-DEBUG: 10:36:04.841: No XOR-MAPPED-ADDRESS: 1
623(process:7): libnice-stun-DEBUG: 10:36:04.841: Mapped address found!
624(process:7): libnice-DEBUG: 10:36:04.841: Agent 0x7f515c0280a0 : stun_bind_process/disc for 0x7f515c039880 res 0.
625(process:7): libnice-DEBUG: 10:36:04.841: Interface: lo
626(process:7): libnice-DEBUG: 10:36:04.841: IP Address: 127.0.0.1
627(process:7): libnice-DEBUG: 10:36:04.841: Interface: eth0
628(process:7): libnice-DEBUG: 10:36:04.841: IP Address: 10.0.1.218
629(process:7): libnice-DEBUG: 10:36:04.841: agent_recv_message_unlocked: Valid STUN packet received.
630(process:7): libnice-DEBUG: 10:36:04.859: Agent 0x7f515c0280a0 : Candidate gathering FINISHED, stopping discovery timer.
631(process:7): libnice-DEBUG: 10:36:04.859: Agent 0x7f515c0280a0: gathered UDP local candidate : [10.0.1.218]:30600 for s1/c1. U/P '(null)'/'(null)'
632(process:7): libnice-DEBUG: 10:36:04.859: Agent 0x7f515c0280a0: gathered UDP local candidate : [3.137.108.204]:39570 for s1/c1. U/P '(null)'/'(null)'
633[450447704721074] Gathering done for stream 1
634(process:7): libnice-DEBUG: 10:36:04.859: Agent 0x7f515c0280a0 : Pair 0x7f5158016010 with s/c-id 1/1 (1:2222700650) unfrozen.
635(process:7): libnice-DEBUG: 10:36:04.859: Agent 0x7f515c0280a0 : pair 0x7f5158016010 state WAITING (priv_conn_check_unfreeze_next)
636(process:7): libnice-DEBUG: 10:36:04.859: Agent 0x7f515c0280a0 : pair 0x7f5158016010 state IN_PROGRESS (priv_conn_check_initiate)
637(process:7): libnice-DEBUG: 10:36:04.859: Agent 0x7f515c0280a0 : STUN-CC REQ [10.0.1.218]:30600 --> [192.168.1.105]:50404, socket=47, pair=0x7f5158016010 (c-id:1), tie=14450994160176994371, username='HKb7:8Pq7' (9), password='IJgXA7dszBOylh4aiHl8ucYN' (24), prio=6e2000ff, controlled.
638(process:7): libnice-DEBUG: 10:36:04.859: Agent 0x7f515c0280a0 : conn_check_send: set cand_use=0 (aggressive nomination).
639(process:7): libnice-stun-DEBUG: 10:36:04.859: Message HMAC-SHA1 message integrity:
640(process:7): libnice-stun-DEBUG: 10:36:04.859: key : 0x494a6758413764737a424f796c68346169486c387563594e
641(process:7): libnice-stun-DEBUG: 10:36:04.859: sent : 0xb658c2b320901ed72f392bc474809661e67568cb
642(process:7): libnice-stun-DEBUG: 10:36:04.859: Message HMAC-SHA1 fingerprint: 0x37b527e7
643(process:7): libnice-DEBUG: 10:36:04.859: Agent 0x7f515c0280a0: conncheck created 88 - 0x7f515801b748
644(process:7): libnice-DEBUG: 10:36:04.859: Agent 0x7f515c0280a0 : timer set to 500ms, waiting+in_progress=1
645(process:7): libnice-DEBUG: 10:36:04.859: Agent 0x7f515c0280a0 : stream 1: timer tick #1: 0 frozen, 1 in-progress, 0 waiting, 0 succeeded, 0 discovered, 0 nominated, 0 waiting-for-nom, 0 valid
646(process:7): libnice-stun-DEBUG: 10:36:04.859: Message HMAC-SHA1 fingerprint: 0x5f42540d
647(process:7): libnice-DEBUG: 10:36:04.859: Agent 0x7f515c0280a0 : resending STUN to keep the local candidate 10.0.1.218:30600 alive in s1/c1.
648 -------------------------------------------
649 >> Anonymized
650 -------------------------------------------
651[450447704721074] We have 2 candidates for Stream #1, Component #1
652[450447704721074] Address: 10.0.1.218:30600
653[450447704721074] Priority: 2015363327
654[450447704721074] Foundation: 1
655[450447704721074] 1 1 udp 2015363327 10.0.1.218 30600 typ host
656[450447704721074] Address: 3.137.108.204:39570
657[450447704721074] Priority: 1679819007
658[450447704721074] Foundation: 2
659[450447704721074] 2 1 udp 1679819007 3.137.108.204 39570 typ srflx raddr 10.0.1.218 rport 30600
660[450447704721074] We have 2 candidates for Stream #1, Component #1
661[450447704721074] Address: 10.0.1.218:30600
662[450447704721074] Priority: 2015363327
663[450447704721074] Foundation: 1
664[450447704721074] 1 1 udp 2015363327 10.0.1.218 30600 typ host
665[450447704721074] Address: 3.137.108.204:39570
666[450447704721074] Priority: 1679819007
667[450447704721074] Foundation: 2
668[450447704721074] 2 1 udp 1679819007 3.137.108.204 39570 typ srflx raddr 10.0.1.218 rport 30600
669 -------------------------------------------
670 >> Merged (2404 bytes)
671 -------------------------------------------
672v=0
673o=- 6483593611606552955 2 IN IP4 3.137.108.204
674s=VideoRoom 883
675t=0 0
676a=group:BUNDLE 0 1
677a=msid-semantic: WMS janus
678m=audio 9 UDP/TLS/RTP/SAVPF 111
679c=IN IP4 3.137.108.204
680a=recvonly
681a=mid:0
682a=rtcp-mux
683a=ice-ufrag:8Pq7
684a=ice-pwd:h3dareNxg0d3ew5ftiOrzF
685a=ice-options:trickle
686a=fingerprint:sha-256 73:33:21:DC:26:4C:46:BA:B2:6A:39:68:7C:E2:7B:28:4D:87:B5:59:8F:5A:00:89:23:01:F1:76:69:77:47:C9
687a=setup:active
688a=rtpmap:111 opus/48000/2
689a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
690a=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
691a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid
692a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id
693a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id
694a=msid:janus janusa0
695a=ssrc:3332106274 cname:janus
696a=ssrc:3332106274 msid:janus janusa0
697a=ssrc:3332106274 mslabel:janus
698a=ssrc:3332106274 label:janusa0
699a=candidate:1 1 udp 2015363327 10.0.1.218 30600 typ host
700a=candidate:2 1 udp 1679819007 3.137.108.204 39570 typ srflx raddr 10.0.1.218 rport 30600
701a=end-of-candidates
702m=video 9 UDP/TLS/RTP/SAVPF 96 97
703c=IN IP4 3.137.108.204
704a=recvonly
705a=mid:1
706a=rtcp-mux
707a=ice-ufrag:8Pq7
708a=ice-pwd:h3dareNxg0d3ew5ftiOrzF
709a=ice-options:trickle
710a=fingerprint:sha-256 73:33:21:DC:26:4C:46:BA:B2:6A:39:68:7C:E2:7B:28:4D:87:B5:59:8F:5A:00:89:23:01:F1:76:69:77:47:C9
711a=setup:active
712a=rtpmap:96 VP8/90000
713a=rtcp-fb:96 ccm fir
714a=rtcp-fb:96 nack
715a=rtcp-fb:96 nack pli
716a=rtcp-fb:96 goog-remb
717a=rtcp-fb:96 transport-cc
718a=extmap:13 urn:3gpp:video-orientation
719a=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
720a=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay
721a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid
722a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id
723a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id
724a=rtpmap:97 rtx/90000
725a=fmtp:97 apt=96
726a=msid:janus janusv0
727a=ssrc:305850080 cname:janus
728a=ssrc:305850080 msid:janus janusv0
729a=ssrc:305850080 mslabel:janus
730a=ssrc:305850080 label:janusv0
731a=ssrc:4005639875 cname:janus
732a=ssrc:4005639875 msid:janus janusv0
733a=ssrc:4005639875 mslabel:janus
734a=ssrc:4005639875 label:janusv0
735a=candidate:1 1 udp 2015363327 10.0.1.218 30600 typ host
736a=candidate:2 1 udp 1679819007 3.137.108.204 39570 typ srflx raddr 10.0.1.218 rport 30600
737a=end-of-candidates
738
739[450447704721074] Sending answer, ready to setup remote candidates and send connectivity checks...
740[450447704721074] Done! Sending connectivity checks...
741[450447704721074] Component 1 in stream 1 has already been set up
742[450447704721074] Sending event to transport...
743Sending event to janus.transport.websockets (0x7f5154026660)
744 >> Pushing event: 0 (took 20732 us)
745[WSS-0x7f5154029b10] Sending WebSocket message (2952 bytes)...
746[WSS-0x7f5154029b10] Re-allocating to 2968 bytes (was 415, response is 2952 bytes)
747[WSS-0x7f5154029b10] -- First=1, Last=0, Requested=2800 bytes, Sent=2800 bytes, Missing=152 bytes
748[WSS-0x7f5154029b10] -- Couldn't write all bytes (152 missing), setting offset 2816
749[WSS-0x7f5154029b10] Completing pending WebSocket write (still need to write last 152 bytes)...
750[WSS-0x7f5154029b10] -- First=0, Last=1, Requested=152 bytes, Sent=152 bytes, Missing=0 bytes
751(process:7): libnice-DEBUG: 10:36:04.860: Agent 0x7f515c0280a0: inbound STUN packet for 1/1 (stream/component) from [18.222.209.43]:3479 (84 octets) :
752(process:7): libnice-stun-DEBUG: 10:36:04.860: STUN demux: OK!
753(process:7): libnice-DEBUG: 10:36:04.861: Agent 0x7f515c0280a0 : Valid STUN response for which we don't have a request, ignoring
754(process:7): libnice-DEBUG: 10:36:04.861: agent_recv_message_unlocked: Valid STUN packet received.
755(process:7): libnice-stun-DEBUG: 10:36:04.880: Message HMAC-SHA1 fingerprint: 0x09639329
756[WSS-0x7f5154029b10] Got 317 bytes:
757[WSS-0x7f5154029b10] First fragment: 317 bytes, 0 remaining
758{"janus":"trickle","candidate":{"candidate":"candidate:87277278 1 udp 1686052607 202.94.161.175 50404 typ srflx raddr 192.168.1.105 rport 50404 generation 0 ufrag HKb7 network-id 1 network-cost 10","sdpMid":"0","sdpMLineIndex":0},"transaction":"sXstICiqWGg5","session_id":5662156617889035,"handle_id":450447704721074}
759[WSS-0x7f5154029b10] Done, parsing message: 317 bytes
760Got a Janus API request from janus.transport.websockets (0x7f5154026660)
761[WSS-0x7f5154029b10] Got 317 bytes:
762[WSS-0x7f5154029b10] First fragment: 317 bytes, 0 remaining
763{"janus":"trickle","candidate":{"candidate":"candidate:87277278 1 udp 1686052607 202.94.161.175 56485 typ srflx raddr 192.168.1.105 rport 56485 generation 0 ufrag HKb7 network-id 1 network-cost 10","sdpMid":"1","sdpMLineIndex":1},"transaction":"XlVkK4aA6LAL","session_id":5662156617889035,"handle_id":450447704721074}
764[WSS-0x7f5154029b10] Done, parsing message: 317 bytes
765Got a Janus API request from janus.transport.websockets (0x7f5154026660)
766[WSS-0x7f5154029b10] Got 296 bytes:
767[WSS-0x7f5154029b10] First fragment: 296 bytes, 0 remaining
768{"janus":"trickle","candidate":{"candidate":"candidate:3405268122 1 tcp 1518280447 192.168.1.105 9 typ host tcptype active generation 0 ufrag HKb7 network-id 1 network-cost 10","sdpMid":"0","sdpMLineIndex":0},"transaction":"n1eHWbSyn72G","session_id":5662156617889035,"handle_id":450447704721074}
769[WSS-0x7f5154029b10] Done, parsing message: 296 bytes
770Got a Janus API request from janus.transport.websockets (0x7f5154026660)
771[WSS-0x7f5154029b10] Got 296 bytes:
772[WSS-0x7f5154029b10] First fragment: 296 bytes, 0 remaining
773{"janus":"trickle","candidate":{"candidate":"candidate:3405268122 1 tcp 1518280447 192.168.1.105 9 typ host tcptype active generation 0 ufrag HKb7 network-id 1 network-cost 10","sdpMid":"1","sdpMLineIndex":1},"transaction":"FvUHBbUGKTAQ","session_id":5662156617889035,"handle_id":450447704721074}
774[WSS-0x7f5154029b10] Done, parsing message: 296 bytes
775Got a Janus API request from janus.transport.websockets (0x7f5154026660)
776[WSS-0x7f5154029b10] Got 137 bytes:
777[WSS-0x7f5154029b10] First fragment: 137 bytes, 0 remaining
778{"janus":"trickle","candidate":{"completed":true},"transaction":"ZB55zsR7Tcsc","session_id":5662156617889035,"handle_id":450447704721074}
779[WSS-0x7f5154029b10] Done, parsing message: 137 bytes
780Got a Janus API request from janus.transport.websockets (0x7f5154026660)
781[450447704721074] Trickle candidate (0): candidate:87277278 1 udp 1686052607 202.94.161.175 50404 typ srflx raddr 192.168.1.105 rport 50404 generation 0 ufrag HKb7 network-id 1 network-cost 10
782[450447704721074] Adding remote candidate component:1 stream:1 type:srflx 192.168.1.105:50404 --> 202.94.161.175:50404
783[450447704721074] Transport: UDP
784[450447704721074] Candidate added to the list! (2 elements for 1/1)
785[450447704721074] Queueing candidate 0x7f515c031ee0
786Sending Janus API response to janus.transport.websockets (0x7f5154026660)
787[450447704721074] Trickle candidate (1): candidate:87277278 1 udp 1686052607 202.94.161.175 56485 typ srflx raddr 192.168.1.105 rport 56485 generation 0 ufrag HKb7 network-id 1 network-cost 10
788[450447704721074] Got a mid='1' candidate (index 1) but we're bundling, ignoring...
789Sending Janus API response to janus.transport.websockets (0x7f5154026660)
790[450447704721074] Trickle candidate (0): candidate:3405268122 1 tcp 1518280447 192.168.1.105 9 typ host tcptype active generation 0 ufrag HKb7 network-id 1 network-cost 10
791[450447704721074] Adding remote candidate component:1 stream:1 type:host 192.168.1.105:9
792[450447704721074] Skipping unsupported transport 'tcp' for media
793Sending Janus API response to janus.transport.websockets (0x7f5154026660)
794[450447704721074] Trickle candidate (1): candidate:3405268122 1 tcp 1518280447 192.168.1.105 9 typ host tcptype active generation 0 ufrag HKb7 network-id 1 network-cost 10
795[450447704721074] Got a mid='1' candidate (index 1) but we're bundling, ignoring...
796Sending Janus API response to janus.transport.websockets (0x7f5154026660)
797No more remote candidates for handle 450447704721074!
798Sending Janus API response to janus.transport.websockets (0x7f5154026660)
799[WSS-0x7f5154029b10] Sending WebSocket message (90 bytes)...
800[WSS-0x7f5154029b10] -- First=1, Last=1, Requested=90 bytes, Sent=90 bytes, Missing=0 bytes
801[WSS-0x7f5154029b10] Sending WebSocket message (90 bytes)...
802[WSS-0x7f5154029b10] -- First=1, Last=1, Requested=90 bytes, Sent=90 bytes, Missing=0 bytes
803[WSS-0x7f5154029b10] Sending WebSocket message (90 bytes)...
804[WSS-0x7f5154029b10] -- First=1, Last=1, Requested=90 bytes, Sent=90 bytes, Missing=0 bytes
805[WSS-0x7f5154029b10] Sending WebSocket message (90 bytes)...
806[WSS-0x7f5154029b10] -- First=1, Last=1, Requested=90 bytes, Sent=90 bytes, Missing=0 bytes
807[450447704721074] Processing candidate 0x7f515c031ee0
808(process:7): libnice-DEBUG: 10:36:05.061: Agent 0x7f515c0280a0: set_remote_candidates 1 1
809(process:7): libnice-DEBUG: 10:36:05.061: Agent 0x7f515c0280a0 : Adding UDP remote candidate with addr [202.94.161.175]:50404 for s1/c1. U/P '(null)'/'(null)' prio: 647f1eff
810(process:7): libnice-DEBUG: 10:36:05.061: Agent 0x7f515c0280a0 : creating a new pair
811(process:7): libnice-DEBUG: 10:36:05.061: Agent 0x7f515c0280a0 : pair 0x7f51580160c0 state FROZEN (priv_add_new_check_pair)
812(process:7): libnice-DEBUG: 10:36:05.061: Agent 0x7f515c0280a0 : new pair 0x7f51580160c0 : [10.0.1.218]:30600 --> [202.94.161.175]:50404
813(process:7): libnice-DEBUG: 10:36:05.062: Interface: lo
814(process:7): libnice-DEBUG: 10:36:05.062: IP Address: 127.0.0.1
815(process:7): libnice-DEBUG: 10:36:05.062: Interface: eth0
816(process:7): libnice-DEBUG: 10:36:05.062: IP Address: 10.0.1.218
817(process:7): libnice-DEBUG: 10:36:05.062: Agent 0x7f515c0280a0 : added a new pair 0x7f51580160c0 with foundation '1:87277278' and transport udp:udp to stream 1 component 1
818(process:7): libnice-DEBUG: 10:36:05.062: Agent 0x7f515c0280a0 : conn_check_remote_candidates_set 1 1
819[450447704721074] 1 remote candidate added
820[WSS-0x7f5154029b10] Sending WebSocket message (90 bytes)...
821[WSS-0x7f5154029b10] -- First=1, Last=1, Requested=90 bytes, Sent=90 bytes, Missing=0 bytes
822(process:7): libnice-DEBUG: 10:36:05.082: Agent 0x7f515c0280a0 : Pair 0x7f51580160c0 with s/c-id 1/1 (1:87277278) unfrozen.
823(process:7): libnice-DEBUG: 10:36:05.082: Agent 0x7f515c0280a0 : pair 0x7f51580160c0 state WAITING (priv_conn_check_unfreeze_next)
824(process:7): libnice-DEBUG: 10:36:05.082: Agent 0x7f515c0280a0 : pair 0x7f51580160c0 state IN_PROGRESS (priv_conn_check_initiate)
825(process:7): libnice-DEBUG: 10:36:05.082: Agent 0x7f515c0280a0 : STUN-CC REQ [10.0.1.218]:30600 --> [202.94.161.175]:50404, socket=47, pair=0x7f51580160c0 (c-id:1), tie=14450994160176994371, username='HKb7:8Pq7' (9), password='IJgXA7dszBOylh4aiHl8ucYN' (24), prio=6e2000ff, controlled.
826(process:7): libnice-DEBUG: 10:36:05.082: Agent 0x7f515c0280a0 : conn_check_send: set cand_use=0 (aggressive nomination).
827(process:7): libnice-stun-DEBUG: 10:36:05.082: Message HMAC-SHA1 message integrity:
828(process:7): libnice-stun-DEBUG: 10:36:05.082: key : 0x494a6758413764737a424f796c68346169486c387563594e
829(process:7): libnice-stun-DEBUG: 10:36:05.082: sent : 0xd7d1a01f55690315e40ed4a0ffc29e50e692f1b6
830(process:7): libnice-stun-DEBUG: 10:36:05.082: Message HMAC-SHA1 fingerprint: 0x5783b393
831(process:7): libnice-DEBUG: 10:36:05.082: Agent 0x7f515c0280a0: conncheck created 88 - 0x7f515801cb28
832(process:7): libnice-DEBUG: 10:36:05.082: Agent 0x7f515c0280a0 : timer set to 500ms, waiting+in_progress=2
833(process:7): libnice-DEBUG: 10:36:05.364: Agent 0x7f515c0280a0 :STUN transaction retransmitted on pair 0x7f5158016010 (timer=2/3 0/1000ms).
834(process:7): libnice-DEBUG: 10:36:05.586: Agent 0x7f515c0280a0 :STUN transaction retransmitted on pair 0x7f51580160c0 (timer=2/3 0/1000ms).
835(process:7): libnice-DEBUG: 10:36:05.870: Agent 0x7f515c0280a0 : stream 1: timer tick #51: 0 frozen, 2 in-progress, 0 waiting, 0 succeeded, 0 discovered, 0 nominated, 0 waiting-for-nom, 0 valid
836(process:7): libnice-DEBUG: 10:36:06.373: Agent 0x7f515c0280a0 :STUN transaction retransmitted on pair 0x7f5158016010 (timer=3/3 1/500ms).
837(process:7): libnice-DEBUG: 10:36:06.595: Agent 0x7f515c0280a0 :STUN transaction retransmitted on pair 0x7f51580160c0 (timer=3/3 0/500ms).
838(process:7): libnice-DEBUG: 10:36:06.878: Agent 0x7f515c0280a0 : Retransmissions failed, giving up on pair 0x7f5158016010
839(process:7): libnice-DEBUG: 10:36:06.878: Agent 0x7f515c0280a0 : Failed pair is [10.0.1.218]:30600 --> [192.168.1.105]:50404
840(process:7): libnice-DEBUG: 10:36:06.878: Agent 0x7f515c0280a0 : pair 0x7f5158016010 state FAILED (candidate_check_pair_fail)
841(process:7): libnice-DEBUG: 10:36:06.878: Agent 0x7f515c0280a0 : conn.check list status: 0 nominated, 0 valid, c-id 1.
842(process:7): libnice-DEBUG: 10:36:06.878: Agent 0x7f515c0280a0 : stream 1: timer tick #101: 0 frozen, 1 in-progress, 0 waiting, 0 succeeded, 0 discovered, 0 nominated, 0 waiting-for-nom, 0 valid
843(process:7): libnice-DEBUG: 10:36:07.100: Agent 0x7f515c0280a0 : Retransmissions failed, giving up on pair 0x7f51580160c0
844(process:7): libnice-DEBUG: 10:36:07.100: Agent 0x7f515c0280a0 : Failed pair is [10.0.1.218]:30600 --> [202.94.161.175]:50404
845(process:7): libnice-DEBUG: 10:36:07.100: Agent 0x7f515c0280a0 : pair 0x7f51580160c0 state FAILED (candidate_check_pair_fail)
846(process:7): libnice-DEBUG: 10:36:07.100: Agent 0x7f515c0280a0 : conn.check list status: 0 nominated, 0 valid, c-id 1.
847(process:7): libnice-DEBUG: 10:36:07.100: Agent 0x7f515c0280a0 : waiting 5000 msecs before checking for failed components.
848(process:7): libnice-DEBUG: 10:36:07.886: Agent 0x7f515c0280a0 : stream 1: timer tick #151: 0 frozen, 0 in-progress, 0 waiting, 0 succeeded, 0 discovered, 0 nominated, 0 waiting-for-nom, 0 valid
849(process:7): libnice-DEBUG: 10:36:08.893: Agent 0x7f515c0280a0 : stream 1: timer tick #201: 0 frozen, 0 in-progress, 0 waiting, 0 succeeded, 0 discovered, 0 nominated, 0 waiting-for-nom, 0 valid
850(process:7): libnice-DEBUG: 10:36:09.901: Agent 0x7f515c0280a0 : stream 1: timer tick #251: 0 frozen, 0 in-progress, 0 waiting, 0 succeeded, 0 discovered, 0 nominated, 0 waiting-for-nom, 0 valid
851(process:7): libnice-DEBUG: 10:36:10.908: Agent 0x7f515c0280a0 : stream 1: timer tick #301: 0 frozen, 0 in-progress, 0 waiting, 0 succeeded, 0 discovered, 0 nominated, 0 waiting-for-nom, 0 valid
852(process:7): libnice-DEBUG: 10:36:11.916: Agent 0x7f515c0280a0 : stream 1: timer tick #351: 0 frozen, 0 in-progress, 0 waiting, 0 succeeded, 0 discovered, 0 nominated, 0 waiting-for-nom, 0 valid
853(process:7): libnice-DEBUG: 10:36:12.117: Agent 0x7f515c0280a0 : checking for failed components now.
854(process:7): libnice-DEBUG: 10:36:12.118: Agent 0x7f515c0280a0 : stream 1 component 1 STATE-CHANGE connecting -> failed.
855(process:7): libnice-DEBUG: 10:36:12.118: Agent 0x7f515c0280a0 : priv_conn_check_tick_agent_locked: stopping conncheck timer
856(process:7): libnice-DEBUG: 10:36:12.118: Agent 0x7f515c0280a0 : changing conncheck state to COMPLETED.
857[450447704721074] Component state changed for component 1 in stream 1: 5 (failed)
858[WARN] [450447704721074] ICE failed for component 1 in stream 1, but let's give it some time... (trickle received, answer received, alert not set)
859[450447704721074] Creating ICE state check timer with ID 8
860(process:7): libnice-DEBUG: 10:36:17.123: Agent 0x7f515c0280a0, freeing conncheck_list of stream 0x7f515c02a5f0
861(process:7): libnice-DEBUG: 10:36:17.123: Free socket sources for component 0x7f515c02d400.
862(process:7): libnice-DEBUG: 10:36:17.125: Detaching source 0x7f515c038b50 (socket 0x7f515c024730, FD 47) from context 0x7f51600043a0
863(process:7): libnice-DEBUG: 10:36:17.127: Destroyed NiceComponent (1 created, 1 destroyed)
864(process:7): libnice-DEBUG: 10:36:17.127: Destroyed NiceStream (1 created, 1 destroyed)
865[ERR] [ice.c:janus_ice_check_failed:1760] [450447704721074] ICE failed for component 1 in stream 1...
866[450447704721074] Hanging up PeerConnection because of a ICE failed
867[450447704721074] Telling the plugin about the hangup (JANUS VideoRoom plugin)
868[janus.plugin.videoroom-0x7f5154002d00] No WebRTC media anymore; 0x7f5160003110 0x7f5160004340
869[450447704721074] Notifying WebRTC hangup; 0x7f5160003110
870[450447704721074] Sending event to transport...; 0x7f5160003110
871Sending event to janus.transport.websockets (0x7f5154026660)
872[450447704721074] WebRTC resources freed; 0x7f5160003110 0x7f5154015850
873[WSS-0x7f5154029b10] Sending WebSocket message (116 bytes)...
874[WSS-0x7f5154029b10] -- First=1, Last=1, Requested=116 bytes, Sent=116 bytes, Missing=0 bytes
875[WSS-0x7f5154029b10] Got 80 bytes:
876[WSS-0x7f5154029b10] First fragment: 80 bytes, 0 remaining
877{"janus":"keepalive","session_id":5662156617889035,"transaction":"oLmgC3LiHXP9"}
878[WSS-0x7f5154029b10] Done, parsing message: 80 bytes
879Got a Janus API request from janus.transport.websockets (0x7f5154026660)
880Got a keep-alive on session 5662156617889035
881Sending Janus API response to janus.transport.websockets (0x7f5154026660)
882[WSS-0x7f5154029b10] Sending WebSocket message (90 bytes)...
883[WSS-0x7f5154029b10] -- First=1, Last=1, Requested=90 bytes, Sent=90 bytes, Missing=0 bytes
884[WSS-0x7f5154029b10] Got 80 bytes:
885[WSS-0x7f5154029b10] First fragment: 80 bytes, 0 remaining
886{"janus":"keepalive","session_id":5662156617889035,"transaction":"wIFRUjYEl7PD"}
887[WSS-0x7f5154029b10] Done, parsing message: 80 bytes
888Got a Janus API request from janus.transport.websockets (0x7f5154026660)
889Got a keep-alive on session 5662156617889035
890Sending Janus API response to janus.transport.websockets (0x7f5154026660)
891[WSS-0x7f5154029b10] Sending WebSocket message (90 bytes)...
892[WSS-0x7f5154029b10] -- First=1, Last=1, Requested=90 bytes, Sent=90 bytes, Missing=0 bytes
893[file-live-sample] Rewind! (/usr/local/share/janus/streams/radio.alaw)
894[WSS-0x7f5154029b10] Got 80 bytes:
895[WSS-0x7f5154029b10] First fragment: 80 bytes, 0 remaining
896{"janus":"keepalive","session_id":5662156617889035,"transaction":"vWmVZUL7qmsq"}
897[WSS-0x7f5154029b10] Done, parsing message: 80 bytes
898Got a Janus API request from janus.transport.websockets (0x7f5154026660)
899Got a keep-alive on session 5662156617889035
900Sending Janus API response to janus.transport.websockets (0x7f5154026660)
901[WSS-0x7f5154029b10] Sending WebSocket message (90 bytes)...
902[WSS-0x7f5154029b10] -- First=1, Last=1, Requested=90 bytes, Sent=90 bytes, Missing=0 bytes
903[WSS-0x7f5154014e30] 77 ((null))
904[WSS-0x7f5154014e30] 20 (LWS_CALLBACK_FILTER_PROTOCOL_CONNECTION)
905[WSS-0x7f5154014e30] 53 ((null))