2018-11-05 04:03:14,407 DEBG 'kms' stdout output: 0:01:00.584441431 8 0x7fba900028a0 INFO KurentoWebSocketTransport WebSocketTransport.cpp:263:keepAliveSessions: Keep alive 684becf4-e6f5-4204-a482-dcb7cee67b78 2018-11-05 04:03:29,160 DEBG 'openvidu-server' stdout output: [INFO] 2018-11-05 04:03:29,159 [m3cmu1-e2-t0] io.openvidu.server.rpc.RpcHandler (sanityCheckOfSession) - Participant il9st3pdlahq4xod is calling method 'disconnect' in session kcibrlwwmnfc1zei 2018-11-05 04:03:29,161 DEBG 'openvidu-server' stdout output: [INFO] 2018-11-05 04:03:29,161 [m3cmu1-e2-t0] io.openvidu.server.core.Session (leave) - PARTICIPANT il9st3pdlahq4xod: Leaving session kcibrlwwmnfc1zei 2018-11-05 04:03:29,161 DEBG 'openvidu-server' stdout output: [INFO] 2018-11-05 04:03:29,161 [m3cmu1-e2-t0] io.openvidu.server.kurento.core.KurentoParticipant (cancelReceivingMedia) - PARTICIPANT RECORDER: cancel receiving media from il9st3pdlahq4xod 2018-11-05 04:03:29,163 DEBG 'kms' stdout output: 0:01:15.341051380 8 0x7fbae4001d20 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:427:processMessage: Message: {"id":89,"method":"release","params":{"object":"ec7fc361-5119-4188-a749-561ce19a60d6_kurento.MediaPipeline/bac0efca-c9ea-4a11-a97d-08996a0ca787_kurento.WebRtcEndpoint","sessionId":"684becf4-e6f5-4204-a482-dcb7cee67b78"},"jsonrpc":"2.0"} 2018-11-05 04:03:29,164 DEBG 'kms' stdout output: 0:01:15.341256326 8 0x561c29337700 DEBUG KurentoMediaElementImpl MediaElementImpl.cpp:1007:disconnect: Disconnecting ec7fc361-5119-4188-a749-561ce19a60d6_kurento.MediaPipeline/e5083987-b292-452f-b490-373465dd7d32_kurento.PassThrough - ec7fc361-5119-4188-a749-561ce19a60d6_kurento.MediaPipeline/bac0efca-c9ea-4a11-a97d-08996a0ca787_kurento.WebRtcEndpoint params AUDIO default default 0:01:15.341283558 8 0x7fbae4001d20 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:429:processMessage: Response: {"id":89,"jsonrpc":"2.0","result":{"sessionId":"684becf4-e6f5-4204-a482-dcb7cee67b78"}} 2018-11-05 04:03:29,164 DEBG 'kms' stdout output: 0:01:15.341447084 8 0x561c29337700 DEBUG KurentoMediaElementImpl MediaElementImpl.cpp:1007:disconnect: Disconnecting ec7fc361-5119-4188-a749-561ce19a60d6_kurento.MediaPipeline/e5083987-b292-452f-b490-373465dd7d32_kurento.PassThrough - ec7fc361-5119-4188-a749-561ce19a60d6_kurento.MediaPipeline/bac0efca-c9ea-4a11-a97d-08996a0ca787_kurento.WebRtcEndpoint params DATA default default 0:01:15.341465984 8 0x561c29337700 DEBUG KurentoMediaElementImpl MediaElementImpl.cpp:1007:disconnect: Disconnecting ec7fc361-5119-4188-a749-561ce19a60d6_kurento.MediaPipeline/e5083987-b292-452f-b490-373465dd7d32_kurento.PassThrough - ec7fc361-5119-4188-a749-561ce19a60d6_kurento.MediaPipeline/bac0efca-c9ea-4a11-a97d-08996a0ca787_kurento.WebRtcEndpoint params VIDEO default default 2018-11-05 04:03:29,164 DEBG 'kms' stdout output: 0:01:15.341555585 8 0x561c29337700 DEBUG KurentoMediaSet MediaSet.cpp:470:async_delete: Destroying WebRtcEndpoint -> ec7fc361-5119-4188-a749-561ce19a60d6_kurento.MediaPipeline/bac0efca-c9ea-4a11-a97d-08996a0ca787_kurento.WebRtcEndpoint 2018-11-05 04:03:29,165 DEBG 'openvidu-server' stdout output: [INFO] 2018-11-05 04:03:29,163 [m3cmu1-e2-t0] io.openvidu.server.kurento.core.KurentoParticipant (cancelReceivingMedia) - PARTICIPANT RECORDER: stopped receiving media from il9st3pdlahq4xod in room kcibrlwwmnfc1zei 2018-11-05 04:03:29,171 DEBG 'kms' stdout output: 0:01:15.347783007 8 0x7fba98001780 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:427:processMessage: Message: {"id":90,"method":"invoke","params":{"object":"ec7fc361-5119-4188-a749-561ce19a60d6_kurento.MediaPipeline/798afcf1-4327-4faf-87a6-57cd785b63a8_kurento.WebRtcEndpoint","operation":"getTag","operationParams":{"key":"name"},"sessionId":"684becf4-e6f5-4204-a482-dcb7cee67b78"},"jsonrpc":"2.0"} 0:01:15.348018756 8 0x7fba98001780 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:429:processMessage: Response: {"id":90,"jsonrpc":"2.0","result":{"sessionId":"684becf4-e6f5-4204-a482-dcb7cee67b78","value":"il9st3pdlahq4xod_CAMERA_WVFUE"}} 2018-11-05 04:03:29,179 DEBG 'kms' stdout output: 0:01:15.356603273 8 0x7fbac8001f20 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:427:processMessage: Message: {"id":91,"method":"unsubscribe","params":{"subscription":"2681543b-7402-4713-aeda-f7b175e9c612","object":"ec7fc361-5119-4188-a749-561ce19a60d6_kurento.MediaPipeline/e5083987-b292-452f-b490-373465dd7d32_kurento.PassThrough","sessionId":"684becf4-e6f5-4204-a482-dcb7cee67b78"},"jsonrpc":"2.0"} 2018-11-05 04:03:29,179 DEBG 'kms' stdout output: 0:01:15.356750147 8 0x7fbac8001f20 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:429:processMessage: Response: {"id":91,"jsonrpc":"2.0","result":{"sessionId":"684becf4-e6f5-4204-a482-dcb7cee67b78"}} 2018-11-05 04:03:29,183 DEBG 'openvidu-server' stdout output: [INFO] 2018-11-05 04:03:29,180 [m3cmu1-e2-t0] io.openvidu.server.core.SessionManager (showTokens) - : {kcibrlwwmnfc1zei={}} [INFO] 2018-11-05 04:03:29,182 [m3cmu1-e2-t0] io.openvidu.server.kurento.core.KurentoSessionManager (leaveRoom) - Last participant left. Stopping recording for session kcibrlwwmnfc1zei 2018-11-05 04:03:29,183 DEBG 'kms' stdout output: 0:01:15.358163867 8 0x7fbaf0001840 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:427:processMessage: Message: {"id":92,"method":"release","params":{"object":"ec7fc361-5119-4188-a749-561ce19a60d6_kurento.MediaPipeline/e5083987-b292-452f-b490-373465dd7d32_kurento.PassThrough","sessionId":"684becf4-e6f5-4204-a482-dcb7cee67b78"},"jsonrpc":"2.0"} 0:01:15.358331199 8 0x7fbaf0001840 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:429:processMessage: Response: {"id":92,"jsonrpc":"2.0","result":{"sessionId":"684becf4-e6f5-4204-a482-dcb7cee67b78"}} 0:01:15.358352083 8 0x7fbaf0001840 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:427:processMessage: Message: {"id":93,"method":"release","params":{"object":"ec7fc361-5119-4188-a749-561ce19a60d6_kurento.MediaPipeline/798afcf1-4327-4faf-87a6-57cd785b63a8_kurento.WebRtcEndpoint","sessionId":"684becf4-e6f5-4204-a482-dcb7cee67b78"},"jsonrpc":"2.0"} 0:01:15.358436517 8 0x7fbaf0001840 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:429:processMessage: Response: {"id":93,"jsonrpc":"2.0","result":{"sessionId":"684becf4-e6f5-4204-a482-dcb7cee67b78"}} 0:01:15.358557483 8 0x561c29337700 DEBUG KurentoMediaElementImpl MediaElementImpl.cpp:1007:disconnect: Disconnecting ec7fc361-5119-4188-a749-561ce19a60d6_kurento.MediaPipeline/798afcf1-4327-4faf-87a6-57cd785b63a8_kurento.WebRtcEndpoint - ec7fc361-5119-4188-a749-561ce19a60d6_kurento.MediaPipeline/e5083987-b292-452f-b490-373465dd7d32_kurento.PassThrough params AUDIO default default 0:01:15.358803067 8 0x561c29337700 DEBUG KurentoMediaElementImpl MediaElementImpl.cpp:1007:disconnect: Disconnecting ec7fc361-5119-4188-a749-561ce19a60d6_kurento.MediaPipeline/798afcf1-4327-4faf-87a6-57cd785b63a8_kurento.WebRtcEndpoint - ec7fc361-5119-4188-a749-561ce19a60d6_kurento.MediaPipeline/e5083987-b292-452f-b490-373465dd7d32_kurento.PassThrough params DATA default default 0:01:15.358827392 8 0x561c29337700 DEBUG KurentoMediaElementImpl MediaElementImpl.cpp:1007:disconnect: Disconnecting ec7fc361-5119-4188-a749-561ce19a60d6_kurento.MediaPipeline/798afcf1-4327-4faf-87a6-57cd785b63a8_kurento.WebRtcEndpoint - ec7fc361-5119-4188-a749-561ce19a60d6_kurento.MediaPipeline/e5083987-b292-452f-b490-373465dd7d32_kurento.PassThrough params VIDEO default default 0:01:15.359002326 8 0x561c29337700 DEBUG KurentoMediaSet MediaSet.cpp:470:async_delete: Destroying PassThrough -> ec7fc361-5119-4188-a749-561ce19a60d6_kurento.MediaPipeline/e5083987-b292-452f-b490-373465dd7d32_kurento.PassThrough 0:01:15.359485931 8 0x561c29337700 DEBUG KurentoMediaSet MediaSet.cpp:470:async_delete: Destroying WebRtcEndpoint -> ec7fc361-5119-4188-a749-561ce19a60d6_kurento.MediaPipeline/798afcf1-4327-4faf-87a6-57cd785b63a8_kurento.WebRtcEndpoint 2018-11-05 04:03:29,199 DEBG 'openvidu-server' stdout output: [INFO] 2018-11-05 04:03:29,198 [m3cmu1-e2-t0] io.openvidu.server.core.Session (leave) - PARTICIPANT RECORDER: Leaving session kcibrlwwmnfc1zei [WARN] 2018-11-05 04:03:29,198 [m3cmu1-e2-t0] io.openvidu.server.kurento.core.KurentoParticipant (releasePublisherEndpoint) - PARTICIPANT RECORDER: Trying to release publisher endpoint but is null [INFO] 2018-11-05 04:03:29,198 [m3cmu1-e2-t0] io.openvidu.server.core.SessionManager (showTokens) - : {kcibrlwwmnfc1zei={}} 2018-11-05 04:03:29,200 DEBG 'openvidu-server' stdout output: [INFO] 2018-11-05 04:03:29,199 [m3cmu1-e2-t0] io.openvidu.server.kurento.core.KurentoSessionManager (leaveRoom) - No more participants in session 'kcibrlwwmnfc1zei', removing it and closing it 2018-11-05 04:03:29,203 DEBG 'openvidu-server' stdout output: [WARN] 2018-11-05 04:03:29,200 [m3cmu1-e2-t0] io.openvidu.server.core.SessionManager (closeSessionAndEmptyCollections) - Session 'kcibrlwwmnfc1zei' removed and closed [INFO] 2018-11-05 04:03:29,200 [m3cmu1-e2-t0] io.openvidu.server.core.SessionManager (showTokens) - : {} 2018-11-05 04:03:29,204 DEBG 'kms' stdout output: 0:01:15.378207752 8 0x7fbadc04acc0 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:427:processMessage: Message: {"id":94,"method":"release","params":{"object":"ec7fc361-5119-4188-a749-561ce19a60d6_kurento.MediaPipeline","sessionId":"684becf4-e6f5-4204-a482-dcb7cee67b78"},"jsonrpc":"2.0"} 0:01:15.378326717 8 0x561c29337700 DEBUG KurentoMediaSet MediaSet.cpp:470:async_delete: Destroying MediaPipeline -> ec7fc361-5119-4188-a749-561ce19a60d6_kurento.MediaPipeline 0:01:15.378380084 8 0x7fbadc04acc0 DEBUG KurentoWebSocketTransport WebSocketTransport.cpp:429:processMessage: Response: {"id":94,"jsonrpc":"2.0","result":{"sessionId":"684becf4-e6f5-4204-a482-dcb7cee67b78"}} 2018-11-05 04:03:29,209 DEBG 'openvidu-server' stdout output: [INFO] 2018-11-05 04:03:29,207 [m3cmu1-e2-t0] io.openvidu.server.rpc.RpcNotificationService (closeRpcSession) - Closed session for participant with private id 1hsi3c1gt3cvlslgrdg55ol0u3 [WARN] 2018-11-05 04:03:29,207 [Thread-5] io.openvidu.server.recording.ComposedRecordingService (lambda$stopRecording$0) - Session closed while starting recording container [INFO] 2018-11-05 04:03:29,207 [m3cmu1-e2-t0] io.openvidu.server.rpc.RpcNotificationService (showRpcConnections) - : {vr5ubjehf62iit3lnkmim3cmu1=io.openvidu.server.rpc.RpcConnection@4c4a3ea8} 2018-11-05 04:03:29,211 DEBG 'openvidu-server' stdout output: [WARN] 2018-11-05 04:03:29,208 [Thread-5] io.openvidu.server.recording.ComposedRecordingService (lambda$stopRecording$0) - Waiting for container to be launched... 2018-11-05 04:03:29,212 DEBG 'openvidu-server' stdout output: [INFO] 2018-11-05 04:03:29,211 [m3cmu1-e2-t0] io.openvidu.server.rpc.RpcNotificationService (closeRpcSession) - Closed session for participant with private id vr5ubjehf62iit3lnkmim3cmu1 [INFO] 2018-11-05 04:03:29,212 [m3cmu1-e2-t0] io.openvidu.server.rpc.RpcNotificationService (showRpcConnections) - : {} [INFO] 2018-11-05 04:03:29,212 [m3cmu1-e2-t0] io.openvidu.server.rpc.RpcHandler (leaveRoom) - Participant il9st3pdlahq4xod has left session kcibrlwwmnfc1zei 2018-11-05 04:03:29,711 DEBG 'openvidu-server' stdout output: [WARN] 2018-11-05 04:03:29,711 [Thread-5] io.openvidu.server.recording.ComposedRecordingService (lambda$stopRecording$0) - Waiting for container to be launched... 2018-11-05 04:03:30,211 DEBG 'openvidu-server' stdout output: [WARN] 2018-11-05 04:03:30,211 [Thread-5] io.openvidu.server.recording.ComposedRecordingService (lambda$stopRecording$0) - Waiting for container to be launched... 2018-11-05 04:03:30,712 DEBG 'openvidu-server' stdout output: [WARN] 2018-11-05 04:03:30,711 [Thread-5] io.openvidu.server.recording.ComposedRecordingService (lambda$stopRecording$0) - Waiting for container to be launched... 2018-11-05 04:03:31,212 DEBG 'openvidu-server' stdout output: [WARN] 2018-11-05 04:03:31,212 [Thread-5] io.openvidu.server.recording.ComposedRecordingService (lambda$stopRecording$0) - Waiting for container to be launched... 2018-11-05 04:03:31,712 DEBG 'openvidu-server' stdout output: [WARN] 2018-11-05 04:03:31,712 [Thread-5] io.openvidu.server.recording.ComposedRecordingService (lambda$stopRecording$0) - Waiting for container to be launched... 2018-11-05 04:03:32,213 DEBG 'openvidu-server' stdout output: [WARN] 2018-11-05 04:03:32,212 [Thread-5] io.openvidu.server.recording.ComposedRecordingService (lambda$stopRecording$0) - Waiting for container to be launched... 2018-11-05 04:03:32,713 DEBG 'openvidu-server' stdout output: [WARN] 2018-11-05 04:03:32,712 [Thread-5] io.openvidu.server.recording.ComposedRecordingService (lambda$stopRecording$0) - Waiting for container to be launched...