2020-07-07 20:33:47.837  - DEBUG: SocketIOServer - onClientJoined, id: a7UdvOiV1tOs8l8UAAAA client.tokenCode: 20852768642
console.js:8 2020-07-07 20:34:00.204  - DEBUG: SocketIOServer - client reconnect a7UdvOiV1tOs8l8UAAAA
console.js:8 2020-07-07 20:34:00.206  - DEBUG: SocketIOServer - close it, client_id: a7UdvOiV1tOs8l8UAAAA
console.js:8 2020-07-07 20:34:00.208  - DEBUG: SocketIOServer - socket.io disconnected, reason: server namespace disconnect
console.js:8 2020-07-07 20:34:35.301  - DEBUG: SocketIOServer - socket.io disconnected, reason: ping timeout
console.js:8 2020-07-07 20:35:00.212  - INFO: SocketIOServer - a7UdvOiV1tOs8l8UAAAA waiting for reconnecting timeout.
console.js:8 2020-07-07 20:35:00.214  - DEBUG: SocketIOServer - forceClientLeave, client_id: a7UdvOiV1tOs8l8UAAAA
console.js:8 2020-07-07 20:35:18.990  - DEBUG: Portal - participant[ r9SQzLm0WO74DwuUAAAC ] join with token: {"tokenId":"5f04dc86767223197c2a35dc","host":"192.168.33.10:9301","secure":true,"signature":"MWZhMjMxYmQ3ZWIyODQwMTUwOTYxN2Q4MWVjNGY4ZTk0NDMyNjI4MjUzOTk3YTM4NzI2Y2E1M2NhOGIxYTE0NA=="}
console.js:8 2020-07-07 20:35:18.992  - DEBUG: Portal - token validation ok.
console.js:8 2020-07-07 20:35:19.001  - DEBUG: Portal - login ok. { _id: 5f04dc86767223197c2a35dc,
  user: 'user',
  room: 5f04dc85cf81ae1988267bdf,
  role: 'presenter',
  service: 5eb08d32ef9f2a0cef71657a,
  creationDate: 2020-07-07T20:35:18.035Z,
  origin: { isp: 'isp', region: 'region' },
  code: '92998175522',
  secure: true,
  host: '192.168.33.10:9301',
  __v: 0 }
console.js:8 2020-07-07 20:35:19.013  - DEBUG: Portal - got controller: conference-1ecd2abf82202779e280@10.0.2.15_1
console.js:8 2020-07-07 20:35:19.486  - DEBUG: Portal - join ok, result: { permission: 
   { publish: { video: true, audio: true },
     subscribe: { video: true, audio: true } },
  room: 
   { id: '5f04dc85cf81ae1988267bdf',
     views: [ 'common' ],
     participants: [ [Object] ],
     streams: [ [Object] ] } }
console.js:8 2020-07-07 20:35:19.490  - DEBUG: SocketIOServer - onClientJoined, id: r9SQzLm0WO74DwuUAAAC client.tokenCode: 92998175522
console.js:8 2020-07-07 20:35:27.700  - DEBUG: Portal - publish, participantId: r9SQzLm0WO74DwuUAAAC streamId: 267741573549992160 pubInfo: { media: 
   { audio: false,
     video: { source: 'camera', parameters: [Object] } },
  type: 'webrtc' }
console.js:8 2020-07-07 20:35:28.583  - DEBUG: Portal - onSessionSignaling, participantId: r9SQzLm0WO74DwuUAAAC sessionId: 267741573549992160 signaling: { type: 'offer',
  sdp: 'v=0\r\no=- 4940778138833369818 2 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=group:BUNDLE 0\r\na=msid-semantic: WMS\r\nm=video 9 UDP/TLS/RTP/SAVPF 96 97 98 99 100 101 102 125 127\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:paRA\r\na=ice-pwd:1Le2kBMLMLFzKCHGfnqqpqLC\r\na=ice-options:trickle\r\na=fingerprint:sha-256 01:0D:34:1F:D1:EE:D2:C2:15:0C:09:34:A1:6B:8B:11:39:04:43:A8:46:02:59:D7:49:00:15:66:B7:D3:04:E3\r\na=setup:actpass\r\na=mid:0\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://tools.ietf.org/html/draft-ietf-avtext-framemarking-07\r\na=extmap:9 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:- aaf0ae2d-2ff8-4445-889b-995a5fa54490\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 red/90000\r\na=rtpmap:125 rtx/90000\r\na=fmtp:125 apt=102\r\na=rtpmap:127 ulpfec/90000\r\na=ssrc-group:FID 2597423115 79798862\r\na=ssrc:2597423115 cname:st4+ut/6Oto9h67j\r\na=ssrc:2597423115 msid:- aaf0ae2d-2ff8-4445-889b-995a5fa54490\r\na=ssrc:2597423115 mslabel:-\r\na=ssrc:2597423115 label:aaf0ae2d-2ff8-4445-889b-995a5fa54490\r\na=ssrc:79798862 cname:st4+ut/6Oto9h67j\r\na=ssrc:79798862 msid:- aaf0ae2d-2ff8-4445-889b-995a5fa54490\r\na=ssrc:79798862 mslabel:-\r\na=ssrc:79798862 label:aaf0ae2d-2ff8-4445-889b-995a5fa54490\r\n' }
console.js:8 2020-07-07 20:35:28.908  - DEBUG: SocketIOServer - notify participant: r9SQzLm0WO74DwuUAAAC event: progress data: { id: '267741573549992160',
  status: 'soac',
  data: 
   { type: 'answer',
     sdp: 'v=0\no=- 0 0 IN IP4 127.0.0.1\ns=IntelWebRTCMCU\nt=0 0\na=group:BUNDLE 0\na=msid-semantic: WMS RLg10lG4GK\nm=video 1 UDP/TLS/RTP/SAVPF 96 102 127\nc=IN IP4 0.0.0.0\na=rtcp:1 IN IP4 0.0.0.0\na=candidate:25 1 udp 2013266431 172.17.0.1 1024 typ host\na=candidate:50 1 udp 2013266431 10.0.2.15 1024 typ host\na=candidate:75 1 udp 2013266431 192.168.33.10 1024 typ host\na=candidate:76 1 udp 1677721855 192.168.33.10 1024 typ srflx raddr 172.17.0.1 rport 1024\na=candidate:77 1 udp 1677721855 192.168.33.10 1025 typ srflx raddr 10.0.2.15 rport 1024\na=ice-ufrag:WhSx\na=ice-pwd:HgQbOaG+ql8XHLorG5Ayov\na=extmap:14 urn:ietf:params:rtp-hdrext:toffset\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\na=fingerprint:sha-256 59:E0:3C:C8:33:F5:56:C5:AF:83:95:99:72:43:9D:35:B0:FE:31:63:82:42:79:5B:E0:04:0D:AE:29:22:B0:E3\na=setup:active\na=recvonly\na=mid:0\na=rtcp-mux\na=rtpmap:96 VP8/90000\na=rtcp-fb:96 ccm fir\na=rtcp-fb:96 nack\na=rtcp-fb:96 goog-remb\na=rtpmap:102 red/90000\na=rtpmap:127 ulpfec/90000\n' } }
console.js:8 2020-07-07 20:35:28.924  - DEBUG: SocketIOServer - sendMessage, event: progress data: { id: '267741573549992160',
  status: 'soac',
  data: 
   { type: 'answer',
     sdp: 'v=0\no=- 0 0 IN IP4 127.0.0.1\ns=IntelWebRTCMCU\nt=0 0\na=group:BUNDLE 0\na=msid-semantic: WMS RLg10lG4GK\nm=video 1 UDP/TLS/RTP/SAVPF 96 102 127\nc=IN IP4 0.0.0.0\na=rtcp:1 IN IP4 0.0.0.0\na=candidate:25 1 udp 2013266431 172.17.0.1 1024 typ host\na=candidate:50 1 udp 2013266431 10.0.2.15 1024 typ host\na=candidate:75 1 udp 2013266431 192.168.33.10 1024 typ host\na=candidate:76 1 udp 1677721855 192.168.33.10 1024 typ srflx raddr 172.17.0.1 rport 1024\na=candidate:77 1 udp 1677721855 192.168.33.10 1025 typ srflx raddr 10.0.2.15 rport 1024\na=ice-ufrag:WhSx\na=ice-pwd:HgQbOaG+ql8XHLorG5Ayov\na=extmap:14 urn:ietf:params:rtp-hdrext:toffset\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\na=fingerprint:sha-256 59:E0:3C:C8:33:F5:56:C5:AF:83:95:99:72:43:9D:35:B0:FE:31:63:82:42:79:5B:E0:04:0D:AE:29:22:B0:E3\na=setup:active\na=recvonly\na=mid:0\na=rtcp-mux\na=rtpmap:96 VP8/90000\na=rtcp-fb:96 ccm fir\na=rtcp-fb:96 nack\na=rtcp-fb:96 goog-remb\na=rtpmap:102 red/90000\na=rtpmap:127 ulpfec/90000\n' } }
console.js:8 2020-07-07 20:35:29.811  - DEBUG: Portal - onSessionSignaling, participantId: r9SQzLm0WO74DwuUAAAC sessionId: 267741573549992160 signaling: { type: 'candidate',
  candidate: 
   { candidate: 'a=candidate:841689039 1 udp 2113937151 10.0.2.16 49578 typ host generation 0 ufrag paRA network-cost 999',
     sdpMid: '0',
     sdpMLineIndex: 0 } }
console.js:8 2020-07-07 20:35:29.964  - DEBUG: Portal - onSessionSignaling, participantId: r9SQzLm0WO74DwuUAAAC sessionId: 267741573549992160 signaling: { type: 'candidate',
  candidate: 
   { candidate: 'a=candidate:977088318 1 udp 2113929727 fec0::714c:b905:9299:5269 51972 typ host generation 0 ufrag paRA network-cost 999',
     sdpMid: '0',
     sdpMLineIndex: 0 } }
console.js:8 2020-07-07 20:35:29.986  - DEBUG: Portal - onSessionSignaling, participantId: r9SQzLm0WO74DwuUAAAC sessionId: 267741573549992160 signaling: { type: 'candidate',
  candidate: 
   { candidate: 'a=candidate:842163049 1 udp 1677729535 192.168.33.1 60345 typ srflx raddr 10.0.2.16 rport 49578 generation 0 ufrag paRA network-cost 999',
     sdpMid: '0',
     sdpMLineIndex: 0 } }
console.js:8 2020-07-07 20:35:30.223  - DEBUG: SocketIOServer - notify participant: r9SQzLm0WO74DwuUAAAC event: progress data: { id: '267741573549992160', status: 'ready' }
console.js:8 2020-07-07 20:35:30.225  - DEBUG: SocketIOServer - sendMessage, event: progress data: { id: '267741573549992160', status: 'ready' }
console.js:8 2020-07-07 20:35:30.233  - DEBUG: SocketIOServer - notify participant: r9SQzLm0WO74DwuUAAAC event: stream data: { id: '267741573549992160',
  status: 'add',
  data: 
   { id: '267741573549992160',
     type: 'forward',
     media: { video: [Object] },
     info: { type: 'webrtc', owner: 'r9SQzLm0WO74DwuUAAAC', inViews: [] } } }
console.js:8 2020-07-07 20:35:30.235  - DEBUG: SocketIOServer - sendMessage, event: stream data: { id: '267741573549992160',
  status: 'add',
  data: 
   { id: '267741573549992160',
     type: 'forward',
     media: { video: [Object] },
     info: { type: 'webrtc', owner: 'r9SQzLm0WO74DwuUAAAC', inViews: [] } } }
console.js:8 2020-07-07 20:35:31.074  - DEBUG: SocketIOServer - notify participant: r9SQzLm0WO74DwuUAAAC event: stream data: { status: 'update',
  id: '5f04dc85cf81ae1988267bdf-common',
  data: { field: 'video.layout', value: [ [Object] ] } }
console.js:8 2020-07-07 20:35:31.075  - DEBUG: SocketIOServer - sendMessage, event: stream data: { status: 'update',
  id: '5f04dc85cf81ae1988267bdf-common',
  data: { field: 'video.layout', value: [ [Object] ] } }
console.js:8 2020-07-07 20:35:35.303  - INFO: SocketIOServer - a7UdvOiV1tOs8l8UAAAA waiting for reconnecting timeout.
console.js:8 2020-07-07 20:35:35.306  - DEBUG: SocketIOServer - forceClientLeave, client_id: a7UdvOiV1tOs8l8UAAAA
console.js:8 2020-07-07 20:35:35.311  - DEBUG: Portal - participant leave: a7UdvOiV1tOs8l8UAAAA
console.js:8 2020-07-07 20:35:35.319  - DEBUG: SocketIOServer - onClientLeft, id: a7UdvOiV1tOs8l8UAAAA
console.js:8 2020-07-07 20:36:59.898  - DEBUG: SocketIOServer - close it, client_id: undefined
console.js:8 2020-07-07 20:42:05.783  - DEBUG: SocketIOServer - notify participant: r9SQzLm0WO74DwuUAAAC event: progress data: { id: '267741573549992160',
  status: 'error',
  data: 'Access node exited unexpectedly' }
console.js:8 2020-07-07 20:42:05.784  - DEBUG: SocketIOServer - sendMessage, event: progress data: { id: '267741573549992160',
  status: 'error',
  data: 'Access node exited unexpectedly' }
console.js:8 2020-07-07 20:42:05.821  - DEBUG: SocketIOServer - notify participant: r9SQzLm0WO74DwuUAAAC event: stream data: { id: '267741573549992160', status: 'remove' }
console.js:8 2020-07-07 20:42:05.821  - DEBUG: SocketIOServer - sendMessage, event: stream data: { id: '267741573549992160', status: 'remove' }
console.js:8 2020-07-07 20:42:05.915  - DEBUG: Portal - unpublish, participantId: r9SQzLm0WO74DwuUAAAC streamId: 267741573549992160
console.js:8 2020-07-07 20:42:05.936  - ERROR: V11Client - unpublish failed: Stream does NOT exist
console.js:8 2020-07-07 20:42:07.424  - ERROR: Main - Fault on conference controller(type: node id: conference-1ecd2abf82202779e280@10.0.2.15_1 ) of participant r9SQzLm0WO74DwuUAAAC was detected, drop it.
console.js:8 2020-07-07 20:42:07.425  - DEBUG: SocketIOServer - sendMessage, event: drop data: undefined
console.js:8 2020-07-07 20:42:07.430  - DEBUG: Portal - participant leave: r9SQzLm0WO74DwuUAAAC
console.js:8 2020-07-07 20:42:07.432  - DEBUG: SocketIOServer - close it, client_id: r9SQzLm0WO74DwuUAAAC
console.js:8 2020-07-07 20:42:07.434  - DEBUG: SocketIOServer - forceClientLeave, client_id: r9SQzLm0WO74DwuUAAAC
console.js:8 2020-07-07 20:42:07.436  - DEBUG: Portal - participant leave: r9SQzLm0WO74DwuUAAAC
console.js:8 2020-07-07 20:42:07.439  - DEBUG: SocketIOServer - onClientLeft, id: r9SQzLm0WO74DwuUAAAC
console.js:8 2020-07-07 20:42:07.508  - DEBUG: SocketIOServer - socket.io disconnected, reason: server namespace disconnect
console.js:8 2020-07-07 20:42:09.431  - INFO: Portal - Failed in leaving,  Timeout to make rpc to conference-1ecd2abf82202779e280@10.0.2.15_1.leave