OpenVidu 2.28.0 CE Failing to keep a single connection
We have been using OV CE for a long time now and it works great. We upgraded from 2.27.0 to 2.28.0 and now all connections are failing after a few seconds.
We are an online testing company. We have single subscriber sessions to record video of people taking exams online.
Please help. We are down and will have to revert.
Browser log snippet for a single session with one publisher:
openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1 [ICE_CONNECTION_DISCONNECTED] Stream str_CAM_KTPz_con_Fj4hr37BR7 (Subscriber) failed to reconnect after ICE_CONNECTION_DISCONNECTED. Current ICE connection state: checking
OpenViduLogger.error @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
(anonymous) @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
step @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
(anonymous) @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
fulfilled @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1 [ICE_CONNECTION_DISCONNECTED] Stream str_CAM_P1Vv_con_VjRDQ7K6JX (Subscriber) failed to reconnect after ICE_CONNECTION_DISCONNECTED. Current ICE connection state: checking
OpenViduLogger.error @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
(anonymous) @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
step @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
(anonymous) @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
fulfilled @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1 [ICE_CONNECTION_DISCONNECTED] Stream str_CAM_P1Vv_con_VjRDQ7K6JX (Subscriber) failed to reconnect after ICE_CONNECTION_DISCONNECTED. Current ICE connection state: checking
OpenViduLogger.error @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
(anonymous) @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
step @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
(anonymous) @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
fulfilled @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1 Error for ‘Subscriber’ (str_CAM_P1Vv_con_VjRDQ7K6JX) while trying to reconnect: UnknownError: Failed to construct ‘RTCPeerConnection’: Cannot create so many PeerConnections
OpenViduLogger.error @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
Stream.finalRejectForSubscription @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
(anonymous) @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
Promise.catch (async)
(anonymous) @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
Stream.initWebRtcPeerReceive @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
(anonymous) @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
step @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
(anonymous) @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
fulfilled @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
Promise.then (async)
step @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
(anonymous) @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
__awaiter @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
Stream.reconnectStream @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
(anonymous) @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
step @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
(anonymous) @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
(anonymous) @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
__awaiter @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
Stream.reconnectStreamAndReturnIceConnectionState @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
(anonymous) @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
step @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
(anonymous) @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
(anonymous) @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
__awaiter @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
Stream.reconnectStreamAndLogResultingIceConnectionState @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
(anonymous) @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1 Error for ‘Subscriber’ (str_CAM_GIAT_con_UHoDLPP5nS) while trying to reconnect: UnknownError: Failed to construct ‘RTCPeerConnection’: Cannot create so many PeerConnections
OpenViduLogger.error @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
Stream.finalRejectForSubscription @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
(anonymous) @ openvidu-browser-2.28.0.min.js.xhtml?ln=openvidu:1
OpenVidu Logs Look ok but clearly a lot coming from the browser:
rkr: {connectionId: “con_F7cd5q0rBZ”, sdpMid: 1, sdpMLineIndex: 1, candidate: “candidate:987269639 1 tcp 1518283007 2600:4040:2319:6200:6c47:e0d3:644a:35bf 9 typ host tcptype active generation 0 ufrag GEj1 network-id 2 network-cost 10”}
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:46,648 [SessionHandler-g9jra6ciut1i4gc5ajm6t93in1-e1212-t0] io.openvidu.server.rpc.RpcHandler - Participant con_XW8IO0Lrkr is calling method ‘onIceCandidate’ in session tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:46,648 [SessionHandler-g9jra6ciut1i4gc5ajm6t93in1-e1212-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_XW8IO0Lrkr: {connectionId: “con_F7cd5q0rBZ”, sdpMid: 1, sdpMLineIndex: 1, candidate: “candidate:163664579 1 tcp 1518217471 2600:4040:2319:6200:eea4:763e:1e6a:39bb 9 typ host tcptype active generation 0 ufrag GEj1 network-id 3 network-cost 10”}
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:46,648 [SessionHandler-b5rspupbdhr0lnkkbe7gtjkjtv-e1206-t0] io.openvidu.server.rpc.RpcHandler - Participant con_WkjzSzsfPD is calling method ‘onIceCandidate’ in session tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:46,648 [SessionHandler-b5rspupbdhr0lnkkbe7gtjkjtv-e1206-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_WkjzSzsfPD: {connectionId: “con_F7cd5q0rBZ”, sdpMid: 1, sdpMLineIndex: 1, candidate: “candidate:3436261864 1 tcp 1518149375 192.168.1.168 9 typ host tcptype active generation 0 ufrag 39h7 network-id 1 network-cost 10”}
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:46,648 [SessionHandler-b5rspupbdhr0lnkkbe7gtjkjtv-e1206-t0] io.openvidu.server.rpc.RpcHandler - Participant con_WkjzSzsfPD is calling method ‘onIceCandidate’ in session tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:46,649 [SessionHandler-b5rspupbdhr0lnkkbe7gtjkjtv-e1206-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_WkjzSzsfPD: {connectionId: “con_F7cd5q0rBZ”, sdpMid: 1, sdpMLineIndex: 1, candidate: “candidate:1529534067 1 tcp 1518283007 2600:4040:2319:6200:6c47:e0d3:644a:35bf 9 typ host tcptype active generation 0 ufrag 39h7 network-id 2 network-cost 10”}
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:46,649 [SessionHandler-b5rspupbdhr0lnkkbe7gtjkjtv-e1206-t0] io.openvidu.server.rpc.RpcHandler - Participant con_WkjzSzsfPD is calling method ‘onIceCandidate’ in session tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:46,649 [SessionHandler-b5rspupbdhr0lnkkbe7gtjkjtv-e1206-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_WkjzSzsfPD: {connectionId: “con_F7cd5q0rBZ”, sdpMid: 1, sdpMLineIndex: 1, candidate: “candidate:3339766018 1 tcp 1518217471 2600:4040:2319:6200:eea4:763e:1e6a:39bb 9 typ host tcptype active generation 0 ufrag 39h7 network-id 3 network-cost 10”}
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:46,651 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2207] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceComponentStateChanged]: → endpoint: con_XW8IO0Lrkr_str_CAM_XFsp_con_F7cd5q0rBZ (subscriber) | state: CONNECTING | componentId: 1 | streamId: 1 | timestamp: 1688048626647
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:48,239 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2183] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceGatheringDone] → endpoint: con_F7cd5q0rBZ_str_CAM_RLKP_con_UlcUOv34IK (subscriber) | timestamp: 1688048628239
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:48,300 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2183] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceGatheringDone] → endpoint: con_F7cd5q0rBZ_str_CAM_PZ7L_con_WkjzSzsfPD (subscriber) | timestamp: 1688048628299
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:48,355 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2183] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceGatheringDone] → endpoint: con_F7cd5q0rBZ_str_CAM_MWGi_con_XW8IO0Lrkr (subscriber) | timestamp: 1688048628354
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:48,455 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2183] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceGatheringDone] → endpoint: con_F7cd5q0rBZ_str_CAM_VIBc_con_QBUpZg8ZwP (subscriber) | timestamp: 1688048628455
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:48,521 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2183] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceGatheringDone] → endpoint: str_CAM_XFsp_con_F7cd5q0rBZ (publisher) | timestamp: 1688048628521
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:48,557 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2183] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceComponentStateChanged]: → endpoint: con_XW8IO0Lrkr_str_CAM_PZ7L_con_WkjzSzsfPD (subscriber) | state: FAILED | componentId: 1 | streamId: 1 | timestamp: 1688048628557
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:48,560 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2183] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceComponentStateChanged]: → endpoint: con_XW8IO0Lrkr_str_CAM_RLKP_con_UlcUOv34IK (subscriber) | state: FAILED | componentId: 1 | streamId: 1 | timestamp: 1688048628559
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:48,562 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2183] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceComponentStateChanged]: → endpoint: con_XW8IO0Lrkr_str_CAM_VIBc_con_QBUpZg8ZwP (subscriber) | state: FAILED | componentId: 1 | streamId: 1 | timestamp: 1688048628561
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:48,606 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2183] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceGatheringDone] → endpoint: con_UlcUOv34IK_str_CAM_XFsp_con_F7cd5q0rBZ (subscriber) | timestamp: 1688048628606
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:48,621 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2183] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceGatheringDone] → endpoint: con_QBUpZg8ZwP_str_CAM_XFsp_con_F7cd5q0rBZ (subscriber) | timestamp: 1688048628621
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:48,622 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2183] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceComponentStateChanged]: → endpoint: str_CAM_MWGi_con_XW8IO0Lrkr (publisher) | state: FAILED | componentId: 1 | streamId: 1 | timestamp: 1688048628622
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:48,623 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2183] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceGatheringDone] → endpoint: con_XW8IO0Lrkr_str_CAM_XFsp_con_F7cd5q0rBZ (subscriber) | timestamp: 1688048628623
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:48,629 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2183] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceGatheringDone] → endpoint: con_WkjzSzsfPD_str_CAM_XFsp_con_F7cd5q0rBZ (subscriber) | timestamp: 1688048628629
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:48,720 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2183] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceComponentStateChanged]: → endpoint: con_WkjzSzsfPD_str_CAM_MWGi_con_XW8IO0Lrkr (subscriber) | state: FAILED | componentId: 1 | streamId: 1 | timestamp: 1688048628720
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:48,758 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2183] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceComponentStateChanged]: → endpoint: con_QBUpZg8ZwP_str_CAM_MWGi_con_XW8IO0Lrkr (subscriber) | state: FAILED | componentId: 1 | streamId: 1 | timestamp: 1688048628758
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:48,763 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2183] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceComponentStateChanged]: → endpoint: con_UlcUOv34IK_str_CAM_MWGi_con_XW8IO0Lrkr (subscriber) | state: FAILED | componentId: 1 | streamId: 1 | timestamp: 1688048628762
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:49,820 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2183] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceComponentStateChanged]: → endpoint: con_QBUpZg8ZwP_str_CAM_PZ7L_con_WkjzSzsfPD (subscriber) | state: FAILED | componentId: 1 | streamId: 1 | timestamp: 1688048629819
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:49,826 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2183] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceComponentStateChanged]: → endpoint: str_CAM_VIBc_con_QBUpZg8ZwP (publisher) | state: FAILED | componentId: 1 | streamId: 1 | timestamp: 1688048629825
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:49,907 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2183] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceComponentStateChanged]: → endpoint: con_WkjzSzsfPD_str_CAM_VIBc_con_QBUpZg8ZwP (subscriber) | state: FAILED | componentId: 1 | streamId: 1 | timestamp: 1688048629907
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,065 [http-nio-0.0.0.0-5443-exec-16] io.openvidu.server.rest.SessionRestController - REST API: POST /openvidu/api/sessions {mediaMode=ROUTED, recordingMode=MANUAL, defaultRecordingProperties={name=, hasAudio=true, hasVideo=true, outputMode=INDIVIDUAL, ignoreFailedStreams=false}, customSessionId=tk-MTgwNDI3NQ-MTA-L8GSKF27, forcedVideoCodec=MEDIA_SERVER_PREFERRED, allowTranscoding=false}
openvidu-openvidu-server-1 | [WARN] 2023-06-29 14:23:51,065 [http-nio-0.0.0.0-5443-exec-16] io.openvidu.server.rest.SessionRestController - Session tk-MTgwNDI3NQ-MTA-L8GSKF27 is already created
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,067 [http-nio-0.0.0.0-5443-exec-27] io.openvidu.server.rest.SessionRestController - REST API: GET /openvidu/api/sessions/tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,070 [http-nio-0.0.0.0-5443-exec-28] io.openvidu.server.rest.SessionRestController - REST API: POST /openvidu/api/sessions/tk-MTgwNDI3NQ-MTA-L8GSKF27/connection {session=tk-MTgwNDI3NQ-MTA-L8GSKF27, type=WEBRTC, data={“testkeyid”: “WzAcTe7cJOg*”,“userid”:“x-Biop8DB_4*”}, record=null, role=PUBLISHER, kurentoOptions=null, customIceServers=, rtspUri=null, adaptativeBitrate=null, onlyPlayWithSubscribers=null, networkCache=null}
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,070 [http-nio-0.0.0.0-5443-exec-28] io.openvidu.server.rest.SessionRestController - Generated token wss://vi1.hravatar.com?sessionId=tk-MTgwNDI3NQ-MTA-L8GSKF27&token=tok_Dnogj3pZuwib2SuL
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,099 [http-nio-0.0.0.0-5443-exec-14] io.openvidu.server.config.HttpHandshakeInterceptor - Old HttpSession 6512A4089D3E4AF9454FB08A80AB23F4
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,107 [http-nio-0.0.0.0-5443-exec-26] io.openvidu.server.rpc.RpcHandler - After connection established for WebSocket session: 63elbckr0gt4380kj4ggnp30dc
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,108 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.rpc.RpcHandler - Final user reconnecting
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,108 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.rpc.RpcHandler - New Connection con_IXE5fvNe9S in Session tk-MTgwNDI3NQ-MTA-L8GSKF27 with IP 141.156.187.98 and platform Chrome 114.0.0.0 on Windows 10 64-bit
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,108 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.kurento.core.KurentoSession - SESSION tk-MTgwNDI3NQ-MTA-L8GSKF27: Added participant [participantPrivateId=63elbckr0gt4380kj4ggnp30dc, participantPublicId=con_IXE5fvNe9S, streaming=false]
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,143 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IXE5fvNe9S is calling method ‘receiveVideoFrom’ in session tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,143 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.utils.SDPMunging - [setCodecPreference] codec: VP8
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,145 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.kurento.core.KurentoParticipant - PARTICIPANT con_IXE5fvNe9S: Request to receive media from con_UlcUOv34IK in room tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,165 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.kurento.core.KurentoParticipant - PARTICIPANT con_IXE5fvNe9S: Is now receiving video from con_UlcUOv34IK in room tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,165 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IXE5fvNe9S is calling method ‘receiveVideoFrom’ in session tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,165 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.utils.SDPMunging - [setCodecPreference] codec: VP8
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,168 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.kurento.core.KurentoParticipant - PARTICIPANT con_IXE5fvNe9S: Request to receive media from con_WkjzSzsfPD in room tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,170 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2207] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceComponentStateChanged]: → endpoint: con_IXE5fvNe9S_str_CAM_RLKP_con_UlcUOv34IK (subscriber) | state: GATHERING | componentId: 1 | streamId: 1 | timestamp: 1688048631167
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,211 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2210] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceComponentStateChanged]: → endpoint: con_IXE5fvNe9S_str_CAM_RLKP_con_UlcUOv34IK (subscriber) | state: GATHERING | componentId: 2 | streamId: 1 | timestamp: 1688048631185
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,224 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.kurento.core.KurentoParticipant - PARTICIPANT con_IXE5fvNe9S: Is now receiving video from con_WkjzSzsfPD in room tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,225 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IXE5fvNe9S is calling method ‘receiveVideoFrom’ in session tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,225 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.utils.SDPMunging - [setCodecPreference] codec: VP8
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,227 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.kurento.core.KurentoParticipant - PARTICIPANT con_IXE5fvNe9S: Request to receive media from con_F7cd5q0rBZ in room tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,230 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2182] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceComponentStateChanged]: → endpoint: con_IXE5fvNe9S_str_CAM_PZ7L_con_WkjzSzsfPD (subscriber) | state: GATHERING | componentId: 1 | streamId: 1 | timestamp: 1688048631226
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,271 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2207] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceComponentStateChanged]: → endpoint: con_IXE5fvNe9S_str_CAM_PZ7L_con_WkjzSzsfPD (subscriber) | state: GATHERING | componentId: 2 | streamId: 1 | timestamp: 1688048631245
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,325 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.kurento.core.KurentoParticipant - PARTICIPANT con_IXE5fvNe9S: Is now receiving video from con_F7cd5q0rBZ in room tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,326 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IXE5fvNe9S is calling method ‘receiveVideoFrom’ in session tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,326 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.utils.SDPMunging - [setCodecPreference] codec: VP8
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,329 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.kurento.core.KurentoParticipant - PARTICIPANT con_IXE5fvNe9S: Request to receive media from con_XW8IO0Lrkr in room tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,331 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2210] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceComponentStateChanged]: → endpoint: con_IXE5fvNe9S_str_CAM_XFsp_con_F7cd5q0rBZ (subscriber) | state: GATHERING | componentId: 1 | streamId: 1 | timestamp: 1688048631329
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,375 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2199] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceComponentStateChanged]: → endpoint: con_IXE5fvNe9S_str_CAM_XFsp_con_F7cd5q0rBZ (subscriber) | state: GATHERING | componentId: 2 | streamId: 1 | timestamp: 1688048631345
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,389 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.kurento.core.KurentoParticipant - PARTICIPANT con_IXE5fvNe9S: Is now receiving video from con_XW8IO0Lrkr in room tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,389 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IXE5fvNe9S is calling method ‘receiveVideoFrom’ in session tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,390 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.utils.SDPMunging - [setCodecPreference] codec: VP8
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,392 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.kurento.core.KurentoParticipant - PARTICIPANT con_IXE5fvNe9S: Request to receive media from con_QBUpZg8ZwP in room tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,435 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2197] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceComponentStateChanged]: → endpoint: con_IXE5fvNe9S_str_CAM_MWGi_con_XW8IO0Lrkr (subscriber) | state: GATHERING | componentId: 1 | streamId: 1 | timestamp: 1688048631390
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,436 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t2197] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceComponentStateChanged]: → endpoint: con_IXE5fvNe9S_str_CAM_MWGi_con_XW8IO0Lrkr (subscriber) | state: GATHERING | componentId: 2 | streamId: 1 | timestamp: 1688048631409
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,448 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.kurento.core.KurentoParticipant - PARTICIPANT con_IXE5fvNe9S: Is now receiving video from con_QBUpZg8ZwP in room tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,449 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IXE5fvNe9S is calling method ‘onIceCandidate’ in session tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,449 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_IXE5fvNe9S: {connectionId: “con_UlcUOv34IK”, sdpMid: 0, sdpMLineIndex: 0, candidate: “candidate:1489429814 1 udp 2122129151 192.168.1.168 51385 typ host generation 0 ufrag 7URb network-id 1 network-cost 10”}
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,449 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IXE5fvNe9S is calling method ‘onIceCandidate’ in session tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,449 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_IXE5fvNe9S: {connectionId: “con_UlcUOv34IK”, sdpMid: 0, sdpMLineIndex: 0, candidate: “candidate:3476882093 1 udp 2122262783 2600:4040:2319:6200:6c47:e0d3:644a:35bf 51386 typ host generation 0 ufrag 7URb network-id 2 network-cost 10”}
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,449 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IXE5fvNe9S is calling method ‘onIceCandidate’ in session tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,449 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_IXE5fvNe9S: {connectionId: “con_UlcUOv34IK”, sdpMid: 0, sdpMLineIndex: 0, candidate: “candidate:1392975324 1 udp 2122197247 2600:4040:2319:6200:eea4:763e:1e6a:39bb 51387 typ host generation 0 ufrag 7URb network-id 3 network-cost 10”}
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,450 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IXE5fvNe9S is calling method ‘onIceCandidate’ in session tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,450 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_IXE5fvNe9S: {connectionId: “con_UlcUOv34IK”, sdpMid: 1, sdpMLineIndex: 1, candidate: “candidate:1489429814 1 udp 2122129151 192.168.1.168 51388 typ host generation 0 ufrag 7URb network-id 1 network-cost 10”}
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,450 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IXE5fvNe9S is calling method ‘onIceCandidate’ in session tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,450 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_IXE5fvNe9S: {connectionId: “con_UlcUOv34IK”, sdpMid: 1, sdpMLineIndex: 1, candidate: “candidate:3476882093 1 udp 2122262783 2600:4040:2319:6200:6c47:e0d3:644a:35bf 51389 typ host generation 0 ufrag 7URb network-id 2 network-cost 10”}
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,450 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IXE5fvNe9S is calling method ‘onIceCandidate’ in session tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,450 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_IXE5fvNe9S: {connectionId: “con_UlcUOv34IK”, sdpMid: 1, sdpMLineIndex: 1, candidate: “candidate:1392975324 1 udp 2122197247 2600:4040:2319:6200:eea4:763e:1e6a:39bb 51390 typ host generation 0 ufrag 7URb network-id 3 network-cost 10”}
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,451 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IXE5fvNe9S is calling method ‘onIceCandidate’ in session tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,451 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_IXE5fvNe9S: {connectionId: “con_WkjzSzsfPD”, sdpMid: 0, sdpMLineIndex: 0, candidate: “candidate:1096470452 1 udp 2122129151 192.168.1.168 51391 typ host generation 0 ufrag V0zP network-id 1 network-cost 10”}
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,451 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IXE5fvNe9S is calling method ‘onIceCandidate’ in session tk-MTgwNDI3NQ-MTA-L8GSKF27
openvidu-openvidu-server-1 | [INFO] 2023-06-29 14:23:51,451 [SessionHandler-63elbckr0gt4380kj4ggnp30dc-e1216-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_IXE5fvNe9S: {connectionId: “con_WkjzSzsfPD”, sdpMid: 0, sdpMLineIndex: 0, candidate: “candidate:3600885807 1 udp 2122262783 2600:4040:2319:6200:6c47:e0d3:644a:35bf 51392 typ host generation 0 ufrag V0zP network-id 2 network-cost 10”}