Individual recording - Error waiting for some recorder endpoint to start

OpenVidu v2 Issues developing apps
1

Hi all!

Sometimes we can not start an individual recording via the API call. In this example there were 4 participants. The logs of openVidu show that one of the recorder endpoints could not be started and in this case the whole recording will not be started and the start fails. But we can not find more information in the log files WHY the endpoint could not be started. This error is not easy to reproduce and even in the same conference after some time the recording is suddenly working again - but the error happens regularly.
In the KMS log files you can see that 4 recorder endpoints are started but I can not find a reason why one of them is stopped or fails to start.

Any idea how to find the cause of this problem?

Thanks!

openVidu log file:

e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:36,504 [http-nio-0.0.0.0-5443-exec-2] io.openvidu.server.rest.SessionRestController - REST API: POST /openvidu/api/recordings/start {session=a15ec615d1fd01d6a5e2fd1eb27fe89b7fa4623d, outputMode=INDIVIDUAL}
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:36,513 [http-nio-0.0.0.0-5443-exec-2] io.openvidu.server.recording.service.RecordingService - New recording id (a15ec615d1fd01d6a5e2fd1eb27fe89b7fa4623d-6) and final name (a15ec615d1fd01d6a5e2fd1eb27fe89b7fa4623d-6)
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:36,513 [http-nio-0.0.0.0-5443-exec-2] io.openvidu.server.recording.service.SingleStreamRecordingService - Starting individual (video+audio) recording a15ec615d1fd01d6a5e2fd1eb27fe89b7fa4623d-6 of session a15ec615d1fd01d6a5e2fd1eb27fe89b7fa4623d
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:36,513 [http-nio-0.0.0.0-5443-exec-2] io.openvidu.server.recording.service.SingleStreamRecordingService - Starting single stream recorder for stream str_CAM_RPI5_con_Yp4JZLbSNv in session a15ec615d1fd01d6a5e2fd1eb27fe89b7fa4623d
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:36,555 [http-nio-0.0.0.0-5443-exec-2] io.openvidu.server.recording.service.SingleStreamRecordingService - Starting single stream recorder for stream str_CAM_IOLn_con_PQQfJAcGr0 in session a15ec615d1fd01d6a5e2fd1eb27fe89b7fa4623d
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:36,597 [http-nio-0.0.0.0-5443-exec-2] io.openvidu.server.recording.service.SingleStreamRecordingService - Starting single stream recorder for stream str_CAM_HtQB_con_EwMC71UMD5 in session a15ec615d1fd01d6a5e2fd1eb27fe89b7fa4623d
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:36,639 [http-nio-0.0.0.0-5443-exec-2] io.openvidu.server.recording.service.SingleStreamRecordingService - Starting single stream recorder for stream str_CAM_Z46w_con_KSl61xzNMg in session a15ec615d1fd01d6a5e2fd1eb27fe89b7fa4623d
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:36,640 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15988] io.openvidu.server.recording.service.SingleStreamRecordingService - Recording started event for stream str_CAM_IOLn_con_PQQfJAcGr0
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:36,681 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15988] io.openvidu.server.recording.service.SingleStreamRecordingService - Recording started event for stream str_CAM_HtQB_con_EwMC71UMD5
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:37,120 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15988] io.openvidu.server.recording.service.SingleStreamRecordingService - Recording started event for stream str_CAM_Z46w_con_KSl61xzNMg
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:37,164 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15988] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaFlowInStateChange] -> endpoint: con_KSl61xzNMg_str_CAM_RPI5_con_Yp4JZLbSNv (subscriber) | state: NOT_FLOWING | pad: default | mediaType: AUDIO | timestamp: 1606991857163
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:37,294 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15988] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaFlowInStateChange] -> endpoint: con_EwMC71UMD5_str_CAM_RPI5_con_Yp4JZLbSNv (subscriber) | state: NOT_FLOWING | pad: default | mediaType: AUDIO | timestamp: 1606991857294
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:37,448 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15988] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaFlowInStateChange] -> endpoint: con_PQQfJAcGr0_str_CAM_RPI5_con_Yp4JZLbSNv (subscriber) | state: NOT_FLOWING | pad: default | mediaType: AUDIO | timestamp: 1606991857447
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:37,816 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15988] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaFlowOutStateChange] -> endpoint: str_CAM_RPI5_con_Yp4JZLbSNv (publisher) | state: NOT_FLOWING | pad: default | mediaType: AUDIO | timestamp: 1606991857816
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:38,649 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15985] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaFlowInStateChange] -> endpoint: con_KSl61xzNMg_str_CAM_RPI5_con_Yp4JZLbSNv (subscriber) | state: FLOWING | pad: default | mediaType: AUDIO | timestamp: 1606991858649
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:38,649 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15980] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaFlowInStateChange] -> endpoint: con_EwMC71UMD5_str_CAM_RPI5_con_Yp4JZLbSNv (subscriber) | state: FLOWING | pad: default | mediaType: AUDIO | timestamp: 1606991858649
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:38,649 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15988] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaFlowOutStateChange] -> endpoint: str_CAM_RPI5_con_Yp4JZLbSNv (publisher) | state: FLOWING | pad: default | mediaType: AUDIO | timestamp: 1606991858649
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:38,649 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15995] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaFlowInStateChange] -> endpoint: con_PQQfJAcGr0_str_CAM_RPI5_con_Yp4JZLbSNv (subscriber) | state: FLOWING | pad: default | mediaType: AUDIO | timestamp: 1606991858649
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:39,164 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15995] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaFlowInStateChange] -> endpoint: con_KSl61xzNMg_str_CAM_RPI5_con_Yp4JZLbSNv (subscriber) | state: NOT_FLOWING | pad: default | mediaType: VIDEO | timestamp: 1606991859164
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:39,295 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15995] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaFlowInStateChange] -> endpoint: con_EwMC71UMD5_str_CAM_RPI5_con_Yp4JZLbSNv (subscriber) | state: NOT_FLOWING | pad: default | mediaType: VIDEO | timestamp: 1606991859294
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:39,448 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15995] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaFlowInStateChange] -> endpoint: con_PQQfJAcGr0_str_CAM_RPI5_con_Yp4JZLbSNv (subscriber) | state: NOT_FLOWING | pad: default | mediaType: VIDEO | timestamp: 1606991859448
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:41,448 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15995] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaFlowInStateChange] -> endpoint: con_PQQfJAcGr0_str_CAM_RPI5_con_Yp4JZLbSNv (subscriber) | state: NOT_FLOWING | pad: default | mediaType: AUDIO | timestamp: 1606991861448
e[36mopenvidu-server_1  |e[0m [ERROR] 2020-12-03 10:37:41,681 [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 a15ec615d1fd01d6a5e2fd1eb27fe89b7fa4623d
e[36mopenvidu-server_1  |e[0m [ERROR] 2020-12-03 10:37:41,681 [http-nio-0.0.0.0-5443-exec-2] io.openvidu.server.recording.service.RecordingService - Recording start failed for session a15ec615d1fd01d6a5e2fd1eb27fe89b7fa4623d: Couldn't initialize some RecorderEndpoint
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:41,681 [http-nio-0.0.0.0-5443-exec-2] io.openvidu.server.recording.service.RecordingService - Sealed recording metadata file at /opt/openvidu/recordings/a15ec615d1fd01d6a5e2fd1eb27fe89b7fa4623d-6/.recording.a15ec615d1fd01d6a5e2fd1eb27fe89b7fa4623d-6 with status [failed]
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:41,681 [http-nio-0.0.0.0-5443-exec-2] io.openvidu.server.recording.service.SingleStreamRecordingService - Stopping individual (video+audio) recording a15ec615d1fd01d6a5e2fd1eb27fe89b7fa4623d-6 of session a15ec615d1fd01d6a5e2fd1eb27fe89b7fa4623d. Reason: null
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:41,681 [http-nio-0.0.0.0-5443-exec-2] io.openvidu.server.recording.service.SingleStreamRecordingService - Stopping single stream recorder for stream str_CAM_Z46w_con_KSl61xzNMg in recording a15ec615d1fd01d6a5e2fd1eb27fe89b7fa4623d-6
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:41,682 [http-nio-0.0.0.0-5443-exec-2] io.openvidu.server.recording.service.SingleStreamRecordingService - Stopping single stream recorder for stream str_CAM_IOLn_con_PQQfJAcGr0 in recording a15ec615d1fd01d6a5e2fd1eb27fe89b7fa4623d-6
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:41,682 [http-nio-0.0.0.0-5443-exec-2] io.openvidu.server.recording.service.SingleStreamRecordingService - Stopping single stream recorder for stream str_CAM_HtQB_con_EwMC71UMD5 in recording a15ec615d1fd01d6a5e2fd1eb27fe89b7fa4623d-6
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:41,683 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15995] io.openvidu.server.recording.service.SingleStreamRecordingService - Recording stopped event for stream str_CAM_Z46w_con_KSl61xzNMg
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:41,683 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15988] io.openvidu.server.recording.service.SingleStreamRecordingService - Recording stopped event for stream str_CAM_IOLn_con_PQQfJAcGr0
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:41,683 [http-nio-0.0.0.0-5443-exec-2] io.openvidu.server.recording.service.SingleStreamRecordingService - Stopping single stream recorder for stream str_CAM_RPI5_con_Yp4JZLbSNv in recording a15ec615d1fd01d6a5e2fd1eb27fe89b7fa4623d-6
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:41,683 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15988] io.openvidu.server.recording.service.SingleStreamRecordingService - Recording stopped event for stream str_CAM_HtQB_con_EwMC71UMD5
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:41,817 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15988] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaFlowOutStateChange] -> endpoint: str_CAM_RPI5_con_Yp4JZLbSNv (publisher) | state: NOT_FLOWING | pad: default | mediaType: AUDIO | timestamp: 1606991861817
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:42,878 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15988] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaFlowInStateChange] -> endpoint: con_EwMC71UMD5_str_CAM_RPI5_con_Yp4JZLbSNv (subscriber) | state: FLOWING | pad: default | mediaType: VIDEO | timestamp: 1606991862878
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:42,902 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15995] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaFlowInStateChange] -> endpoint: con_KSl61xzNMg_str_CAM_RPI5_con_Yp4JZLbSNv (subscriber) | state: FLOWING | pad: default | mediaType: VIDEO | timestamp: 1606991862902
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:42,902 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15988] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaFlowInStateChange] -> endpoint: con_PQQfJAcGr0_str_CAM_RPI5_con_Yp4JZLbSNv (subscriber) | state: FLOWING | pad: default | mediaType: VIDEO | timestamp: 1606991862902
e[36mopenvidu-server_1  |e[0m [ERROR] 2020-12-03 10:37:42,903 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15988] org.kurento.client.internal.client.RemoteObjectInvocationHandler - Exception while processing event 'RecordingEvent' with params '{timestampMillis=1606991862902, source=e11ed96a-e9b0-4f76-aa10-22c03331a8b2_kurento.MediaPipeline/6560dd0d-bba1-40dd-aac5-aec7d40cbfb3_kurento.RecorderEndpoint, type=Recording, tags=[], timestamp=1606991862}'
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:43,165 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15988] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaFlowInStateChange] -> endpoint: con_KSl61xzNMg_str_CAM_RPI5_con_Yp4JZLbSNv (subscriber) | state: NOT_FLOWING | pad: default | mediaType: AUDIO | timestamp: 1606991863164
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:43,295 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15988] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaFlowInStateChange] -> endpoint: con_EwMC71UMD5_str_CAM_RPI5_con_Yp4JZLbSNv (subscriber) | state: NOT_FLOWING | pad: default | mediaType: AUDIO | timestamp: 1606991863295
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:43,538 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15980] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaFlowInStateChange] -> endpoint: con_PQQfJAcGr0_str_CAM_RPI5_con_Yp4JZLbSNv (subscriber) | state: FLOWING | pad: default | mediaType: AUDIO | timestamp: 1606991863538
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:43,539 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15995] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaFlowInStateChange] -> endpoint: con_EwMC71UMD5_str_CAM_RPI5_con_Yp4JZLbSNv (subscriber) | state: FLOWING | pad: default | mediaType: AUDIO | timestamp: 1606991863538
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:43,538 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15985] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaFlowInStateChange] -> endpoint: con_KSl61xzNMg_str_CAM_RPI5_con_Yp4JZLbSNv (subscriber) | state: FLOWING | pad: default | mediaType: AUDIO | timestamp: 1606991863538
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:43,539 [AbstractJsonRpcClientWebSocket-reqResEventExec-e2-t15988] io.openvidu.server.kurento.core.KurentoParticipantEndpointConfig - KMS event [MediaFlowOutStateChange] -> endpoint: str_CAM_RPI5_con_Yp4JZLbSNv (publisher) | state: FLOWING | pad: default | mediaType: AUDIO | timestamp: 1606991863538
e[36mopenvidu-server_1  |e[0m [ERROR] 2020-12-03 10:37:46,684 [http-nio-0.0.0.0-5443-exec-2] io.openvidu.server.recording.service.SingleStreamRecordingService - Error waiting for some recorder endpoint to stop in session a15ec615d1fd01d6a5e2fd1eb27fe89b7fa4623d
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:46,767 [http-nio-0.0.0.0-5443-exec-2] io.openvidu.server.recording.service.RecordingService - KMS recording file permissions successfully updated
e[36mopenvidu-server_1  |e[0m [INFO] 2020-12-03 10:37:46,772 [http-nio-0.0.0.0-5443-exec-2] io.openvidu.server.recording.service.RecordingService - Sealed recording metadata file at /opt/openvidu/recordings/a15ec615d1fd01d6a5e2fd1eb27fe89b7fa4623d-6/.recording.a15ec615d1fd01d6a5e2fd1eb27fe89b7fa4623d-6 with status [failed]
2

KMS log file - part 1

2020-12-03T10:37:36,514657 1 0x00007fca503b4700    info KurentoRecorderEndpointImpl RecorderEndpointImpl.cpp:83 RecorderEndpointImpl()  Set WEBM profile
2020-12-03T10:37:36,515631 1 0x00007fc835e49700   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
2020-12-03T10:37:36,515659 1 0x00007fc82e63a700   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
2020-12-03T10:37:36,516352 1 0x00007fca523b8700    info kmsutils                  kmsutils.c:515 kms_utils_pad_monitor_gaps() <'':sink_video_default>  Add probe: DISCONT buffers and GAP events
2020-12-03T10:37:36,547170 1 0x00007fca277fe700 warning rtpjitterbuffer           rtpjitterbuffer.c:735 rtp_jitter_buffer_insert()  rtp delta too big, reset skew
2020-12-03T10:37:36,556453 1 0x00007fca52bb9700    info KurentoRecorderEndpointImpl RecorderEndpointImpl.cpp:83 RecorderEndpointImpl()  Set WEBM profile
2020-12-03T10:37:36,557411 1 0x00007fc83063e700   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
2020-12-03T10:37:36,557618 1 0x00007fc82b634700   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
2020-12-03T10:37:36,558100 1 0x00007fca51bb7700    info kmsutils                  kmsutils.c:515 kms_utils_pad_monitor_gaps() <'':sink_video_default>  Add probe: DISCONT buffers and GAP events
2020-12-03T10:37:36,598490 1 0x00007fca4f3b2700    info KurentoRecorderEndpointImpl RecorderEndpointImpl.cpp:83 RecorderEndpointImpl()  Set WEBM profile
2020-12-03T10:37:36,599478 1 0x00007fc829e31700   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
2020-12-03T10:37:36,599561 1 0x00007fc803de5700   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
2020-12-03T10:37:36,600125 1 0x00007fca503b4700    info kmsutils                  kmsutils.c:515 kms_utils_pad_monitor_gaps() <'':sink_video_default>  Add probe: DISCONT buffers and GAP events
2020-12-03T10:37:36,634033 1 0x00007fc83063e700   fixme basesink                  gstbasesink.c:3125 gst_base_sink_default_event() <filesink1962>  stream-start event without group-id. Consider implementing group-id handling in the upstream elements
2020-12-03T10:37:36,640430 1 0x00007fca533ba700    info KurentoRecorderEndpointImpl RecorderEndpointImpl.cpp:83 RecorderEndpointImpl()  Set WEBM profile
2020-12-03T10:37:36,641431 1 0x00007fc801de1700   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
2020-12-03T10:37:36,641501 1 0x00007fc7ffddd700   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
2020-12-03T10:37:36,642093 1 0x00007fca513b6700    info kmsutils                  kmsutils.c:515 kms_utils_pad_monitor_gaps() <'':sink_video_default>  Add probe: DISCONT buffers and GAP events
2020-12-03T10:37:36,655562 1 0x00007fc829e31700   fixme basesink                  gstbasesink.c:3125 gst_base_sink_default_event() <filesink1963>  stream-start event without group-id. Consider implementing group-id handling in the upstream elements
2020-12-03T10:37:36,807640 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15492, expected 15820)
2020-12-03T10:37:36,808529 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15493, expected 15820)
2020-12-03T10:37:36,811060 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15494, expected 15820)
2020-12-03T10:37:36,850987 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15495, expected 15820)
2020-12-03T10:37:36,851333 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15496, expected 15820)
2020-12-03T10:37:36,873813 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15497, expected 15820)
2020-12-03T10:37:36,967880 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15498, expected 15820)
2020-12-03T10:37:36,968430 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15499, expected 15820)
2020-12-03T10:37:36,972243 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15500, expected 15820)
2020-12-03T10:37:36,977044 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15501, expected 15820)
2020-12-03T10:37:36,982290 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15502, expected 15820)
2020-12-03T10:37:36,987236 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15503, expected 15820)
2020-12-03T10:37:36,992476 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15504, expected 15820)
2020-12-03T10:37:36,997829 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15505, expected 15820)
2020-12-03T10:37:37,002655 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15506, expected 15820)
2020-12-03T10:37:37,007387 1 0x00007fca277fe700 warning rtpjitterbuffer           rtpjitterbuffer.c:735 rtp_jitter_buffer_insert()  rtp delta too big, reset skew
2020-12-03T10:37:37,120044 1 0x00007fc801de1700   fixme basesink                  gstbasesink.c:3125 gst_base_sink_default_event() <filesink1964>  stream-start event without group-id. Consider implementing group-id handling in the upstream elements
2020-12-03T10:37:37,175155 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15595, expected 15838)
2020-12-03T10:37:37,177395 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15596, expected 15838)
2020-12-03T10:37:37,180746 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15597, expected 15838)
2020-12-03T10:37:37,250591 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15598, expected 15838)
2020-12-03T10:37:37,282040 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15599, expected 15838)
2020-12-03T10:37:37,317217 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15600, expected 15838)
2020-12-03T10:37:37,335227 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15601, expected 15838)
2020-12-03T10:37:37,631976 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15602, expected 15838)
2020-12-03T10:37:37,632238 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15603, expected 15838)
2020-12-03T10:37:37,635717 1 0x00007fca277fe700 warning rtpjitterbuffer           rtpjitterbuffer.c:735 rtp_jitter_buffer_insert()  rtp delta too big, reset skew
2020-12-03T10:37:38,132391 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 0 ms at PTS=1:00:22.628325579 (packet loss?); will request a new keyframe
2020-12-03T10:37:38,132578 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 0 ms at PTS=1:00:22.628340134 (packet loss?); will request a new keyframe
2020-12-03T10:37:38,132613 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 0 ms at PTS=1:00:22.628354689 (packet loss?); will request a new keyframe
2020-12-03T10:37:38,132647 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 0 ms at PTS=1:00:22.628369244 (packet loss?); will request a new keyframe
2020-12-03T10:37:38,132675 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 0 ms at PTS=1:00:22.628383799 (packet loss?); will request a new keyframe
2020-12-03T10:37:38,132718 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 0 ms at PTS=1:00:22.628398354 (packet loss?); will request a new keyframe
2020-12-03T10:37:38,132801 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 0 ms at PTS=1:00:22.628412909 (packet loss?); will request a new keyframe
2020-12-03T10:37:38,132829 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 0 ms at PTS=1:00:22.628427464 (packet loss?); will request a new keyframe
2020-12-03T10:37:38,132865 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 0 ms at PTS=1:00:22.628442019 (packet loss?); will request a new keyframe
2020-12-03T10:37:38,132887 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 0 ms at PTS=1:00:22.628456574 (packet loss?); will request a new keyframe
2020-12-03T10:37:38,132907 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 0 ms at PTS=1:00:22.628471129 (packet loss?); will request a new keyframe
2020-12-03T10:37:38,132937 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 0 ms at PTS=1:00:22.628485684 (packet loss?); will request a new keyframe
2020-12-03T10:37:38,132959 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 0 ms at PTS=1:00:22.628500239 (packet loss?); will request a new keyframe
2020-12-03T10:37:38,132979 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 0 ms at PTS=1:00:22.628514794 (packet loss?); will request a new keyframe
2020-12-03T10:37:38,133006 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 0 ms at PTS=1:00:22.628529349 (packet loss?); will request a new keyframe
2020-12-03T10:37:38,133027 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 0 ms at PTS=1:00:22.628543904 (packet loss?); will request a new keyframe
above line repeats ~ 200 times
2020-12-03T10:37:38,137091 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:478 discont_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  DISCONTINUITY at non-keyframe; will drop until keyframe
2020-12-03T10:37:38,249294 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15507, expected 15868)
2020-12-03T10:37:38,254157 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15508, expected 15868)
2020-12-03T10:37:38,259984 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15509, expected 15868)
2020-12-03T10:37:38,264938 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15510, expected 15868)
2020-12-03T10:37:38,271477 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15511, expected 15868)
2020-12-03T10:37:38,276328 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15512, expected 15868)
2020-12-03T10:37:38,467568 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15513, expected 15868)
2020-12-03T10:37:38,469073 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15514, expected 15868)
2020-12-03T10:37:38,471405 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15515, expected 15868)
2020-12-03T10:37:38,478208 1 0x00007fca277fe700 warning rtpjitterbuffer           rtpjitterbuffer.c:735 rtp_jitter_buffer_insert()  rtp delta too big, reset skew
2020-12-03T10:37:38,649489 1 0x00007fc992ffd700 warning rtpsynchronizer           kmsrtpsynchronizer.c:553 kms_rtp_synchronizer_process_rtp_buffer_mapped() <KmsRtpSynchronizer@0x7fc9881392a0>  [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2773791922, seq: 1818, rtp_ts: 1292821128, ext_ts: 1292821128, last: 1:00:15.836567613, current: 1:00:15.375543613, fixed = last: 1:00:15.836567613
2020-12-03T10:37:38,649585 1 0x00007fc992ffd700 warning kmsutils                  kmsutils.c:1449 kms_utils_depayloader_adjust_pts_out() <rtpopusdepay917>  Fix PTS not strictly increasing, last: 1:00:15.836567613, current: 1:00:15.836567613, fixed = last + 1: 1:00:15.837567613
2020-12-03T10:37:38,649651 1 0x00007fc992ffd700 warning rtpsynchronizer           kmsrtpsynchronizer.c:553 kms_rtp_synchronizer_process_rtp_buffer_mapped() <KmsRtpSynchronizer@0x7fc9881392a0>  [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2773791922, seq: 1819, rtp_ts: 1292822088, ext_ts: 1292822088, last: 1:00:15.836567613, current: 1:00:15.395543613, fixed = last: 1:00:15.836567613
2020-12-03T10:37:38,649691 1 0x00007fc992ffd700 warning kmsutils                  kmsutils.c:1449 kms_utils_depayloader_adjust_pts_out() <rtpopusdepay917>  Fix PTS not strictly increasing, last: 1:00:15.837567613, current: 1:00:15.836567613, fixed = last + 1: 1:00:15.838567613
2020-12-03T10:37:38,649735 1 0x00007fc992ffd700 warning rtpsynchronizer           kmsrtpsynchronizer.c:553 kms_rtp_synchronizer_process_rtp_buffer_mapped() <KmsRtpSynchronizer@0x7fc9881392a0>  [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2773791922, seq: 1820, rtp_ts: 1292823048, ext_ts: 1292823048, last: 1:00:15.836567613, current: 1:00:15.415543613, fixed = last: 1:00:15.836567613
2020-12-03T10:37:38,649754 1 0x00007fc992ffd700 warning kmsutils                  kmsutils.c:1449 kms_utils_depayloader_adjust_pts_out() <rtpopusdepay917>  Fix PTS not strictly increasing, last: 1:00:15.838567613, current: 1:00:15.836567613, fixed = last + 1: 1:00:15.839567613
2020-12-03T10:37:38,649787 1 0x00007fc992ffd700 warning rtpsynchronizer           kmsrtpsynchronizer.c:553 kms_rtp_synchronizer_process_rtp_buffer_mapped() <KmsRtpSynchronizer@0x7fc9881392a0>  [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2773791922, seq: 1821, rtp_ts: 1292824008, ext_ts: 1292824008, last: 1:00:15.836567613, current: 1:00:15.435543613, fixed = last: 1:00:15.836567613
2020-12-03T10:37:38,649817 1 0x00007fc992ffd700 warning kmsutils                  kmsutils.c:1449 kms_utils_depayloader_adjust_pts_out() <rtpopusdepay917>  Fix PTS not strictly increasing, last: 1:00:15.839567613, current: 1:00:15.836567613, fixed = last + 1: 1:00:15.840567613
2020-12-03T10:37:38,649851 1 0x00007fc992ffd700 warning rtpsynchronizer           kmsrtpsynchronizer.c:553 kms_rtp_synchronizer_process_rtp_buffer_mapped() <KmsRtpSynchronizer@0x7fc9881392a0>  [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2773791922, seq: 1822, rtp_ts: 1292824968, ext_ts: 1292824968, last: 1:00:15.836567613, current: 1:00:15.455543613, fixed = last: 1:00:15.836567613
2020-12-03T10:37:38,649871 1 0x00007fc992ffd700 warning kmsutils                  kmsutils.c:1449 kms_utils_depayloader_adjust_pts_out() <rtpopusdepay917>  Fix PTS not strictly increasing, last: 1:00:15.840567613, current: 1:00:15.836567613, fixed = last + 1: 1:00:15.841567613
2020-12-03T10:37:38,649901 1 0x00007fc992ffd700 warning rtpsynchronizer           kmsrtpsynchronizer.c:553 kms_rtp_synchronizer_process_rtp_buffer_mapped() <KmsRtpSynchronizer@0x7fc9881392a0>  [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2773791922, seq: 1823, rtp_ts: 1292825928, ext_ts: 1292825928, last: 1:00:15.836567613, current: 1:00:15.475543613, fixed = last: 1:00:15.836567613
2020-12-03T10:37:38,649920 1 0x00007fc992ffd700 warning kmsutils                  kmsutils.c:1449 kms_utils_depayloader_adjust_pts_out() <rtpopusdepay917>  Fix PTS not strictly increasing, last: 1:00:15.841567613, current: 1:00:15.836567613, fixed = last + 1: 1:00:15.842567613
2020-12-03T10:37:38,649949 1 0x00007fc992ffd700 warning rtpsynchronizer           kmsrtpsynchronizer.c:553 kms_rtp_synchronizer_process_rtp_buffer_mapped() <KmsRtpSynchronizer@0x7fc9881392a0>  [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2773791922, seq: 1824, rtp_ts: 1292826888, ext_ts: 1292826888, last: 1:00:15.836567613, current: 1:00:15.495543613, fixed = last: 1:00:15.836567613
2020-12-03T10:37:38,649972 1 0x00007fc992ffd700 warning kmsutils                  kmsutils.c:1449 kms_utils_depayloader_adjust_pts_out() <rtpopusdepay917>  Fix PTS not strictly increasing, last: 1:00:15.842567613, current: 1:00:15.836567613, fixed = last + 1: 1:00:15.843567613
3

KMS log file - part 2

2020-12-03T10:37:38,650001 1 0x00007fc992ffd700 warning rtpsynchronizer           kmsrtpsynchronizer.c:553 kms_rtp_synchronizer_process_rtp_buffer_mapped() <KmsRtpSynchronizer@0x7fc9881392a0>  [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2773791922, seq: 1825, rtp_ts: 1292827848, ext_ts: 1292827848, last: 1:00:15.836567613, current: 1:00:15.515543613, fixed = last: 1:00:15.836567613
2020-12-03T10:37:38,650026 1 0x00007fc992ffd700 warning kmsutils                  kmsutils.c:1449 kms_utils_depayloader_adjust_pts_out() <rtpopusdepay917>  Fix PTS not strictly increasing, last: 1:00:15.843567613, current: 1:00:15.836567613, fixed = last + 1: 1:00:15.844567613
2020-12-03T10:37:38,650059 1 0x00007fc992ffd700 warning rtpsynchronizer           kmsrtpsynchronizer.c:553 kms_rtp_synchronizer_process_rtp_buffer_mapped() <KmsRtpSynchronizer@0x7fc9881392a0>  [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2773791922, seq: 1826, rtp_ts: 1292828808, ext_ts: 1292828808, last: 1:00:15.836567613, current: 1:00:15.535543613, fixed = last: 1:00:15.836567613
2020-12-03T10:37:38,650082 1 0x00007fc992ffd700 warning kmsutils                  kmsutils.c:1449 kms_utils_depayloader_adjust_pts_out() <rtpopusdepay917>  Fix PTS not strictly increasing, last: 1:00:15.844567613, current: 1:00:15.836567613, fixed = last + 1: 1:00:15.845567613
2020-12-03T10:37:38,650118 1 0x00007fc992ffd700 warning rtpsynchronizer           kmsrtpsynchronizer.c:553 kms_rtp_synchronizer_process_rtp_buffer_mapped() <KmsRtpSynchronizer@0x7fc9881392a0>  [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2773791922, seq: 1827, rtp_ts: 1292829768, ext_ts: 1292829768, last: 1:00:15.836567613, current: 1:00:15.555543613, fixed = last: 1:00:15.836567613
2020-12-03T10:37:38,650138 1 0x00007fc992ffd700 warning kmsutils                  kmsutils.c:1449 kms_utils_depayloader_adjust_pts_out() <rtpopusdepay917>  Fix PTS not strictly increasing, last: 1:00:15.845567613, current: 1:00:15.836567613, fixed = last + 1: 1:00:15.846567613
2020-12-03T10:37:38,650165 1 0x00007fc992ffd700 warning rtpsynchronizer           kmsrtpsynchronizer.c:553 kms_rtp_synchronizer_process_rtp_buffer_mapped() <KmsRtpSynchronizer@0x7fc9881392a0>  [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2773791922, seq: 1828, rtp_ts: 1292830728, ext_ts: 1292830728, last: 1:00:15.836567613, current: 1:00:15.575543613, fixed = last: 1:00:15.836567613
2020-12-03T10:37:38,650183 1 0x00007fc992ffd700 warning kmsutils                  kmsutils.c:1449 kms_utils_depayloader_adjust_pts_out() <rtpopusdepay917>  Fix PTS not strictly increasing, last: 1:00:15.846567613, current: 1:00:15.836567613, fixed = last + 1: 1:00:15.847567613
2020-12-03T10:37:38,650212 1 0x00007fc992ffd700 warning rtpsynchronizer           kmsrtpsynchronizer.c:553 kms_rtp_synchronizer_process_rtp_buffer_mapped() <KmsRtpSynchronizer@0x7fc9881392a0>  [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2773791922, seq: 1829, rtp_ts: 1292831688, ext_ts: 1292831688, last: 1:00:15.836567613, current: 1:00:15.595543613, fixed = last: 1:00:15.836567613
2020-12-03T10:37:38,650230 1 0x00007fc992ffd700 warning kmsutils                  kmsutils.c:1449 kms_utils_depayloader_adjust_pts_out() <rtpopusdepay917>  Fix PTS not strictly increasing, last: 1:00:15.847567613, current: 1:00:15.836567613, fixed = last + 1: 1:00:15.848567613
2020-12-03T10:37:38,650257 1 0x00007fc992ffd700 warning rtpsynchronizer           kmsrtpsynchronizer.c:553 kms_rtp_synchronizer_process_rtp_buffer_mapped() <KmsRtpSynchronizer@0x7fc9881392a0>  [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2773791922, seq: 1830, rtp_ts: 1292832648, ext_ts: 1292832648, last: 1:00:15.836567613, current: 1:00:15.615543613, fixed = last: 1:00:15.836567613
2020-12-03T10:37:38,650275 1 0x00007fc992ffd700 warning kmsutils                  kmsutils.c:1449 kms_utils_depayloader_adjust_pts_out() <rtpopusdepay917>  Fix PTS not strictly increasing, last: 1:00:15.848567613, current: 1:00:15.836567613, fixed = last + 1: 1:00:15.849567613
2020-12-03T10:37:38,650300 1 0x00007fc992ffd700 warning rtpsynchronizer           kmsrtpsynchronizer.c:553 kms_rtp_synchronizer_process_rtp_buffer_mapped() <KmsRtpSynchronizer@0x7fc9881392a0>  [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2773791922, seq: 1831, rtp_ts: 1292833608, ext_ts: 1292833608, last: 1:00:15.836567613, current: 1:00:15.635543613, fixed = last: 1:00:15.836567613
2020-12-03T10:37:38,650325 1 0x00007fc992ffd700 warning kmsutils                  kmsutils.c:1449 kms_utils_depayloader_adjust_pts_out() <rtpopusdepay917>  Fix PTS not strictly increasing, last: 1:00:15.849567613, current: 1:00:15.836567613, fixed = last + 1: 1:00:15.850567613
2020-12-03T10:37:38,650360 1 0x00007fc992ffd700 warning rtpsynchronizer           kmsrtpsynchronizer.c:553 kms_rtp_synchronizer_process_rtp_buffer_mapped() <KmsRtpSynchronizer@0x7fc9881392a0>  [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2773791922, seq: 1832, rtp_ts: 1292834568, ext_ts: 1292834568, last: 1:00:15.836567613, current: 1:00:15.655543613, fixed = last: 1:00:15.836567613
2020-12-03T10:37:38,650380 1 0x00007fc992ffd700 warning kmsutils                  kmsutils.c:1449 kms_utils_depayloader_adjust_pts_out() <rtpopusdepay917>  Fix PTS not strictly increasing, last: 1:00:15.850567613, current: 1:00:15.836567613, fixed = last + 1: 1:00:15.851567613
2020-12-03T10:37:38,650407 1 0x00007fc992ffd700 warning rtpsynchronizer           kmsrtpsynchronizer.c:553 kms_rtp_synchronizer_process_rtp_buffer_mapped() <KmsRtpSynchronizer@0x7fc9881392a0>  [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2773791922, seq: 1833, rtp_ts: 1292835528, ext_ts: 1292835528, last: 1:00:15.836567613, current: 1:00:15.675543613, fixed = last: 1:00:15.836567613
2020-12-03T10:37:38,650425 1 0x00007fc992ffd700 warning kmsutils                  kmsutils.c:1449 kms_utils_depayloader_adjust_pts_out() <rtpopusdepay917>  Fix PTS not strictly increasing, last: 1:00:15.851567613, current: 1:00:15.836567613, fixed = last + 1: 1:00:15.852567613
2020-12-03T10:37:38,650452 1 0x00007fc992ffd700 warning rtpsynchronizer           kmsrtpsynchronizer.c:553 kms_rtp_synchronizer_process_rtp_buffer_mapped() <KmsRtpSynchronizer@0x7fc9881392a0>  [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2773791922, seq: 1834, rtp_ts: 1292836488, ext_ts: 1292836488, last: 1:00:15.836567613, current: 1:00:15.695543613, fixed = last: 1:00:15.836567613
2020-12-03T10:37:38,650471 1 0x00007fc992ffd700 warning kmsutils                  kmsutils.c:1449 kms_utils_depayloader_adjust_pts_out() <rtpopusdepay917>  Fix PTS not strictly increasing, last: 1:00:15.852567613, current: 1:00:15.836567613, fixed = last + 1: 1:00:15.853567613
2020-12-03T10:37:38,650495 1 0x00007fc992ffd700 warning rtpsynchronizer           kmsrtpsynchronizer.c:553 kms_rtp_synchronizer_process_rtp_buffer_mapped() <KmsRtpSynchronizer@0x7fc9881392a0>  [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2773791922, seq: 1835, rtp_ts: 1292837448, ext_ts: 1292837448, last: 1:00:15.836567613, current: 1:00:15.715543613, fixed = last: 1:00:15.836567613
2020-12-03T10:37:38,650516 1 0x00007fc992ffd700 warning kmsutils                  kmsutils.c:1449 kms_utils_depayloader_adjust_pts_out() <rtpopusdepay917>  Fix PTS not strictly increasing, last: 1:00:15.853567613, current: 1:00:15.836567613, fixed = last + 1: 1:00:15.854567613
2020-12-03T10:37:38,650541 1 0x00007fc992ffd700 warning rtpsynchronizer           kmsrtpsynchronizer.c:553 kms_rtp_synchronizer_process_rtp_buffer_mapped() <KmsRtpSynchronizer@0x7fc9881392a0>  [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2773791922, seq: 1836, rtp_ts: 1292838408, ext_ts: 1292838408, last: 1:00:15.836567613, current: 1:00:15.735543613, fixed = last: 1:00:15.836567613
2020-12-03T10:37:38,650562 1 0x00007fc992ffd700 warning kmsutils                  kmsutils.c:1449 kms_utils_depayloader_adjust_pts_out() <rtpopusdepay917>  Fix PTS not strictly increasing, last: 1:00:15.854567613, current: 1:00:15.836567613, fixed = last + 1: 1:00:15.855567613
2020-12-03T10:37:38,650587 1 0x00007fc992ffd700 warning rtpsynchronizer           kmsrtpsynchronizer.c:553 kms_rtp_synchronizer_process_rtp_buffer_mapped() <KmsRtpSynchronizer@0x7fc9881392a0>  [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2773791922, seq: 1837, rtp_ts: 1292839368, ext_ts: 1292839368, last: 1:00:15.836567613, current: 1:00:15.755543613, fixed = last: 1:00:15.836567613
2020-12-03T10:37:38,650611 1 0x00007fc992ffd700 warning kmsutils                  kmsutils.c:1449 kms_utils_depayloader_adjust_pts_out() <rtpopusdepay917>  Fix PTS not strictly increasing, last: 1:00:15.855567613, current: 1:00:15.836567613, fixed = last + 1: 1:00:15.856567613
2020-12-03T10:37:38,650636 1 0x00007fc992ffd700 warning rtpsynchronizer           kmsrtpsynchronizer.c:553 kms_rtp_synchronizer_process_rtp_buffer_mapped() <KmsRtpSynchronizer@0x7fc9881392a0>  [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2773791922, seq: 1838, rtp_ts: 1292840328, ext_ts: 1292840328, last: 1:00:15.836567613, current: 1:00:15.775543613, fixed = last: 1:00:15.836567613
2020-12-03T10:37:38,650657 1 0x00007fc992ffd700 warning kmsutils                  kmsutils.c:1449 kms_utils_depayloader_adjust_pts_out() <rtpopusdepay917>  Fix PTS not strictly increasing, last: 1:00:15.856567613, current: 1:00:15.836567613, fixed = last + 1: 1:00:15.857567613
2020-12-03T10:37:38,650682 1 0x00007fc992ffd700 warning rtpsynchronizer           kmsrtpsynchronizer.c:553 kms_rtp_synchronizer_process_rtp_buffer_mapped() <KmsRtpSynchronizer@0x7fc9881392a0>  [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2773791922, seq: 1839, rtp_ts: 1292841288, ext_ts: 1292841288, last: 1:00:15.836567613, current: 1:00:15.795543613, fixed = last: 1:00:15.836567613
2020-12-03T10:37:38,650702 1 0x00007fc992ffd700 warning kmsutils                  kmsutils.c:1449 kms_utils_depayloader_adjust_pts_out() <rtpopusdepay917>  Fix PTS not strictly increasing, last: 1:00:15.857567613, current: 1:00:15.836567613, fixed = last + 1: 1:00:15.858567613
2020-12-03T10:37:38,650727 1 0x00007fc992ffd700 warning rtpsynchronizer           kmsrtpsynchronizer.c:553 kms_rtp_synchronizer_process_rtp_buffer_mapped() <KmsRtpSynchronizer@0x7fc9881392a0>  [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2773791922, seq: 1840, rtp_ts: 1292842248, ext_ts: 1292842248, last: 1:00:15.836567613, current: 1:00:15.815543613, fixed = last: 1:00:15.836567613
2020-12-03T10:37:38,650747 1 0x00007fc992ffd700 warning kmsutils                  kmsutils.c:1449 kms_utils_depayloader_adjust_pts_out() <rtpopusdepay917>  Fix PTS not strictly increasing, last: 1:00:15.858567613, current: 1:00:15.836567613, fixed = last + 1: 1:00:15.859567613
2020-12-03T10:37:38,650772 1 0x00007fc992ffd700 warning rtpsynchronizer           kmsrtpsynchronizer.c:553 kms_rtp_synchronizer_process_rtp_buffer_mapped() <KmsRtpSynchronizer@0x7fc9881392a0>  [Sorted mode] Fix PTS not increasing monotonically, SSRC: 2773791922, seq: 1841, rtp_ts: 1292843208, ext_ts: 1292843208, last: 1:00:15.836567613, current: 1:00:15.835543613, fixed = last: 1:00:15.836567613
2020-12-03T10:37:38,650790 1 0x00007fc992ffd700 warning kmsutils                  kmsutils.c:1449 kms_utils_depayloader_adjust_pts_out() <rtpopusdepay917>  Fix PTS not strictly increasing, last: 1:00:15.859567613, current: 1:00:15.836567613, fixed = last + 1: 1:00:15.860567613
2020-12-03T10:37:38,650819 1 0x00007fc992ffd700 warning kmsutils                  kmsutils.c:1449 kms_utils_depayloader_adjust_pts_out() <rtpopusdepay917>  Fix PTS not strictly increasing, last: 1:00:15.860567613, current: 1:00:15.855543613, fixed = last + 1: 1:00:15.861567613
2020-12-03T10:37:38,684317 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15604, expected 15878)
2020-12-03T10:37:38,719082 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15605, expected 15878)
2020-12-03T10:37:38,912374 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15606, expected 15878)
2020-12-03T10:37:38,916928 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15607, expected 15878)
2020-12-03T10:37:38,919326 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15608, expected 15878)
2020-12-03T10:37:39,437218 1 0x00007fca277fe700 warning rtpjitterbuffer           rtpjitterbuffer.c:735 rtp_jitter_buffer_insert()  rtp delta too big, reset skew
2020-12-03T10:37:39,438310 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 15 ms at PTS=1:00:23.917320035 (packet loss?); will request a new keyframe
2020-12-03T10:37:39,438367 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 1 ms at PTS=1:00:23.933228679 (packet loss?); will request a new keyframe
2020-12-03T10:37:39,439125 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 1 ms at PTS=1:00:23.935146555 (packet loss?); will request a new keyframe
2020-12-03T10:37:39,441046 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 1 ms at PTS=1:00:23.937064431 (packet loss?); will request a new keyframe
2020-12-03T10:37:39,442964 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 1 ms at PTS=1:00:23.938982307 (packet loss?); will request a new keyframe
above line repeats ~ 200 times
2020-12-03T10:37:39,780184 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15516, expected 15892)
2020-12-03T10:37:39,780511 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 1 ms at PTS=1:00:24.276528483 (packet loss?); will request a new keyframe
2020-12-03T10:37:39,782423 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 1 ms at PTS=1:00:24.278446359 (packet loss?); will request a new keyframe
2020-12-03T10:37:39,784348 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 1 ms at PTS=1:00:24.280364235 (packet loss?); will request a new keyframe
above line repeats ~ 60 times
2020-12-03T10:37:39,915948 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15517, expected 15892)
2020-12-03T10:37:39,916682 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 1 ms at PTS=1:00:24.412697679 (packet loss?); will request a new keyframe
2020-12-03T10:37:39,918594 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 1 ms at PTS=1:00:24.414615555 (packet loss?); will request a new keyframe
2020-12-03T10:37:39,920519 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 1 ms at PTS=1:00:24.416533431 (packet loss?); will request a new keyframe
2020-12-03T10:37:39,922440 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 1 ms at PTS=1:00:24.418451307 (packet loss?); will request a new keyframe
2020-12-03T10:37:39,924355 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 1 ms at PTS=1:00:24.420369183 (packet loss?); will request a new keyframe
2020-12-03T10:37:39,926266 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 1 ms at PTS=1:00:24.422287059 (packet loss?); will request a new keyframe
2020-12-03T10:37:39,928183 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 1 ms at PTS=1:00:24.424204935 (packet loss?); will request a new keyframe
2020-12-03T10:37:39,930103 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 1 ms at PTS=1:00:24.426122811 (packet loss?); will request a new keyframe
2020-12-03T10:37:39,932018 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 1 ms at PTS=1:00:24.428040687 (packet loss?); will request a new keyframe
2020-12-03T10:37:39,933940 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 1 ms at PTS=1:00:24.429958563 (packet loss?); will request a new keyframe
2020-12-03T10:37:39,935329 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15518, expected 15892)
2020-12-03T10:37:39,935856 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 1 ms at PTS=1:00:24.431876439 (packet loss?); will request a new keyframe
2020-12-03T10:37:39,935925 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15519, expected 15892)
2020-12-03T10:37:39,953812 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15520, expected 15892)
2020-12-03T10:37:39,963926 1 0x00007fca277fe700 warning rtpjitterbuffer           rtpjitterbuffer.c:735 rtp_jitter_buffer_insert()  rtp delta too big, reset skew
2020-12-03T10:37:40,454052 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 0 ms at PTS=1:00:24.949917190 (packet loss?); will request a new keyframe
2020-12-03T10:37:40,454231 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:502 gap_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  GAP of 0 ms at PTS=1:00:24.949944124 (packet loss?); will request a new keyframe
above line repeats ~ 300 times
2020-12-03T10:37:40,608687 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15521, expected 15902)
2020-12-03T10:37:40,612357 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15522, expected 15902)
2020-12-03T10:37:40,613212 1 0x00007fca277fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 15523, expected 15902)
2020-12-03T10:37:41,205727 1 0x00007fc990ff9700 warning kmsutils                  kmsutils.c:478 discont_detection_probe() <kmswebrtcendpoint3688_kmsagnosticbin2-3642:sink>  DISCONTINUITY at non-keyframe; will drop until keyframe
2020-12-03T10:37:41,682579 1 0x00007fc7fd5d8700 warning recorderendpoint          kmsrecorderendpoint.c:1332 kms_recorder_endpoint_on_eos() <kmsrecorderendpoint1964>  Releasing pending pads
2020-12-03T10:37:41,682954 1 0x00007fc82c636700 warning recorderendpoint          kmsrecorderendpoint.c:1332 kms_recorder_endpoint_on_eos() <kmsrecorderendpoint1962>  Releasing pending pads
2020-12-03T10:37:41,683562 1 0x00007fc7fd5d8700 warning recorderendpoint          kmsrecorderendpoint.c:1332 kms_recorder_endpoint_on_eos() <kmsrecorderendpoint1963>  Releasing pending pads
2020-12-03T10:37:41,684070 1 0x00007fca51bb7700   error KurentoUriEndpointImpl    UriEndpointImpl.cpp:179 stop() <kmsrecorderendpoint1961>  Error: Already in state stop
2020-12-03T10:37:42,902499 1 0x00007fc835e49700   fixme basesink                  gstbasesink.c:3125 gst_base_sink_default_event() <filesink1961>  stream-start event without group-id. Consider implementing group-id handling in the upstream elements
2020-12-03T10:37:43,175040 1 0x00007fc89b7fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 57581, expected 57609)
2020-12-03T10:37:43,175178 1 0x00007fc89b7fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 57582, expected 57609)
2020-12-03T10:37:43,175211 1 0x00007fc89b7fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 57583, expected 57609)
2020-12-03T10:37:43,194703 1 0x00007fc89b7fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 57583, expected 57618)
2020-12-03T10:37:43,210258 1 0x00007fc89b7fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 57581, expected 57618)
2020-12-03T10:37:43,211222 1 0x00007fc89b7fe700 warning rtpsource                 rtpsource.c:1147 update_receiver_stats()  duplicate or reordered packet (seqnr 57582, expected 57618)