I am trying to save recordings but when i hit the api call for recording to start it throws below error
{
"timestamp": "2020-06-04T08:43:39.597+0000",
"status": 500,
"error": "Internal Server Error",
"message": "No message available",
"path": "/api/recordings/start"
}
In file system i have mounted s3 bucket and provided path to directly save data to s3 as i have already implemented before for another project in docker. If i unmount bucket the process works smoothly.
Can you post here the openvidu-server logs when you try to start the recording?
I have deployed it over aws so can you guide me how do i obtain logs
Just like this: https://docs.openvidu.io/en/2.14.0/deployment/deploying-on-premises/#show-service-logs
openvidu-server_1 | [INFO] 2020-06-04 13:21:18,165 [0.0-5443-exec-5] io.openvidu.server.rest.SessionRestController - REST API: POST /api/recordings/start {session=12346, name=12346, outputMode=COMPOSED, hasAudio=true, hasVideo=true, resolution=640x480, recordingLayout=BEST_FIT}
openvidu-server_1 | [INFO] 2020-06-04 13:21:18,165 [0.0-5443-exec-5] io.openvidu.server.recording.service.RecordingService - New recording id (12346) and final name (12346)
openvidu-server_1 | [INFO] 2020-06-04 13:21:18,165 [0.0-5443-exec-5] io.openvidu.server.recording.service.ComposedRecordingService - Starting composed (video + audio) recording 12346 of session 12346
openvidu-server_1 | [INFO] 2020-06-04 13:21:18,165 [0.0-5443-exec-5] io.openvidu.server.recording.service.ComposedRecordingService - {"id":"12346","name":"12346","outputMode":"COMPOSED","resolution":"640x480","recordingLayout":"BEST_FIT","sessionId":"12346","createdAt":1591276878165,"size":0,"duration":0.0,"url":null,"hasAudio":true,"hasVideo":true,"status":"started"}
openvidu-server_1 | [INFO] 2020-06-04 13:21:18,165 [0.0-5443-exec-5] io.openvidu.server.recording.service.ComposedRecordingService - Recorder connecting to url https://USER:PASS@vidu.topdoc.ai/inspector/#/layout-best-fit/12346/PASS/-1/false
openvidu-server_1 | [ERROR] 2020-06-04 13:21:18,171 [0.0-5443-exec-5] io.openvidu.server.utils.DockerManager - The container name recording_12346 is already in use. Probably caused by a session with unique publisher re-publishing a stream
openvidu-server_1 | [ERROR] 2020-06-04 13:21:18,171 [0.0-5443-exec-5] io.openvidu.server.recording.service.RecordingService - Recording start failed for session 12346: Couldn't initialize recording container. Error: Conflict. The container name "/recording_12346" is already in use by container "1ab41dff1e644aa767bf116c7750af29abd07eff42395fa5a01c2f20cc0339fc". You have to remove (or rename) that container to be able to reuse that name.
openvidu-server_1 | [ERROR] 2020-06-04 13:21:18,171 [0.0-5443-exec-5] io.openvidu.server.utils.CustomFileManager - Couldn't overwrite file /opt/openvidu/recordings/12346/.recording.12346. Error: /opt/openvidu/recordings/12346/.recording.12346 (No such file or directory)
openvidu-server_1 | [INFO] 2020-06-04 13:21:18,171 [0.0-5443-exec-5] io.openvidu.server.recording.service.ComposedRecordingService - Stopping composed (video + audio) recording 12346 of session 12346. Reason: null
openvidu-server_1 | [INFO] 2020-06-04 13:21:18,400 [entExec-e55-t66] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaStateChangedEvent]: -> endpoint: str_CAM_VJXM_con_BqLocYijki (publisher) | oldState: DISCONNECTED | newState: CONNECTED | timestamp: 1591276878396
openvidu-server_1 | [INFO] 2020-06-04 13:21:18,404 [O dispatcher 36] io.openvidu.server.pro.cdr.CDRLoggerElasticSearch - New event of type "kms" sent to Elasticsearch: {"oldState":"DISCONNECTED","newState":"CONNECTED","type":"MediaStateChanged","timestamp":1591276878396,"sessionId":"12346","user":"B5B081D097A061A4","connection":"con_BqLocYijki","endpoint":"str_CAM_VJXM_con_BqLocYijki","msSinceEndpointCreation":1637,"elastic_type":"kms"}
openvidu-server_1 | [INFO] 2020-06-04 13:21:18,446 [Timer-4] io.openvidu.server.core.SessionManager - Running non active sessions garbage collector...
openvidu-server_1 | [INFO] 2020-06-04 13:21:20,417 [O dispatcher 36] io.openvidu.server.pro.cdr.CDRLoggerElasticSearch - New event of type "monitoringStats" sent to Elasticsearch: {"timestamp":1591276880411,"cpu":1.268909318846636,"mem":{"used":3237140.0,"percentage":19.701082325905226},"net":{"eth0":{"rxBytes":26273439,"txBytes":38381803},"veth035219c":{"rxBytes":29378,"txBytes":39914},"veth6946c39":{"rxBytes":10500343,"txBytes":4217440},"vethcf9fa3e":{"rxBytes":4490634,"txBytes":10999921}},"elastic_type":"monitoringStats"}
openvidu-server_1 | [INFO] 2020-06-04 13:21:20,645 [Timer-2] io.openvidu.server.core.SessionManager - Running non active sessions garbage collector...
openvidu-server_1 | [INFO] 2020-06-04 13:21:22,294 [0.0-5443-exec-8] io.openvidu.server.rest.SessionRestController - REST API: GET /api/sessions
openvidu-server_1 | [INFO] 2020-06-04 13:21:22,360 [Timer-3] io.openvidu.server.core.SessionManager - Running non active sessions garbage collector...
openvidu-server_1 | [INFO] 2020-06-04 13:21:24,041 [Timer-1] io.openvidu.server.core.SessionManager - Running non active sessions garbage collector...
openvidu-server_1 | [INFO] 2020-06-04 13:21:27,289 [0.0-5443-exec-2] io.openvidu.server.rest.SessionRestController - REST API: GET /api/sessions
openvidu-server_1 | [INFO] 2020-06-04 13:21:27,846 [Thread-12] io.openvidu.server.pro.account.LambdaService - Usage POST HTTP status: HTTP/1.1 200 OK
openvidu-server_1 | [INFO] 2020-06-04 13:21:27,846 [Thread-12] io.openvidu.server.pro.account.LambdaService - Usage successfully posted (160)
openvidu-server_1 | [INFO] 2020-06-04 13:21:28,111 [entExec-e55-t66] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceComponentStateChange]: -> endpoint: str_CAM_VJXM_con_BqLocYijki (publisher) | state: READY | componentId: 1 | streamId: 1 | timestamp: 1591276888108
openvidu-server_1 | [INFO] 2020-06-04 13:21:28,116 [O dispatcher 36] io.openvidu.server.pro.cdr.CDRLoggerElasticSearch - New event of type "kms" sent to Elasticsearch: {"streamId":1,"componentId":1,"state":"READY","type":"IceComponentStateChange","timestamp":1591276888108,"sessionId":"12346","user":"B5B081D097A061A4","connection":"con_BqLocYijki","endpoint":"str_CAM_VJXM_con_BqLocYijki","msSinceEndpointCreation":11349,"elastic_type":"kms"}
openvidu-server_1 | [INFO] 2020-06-04 13:21:28,445 [Timer-4] io.openvidu.server.core.SessionManager - Running non active sessions garbage collector...
openvidu-server_1 | [INFO] 2020-06-04 13:21:32,360 [Timer-3] io.openvidu.server.core.SessionManager - Running non active sessions garbage collector...
openvidu-server_1 | [INFO] 2020-06-04 13:21:32,368 [0.0-5443-exec-4] io.openvidu.server.rest.SessionRestController - REST API: GET /api/sessions
openvidu-server_1 | [INFO] 2020-06-04 13:21:32,635 [entExec-e55-t66] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceGatheringDone] -> endpoint: str_CAM_VJXM_con_BqLocYijki (publisher) | timestamp: 1591276892632
openvidu-server_1 | [INFO] 2020-06-04 13:21:32,639 [O dispatcher 36] io.openvidu.server.pro.cdr.CDRLoggerElasticSearch - New event of type "kms" sent to Elasticsearch: {"type":"IceGatheringDone","timestamp":1591276892632,"sessionId":"12346","user":"B5B081D097A061A4","connection":"con_BqLocYijki","endpoint":"str_CAM_VJXM_con_BqLocYijki","msSinceEndpointCreation":15873,"elastic_type":"kms"}
openvidu-server_1 | [INFO] 2020-06-04 13:21:37,289 [0.0-5443-exec-7] io.openvidu.server.rest.SessionRestController - REST API: GET /api/sessions
openvidu-server_1 | [INFO] 2020-06-04 13:21:38,445 [Timer-4] io.openvidu.server.core.SessionManager - Running non active sessions garbage collector...
openvidu-server_1 | [INFO] 2020-06-04 13:21:42,291 [0.0-5443-exec-5] io.openvidu.server.rest.SessionRestController - REST API: GET /api/sessions
openvidu-server_1 | [INFO] 2020-06-04 13:21:42,360 [Timer-3] io.openvidu.server.core.SessionManager - Running non active sessions garbage collector...
openvidu-server_1 | [INFO] 2020-06-04 13:21:46,765 [O dispatcher 36] io.openvidu.server.pro.cdr.CDRLoggerElasticSearch - New event of type "webrtcStats" sent to Elasticsearch: {"sessionId":"12346","user":"B5B081D097A061A4","connection":"con_BqLocYijki","endpoint":"str_CAM_VJXM_con_BqLocYijki","mediaType":"audio","jitter":0.0032500000670552254,"bytesReceived":109716,"packetsReceived":1446,"packetsLost":0,"timestamp":1591276906757,"fractionLost":0.0,"remb":0,"firCount":0,"pliCount":0,"nackCount":0,"sliCount":0,"elastic_type":"webrtcStats"}
openvidu-server_1 | [INFO] 2020-06-04 13:21:46,765 [O dispatcher 34] io.openvidu.server.pro.cdr.CDRLoggerElasticSearch - New event of type "webrtcStats" sent to Elasticsearch: {"sessionId":"12346","user":"B5B081D097A061A4","connection":"con_BqLocYijki","endpoint":"str_CAM_VJXM_con_BqLocYijki","mediaType":"video","jitter":0.005122222006320953,"bytesReceived":2651741,"packetsReceived":2485,"packetsLost":0,"timestamp":1591276906757,"fractionLost":0.0,"remb":1000000,"firCount":2,"pliCount":0,"nackCount":0,"sliCount":0,"elastic_type":"webrtcStats"}
openvidu-server_1 | [INFO] 2020-06-04 13:21:47,291 [0.0-5443-exec-8] io.openvidu.server.rest.SessionRestController - REST API: GET /api/sessions
openvidu-server_1 | [INFO] 2020-06-04 13:21:48,445 [Timer-4] io.openvidu.server.core.SessionManager - Running non active sessions garbage collector...
openvidu-server_1 | [INFO] 2020-06-04 13:21:50,424 [O dispatcher 36] io.openvidu.server.pro.cdr.CDRLoggerElasticSearch - New event of type "monitoringStats" sent to Elasticsearch: {"timestamp":1591276910418,"cpu":0.767379679144385,"mem":{"used":3237864.0,"percentage":19.705488555973727},"net":{"eth0":{"rxBytes":26370189,"txBytes":38521428},"veth035219c":{"rxBytes":29378,"txBytes":39914},"veth6946c39":{"rxBytes":10542191,"txBytes":4234230},"vethcf9fa3e":{"rxBytes":4509334,"txBytes":11045022}},"elastic_type":"monitoringStats"}
openvidu-server_1 | [INFO] 2020-06-04 13:21:50,646 [Timer-2] io.openvidu.server.core.SessionManager - Running non active sessions garbage collector...
openvidu-server_1 | [INFO] 2020-06-04 13:21:52,291 [0.0-5443-exec-2] io.openvidu.server.rest.SessionRestController - REST API: GET /api/sessions
openvidu-server_1 | [INFO] 2020-06-04 13:21:52,361 [Timer-3] io.openvidu.server.core.SessionManager - Running non active sessions garbage collector...
openvidu-server_1 | [INFO] 2020-06-04 13:21:57,290 [0.0-5443-exec-4] io.openvidu.server.rest.SessionRestController - REST API: GET /api/sessions
openvidu-server_1 | [INFO] 2020-06-04 13:21:58,445 [Timer-4] io.openvidu.server.core.SessionManager - Running non active sessions garbage collector...
openvidu-server_1 | [INFO] 2020-06-04 13:22:02,289 [0.0-5443-exec-7] io.openvidu.server.rest.SessionRestController - REST API: GET /api/sessions
openvidu-server_1 | [INFO] 2020-06-04 13:22:02,360 [Timer-3] io.openvidu.server.core.SessionManager - Running non active sessions garbage collector...
openvidu-server_1 | [INFO] 2020-06-04 13:22:07,289 [0.0-5443-exec-5] io.openvidu.server.rest.SessionRestController - REST API: GET /api/sessions
openvidu-server_1 | [INFO] 2020-06-04 13:22:08,445 [Timer-4] io.openvidu.server.core.SessionManager - Running non active sessions garbage collector...
openvidu-server_1 | [INFO] 2020-06-04 13:22:12,296 [0.0-5443-exec-8] io.openvidu.server.rest.SessionRestController - REST API: GET /api/sessions
openvidu-server_1 | [INFO] 2020-06-04 13:22:12,360 [Timer-3] io.openvidu.server.core.SessionManager - Running non active sessions garbage collector...
openvidu-server_1 | [INFO] 2020-06-04 13:22:16,765 [O dispatcher 34] io.openvidu.server.pro.cdr.CDRLoggerElasticSearch - New event of type "webrtcStats" sent to Elasticsearch: {"sessionId":"12346","user":"B5B081D097A061A4","connection":"con_BqLocYijki","endpoint":"str_CAM_VJXM_con_BqLocYijki","mediaType":"video","jitter":0.00562222208827734,"bytesReceived":6416582,"packetsReceived":5894,"packetsLost":0,"timestamp":1591276936758,"fractionLost":0.0,"remb":1000000,"firCount":2,"pliCount":0,"nackCount":0,"sliCount":0,"elastic_type":"webrtcStats"}
openvidu-server_1 | [INFO] 2020-06-04 13:22:16,765 [O dispatcher 36] io.openvidu.server.pro.cdr.CDRLoggerElasticSearch - New event of type "webrtcStats" sent to Elasticsearch: {"sessionId":"12346","user":"B5B081D097A061A4","connection":"con_BqLocYijki","endpoint":"str_CAM_VJXM_con_BqLocYijki","mediaType":"audio","jitter":0.002812500111758709,"bytesReceived":226782,"packetsReceived":2946,"packetsLost":0,"timestamp":1591276936757,"fractionLost":0.0,"remb":0,"firCount":0,"pliCount":0,"nackCount":0,"sliCount":0,"elastic_type":"webrtcStats"}
openvidu-server_1 | [INFO] 2020-06-04 13:22:17,291 [0.0-5443-exec-2] io.openvidu.server.rest.SessionRestController - REST API: GET /api/sessionsSeems to me that there is a recording container already running for that session. OpenVidu Server is warning about the name of the container already being used.
I’m not sure how you have reached that point. The error tells that is may be caused by a user trying to publish twice to the session (which is not supported).
Try cleaning up the stranded docker containers (openvidu-server should do that for you on startup). docker ps -a command will show you all of the containers in the machine.
The session is not publishing twice it is about storing the recordings which is throwing fatal error. As i have told earlier that using instance storage i am not getting any error and also getting recordings.
So, you are sure the problem is casued by this: "i have mounted s3 bucket and provided path to directly save data to s3". Then I’m not sure how to help you. That is not currently an officially suported feature, so you might encounter problems like this. If you find anything meaningful related to this issue, please come back here and post your findings.
Cheers.