Unable to start recording

Issues developing apps
1

Describe the bug
API call /api/recordings/start returns error 500.
Happens to a particular iPhone SX version 14.3.

Expected behavior
Start Record API success

Wrong current behavior
API call /api/recordings/start returns error 500.

OpenVidu tutorial where to replicate the error
This is an EXTREMELY IMPORTANT STEP. If we are able to replicate the error in any of the official OpenVidu Tutorials or OpenVidu Demos, then we will be able to quickly fix it. If you are getting the error in your own application, please try to add the necessary changes to the most similar tutorial so it fails with the same error (try to keep those changes as contained as possible, so that the original tutorial maintains its integrity). Once you have an application to replicate the error, explain in detail the steps to get it like this:

  1. Clone repository […]
  2. Compile the application like this […]
  3. Run OpenVidu Server like this […]
  4. Run the application like this […]
  5. Join 1 user… Publish a video stream […]
  6. See error

OpenVidu deployment info
How is your OpenVidu Server instance deployed when you get the bug. A couple of possible examples are listed below:

  • Docker container as explained in OpenVidu tutorials, run with command docker compose up on Ubuntu 16.04.7 LTS
  • docker-compose.yml included as txt
    [docker-compose.txt]

/config returns
{
“VERSION”: “2.15.0”,
“DOMAIN_OR_PUBLIC_IP”: “SECRET”,
“HTTPS_PORT”: 443,
“OPENVIDU_PUBLICURL”: “SECRET”,
“OPENVIDU_CDR”: false,
“OPENVIDU_STREAMS_VIDEO_MAX_RECV_BANDWIDTH”: 1000,
“OPENVIDU_STREAMS_VIDEO_MIN_RECV_BANDWIDTH”: 300,
“OPENVIDU_STREAMS_VIDEO_MAX_SEND_BANDWIDTH”: 1000,
“OPENVIDU_STREAMS_VIDEO_MIN_SEND_BANDWIDTH”: 300,
“OPENVIDU_SESSIONS_GARBAGE_INTERVAL”: 900,
“OPENVIDU_SESSIONS_GARBAGE_THRESHOLD”: 3600,
“OPENVIDU_RECORDING”: true,
“OPENVIDU_RECORDING_VERSION”: “2.15.0”,
“OPENVIDU_RECORDING_PATH”: “/opt/openvidu/recordings/”,
“OPENVIDU_RECORDING_PUBLIC_ACCESS”: true,
“OPENVIDU_RECORDING_NOTIFICATION”: “publisher_moderator”,
“OPENVIDU_RECORDING_CUSTOM_LAYOUT”: “/opt/openvidu/custom-layout/”,
“OPENVIDU_RECORDING_AUTOSTOP_TIMEOUT”: 120,
“OPENVIDU_WEBHOOK”: false
}

Client device info (if applicable)
Describe the client device(s) or platform(s) where you are able to replicate the error. For example:

  • iPhone SX version 14.3

Additional context
OpenVidu log
openvidu-server_1 | [INFO] 2021-02-04 14:42:37,712 [http-nio-0.0.0.0-5443-exec-2] io.openvidu.server.rest.SessionRestController - REST API: POST /api/recordings/start {session=ses_XjRMLDJEBB, name=2021020317294844_DELL-COLOR, outputMode=INDIVIDUAL, hasAudio=true, hasVideo=true} openvidu-server_1 | [INFO] 2021-02-04 14:42:37,726 [http-nio-0.0.0.0-5443-exec-2] io.openvidu.server.recording.service.RecordingService - New recording id (ses_XjRMLDJEBB) and final name (2021020317294844_DELL-COLOR) openvidu-server_1 | [INFO] 2021-02-04 14:42:37,726 [http-nio-0.0.0.0-5443-exec-2] io.openvidu.server.recording.service.SingleStreamRecordingService - Starting individual (video+audio) recording ses_XjRMLDJEBB of session ses_XjRMLDJEBB openvidu-server_1 | [INFO] 2021-02-04 14:42:37,726 [http-nio-0.0.0.0-5443-exec-2] io.openvidu.server.recording.service.SingleStreamRecordingService - Starting single stream recorder for stream str_CAM_LnrL_con_IcIxQSDqu4 in session ses_XjRMLDJEBB openvidu-server_1 | [INFO] 2021-02-04 14:42:37,736 [http-nio-0.0.0.0-5443-exec-2] io.openvidu.server.recording.service.SingleStreamRecordingService - Starting single stream recorder for stream str_CAM_W17g_con_EDgUmAAOvY in session ses_XjRMLDJEBB openvidu-server_1 | [INFO] 2021-02-04 14:42:37,796 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.kurento.core.KurentoParticipant - PARTICIPANT con_IcIxQSDqu4: Is now receiving video from con_EDgUmAAOvY in room ses_XjRMLDJEBB openvidu-server_1 | [INFO] 2021-02-04 14:42:37,796 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.cdr.CDRLoggerFile - {"webrtcConnectionCreated":{"sessionId":"ses_XjRMLDJEBB","timestamp":1612420957784,"streamId":"str_CAM_W17g_con_EDgUmAAOvY","participantId":"con_IcIxQSDqu4","connection":"INBOUND","receivingFrom":"con_EDgUmAAOvY","videoSource":"CAMERA","videoFramerate":30,"videoDimensions":"{\"width\":480,\"height\":640}","audioEnabled":true,"videoEnabled":true}} openvidu-server_1 | [INFO] 2021-02-04 14:42:37,797 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IcIxQSDqu4 is calling method 'onIceCandidate' in session ses_XjRMLDJEBB openvidu-server_1 | [INFO] 2021-02-04 14:42:37,797 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_IcIxQSDqu4: {connectionId: "con_EDgUmAAOvY", sdpMid: 0, sdpMLineIndex: 0, candidate: "candidate:1858505977 1 udp 2122260223 10.8.2.55 57625 typ host generation 0 ufrag gXqI network-id 2"} openvidu-server_1 | [INFO] 2021-02-04 14:42:37,797 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IcIxQSDqu4 is calling method 'onIceCandidate' in session ses_XjRMLDJEBB openvidu-server_1 | [INFO] 2021-02-04 14:42:37,797 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_IcIxQSDqu4: {connectionId: "con_EDgUmAAOvY", sdpMid: 0, sdpMLineIndex: 0, candidate: "candidate:253191485 1 udp 2122194687 192.168.0.199 57626 typ host generation 0 ufrag gXqI network-id 1 network-cost 10"} openvidu-server_1 | [INFO] 2021-02-04 14:42:37,798 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IcIxQSDqu4 is calling method 'onIceCandidate' in session ses_XjRMLDJEBB openvidu-server_1 | [INFO] 2021-02-04 14:42:37,798 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_IcIxQSDqu4: {connectionId: "con_EDgUmAAOvY", sdpMid: 1, sdpMLineIndex: 1, candidate: "candidate:1858505977 1 udp 2122260223 10.8.2.55 57627 typ host generation 0 ufrag gXqI network-id 2"} openvidu-server_1 | [INFO] 2021-02-04 14:42:37,798 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IcIxQSDqu4 is calling method 'onIceCandidate' in session ses_XjRMLDJEBB openvidu-server_1 | [INFO] 2021-02-04 14:42:37,798 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_IcIxQSDqu4: {connectionId: "con_EDgUmAAOvY", sdpMid: 1, sdpMLineIndex: 1, candidate: "candidate:253191485 1 udp 2122194687 192.168.0.199 57628 typ host generation 0 ufrag gXqI network-id 1 network-cost 10"} openvidu-server_1 | [INFO] 2021-02-04 14:42:37,798 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IcIxQSDqu4 is calling method 'onIceCandidate' in session ses_XjRMLDJEBB openvidu-server_1 | [INFO] 2021-02-04 14:42:37,798 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_IcIxQSDqu4: {connectionId: "con_EDgUmAAOvY", sdpMid: 0, sdpMLineIndex: 0, candidate: "candidate:743142001 1 udp 1686052607 172.30.78.96 29210 typ srflx raddr 10.8.2.55 rport 57625 generation 0 ufrag gXqI network-id 2"} openvidu-server_1 | [INFO] 2021-02-04 14:42:37,798 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IcIxQSDqu4 is calling method 'onIceCandidate' in session ses_XjRMLDJEBB openvidu-server_1 | [INFO] 2021-02-04 14:42:37,798 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_IcIxQSDqu4: {connectionId: "con_EDgUmAAOvY", sdpMid: 1, sdpMLineIndex: 1, candidate: "candidate:743142001 1 udp 1686052607 172.30.78.96 29208 typ srflx raddr 10.8.2.55 rport 57627 generation 0 ufrag gXqI network-id 2"} openvidu-server_1 | [INFO] 2021-02-04 14:42:37,798 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IcIxQSDqu4 is calling method 'onIceCandidate' in session ses_XjRMLDJEBB openvidu-server_1 | [INFO] 2021-02-04 14:42:37,798 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_IcIxQSDqu4: {connectionId: "con_EDgUmAAOvY", sdpMid: 0, sdpMLineIndex: 0, candidate: "candidate:24757102 1 udp 41885695 172.30.78.162 61351 typ relay raddr 172.30.78.96 rport 29210 generation 0 ufrag gXqI network-id 2"} openvidu-server_1 | [INFO] 2021-02-04 14:42:37,799 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IcIxQSDqu4 is calling method 'onIceCandidate' in session ses_XjRMLDJEBB openvidu-server_1 | [INFO] 2021-02-04 14:42:37,799 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_IcIxQSDqu4: {connectionId: "con_EDgUmAAOvY", sdpMid: 1, sdpMLineIndex: 1, candidate: "candidate:24757102 1 udp 41885695 172.30.78.162 62665 typ relay raddr 172.30.78.96 rport 29208 generation 0 ufrag gXqI network-id 2"} openvidu-server_1 | [INFO] 2021-02-04 14:42:37,799 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IcIxQSDqu4 is calling method 'onIceCandidate' in session ses_XjRMLDJEBB openvidu-server_1 | [INFO] 2021-02-04 14:42:37,799 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_IcIxQSDqu4: {connectionId: "con_EDgUmAAOvY", sdpMid: 1, sdpMLineIndex: 1, candidate: "candidate:1341800350 1 udp 25108223 172.30.78.162 60572 typ relay raddr 172.30.78.96 rport 12303 generation 0 ufrag gXqI network-id 2"} openvidu-server_1 | [INFO] 2021-02-04 14:42:37,799 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IcIxQSDqu4 is calling method 'onIceCandidate' in session ses_XjRMLDJEBB openvidu-server_1 | [INFO] 2021-02-04 14:42:37,799 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_IcIxQSDqu4: {connectionId: "con_EDgUmAAOvY", sdpMid: 0, sdpMLineIndex: 0, candidate: "candidate:1341800350 1 udp 25108223 172.30.78.162 58949 typ relay raddr 172.30.78.96 rport 12298 generation 0 ufrag gXqI network-id 2"} openvidu-server_1 | [INFO] 2021-02-04 14:42:37,799 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IcIxQSDqu4 is calling method 'onIceCandidate' in session ses_XjRMLDJEBB openvidu-server_1 | [INFO] 2021-02-04 14:42:37,799 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_IcIxQSDqu4: {connectionId: "con_EDgUmAAOvY", sdpMid: 0, sdpMLineIndex: 0, candidate: "candidate:541421577 1 tcp 1518280447 10.8.2.55 9 typ host tcptype active generation 0 ufrag gXqI network-id 2"} openvidu-server_1 | [INFO] 2021-02-04 14:42:37,799 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IcIxQSDqu4 is calling method 'onIceCandidate' in session ses_XjRMLDJEBB openvidu-server_1 | [INFO] 2021-02-04 14:42:37,799 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_IcIxQSDqu4: {connectionId: "con_EDgUmAAOvY", sdpMid: 0, sdpMLineIndex: 0, candidate: "candidate:1100257741 1 tcp 1518214911 192.168.0.199 9 typ host tcptype active generation 0 ufrag gXqI network-id 1 network-cost 10"} openvidu-server_1 | [INFO] 2021-02-04 14:42:37,800 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IcIxQSDqu4 is calling method 'onIceCandidate' in session ses_XjRMLDJEBB openvidu-server_1 | [INFO] 2021-02-04 14:42:37,800 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_IcIxQSDqu4: {connectionId: "con_EDgUmAAOvY", sdpMid: 1, sdpMLineIndex: 1, candidate: "candidate:541421577 1 tcp 1518280447 10.8.2.55 9 typ host tcptype active generation 0 ufrag gXqI network-id 2"} openvidu-server_1 | [INFO] 2021-02-04 14:42:37,800 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - Participant con_IcIxQSDqu4 is calling method 'onIceCandidate' in session ses_XjRMLDJEBB openvidu-server_1 | [INFO] 2021-02-04 14:42:37,800 [SessionHandler-cdu0nmembugjn1a6eorsc59d3c-e238-t0] io.openvidu.server.rpc.RpcHandler - New candidate received from participant con_IcIxQSDqu4: {connectionId: "con_EDgUmAAOvY", sdpMid: 1, sdpMLineIndex: 1, candidate: "candidate:1100257741 1 tcp 1518214911 192.168.0.199 9 typ host tcptype active generation 0 ufrag gXqI network-id 1 network-cost 10"} openvidu-server_1 | [INFO] 2021-02-04 14:42:37,802 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t866] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceGatheringDone] -> endpoint: con_IcIxQSDqu4_str_CAM_W17g_con_EDgUmAAOvY (subscriber) | timestamp: 1612420957801 openvidu-server_1 | [INFO] 2021-02-04 14:42:37,842 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t869] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceComponentStateChange]: -> endpoint: con_IcIxQSDqu4_str_CAM_W17g_con_EDgUmAAOvY (subscriber) | state: CONNECTING | componentId: 1 | streamId: 1 | timestamp: 1612420957802 openvidu-server_1 | [INFO] 2021-02-04 14:42:37,978 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t869] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceComponentStateChange]: -> endpoint: con_IcIxQSDqu4_str_CAM_W17g_con_EDgUmAAOvY (subscriber) | state: CONNECTED | componentId: 1 | streamId: 1 | timestamp: 1612420957977 openvidu-server_1 | [INFO] 2021-02-04 14:42:37,978 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t865] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [NewCandidatePairSelected]: -> endpoint: con_IcIxQSDqu4_str_CAM_W17g_con_EDgUmAAOvY (subscriber) | local: candidate:1 1 UDP 2015363327 172.30.79.73 56679 typ host | remote: candidate:remote1 1 UDP 1853825023 172.30.78.125 61351 typ prflx | timestamp: 1612420957977 openvidu-server_1 | [INFO] 2021-02-04 14:42:38,079 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t865] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [ConnectionStateChanged]: -> endpoint: con_EDgUmAAOvY_str_CAM_LnrL_con_IcIxQSDqu4 (subscriber) | oldState: DISCONNECTED | newState: CONNECTED | timestamp: 1612420958078 openvidu-server_1 | [INFO] 2021-02-04 14:42:38,089 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t865] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaFlowInStateChange] -> endpoint: con_EDgUmAAOvY_str_CAM_LnrL_con_IcIxQSDqu4 (subscriber) | state: FLOWING | pad: default | mediaType: AUDIO | timestamp: 1612420958088 openvidu-server_1 | [INFO] 2021-02-04 14:42:38,824 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t865] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [ConnectionStateChanged]: -> endpoint: con_IcIxQSDqu4_str_CAM_W17g_con_EDgUmAAOvY (subscriber) | oldState: DISCONNECTED | newState: CONNECTED | timestamp: 1612420958823 openvidu-server_1 | [INFO] 2021-02-04 14:42:38,922 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t865] io.openvidu.server.recording.service.SingleStreamRecordingService - Recording started event for stream str_CAM_LnrL_con_IcIxQSDqu4 openvidu-server_1 | [INFO] 2021-02-04 14:42:38,940 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t865] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaFlowInStateChange] -> endpoint: con_EDgUmAAOvY_str_CAM_LnrL_con_IcIxQSDqu4 (subscriber) | state: FLOWING | pad: default | mediaType: VIDEO | timestamp: 1612420958939 openvidu-server_1 | [INFO] 2021-02-04 14:42:39,202 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t865] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaStateChangedEvent]: -> endpoint: con_EDgUmAAOvY_str_CAM_LnrL_con_IcIxQSDqu4 (subscriber) | oldState: DISCONNECTED | newState: CONNECTED | timestamp: 1612420959200 openvidu-server_1 | [INFO] 2021-02-04 14:42:39,280 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t865] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceComponentStateChange]: -> endpoint: con_EDgUmAAOvY_str_CAM_LnrL_con_IcIxQSDqu4 (subscriber) | state: READY | componentId: 1 | streamId: 1 | timestamp: 1612420959279 openvidu-server_1 | [INFO] 2021-02-04 14:42:39,963 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t865] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [IceComponentStateChange]: -> endpoint: con_IcIxQSDqu4_str_CAM_W17g_con_EDgUmAAOvY (subscriber) | state: READY | componentId: 1 | streamId: 1 | timestamp: 1612420959962 openvidu-server_1 | [ERROR] 2021-02-04 14:42:42,744 [http-nio-0.0.0.0-5443-exec-2] io.openvidu.server.recording.service.SingleStreamRecordingService - Error waiting for some recorder endpoint to start in session ses_XjRMLDJEBB

KMS server log
131:08:45.621889891 1 0x7ff6f412f450 FIXME basesink gstbasesink.c:3125:gst_base_sink_default_event:<nicesink74> stream-start event without group-id. Consider implementing group-id handling in the upstream elements 131:08:45.622383122 1 0x7ff6f412f800 FIXME default gstutils.c:3766:gst_pad_create_stream_id_internal:<nicesrc74:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id 131:08:45.622928853 1 0x7ff6f4021930 INFO basertpendpoint kmsbasertpendpoint.c:1118:kms_base_rtp_endpoint_start_transport_send:<kmswebrtcendpoint74> Media 'video' has REMB 131:08:45.622945569 1 0x7ff6f4021930 INFO basertpendpoint kmsbasertpendpoint.c:1064:kms_base_rtp_endpoint_create_remb_manager:<kmswebrtcendpoint74> Creating REMB for session ID 0 (kmswebrtcendpoint74-sess0) and remote video SSRC 0 131:08:45.622954611 1 0x7ff6f4021930 INFO basertpendpoint kmsbasertpendpoint.c:1075:kms_base_rtp_endpoint_create_remb_manager:<kmswebrtcendpoint74> REMB: Set RTCP min interval to 500 ms 131:08:45.633784098 1 0x7ff70c00b390 INFO KurentoRecorderEndpointImpl RecorderEndpointImpl.cpp:83:RecorderEndpointImpl: Set WEBM profile 131:08:45.635998686 1 0x7ff6d8015320 FIXME default gstutils.c:3766:gst_pad_create_stream_id_internal:<videoSrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id 131:08:45.636110657 1 0x7ff6d80151e0 FIXME default gstutils.c:3766:gst_pad_create_stream_id_internal:<audioSrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id 131:08:45.636145754 1 0x7ff6e4003130 INFO kmsutils kmsutils.c:494:kms_utils_pad_monitor_gaps:<'':sink_video_default> Add probe: Detect stream gaps 131:08:45.676704124 1 0x7ff6f4021930 INFO KurentoRecorderEndpointImpl RecorderEndpointImpl.cpp:83:RecorderEndpointImpl: Set WEBM profile 131:08:45.680238339 1 0x7ff5c000e1e0 FIXME default gstutils.c:3766:gst_pad_create_stream_id_internal:<videoSrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id 131:08:45.680875789 1 0x7ff6680558a0 FIXME default gstutils.c:3766:gst_pad_create_stream_id_internal:<audioSrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id 131:08:45.683490993 1 0x7ff6f4021960 INFO kmsutils kmsutils.c:494:kms_utils_pad_monitor_gaps:<'':sink_video_default> Add probe: Detect stream gaps 131:08:45.730588664 1 0x7ff5c000e1e0 FIXME basesink gstbasesink.c:3125:gst_base_sink_default_event:<filesink51> stream-start event without group-id. Consider implementing group-id handling in the upstream elements 131:08:45.931984869 1 0x7ff638003400 INFO kmsutils kmsutils.c:494:kms_utils_pad_monitor_gaps:<'':sink_video_default> Add probe: Detect stream gaps 131:08:46.049814987 1 0x7ff5a40bc770 FIXME videodecoder gstvideodecoder.c:1057:gst_video_decoder_drain_out:<vp8dec6> Sub-class should implement drain() 131:08:46.550909218 1 0x7ff6bc012d40 INFO kmsutils kmsutils.c:494:kms_utils_pad_monitor_gaps:<'':sink_video_default> Add probe: Detect stream gaps 131:08:46.620924161 1 0x7ff6bc012d40 INFO basertpendpoint kmsbasertpendpoint.c:1914:kms_base_rtp_endpoint_jitterbuffer_set_latency:<rtpjitterbuffer83> Add probe: Set jitterbuffer latency 131:08:46.620947822 1 0x7ff6bc012d40 INFO basertpendpoint kmsbasertpendpoint.c:1963:kms_base_rtp_endpoint_jitterbuffer_monitor_rtp_out:<rtpjitterbuffer83> Add probe: Adjust jitterbuffer PTS out 131:08:46.621121979 1 0x7ff6bc012d40 INFO basertpendpoint kmsbasertpendpoint.c:2008:kms_base_rtp_endpoint_jitterbuffer_monitor_rtcp_in:<rtpjitterbuffer83> Add probe: Get jitterbuffer RTCP SR timing 131:08:46.621252703 1 0x7ff6a4002b70 INFO basertpendpoint kmsbasertpendpoint.c:1899:kms_base_rtp_endpoint_jitterbuffer_set_latency_probe:<rtpjitterbuffer83> Setting latency to: 500 131:08:46.621286802 1 0x7ff6a4002b70 INFO basertpendpoint kmsbasertpendpoint.c:1902:kms_base_rtp_endpoint_jitterbuffer_set_latency_probe:<rtpjitterbuffer83> Jitterbuffer latency set; remove probe 131:08:46.621493529 1 0x7ff6a4002b70 INFO kmsutils kmsutils.c:494:kms_utils_pad_monitor_gaps:<'':sink> Add probe: Detect stream gaps 131:08:46.622159533 1 0x7ff6a4002b70 INFO kmsutils kmsutils.c:1481:kms_utils_depayloader_monitor_pts_out:<rtph264depay5> Add probe: Adjust depayloader PTS out 131:08:46.639544042 1 0x7ff60c022770 INFO agnosticbin kmsagnosticbin.c:729:kms_agnostic_bin2_find_or_create_bin_for_caps:<kmsagnosticbin2-170> TRANSCODING ACTIVE for video 131:08:46.639949215 1 0x7ff60c022770 WARN libav gstavcodecmap.c:2441:gst_ffmpeg_caps_to_pixfmt: ignoring insane framerate 1/0 131:08:46.663951362 1 0x7ff6f412f800 INFO kmsutils kmsutils.c:494:kms_utils_pad_monitor_gaps:<'':sink_video_default> Add probe: Detect stream gaps 131:08:47.061780310 1 0x7ff6a4002b70 WARN rtpsynchronizer kmsrtpsynchronizer.c:539:kms_rtp_synchronizer_process_rtp_buffer_mapped:<KmsRtpSynchronizer@0x7ff56400e9f0> [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2949340791, seq: 8151, rtp_ts: 3236747188, ext_ts: 3236747188, last: 0:00:50.210768301, current: 0:00:50.174269119, fixed = last: 0:00:50.210768301 131:08:47.061814665 1 0x7ff6a4002b70 WARN kmsutils kmsutils.c:1428:kms_utils_depayloader_adjust_pts_out:<rtph264depay5> Fix PTS not strictly increasing, last: 0:00:50.210768301, current: 0:00:50.210768301, fixed = last + 1: 0:00:50.211768301 131:08:47.082248917 1 0x7ff6a4002b70 WARN rtpsynchronizer kmsrtpsynchronizer.c:539:kms_rtp_synchronizer_process_rtp_buffer_mapped:<KmsRtpSynchronizer@0x7ff56400e9f0> [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2949340791, seq: 8152, rtp_ts: 3236748718, ext_ts: 3236748718, last: 0:00:50.210768301, current: 0:00:50.191269119, fixed = last: 0:00:50.210768301 131:08:47.088978782 1 0x7ff6a4002b70 WARN kmsutils kmsutils.c:1428:kms_utils_depayloader_adjust_pts_out:<rtph264depay5> Fix PTS not strictly increasing, last: 0:00:50.211768301, current: 0:00:50.210768301, fixed = last + 1: 0:00:50.212768301 131:08:50.720746289 1 0x7ff6e80012a0 ERROR KurentoUriEndpointImpl UriEndpointImpl.cpp:179:stop:<kmsrecorderendpoint50> Error: Already in state stop 131:08:50.722923415 1 0x7ff6dc003990 WARN recorderendpoint kmsrecorderendpoint.c:1275:kms_recorder_endpoint_on_eos:<kmsrecorderendpoint51> Releasing pending pads 131:08:55.839683036 1 0x7ff6f40218d0 INFO KurentoRecorderEndpointImpl RecorderEndpointImpl.cpp:83:RecorderEndpointImpl: Set WEBM profile 131:08:55.843203759 1 0x7ff6ac001f20 FIXME default gstutils.c:3766:gst_pad_create_stream_id_internal:<videoSrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id 131:08:55.843233822 1 0x7ff6dc003990 FIXME default gstutils.c:3766:gst_pad_create_stream_id_internal:<audioSrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id 131:08:55.843302617 1 0x7ff70c00b390 INFO kmsutils kmsutils.c:494:kms_utils_pad_monitor_gaps:<'':sink_video_default> Add probe: Detect stream gaps 131:08:55.845778272 1 0x7ff7000038d0 INFO KurentoRecorderEndpointImpl RecorderEndpointImpl.cpp:83:RecorderEndpointImpl: Set WEBM profile 131:08:55.847909823 1 0x7ff6d0004a80 FIXME default gstutils.c:3766:gst_pad_create_stream_id_internal:<videoSrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id 131:08:55.848014477 1 0x7ff62c1628f0 FIXME default gstutils.c:3766:gst_pad_create_stream_id_internal:<audioSrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id 131:08:55.849009575 1 0x7ff6f4021960 INFO kmsutils kmsutils.c:494:kms_utils_pad_monitor_gaps:<'':sink_video_default> Add probe: Detect stream gaps 131:08:55.918236909 1 0x7ff6d0004a80 FIXME basesink gstbasesink.c:3125:gst_base_sink_default_event:<filesink53> stream-start event without group-id. Consider implementing group-id handling in the upstream elements 131:09:00.888927864 1 0x55a698beb900 ERROR KurentoUriEndpointImpl UriEndpointImpl.cpp:179:stop:<kmsrecorderendpoint52> Error: Already in state stop