Kurento / bugtracker

[ARCHIVED] Contents migrated to monorepo: https://github.com/Kurento/kurento
46 stars 10 forks source link

Kurento Recorder doesn't write Screen Capture (getDisplayMedia stream) #630

Closed RaistGH closed 2 years ago

RaistGH commented 2 years ago

Prerequisites

These are MANDATORY, otherwise the issue will be automatically closed.

Issue description

When using Kurento to capture user's screen, the video stream gets into the pipeline and can perform the full loopback but the recorder is not writing the stream to the file. The file is created and keeps 0 bytes always. The stream flows in the RecorderEndpoint but the recorder doesn't write buffered data to file. This is happening with "Kurento Tutorial: Loopback with recording using generators".

How to reproduce?

  1. In kurento-utils.js search for following code:
            getScreenConstraints(sendSource, function (error, constraints_) {
                if (error)
                    return callback(error);
                constraints = [mediaConstraints];
                constraints.unshift(constraints_);
                getMedia(recursive.apply(undefined, constraints));
            }, guid);
  2. Replace it by following code:
                navigator.mediaDevices.getUserMedia(mediaConstraints).then(function (stream) {
                    videoStream = stream;
                    start();
                }).catch(callback);
  3. Go to your JS application and add option sendSource with value other than 'webcam':
    var options =
    {
    localVideo: videoInput,
    remoteVideo: videoOutput,
    sendSource: 'screen'
    }
  4. Run application.
  5. Loopback will work and show captured screen.
  6. Recording file is 0 bytes after stopping.

Expected & current behavior

KMS should write buffer data to file independently on streaming source.

Attached log output when recording is done using "getUserMedia" (sendSource: 'webcam'), removed all ICE negotiation except SDP exchanges. Timestamp of disconnection is 2022-07-08T10:11:34,885951

(attached in folowing comments because the reporting tool says body is too long)

Attached log output when recording is done using "getDisplayMedia" (sendSource: 'screen'), removed all ICE negotiation except SDP exchanges. Timestamp of disconnection is 2022-07-08T10:14:40,387015

(attached in folowing comments because the reporting tool says body is too long)

Differences I see:

(Optional) Possible solution

Is there any flag marking Screen Capture and disabling recording?

Info about your environment

About Kurento Media Server

About your Application Server

Programming Language: javascript Kurento Client version: 6.16 This is happening also with "Kurento Tutorial: Loopback with recording using generators".

Run these commands

cat /etc/lsb-release
DISTRIB_ID=Ubuntu
DISTRIB_RELEASE=16.04
DISTRIB_CODENAME=xenial
DISTRIB_DESCRIPTION="Ubuntu 16.04.7 LTS"
kurento-media-server --version
ALVAR 2.0.0 - A Library for Virtual and Augmented Reality
Copyright 2007-2012 VTT Technical Research Centre of Finland
Licensed under the GNU Lesser General Public License
Built on 2022-03-30 for Linux 4.4.0-210-generic x86_64

Kurento Media Server version: 6.17.0
Found modules:
    'chroma' version 6.17.0
    'core' version 6.17.0
    'crowddetector' version 6.17.0
    'datachannelexample' version 6.17.0
    'elements' version 6.17.0
    'filters' version 6.17.0
    'markerdetector' version 6.17.0
    'platedetector' version 6.17.0
    'pointerdetector' version 6.17.0
dpkg -l | grep -Pi 'kurento|kms-|gst.*1.5|nice'
ii  gstreamer1.5-alsa:amd64              1.8.1-1kurento2.16.04                    amd64        GStreamer plugin for ALSA
ii  gstreamer1.5-libav:amd64             1.8.1-1kurento1.16.04                    amd64        libav plugin for GStreamer
ii  gstreamer1.5-libav-dbg:amd64         1.8.1-1kurento1.16.04                    amd64        libav plugin for GStreamer (debug symbols)
ii  gstreamer1.5-nice:amd64              0.1.18-0kurento1.16.04                   amd64        ICE library (GStreamer 1.5 plugin)
ii  gstreamer1.5-nice-dbgsym:amd64       0.1.18-0kurento1.16.04                   amd64        debug symbols for package gstreamer1.5-nice
ii  gstreamer1.5-plugins-bad:amd64       1.8.1-1kurento5.16.04                    amd64        GStreamer plugins from the "bad" set
ii  gstreamer1.5-plugins-bad-dbg:amd64   1.8.1-1kurento5.16.04                    amd64        GStreamer plugins from the "bad" set (debug symbols)
ii  gstreamer1.5-plugins-base:amd64      1.8.1-1kurento2.16.04                    amd64        GStreamer plugins from the "base" set
ii  gstreamer1.5-plugins-base-dbg:amd64  1.8.1-1kurento2.16.04                    amd64        GStreamer plugins from the "base" set
ii  gstreamer1.5-plugins-good:amd64      1.8.1-1kurento5.16.04                    amd64        GStreamer plugins from the "good" set
ii  gstreamer1.5-plugins-good-dbg:amd64  1.8.1-1kurento5.16.04                    amd64        GStreamer plugins from the "good" set
ii  gstreamer1.5-plugins-ugly:amd64      1.8.1-1kurento1.16.04                    amd64        GStreamer plugins from the "ugly" set
ii  gstreamer1.5-plugins-ugly-dbg:amd64  1.8.1-1kurento1.16.04                    amd64        GStreamer plugins from the "ugly" set (debug symbols)
ii  gstreamer1.5-pulseaudio:amd64        1.8.1-1kurento5.16.04                    amd64        GStreamer plugin for PulseAudio
ii  gstreamer1.5-x:amd64                 1.8.1-1kurento2.16.04                    amd64        GStreamer plugins for X11 and Pango
ii  kms-chroma                           6.17.0-0kurento1.16.04                   amd64        Kurento Chroma filter
ii  kms-core                             6.17.0-0kurento1.16.04                   amd64        Kurento Core module
ii  kms-core-dbg                         6.17.0-0kurento1.16.04                   amd64        Kurento Core module
ii  kms-crowddetector                    6.17.0-0kurento1.16.04                   amd64        Kurento Crowd Detector filter
ii  kms-datachannelexample               6.17.0-0kurento1.16.04                   amd64        Kurento WebRTC DataChannel example
ii  kms-elements                         6.17.0-0kurento1.16.04                   amd64        Kurento Elements module
ii  kms-elements-dbg                     6.17.0-0kurento1.16.04                   amd64        Kurento Elements module
ii  kms-filters                          6.17.0-0kurento1.16.04                   amd64        Kurento Filters module
ii  kms-filters-dbg                      6.17.0-0kurento1.16.04                   amd64        Kurento Filters module
ii  kms-jsonrpc                          6.17.0-0kurento1.16.04                   amd64        Kurento JSON-RPC library
ii  kms-jsonrpc-dbg                      6.17.0-0kurento1.16.04                   amd64        Kurento JSON-RPC library
ii  kms-markerdetector                   6.17.0-0kurento1.16.04                   amd64        Kurento Augmented Reality marker detector
ii  kms-platedetector                    6.17.0-0kurento1.16.04                   amd64        Kurento Plate Detector filter
ii  kms-pointerdetector                  6.17.0-0kurento1.16.04                   amd64        Kurento Pointer Detector filter
ii  kmsjsoncpp                           1.6.3-1kurento1.16.04                    amd64        Kurento jsoncpp library
ii  kmsjsoncpp-dbg                       1.6.3-1kurento1.16.04                    amd64        Kurento jsoncpp library
ii  kurento-dbg                          6.17.0-0kurento1.16.04                   amd64        Meta-package that installs debug symbols
ii  kurento-media-server                 6.17.0-0kurento1.16.04                   amd64        Kurento Media Server
ii  kurento-media-server-dbg             6.17.0-0kurento1.16.04                   amd64        Kurento Media Server
ii  libgstreamer-plugins-bad1.5-0:amd64  1.8.1-1kurento5.16.04                    amd64        GStreamer development files for libraries from the "bad" set
ii  libgstreamer-plugins-base1.5-0:amd64 1.8.1-1kurento2.16.04                    amd64        GStreamer libraries from the "base" set
ii  libgstreamer1.5-0:amd64              1.8.1-1kurento2.16.04                    amd64        Core GStreamer libraries and elements
ii  libgstreamer1.5-0-dbg:amd64          1.8.1-1kurento2.16.04                    amd64        Core GStreamer libraries and elements
ii  libnice10:amd64                      0.1.18-0kurento1.16.04                   amd64        ICE library (shared library)
ii  libnice10-dbgsym:amd64               0.1.18-0kurento1.16.04                   amd64        debug symbols for package libnice10
ii  libsrtp0:amd64                       1.6.0-0kurento1.16.04                    amd64        Secure RTP (SRTP) and UST Reference Implementations - shared library
ii  libusrsctp                           0.9.2-1kurento1.16.04                    amd64        sctp-refimpl library
ii  openh264                             1.5.0-0kurento1.16.04                    amd64        H.264 Video Codec provided by Cisco Systems, Inc.
ii  openh264-gst-plugins-bad-1.5:amd64   1.8.1-1kurento5.16.04                    amd64        GStreamer plugins from openh264
ii  openwebrtc-gst-plugins               0.10.0-1kurento1.16.04                   amd64        OpenWebRTC specific GStreamer plugins
ii  openwebrtc-gst-plugins-dbg           0.10.0-1kurento1.16.04                   amd64        OpenWebRTC specific GStreamer plugins
RaistGH commented 2 years ago

Attached log output when recording is done using "getUserMedia" (sendSource: 'webcam'), removed all ICE negotiation except SDP exchanges. Timestamp of disconnection is 2022-07-08T10:11:34,885951

2022-07-08T10:11:28,826294 1 0x00007fa1aadf6700   debug KurentoWebSocketTransport WebSocketTransport.cpp:532 processMessage()  Message: {"jsonrpc":"2.0","method":"create","params":{"type":"RecorderEndpoint","constructorParams":{"mediaPipeline":"95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline","uri":"file:///tmp/kurento-hello-world-recording.webm"},"properties":{},"sessionId":"8fa7d30b-4e78-439e-a6fe-5e249cfa263b"},"id":6}
2022-07-08T10:11:28,827074 1 0x00007fa1aadf6700   debug GST_URI                   gsturi.c:644 gst_element_make_from_uri()  type:1, uri:file:///tmp/kurento-hello-world-recording.webm, elementname:(null)
2022-07-08T10:11:28,827473 1 0x00007fa1aadf6700     log GST_URI                   gsturi.c:498 gst_uri_get_location()  extracted location '/tmp/kurento-hello-world-recording.webm' from URI 'file:///tmp/kurento-hello-world-recording.webm'
2022-07-08T10:11:28,827617 1 0x00007fa1aadf6700   debug GST_URI                   gsturi.c:953 gst_filename_to_uri()  '/tmp/kurento-hello-world-recording.webm' -> 'file:///tmp/kurento-hello-world-recording.webm'
2022-07-08T10:11:28,827759 1 0x00007fa1aadf6700     log GST_URI                   gsturi.c:690 gst_element_make_from_uri() <filesink25>  created sink for URL 'file:///tmp/kurento-hello-world-recording.webm'
2022-07-08T10:11:28,827865 1 0x00007fa1aadf6700   debug basemediamuxer            kmsbasemediamuxer.c:185 kms_base_media_muxer_get_sink() <filesink25>  Muxer sink created for URI 'file:///tmp/kurento-hello-world-recording.webm'
2022-07-08T10:11:28,828034 1 0x00007fa1aadf6700     log GST_URI                   gsturi.c:498 gst_uri_get_location()  extracted location '/tmp/kurento-hello-world-recording.webm' from URI 'file:///tmp/kurento-hello-world-recording.webm'
2022-07-08T10:11:28,828146 1 0x00007fa1aadf6700   debug GST_URI                   gsturi.c:953 gst_filename_to_uri()  '/tmp/kurento-hello-world-recording.webm' -> 'file:///tmp/kurento-hello-world-recording.webm'
2022-07-08T10:11:28,828562 1 0x00007fa1aadf6700    info KurentoRecorderEndpointImpl RecorderEndpointImpl.cpp:87 RecorderEndpointImpl()  Set WEBM profile
2022-07-08T10:11:28,828819 1 0x00007fa1aadf6700     log KurentoServerMethods      ServerMethods.cpp:351 postProcess()  Caching: {
   "id" : 6,
   "jsonrpc" : "2.0",
   "result" : {
      "sessionId" : "8fa7d30b-4e78-439e-a6fe-5e249cfa263b",
      "value" : "95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/476c74fa-d7fc-45ba-91c5-d2f81dca9176_kurento.RecorderEndpoint"
   }
}

2022-07-08T10:11:28,829001 1 0x00007fa1aadf6700   debug KurentoWebSocketTransport WebSocketTransport.cpp:534 processMessage()  Response: {"id":6,"jsonrpc":"2.0","result":{"sessionId":"8fa7d30b-4e78-439e-a6fe-5e249cfa263b","value":"95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/476c74fa-d7fc-45ba-91c5-d2f81dca9176_kurento.RecorderEndpoint"}}
2022-07-08T10:11:28,870947 1 0x00007fa1abdf8700   debug KurentoWebSocketTransport WebSocketTransport.cpp:532 processMessage()  Message: {"jsonrpc":"2.0","method":"invoke","params":{"object":"95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint","operation":"connect","operationParams":{"sink":"95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/476c74fa-d7fc-45ba-91c5-d2f81dca9176_kurento.RecorderEndpoint"},"sessionId":"8fa7d30b-4e78-439e-a6fe-5e249cfa263b"},"id":31}
2022-07-08T10:11:28,871421 1 0x00007fa1abdf8700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:1001 connect()  Connecting 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint -> 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/476c74fa-d7fc-45ba-91c5-d2f81dca9176_kurento.RecorderEndpoint params AUDIO default default
2022-07-08T10:11:28,871739 1 0x00007fa1abdf8700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1059 performConnection()  Still waiting for src pad 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint:audio_src_default_0
2022-07-08T10:11:28,871895 1 0x00007fa1abdf8700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:1001 connect()  Connecting 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint -> 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/476c74fa-d7fc-45ba-91c5-d2f81dca9176_kurento.RecorderEndpoint params VIDEO default default
2022-07-08T10:11:28,872032 1 0x00007fa1abdf8700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1059 performConnection()  Still waiting for src pad 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint:video_src_default_0
2022-07-08T10:11:28,872159 1 0x00007fa1abdf8700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:1001 connect()  Connecting 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint -> 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/476c74fa-d7fc-45ba-91c5-d2f81dca9176_kurento.RecorderEndpoint params DATA default default
2022-07-08T10:11:28,872320 1 0x00007fa1abdf8700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1059 performConnection()  Still waiting for src pad 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint:data_src_default_0
2022-07-08T10:11:28,872484 1 0x00007fa1abdf8700     log KurentoServerMethods      ServerMethods.cpp:351 postProcess()  Caching: {
   "id" : 31,
   "jsonrpc" : "2.0",
   "result" : {
      "sessionId" : "8fa7d30b-4e78-439e-a6fe-5e249cfa263b",
      "value" : null
   }
}

2022-07-08T10:11:28,872677 1 0x00007fa1abdf8700   debug KurentoWebSocketTransport WebSocketTransport.cpp:534 processMessage()  Response: {"id":31,"jsonrpc":"2.0","result":{"sessionId":"8fa7d30b-4e78-439e-a6fe-5e249cfa263b","value":null}}
2022-07-08T10:11:28,917975 1 0x00007fa1ac5f9700   debug KurentoWebSocketTransport WebSocketTransport.cpp:532 processMessage()  Message: {"jsonrpc":"2.0","method":"invoke","params":{"object":"95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint","operation":"connect","operationParams":{"sink":"95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint"},"sessionId":"8fa7d30b-4e78-439e-a6fe-5e249cfa263b"},"id":32}
2022-07-08T10:11:28,918331 1 0x00007fa1ac5f9700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:1001 connect()  Connecting 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint -> 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint params AUDIO default default
2022-07-08T10:11:28,918544 1 0x00007fa1ac5f9700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1059 performConnection()  Still waiting for src pad 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint:audio_src_default_1
2022-07-08T10:11:28,918651 1 0x00007fa1ac5f9700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:1001 connect()  Connecting 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint -> 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint params VIDEO default default
2022-07-08T10:11:28,918771 1 0x00007fa1ac5f9700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1059 performConnection()  Still waiting for src pad 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint:video_src_default_1
2022-07-08T10:11:28,918868 1 0x00007fa1ac5f9700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:1001 connect()  Connecting 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint -> 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint params DATA default default
2022-07-08T10:11:28,918978 1 0x00007fa1ac5f9700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1059 performConnection()  Still waiting for src pad 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint:data_src_default_1
2022-07-08T10:11:28,919106 1 0x00007fa1ac5f9700     log KurentoServerMethods      ServerMethods.cpp:351 postProcess()  Caching: {
   "id" : 32,
   "jsonrpc" : "2.0",
   "result" : {
      "sessionId" : "8fa7d30b-4e78-439e-a6fe-5e249cfa263b",
      "value" : null
   }
}

2022-07-08T10:11:28,919241 1 0x00007fa1ac5f9700   debug KurentoWebSocketTransport WebSocketTransport.cpp:534 processMessage()  Response: {"id":32,"jsonrpc":"2.0","result":{"sessionId":"8fa7d30b-4e78-439e-a6fe-5e249cfa263b","value":null}}
2022-07-08T10:11:28,921695 1 0x00007fa1addfc700   debug KurentoWebSocketTransport WebSocketTransport.cpp:532 processMessage()  Message: {"jsonrpc":"2.0","method":"invoke","params":{"object":"95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/476c74fa-d7fc-45ba-91c5-d2f81dca9176_kurento.RecorderEndpoint","operation":"record","sessionId":"8fa7d30b-4e78-439e-a6fe-5e249cfa263b"},"id":33}
2022-07-08T10:11:28,921944 1 0x00007fa1addfc700     log GST_URI                   gsturi.c:498 gst_uri_get_location()  extracted location '/tmp/kurento-hello-world-recording.webm' from URI 'file:///tmp/kurento-hello-world-recording.webm'
2022-07-08T10:11:28,922518 1 0x00007fa1addfc700     log KurentoMediaElementImpl   MediaElementImpl.cpp:268 _media_element_pad_added() <kmsrecorderendpoint25:sink_audio_default>  Pad added
2022-07-08T10:11:28,922737 1 0x00007fa1addfc700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1059 performConnection()  Still waiting for src pad 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint:audio_src_default_0
2022-07-08T10:11:28,923120 1 0x00007fa1addfc700     log KurentoMediaElementImpl   MediaElementImpl.cpp:268 _media_element_pad_added() <kmsrecorderendpoint25:sink_video_default>  Pad added
2022-07-08T10:11:28,923276 1 0x00007fa1addfc700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1059 performConnection()  Still waiting for src pad 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint:video_src_default_0
2022-07-08T10:11:28,923586 1 0x00007fa1addfc700     log KurentoServerMethods      ServerMethods.cpp:351 postProcess()  Caching: {
   "id" : 33,
   "jsonrpc" : "2.0",
   "result" : {
      "sessionId" : "8fa7d30b-4e78-439e-a6fe-5e249cfa263b",
      "value" : null
   }
}

2022-07-08T10:11:28,923809 1 0x00007fa1addfc700   debug KurentoWebSocketTransport WebSocketTransport.cpp:534 processMessage()  Response: {"id":33,"jsonrpc":"2.0","result":{"sessionId":"8fa7d30b-4e78-439e-a6fe-5e249cfa263b","value":null}}
2022-07-08T10:11:28,926764 1 0x00007fa1ab5f7700   debug KurentoWebSocketTransport WebSocketTransport.cpp:532 processMessage()  Message: {"jsonrpc":"2.0","method":"invoke","params":{"object":"95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint","operation":"processOffer","operationParams":{"offer":"v=0\r\no=mozilla...THIS_IS_SDPARTA-99.0 1595045240948010278 0 IN IP4 0.0.0.0\r\ns=-\r\nt=0 0\r\na=sendrecv\r\na=fingerprint:sha-256 3B:5C:B9:66:57:FB:B8:C3:30:A5:CB:BD:7E:9F:EC:23:CB:60:FF:F7:F2:AF:FA:C3:C5:1D:54:04:DD:1F:85:00\r\na=group:BUNDLE 0 1\r\na=ice-options:trickle\r\na=msid-semantic:WMS *\r\nm=audio 9 UDP/TLS/RTP/SAVPF 109 9 0 8 101\r\nc=IN IP4 0.0.0.0\r\na=sendrecv\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:2/recvonly urn:ietf:params:rtp-hdrext:csrc-audio-level\r\na=extmap:3 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=fmtp:109 maxplaybackrate=48000;stereo=1;useinbandfec=1\r\na=fmtp:101 0-15\r\na=ice-pwd:b466554aea38249734267e8b599c9859\r\na=ice-ufrag:57b1f03c\r\na=mid:0\r\na=msid:{d257971c-4038-4d93-bb0c-2e0ac979f3f6} {88e4a6d9-8f02-46f8-992c-ada29cc3d282}\r\na=rtcp-mux\r\na=rtpmap:109 opus/48000/2\r\na=rtpmap:9 G722/8000/1\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:101 telephone-event/8000\r\na=setup:actpass\r\na=ssrc:1320035078 cname:{537f8bf0-32c7-4360-a01f-8be63f733b08}\r\nm=video 9 UDP/TLS/RTP/SAVPF 120 124 121 125 126 127 97 98\r\nc=IN IP4 0.0.0.0\r\na=sendrecv\r\na=extmap:3 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:4 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:5 urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:6/recvonly http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:7 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=fmtp:126 profile-level-id=42e01f;level-asymmetry-allowed=1;packetization-mode=1\r\na=fmtp:97 profile-level-id=42e01f;level-asymmetry-allowed=1\r\na=fmtp:120 max-fs=12288;max-fr=60\r\na=fmtp:124 apt=120\r\na=fmtp:121 max-fs=12288;max-fr=60\r\na=fmtp:125 apt=121\r\na=fmtp:127 apt=126\r\na=fmtp:98 apt=97\r\na=ice-pwd:b466554aea38249734267e8b599c9859\r\na=ice-ufrag:57b1f03c\r\na=mid:1\r\na=msid:{d257971c-4038-4d93-bb0c-2e0ac979f3f6} {dde363c2-44b8-4249-bda4-19ae88e5754b}\r\na=rtcp-fb:120 nack\r\na=rtcp-fb:120 nack pli\r\na=rtcp-fb:120 ccm fir\r\na=rtcp-fb:120 goog-remb\r\na=rtcp-fb:120 transport-cc\r\na=rtcp-fb:121 nack\r\na=rtcp-fb:121 nack pli\r\na=rtcp-fb:121 ccm fir\r\na=rtcp-fb:121 goog-remb\r\na=rtcp-fb:121 transport-cc\r\na=rtcp-fb:126 nack\r\na=rtcp-fb:126 nack pli\r\na=rtcp-fb:126 ccm fir\r\na=rtcp-fb:126 goog-remb\r\na=rtcp-fb:126 transport-cc\r\na=rtcp-fb:97 nack\r\na=rtcp-fb:97 nack pli\r\na=rtcp-fb:97 ccm fir\r\na=rtcp-fb:97 goog-remb\r\na=rtcp-fb:97 transport-cc\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:120 VP8/90000\r\na=rtpmap:124 rtx/90000\r\na=rtpmap:121 VP9/90000\r\na=rtpmap:125 rtx/90000\r\na=rtpmap:126 H264/90000\r\na=rtpmap:127 rtx/90000\r\na=rtpmap:97 H264/90000\r\na=rtpmap:98 rtx/90000\r\na=setup:actpass\r\na=ssrc:291233836 cname:{537f8bf0-32c7-4360-a01f-8be63f733b08}\r\na=ssrc:3168277022 cname:{537f8bf0-32c7-4360-a01f-8be63f733b08}\r\na=ssrc-group:FID 291233836 3168277022\r\n"},"sessionId":"8fa7d30b-4e78-439e-a6fe-5e249cfa263b"},"id":34}
2022-07-08T10:11:28,944809 1 0x00007fa1ab5f7700     log KurentoServerMethods      ServerMethods.cpp:351 postProcess()  Caching: {
   "id" : 34,
   "jsonrpc" : "2.0",
   "result" : {
      "sessionId" : "8fa7d30b-4e78-439e-a6fe-5e249cfa263b",
      "value" : "v=0\r\no=- 3866263888 3866263888 IN IP4 0.0.0.0\r\ns=Kurento Media Server\r\nc=IN IP4 0.0.0.0\r\nt=0 0\r\na=sendrecv:\r\na=fingerprint:sha-256 3B:5C:B9:66:57:FB:B8:C3:30:A5:CB:BD:7E:9F:EC:23:CB:60:FF:F7:F2:AF:FA:C3:C5:1D:54:04:DD:1F:85:00\r\na=ice-options:trickle\r\na=msid-semantic:WMS *\r\na=group:BUNDLE 0 1\r\nm=audio 1 UDP/TLS/RTP/SAVPF 109 0\r\na=sendrecv\r\na=mid:0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=rtpmap:109 opus/48000/2\r\na=rtpmap:0 PCMU/8000\r\na=fmtp:109 maxplaybackrate=48000;stereo=1;useinbandfec=1\r\na=rtcp-mux\r\na=setup:active\r\na=ssrc:2441405278 cname:user2732193411@host-89019f27\r\na=ice-ufrag:HnSZ\r\na=ice-pwd:4iDozj3mU+RxJoTl5AsQ8v\r\na=fingerprint:sha-256 D5:6D:C7:9A:B5:58:EC:54:33:0E:4B:51:1C:32:78:61:15:C2:7C:03:A7:AA:1B:18:58:C4:95:09:BC:88:51:ED\r\nm=video 1 UDP/TLS/RTP/SAVPF 120 126 97\r\na=extmap:4 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=sendrecv\r\na=mid:1\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=rtpmap:120 VP8/90000\r\na=rtpmap:126 H264/90000\r\na=rtpmap:97 H264/90000\r\na=rtcp-fb:120 nack\r\na=rtcp-fb:120 nack pli\r\na=rtcp-fb:120 ccm fir\r\na=rtcp-fb:120 goog-remb\r\na=rtcp-fb:126 nack\r\na=rtcp-fb:126 nack pli\r\na=rtcp-fb:126 ccm fir\r\na=rtcp-fb:126 goog-remb\r\na=rtcp-fb:97 nack\r\na=rtcp-fb:97 nack pli\r\na=rtcp-fb:97 ccm fir\r\na=rtcp-fb:97 goog-remb\r\na=fmtp:126 profile-level-id=42e01f;level-asymmetry-allowed=1;packetization-mode=1\r\na=fmtp:97 profile-level-id=42e01f;level-asymmetry-allowed=1\r\na=fmtp:120 max-fs=12288;max-fr=60\r\na=rtcp-mux\r\na=setup:active\r\na=ssrc:2284685070 cname:user2732193411@host-89019f27\r\na=ice-ufrag:HnSZ\r\na=ice-pwd:4iDozj3mU+RxJoTl5AsQ8v\r\na=fingerprint:sha-256 D5:6D:C7:9A:B5:58:EC:54:33:0E:4B:51:1C:32:78:61:15:C2:7C:03:A7:AA:1B:18:58:C4:95:09:BC:88:51:ED\r\n"
   }
}

2022-07-08T10:11:28,945153 1 0x00007fa1ab5f7700   debug KurentoWebSocketTransport WebSocketTransport.cpp:534 processMessage()  Response: {"id":34,"jsonrpc":"2.0","result":{"sessionId":"8fa7d30b-4e78-439e-a6fe-5e249cfa263b","value":"v=0\r\no=- 3866263888 3866263888 IN IP4 0.0.0.0\r\ns=Kurento Media Server\r\nc=IN IP4 0.0.0.0\r\nt=0 0\r\na=sendrecv:\r\na=fingerprint:sha-256 3B:5C:B9:66:57:FB:B8:C3:30:A5:CB:BD:7E:9F:EC:23:CB:60:FF:F7:F2:AF:FA:C3:C5:1D:54:04:DD:1F:85:00\r\na=ice-options:trickle\r\na=msid-semantic:WMS *\r\na=group:BUNDLE 0 1\r\nm=audio 1 UDP/TLS/RTP/SAVPF 109 0\r\na=sendrecv\r\na=mid:0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=rtpmap:109 opus/48000/2\r\na=rtpmap:0 PCMU/8000\r\na=fmtp:109 maxplaybackrate=48000;stereo=1;useinbandfec=1\r\na=rtcp-mux\r\na=setup:active\r\na=ssrc:2441405278 cname:user2732193411@host-89019f27\r\na=ice-ufrag:HnSZ\r\na=ice-pwd:4iDozj3mU+RxJoTl5AsQ8v\r\na=fingerprint:sha-256 D5:6D:C7:9A:B5:58:EC:54:33:0E:4B:51:1C:32:78:61:15:C2:7C:03:A7:AA:1B:18:58:C4:95:09:BC:88:51:ED\r\nm=video 1 UDP/TLS/RTP/SAVPF 120 126 97\r\na=extmap:4 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=sendrecv\r\na=mid:1\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=rtpmap:120 VP8/90000\r\na=rtpmap:126 H264/90000\r\na=rtpmap:97 H264/90000\r\na=rtcp-fb:120 nack\r\na=rtcp-fb:120 nack pli\r\na=rtcp-fb:120 ccm fir\r\na=rtcp-fb:120 goog-remb\r\na=rtcp-fb:126 nack\r\na=rtcp-fb:126 nack pli\r\na=rtcp-fb:126 ccm fir\r\na=rtcp-fb:126 goog-remb\r\na=rtcp-fb:97 nack\r\na=rtcp-fb:97 nack pli\r\na=rtcp-fb:97 ccm fir\r\na=rtcp-fb:97 goog-remb\r\na=fmtp:126 profile-level-id=42e01f;level-asymmetry-allowed=1;packetization-mode=1\r\na=fmtp:97 profile-level-id=42e01f;level-asymmetry-allowed=1\r\na=fmtp:120 max-fs=12288;max-fr=60\r\na=rtcp-mux\r\na=setup:active\r\na=ssrc:2284685070 cname:user2732193411@host-89019f27\r\na=ice-ufrag:HnSZ\r\na=ice-pwd:4iDozj3mU+RxJoTl5AsQ8v\r\na=fingerprint:sha-256 D5:6D:C7:9A:B5:58:EC:54:33:0E:4B:51:1C:32:78:61:15:C2:7C:03:A7:AA:1B:18:58:C4:95:09:BC:88:51:ED\r\n"}}
2022-07-08T10:11:28,949088 1 0x00007fa1ad5fb700   debug KurentoWebSocketTransport WebSocketTransport.cpp:532 processMessage()  Message: {"jsonrpc":"2.0","method":"invoke","params":{"object":"95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint","operation":"gatherCandidates","sessionId":"8fa7d30b-4e78-439e-a6fe-5e249cfa263b"},"id":35}
2022-07-08T10:11:28,973908 1 0x00007fa176ffd700   debug KurentoWebRtcEndpointImpl WebRtcEndpointImpl.cpp:335 newSelectedPairFull() <kmswebrtcendpoint27>  New candidate pair selected, local: 'candidate:1 1 UDP 2015363327 172.17.0.2 50003 typ host', remote: 'candidate:0 1 UDP 2122187007 192.168.8.101 32910 typ host', stream_id: '1', component_id: 1
2022-07-08T10:11:29,943549 1 0x00007fa176ffd700   debug KurentoBaseRtpEndpointImpl BaseRtpEndpointImpl.cpp:190 updateConnectionState() <kmswebrtcendpoint27>  ConnectionState changed to 'CONNECTED'
2022-07-08T10:11:29,943996 1 0x00007fa176ffd700     log KurentoMediaElementImpl   MediaElementImpl.cpp:268 _media_element_pad_added() <kmswebrtcendpoint27:sink_audio_default>  Pad added
2022-07-08T10:11:29,944234 1 0x00007fa176ffd700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1059 performConnection()  Still waiting for src pad 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint:audio_src_default_1
2022-07-08T10:11:29,944617 1 0x00007fa176ffd700     log KurentoMediaElementImpl   MediaElementImpl.cpp:268 _media_element_pad_added() <kmswebrtcendpoint27:sink_video_default>  Pad added
2022-07-08T10:11:29,944768 1 0x00007fa176ffd700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1059 performConnection()  Still waiting for src pad 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint:video_src_default_1
2022-07-08T10:11:29,963738 1 0x00007fa176ffd700   debug KurentoBaseRtpEndpointImpl BaseRtpEndpointImpl.cpp:153 updateMediaState() <kmswebrtcendpoint27>  MediaState changed to 'CONNECTED'
2022-07-08T10:11:29,968390 1 0x00007fa131bd7700     log KurentoMediaElementImpl   MediaElementImpl.cpp:268 _media_element_pad_added() <kmswebrtcendpoint27:audio_src_default_1>  Pad added
2022-07-08T10:11:29,968627 1 0x00007fa131bd7700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1070 performConnection()  Linking 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint:audio_src_default_1 -> 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint:sink_audio_default
2022-07-08T10:11:29,968813 1 0x00007fa131bd7700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1078 performConnection()  Link done
2022-07-08T10:11:29,968960 1 0x00007fa131bd7700     log KurentoMediaElementImpl   MediaElementImpl.cpp:268 _media_element_pad_added() <kmswebrtcendpoint27:audio_src_default_0>  Pad added
2022-07-08T10:11:29,969102 1 0x00007fa131bd7700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1070 performConnection()  Linking 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint:audio_src_default_0 -> 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/476c74fa-d7fc-45ba-91c5-d2f81dca9176_kurento.RecorderEndpoint:sink_audio_default
2022-07-08T10:11:29,969240 1 0x00007fa131bd7700   debug recorderendpoint          kmsrecorderendpoint.c:1153 link_sinkpad_cb() <kmsrecorderendpoint25:sink_audio_default>  linked to <kmswebrtcendpoint27:audio_src_default_0>
2022-07-08T10:11:29,969581 1 0x00007fa131bd7700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1078 performConnection()  Link done
2022-07-08T10:11:29,971464 1 0x00007fa1337fe700     log KurentoMediaElementImpl   MediaElementImpl.cpp:268 _media_element_pad_added() <kmswebrtcendpoint27:video_src_default_1>  Pad added
2022-07-08T10:11:29,971797 1 0x00007fa1337fe700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1070 performConnection()  Linking 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint:video_src_default_1 -> 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint:sink_video_default
2022-07-08T10:11:29,972148 1 0x00007fa1337fe700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1078 performConnection()  Link done
2022-07-08T10:11:29,972399 1 0x00007fa1337fe700     log KurentoMediaElementImpl   MediaElementImpl.cpp:268 _media_element_pad_added() <kmswebrtcendpoint27:video_src_default_0>  Pad added
2022-07-08T10:11:29,972615 1 0x00007fa1337fe700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1070 performConnection()  Linking 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint:video_src_default_0 -> 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/476c74fa-d7fc-45ba-91c5-d2f81dca9176_kurento.RecorderEndpoint:sink_video_default
2022-07-08T10:11:29,972814 1 0x00007fa1337fe700   debug recorderendpoint          kmsrecorderendpoint.c:1153 link_sinkpad_cb() <kmsrecorderendpoint25:sink_video_default>  linked to <kmswebrtcendpoint27:video_src_default_0>
2022-07-08T10:11:29,973015 1 0x00007fa1337fe700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1078 performConnection()  Link done
2022-07-08T10:11:29,974467 1 0x00007fa131bd7700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:526 mediaFlowOutStateChange() <kmswebrtcendpoint27>  MediaFlowOutStateChange: FLOWING, pad: 'default', type: 'audio'
2022-07-08T10:11:29,974861 1 0x00007fa131bd7700   debug agnosticbin               kmsagnosticbin.c:897 input_bin_src_caps_probe() <kmswebrtcendpoint27_kmsagnosticbin2-36>  Set input caps: audio/x-opus, rate=(int)48000, channels=(int)2, channel-mapping-family=(int)0, stream-count=(int)1, coupled-count=(int)1, streamheader=(buffer)< 4f707573486561640102000080bb0000000000, 4f707573546167731f0000004753747265616d657220656e636f64656420766f72626973636f6d6d656e740000000001 >
2022-07-08T10:11:29,975087 1 0x00007fa131bd7700   debug agnosticbin               kmsagnosticbin.c:772 kms_agnostic_bin2_link_pad() <kmswebrtcendpoint27_kmsagnosticbin2-36>  Upstream provided caps: ANY
2022-07-08T10:11:29,975417 1 0x00007fa1337fe700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:526 mediaFlowOutStateChange() <kmswebrtcendpoint27>  MediaFlowOutStateChange: FLOWING, pad: 'default', type: 'video'
2022-07-08T10:11:29,975409 1 0x00007fa131bd7700   debug agnosticbin               kmsagnosticbin.c:781 kms_agnostic_bin2_link_pad() <kmswebrtcendpoint27_kmsagnosticbin2-36>  Downstream wanted caps: audio/x-opus, channels=(int)[ 1, 2 ], channel-mapping-family=(int)0
2022-07-08T10:11:29,975634 1 0x00007fa131bd7700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:601 onMediaTranscodingStateChange() <kmswebrtcendpoint27>  MediaTranscodingStateChange: NOT TRANSCODING, bin: 'kmswebrtcendpoint27_kmsagnosticbin2-36', type: 'audio'
2022-07-08T10:11:29,975742 1 0x00007fa131bd7700   debug agnosticbin               kmsagnosticbin.c:741 kms_agnostic_bin2_find_or_create_bin_for_caps() <kmswebrtcendpoint27_kmsagnosticbin2-36>  TRANSCODING INACTIVE for audio
2022-07-08T10:11:29,976890 1 0x00007fa131bd7700   debug agnosticbin               kmsagnosticbin.c:772 kms_agnostic_bin2_link_pad() <kmswebrtcendpoint27_kmsagnosticbin2-36>  Upstream provided caps: ANY
2022-07-08T10:11:29,977203 1 0x00007fa1337fe700   debug agnosticbin               kmsagnosticbin.c:897 input_bin_src_caps_probe() <kmswebrtcendpoint27_kmsagnosticbin2-37>  Set input caps: video/x-vp8, framerate=(fraction)52/1, height=(int)480, width=(int)640, profile=(string)0
2022-07-08T10:11:29,977315 1 0x00007fa131bd7700   debug agnosticbin               kmsagnosticbin.c:781 kms_agnostic_bin2_link_pad() <kmswebrtcendpoint27_kmsagnosticbin2-36>  Downstream wanted caps: audio/x-opus, channels=(int)[ 1, 2147483647 ], rate=(int)[ 1, 2147483647 ]
2022-07-08T10:11:29,977389 1 0x00007fa1337fe700   debug agnosticbin               kmsagnosticbin.c:772 kms_agnostic_bin2_link_pad() <kmswebrtcendpoint27_kmsagnosticbin2-37>  Upstream provided caps: ANY
2022-07-08T10:11:29,977566 1 0x00007fa1337fe700   debug agnosticbin               kmsagnosticbin.c:781 kms_agnostic_bin2_link_pad() <kmswebrtcendpoint27_kmsagnosticbin2-37>  Downstream wanted caps: video/x-vp8
2022-07-08T10:11:29,977715 1 0x00007fa1337fe700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:601 onMediaTranscodingStateChange() <kmswebrtcendpoint27>  MediaTranscodingStateChange: NOT TRANSCODING, bin: 'kmswebrtcendpoint27_kmsagnosticbin2-37', type: 'video'
2022-07-08T10:11:29,977852 1 0x00007fa1337fe700   debug agnosticbin               kmsagnosticbin.c:741 kms_agnostic_bin2_find_or_create_bin_for_caps() <kmswebrtcendpoint27_kmsagnosticbin2-37>  TRANSCODING INACTIVE for video
2022-07-08T10:11:29,978530 1 0x00007fa1337fe700   debug agnosticbin               kmsagnosticbin.c:772 kms_agnostic_bin2_link_pad() <kmswebrtcendpoint27_kmsagnosticbin2-37>  Upstream provided caps: ANY
2022-07-08T10:11:29,978814 1 0x00007fa1337fe700   debug agnosticbin               kmsagnosticbin.c:781 kms_agnostic_bin2_link_pad() <kmswebrtcendpoint27_kmsagnosticbin2-37>  Downstream wanted caps: video/x-vp8, width=(int)[ 16, 4096 ], height=(int)[ 16, 4096 ], framerate=(fraction)[ 0/1, 2147483647/1 ]
2022-07-08T10:11:29,979553 1 0x00007fa1177fe700   debug recorderendpoint          kmsrecorderendpoint.c:1046 appsink_event_probe() <appsink50:sink>  Processing CAPS event audio/x-opus, rate=(int)48000, channels=(int)2, channel-mapping-family=(int)0, stream-count=(int)1, coupled-count=(int)1, streamheader=(buffer)< 4f707573486561640102000080bb0000000000, 4f707573546167731f0000004753747265616d657220656e636f64656420766f72626973636f6d6d656e740000000001 >
2022-07-08T10:11:29,979826 1 0x00007fa1177fe700   debug recorderendpoint          kmsrecorderendpoint.c:1054 appsink_event_probe() <appsink50>  Setting caps: audio/x-opus, rate=(int)48000, channels=(int)2, channel-mapping-family=(int)0, stream-count=(int)1, coupled-count=(int)1, streamheader=(buffer)< 4f707573486561640102000080bb0000000000, 4f707573546167731f0000004753747265616d657220656e636f64656420766f72626973636f6d6d656e740000000001 >
2022-07-08T10:11:29,980016 1 0x00007fa1177fe700   debug recorderendpoint          kmsrecorderendpoint.c:1021 set_appsink_caps() <appsink50>  Setting sink caps audio/x-opus, rate=(int)48000, channels=(int)2, channel-mapping-family=(int)0, stream-count=(int)1, coupled-count=(int)1, streamheader=(buffer)< 4f707573486561640102000080bb0000000000, 4f707573546167731f0000004753747265616d657220656e636f64656420766f72626973636f6d6d656e740000000001 >
2022-07-08T10:11:29,980251 1 0x00007fa1177fe700   debug recorderendpoint          kmsrecorderendpoint.c:981 set_appsrc_caps() <audioSrc>  Setting source caps audio/x-opus, rate=(int)48000, channels=(int)2, channel-mapping-family=(int)0, stream-count=(int)1, coupled-count=(int)1, streamheader=(buffer)< 4f707573486561640102000080bb0000000000, 4f707573546167731f0000004753747265616d657220656e636f64656420766f72626973636f6d6d656e740000000001 >, framerate=(fraction)0/1
2022-07-08T10:11:29,980634 1 0x00007fa116ffd700   debug recorderendpoint          kmsrecorderendpoint.c:1046 appsink_event_probe() <appsink51:sink>  Processing CAPS event video/x-vp8, framerate=(fraction)52/1, height=(int)480, width=(int)640, profile=(string)0
2022-07-08T10:11:29,980822 1 0x00007fa116ffd700   debug recorderendpoint          kmsrecorderendpoint.c:1054 appsink_event_probe() <appsink51>  Setting caps: video/x-vp8, framerate=(fraction)52/1, height=(int)480, width=(int)640, profile=(string)0
2022-07-08T10:11:29,980948 1 0x00007fa116ffd700   debug recorderendpoint          kmsrecorderendpoint.c:1021 set_appsink_caps() <appsink51>  Setting sink caps video/x-vp8, profile=(string)0
2022-07-08T10:11:29,981070 1 0x00007fa116ffd700   debug recorderendpoint          kmsrecorderendpoint.c:981 set_appsrc_caps() <videoSrc>  Setting source caps video/x-vp8, framerate=(fraction)0/1, height=(int)480, width=(int)640, profile=(string)0
2022-07-08T10:11:29,981809 1 0x00007fa1313d6700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:561 mediaFlowInStateChange() <kmswebrtcendpoint27>  MediaFlowInStateChange: FLOWING, pad: 'default', type: 'audio'
2022-07-08T10:11:29,981820 1 0x00007fa1177fe700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:561 mediaFlowInStateChange() <kmsrecorderendpoint25>  MediaFlowInStateChange: FLOWING, pad: 'default', type: 'audio'
2022-07-08T10:11:29,982111 1 0x00007fa1177fe700   debug recorderendpoint          kmsrecorderendpoint.c:364 recv_sample() <kmsrecorderendpoint25>  Setting PTS base time to 0:00:01.177749780
2022-07-08T10:11:29,982222 1 0x00007fa1177fe700   debug recorderendpoint          kmsrecorderendpoint.c:371 recv_sample() <kmsrecorderendpoint25>  Setting DTS base time to 0:00:01.177749780
2022-07-08T10:11:30,039695 1 0x00007fa130bd5700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:561 mediaFlowInStateChange() <kmswebrtcendpoint27>  MediaFlowInStateChange: FLOWING, pad: 'default', type: 'video'
2022-07-08T10:11:30,039677 1 0x00007fa116ffd700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:561 mediaFlowInStateChange() <kmsrecorderendpoint25>  MediaFlowInStateChange: FLOWING, pad: 'default', type: 'video'
2022-07-08T10:11:30,040571 1 0x00007fa115ffb700   debug uriendpoint               kmsuriendpoint.c:108 kms_uri_endpoint_change_state_impl() <kmsrecorderendpoint25>  State changed from stop to start
2022-07-08T10:11:30,042088 1 0x00007fa115ffb700   debug KurentoRecorderEndpointImpl RecorderEndpointImpl.cpp:194 onStateChanged() <kmsrecorderendpoint25>  State changed to Recording
2022-07-08T10:11:30,042332 1 0x00007fa115ffb700   trace KurentoRecorderEndpointImpl RecorderEndpointImpl.cpp:222 onStateChanged() <kmsrecorderendpoint25>  State changed to 1
2022-07-08T10:11:34,731395 1 0x00007fa1a8df2700    info KurentoWebSocketTransport WebSocketTransport.cpp:346 keepAliveSessions()  Keep-Alive for session '0dd12f24-36a6-4efd-9901-58cbc952519d'
2022-07-08T10:11:34,731766 1 0x00007fa1a8df2700    info KurentoWebSocketTransport WebSocketTransport.cpp:346 keepAliveSessions()  Keep-Alive for session '8fa7d30b-4e78-439e-a6fe-5e249cfa263b'
2022-07-08T10:11:34,885951 1 0x00007fa1a95f3700   debug KurentoWebSocketTransport WebSocketTransport.cpp:532 processMessage()  Message: {"jsonrpc":"2.0","method":"release","params":{"object":"95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline","sessionId":"8fa7d30b-4e78-439e-a6fe-5e249cfa263b"},"id":37}
2022-07-08T10:11:34,886395 1 0x00007fa1a95f3700     log KurentoServerMethods      ServerMethods.cpp:351 postProcess()  Caching: {
   "id" : 37,
   "jsonrpc" : "2.0",
   "result" : {
      "sessionId" : "8fa7d30b-4e78-439e-a6fe-5e249cfa263b"
   }
}

2022-07-08T10:11:34,886416 1 0x00007fa1b0601700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:1130 disconnect()  Disconnecting 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint - 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/476c74fa-d7fc-45ba-91c5-d2f81dca9176_kurento.RecorderEndpoint params AUDIO default default
2022-07-08T10:11:34,886494 1 0x00007fa1b1603700   debug recorderendpoint          kmsrecorderendpoint.c:249 send_eos()  Send EOS to audioSrc
2022-07-08T10:11:34,886682 1 0x00007fa1a95f3700   debug KurentoWebSocketTransport WebSocketTransport.cpp:534 processMessage()  Response: {"id":37,"jsonrpc":"2.0","result":{"sessionId":"8fa7d30b-4e78-439e-a6fe-5e249cfa263b"}}
2022-07-08T10:11:34,886852 1 0x00007fa1b1603700   debug recorderendpoint          kmsrecorderendpoint.c:249 send_eos()  Send EOS to videoSrc
2022-07-08T10:11:34,886962 1 0x00007fa1b0601700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:1130 disconnect()  Disconnecting 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint - 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint params AUDIO default default
2022-07-08T10:11:34,887170 1 0x00007fa1b1603700   debug recorderendpoint          kmsrecorderendpoint.c:1218 unlink_sinkpad_cb() <kmsrecorderendpoint25>  Stop operation is pending
2022-07-08T10:11:34,887401 1 0x00007fa1b0601700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:1130 disconnect()  Disconnecting 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint - 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/476c74fa-d7fc-45ba-91c5-d2f81dca9176_kurento.RecorderEndpoint params DATA default default
2022-07-08T10:11:34,887491 1 0x00007fa1b1603700   debug recorderendpoint          kmsrecorderendpoint.c:834 kms_recorder_endpoint_stopped() <kmsrecorderendpoint25>  Pipeline will stop when all eos are processed
2022-07-08T10:11:34,887551 1 0x00007fa1b0601700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:1130 disconnect()  Disconnecting 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint - 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint params DATA default default
2022-07-08T10:11:34,887682 1 0x00007fa1157fa700   debug recorderendpoint          kmsrecorderendpoint.c:1338 kms_recorder_endpoint_on_eos() <kmsrecorderendpoint25>  Received EOS in muxing pipeline
2022-07-08T10:11:34,887888 1 0x00007fa1b0601700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:1130 disconnect()  Disconnecting 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint - 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/476c74fa-d7fc-45ba-91c5-d2f81dca9176_kurento.RecorderEndpoint params VIDEO default default
2022-07-08T10:11:34,887931 1 0x00007fa1157fa700 warning recorderendpoint          kmsrecorderendpoint.c:1345 kms_recorder_endpoint_on_eos() <kmsrecorderendpoint25>  Releasing pending pads
2022-07-08T10:11:34,888087 1 0x00007fa1b0601700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:1130 disconnect()  Disconnecting 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint - 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint params VIDEO default default
2022-07-08T10:11:34,888117 1 0x00007fa1157fa700   debug recorderendpoint          kmsrecorderendpoint.c:1357 kms_recorder_endpoint_on_eos() <kmsrecorderendpoint25>  EOS received as a result of a stop operation. Setting pipeline to NULL
2022-07-08T10:11:34,888379 1 0x00007fa1b0601700   debug KurentoMediaSet           MediaSet.cpp:463 async_delete()  Destroying WebRtcEndpoint -> 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint
2022-07-08T10:11:34,888519 1 0x00007fa1b0601700     log KurentoMediaElementImpl   MediaElementImpl.cpp:706 ~MediaElementImpl()  Deleting media element 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/9b3c206a-cf39-41cb-abc3-4c2dd0c661f4_kurento.WebRtcEndpoint
2022-07-08T10:11:34,888548 1 0x00007fa1157fa700   debug uriendpoint               kmsuriendpoint.c:108 kms_uri_endpoint_change_state_impl() <kmsrecorderendpoint25>  State changed from start to stop
2022-07-08T10:11:34,888681 1 0x00007fa1157fa700   debug KurentoRecorderEndpointImpl RecorderEndpointImpl.cpp:181 onStateChanged() <kmsrecorderendpoint25>  State changed to Stopped
2022-07-08T10:11:34,888766 1 0x00007fa1157fa700   trace KurentoRecorderEndpointImpl RecorderEndpointImpl.cpp:222 onStateChanged() <kmsrecorderendpoint25>  State changed to 0
2022-07-08T10:11:34,888876 1 0x00007fa1b1603700   debug KurentoMediaSet           MediaSet.cpp:463 async_delete()  Destroying RecorderEndpoint -> 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/476c74fa-d7fc-45ba-91c5-d2f81dca9176_kurento.RecorderEndpoint
2022-07-08T10:11:34,889012 1 0x00007fa1b1603700     log KurentoMediaElementImpl   MediaElementImpl.cpp:706 ~MediaElementImpl()  Deleting media element 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline/476c74fa-d7fc-45ba-91c5-d2f81dca9176_kurento.RecorderEndpoint
2022-07-08T10:11:34,889669 1 0x00007fa1bf377240   debug recorderendpoint          kmsrecorderendpoint.c:582 kms_recorder_endpoint_dispose() <kmsrecorderendpoint25>  dispose
2022-07-08T10:11:34,889926 1 0x00007fa1bf377240   debug uriendpoint               kmsuriendpoint.c:214 kms_uri_endpoint_dispose() <kmsrecorderendpoint25>  dispose
2022-07-08T10:11:34,890224 1 0x00007fa1bf377240   debug recorderendpoint          kmsrecorderendpoint.c:634 kms_recorder_endpoint_finalize() <kmsrecorderendpoint25>  releasing resources...
2022-07-08T10:11:34,890496 1 0x00007fa1bf377240     log basemediamuxer            kmsbasemediamuxer.c:71 kms_base_media_muxer_finalize() <KmsAVMuxer@0x7fa0f0012520>  finalize
2022-07-08T10:11:34,890826 1 0x00007fa1bf377240   debug recorderendpoint          kmsrecorderendpoint.c:648 kms_recorder_endpoint_finalize() <kmsrecorderendpoint25>  finalized
2022-07-08T10:11:34,890965 1 0x00007fa1bf377240   debug uriendpoint               kmsuriendpoint.c:224 kms_uri_endpoint_finalize() <kmsrecorderendpoint25>  finalize
2022-07-08T10:11:34,892199 1 0x00007fa1b0601700   debug KurentoMediaSet           MediaSet.cpp:463 async_delete()  Destroying MediaPipeline -> 95acf317-36ec-4e66-a9bb-259e7e9cc34a_kurento.MediaPipeline
RaistGH commented 2 years ago

Attached log output when recording is done using "getDisplayMedia" (sendSource: 'screen'), removed all ICE negotiation except SDP exchanges. Timestamp of disconnection is 2022-07-08T10:14:40,387015

2022-07-08T10:13:29,408832 1 0x00007fa1ad5fb700   debug KurentoWebSocketTransport WebSocketTransport.cpp:532 processMessage()  Message: {"jsonrpc":"2.0","method":"create","params":{"type":"RecorderEndpoint","constructorParams":{"mediaPipeline":"b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline","uri":"file:///tmp/kurento-hello-world-recording.webm"},"properties":{},"sessionId":"4070b3e8-f3c7-4435-b3bf-13f4c6937f48"},"id":41}
2022-07-08T10:13:29,409630 1 0x00007fa1ad5fb700   debug GST_URI                   gsturi.c:644 gst_element_make_from_uri()  type:1, uri:file:///tmp/kurento-hello-world-recording.webm, elementname:(null)
2022-07-08T10:13:29,410197 1 0x00007fa1ad5fb700     log GST_URI                   gsturi.c:498 gst_uri_get_location()  extracted location '/tmp/kurento-hello-world-recording.webm' from URI 'file:///tmp/kurento-hello-world-recording.webm'
2022-07-08T10:13:29,410374 1 0x00007fa1ad5fb700   debug GST_URI                   gsturi.c:953 gst_filename_to_uri()  '/tmp/kurento-hello-world-recording.webm' -> 'file:///tmp/kurento-hello-world-recording.webm'
2022-07-08T10:13:29,410546 1 0x00007fa1ad5fb700     log GST_URI                   gsturi.c:690 gst_element_make_from_uri() <filesink27>  created sink for URL 'file:///tmp/kurento-hello-world-recording.webm'
2022-07-08T10:13:29,410808 1 0x00007fa1ad5fb700   debug basemediamuxer            kmsbasemediamuxer.c:185 kms_base_media_muxer_get_sink() <filesink27>  Muxer sink created for URI 'file:///tmp/kurento-hello-world-recording.webm'
2022-07-08T10:13:29,410964 1 0x00007fa1ad5fb700     log GST_URI                   gsturi.c:498 gst_uri_get_location()  extracted location '/tmp/kurento-hello-world-recording.webm' from URI 'file:///tmp/kurento-hello-world-recording.webm'
2022-07-08T10:13:29,411131 1 0x00007fa1ad5fb700   debug GST_URI                   gsturi.c:953 gst_filename_to_uri()  '/tmp/kurento-hello-world-recording.webm' -> 'file:///tmp/kurento-hello-world-recording.webm'
2022-07-08T10:13:29,411751 1 0x00007fa1ad5fb700    info KurentoRecorderEndpointImpl RecorderEndpointImpl.cpp:87 RecorderEndpointImpl()  Set WEBM profile
2022-07-08T10:13:29,412138 1 0x00007fa1ad5fb700     log KurentoServerMethods      ServerMethods.cpp:351 postProcess()  Caching: {
   "id" : 41,
   "jsonrpc" : "2.0",
   "result" : {
      "sessionId" : "4070b3e8-f3c7-4435-b3bf-13f4c6937f48",
      "value" : "b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/6371117b-7672-46ee-adb8-1e3f3de749ff_kurento.RecorderEndpoint"
   }
}

2022-07-08T10:13:29,412394 1 0x00007fa1ad5fb700   debug KurentoWebSocketTransport WebSocketTransport.cpp:534 processMessage()  Response: {"id":41,"jsonrpc":"2.0","result":{"sessionId":"4070b3e8-f3c7-4435-b3bf-13f4c6937f48","value":"b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/6371117b-7672-46ee-adb8-1e3f3de749ff_kurento.RecorderEndpoint"}}
2022-07-08T10:13:29,464571 1 0x00007fa1a95f3700   debug KurentoWebSocketTransport WebSocketTransport.cpp:532 processMessage()  Message: {"jsonrpc":"2.0","method":"invoke","params":{"object":"b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint","operation":"connect","operationParams":{"sink":"b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/6371117b-7672-46ee-adb8-1e3f3de749ff_kurento.RecorderEndpoint"},"sessionId":"4070b3e8-f3c7-4435-b3bf-13f4c6937f48"},"id":63}
2022-07-08T10:13:29,465001 1 0x00007fa1a95f3700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:1001 connect()  Connecting b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint -> b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/6371117b-7672-46ee-adb8-1e3f3de749ff_kurento.RecorderEndpoint params AUDIO default default
2022-07-08T10:13:29,465235 1 0x00007fa1a95f3700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1059 performConnection()  Still waiting for src pad b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint:audio_src_default_0
2022-07-08T10:13:29,465434 1 0x00007fa1a95f3700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:1001 connect()  Connecting b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint -> b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/6371117b-7672-46ee-adb8-1e3f3de749ff_kurento.RecorderEndpoint params VIDEO default default
2022-07-08T10:13:29,465588 1 0x00007fa1a95f3700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1059 performConnection()  Still waiting for src pad b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint:video_src_default_0
2022-07-08T10:13:29,465766 1 0x00007fa1a95f3700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:1001 connect()  Connecting b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint -> b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/6371117b-7672-46ee-adb8-1e3f3de749ff_kurento.RecorderEndpoint params DATA default default
2022-07-08T10:13:29,465955 1 0x00007fa1a95f3700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1059 performConnection()  Still waiting for src pad b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint:data_src_default_0
2022-07-08T10:13:29,466095 1 0x00007fa1a95f3700     log KurentoServerMethods      ServerMethods.cpp:351 postProcess()  Caching: {
   "id" : 63,
   "jsonrpc" : "2.0",
   "result" : {
      "sessionId" : "4070b3e8-f3c7-4435-b3bf-13f4c6937f48",
      "value" : null
   }
}

2022-07-08T10:13:29,466211 1 0x00007fa1a95f3700   debug KurentoWebSocketTransport WebSocketTransport.cpp:534 processMessage()  Response: {"id":63,"jsonrpc":"2.0","result":{"sessionId":"4070b3e8-f3c7-4435-b3bf-13f4c6937f48","value":null}}
2022-07-08T10:13:29,470753 1 0x00007fa1addfc700   debug KurentoWebSocketTransport WebSocketTransport.cpp:532 processMessage()  Message: {"jsonrpc":"2.0","method":"invoke","params":{"object":"b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint","operation":"connect","operationParams":{"sink":"b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint"},"sessionId":"4070b3e8-f3c7-4435-b3bf-13f4c6937f48"},"id":65}
2022-07-08T10:13:29,471360 1 0x00007fa1addfc700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:1001 connect()  Connecting b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint -> b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint params AUDIO default default
2022-07-08T10:13:29,472050 1 0x00007fa1addfc700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1059 performConnection()  Still waiting for src pad b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint:audio_src_default_1
2022-07-08T10:13:29,472567 1 0x00007fa1addfc700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:1001 connect()  Connecting b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint -> b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint params VIDEO default default
2022-07-08T10:13:29,473101 1 0x00007fa1addfc700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1059 performConnection()  Still waiting for src pad b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint:video_src_default_1
2022-07-08T10:13:29,473466 1 0x00007fa1addfc700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:1001 connect()  Connecting b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint -> b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint params DATA default default
2022-07-08T10:13:29,473707 1 0x00007fa1addfc700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1059 performConnection()  Still waiting for src pad b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint:data_src_default_1
2022-07-08T10:13:29,473997 1 0x00007fa1addfc700     log KurentoServerMethods      ServerMethods.cpp:351 postProcess()  Caching: {
   "id" : 65,
   "jsonrpc" : "2.0",
   "result" : {
      "sessionId" : "4070b3e8-f3c7-4435-b3bf-13f4c6937f48",
      "value" : null
   }
}

2022-07-08T10:13:29,474388 1 0x00007fa1addfc700   debug KurentoWebSocketTransport WebSocketTransport.cpp:534 processMessage()  Response: {"id":65,"jsonrpc":"2.0","result":{"sessionId":"4070b3e8-f3c7-4435-b3bf-13f4c6937f48","value":null}}
2022-07-08T10:13:29,499685 1 0x00007fa1aadf6700   debug KurentoWebSocketTransport WebSocketTransport.cpp:532 processMessage()  Message: {"jsonrpc":"2.0","method":"invoke","params":{"object":"b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/6371117b-7672-46ee-adb8-1e3f3de749ff_kurento.RecorderEndpoint","operation":"record","sessionId":"4070b3e8-f3c7-4435-b3bf-13f4c6937f48"},"id":68}
2022-07-08T10:13:29,500065 1 0x00007fa1aadf6700     log GST_URI                   gsturi.c:498 gst_uri_get_location()  extracted location '/tmp/kurento-hello-world-recording.webm' from URI 'file:///tmp/kurento-hello-world-recording.webm'
2022-07-08T10:13:29,500771 1 0x00007fa1aadf6700     log KurentoMediaElementImpl   MediaElementImpl.cpp:268 _media_element_pad_added() <kmsrecorderendpoint27:sink_audio_default>  Pad added
2022-07-08T10:13:29,500985 1 0x00007fa1aadf6700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1059 performConnection()  Still waiting for src pad b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint:audio_src_default_0
2022-07-08T10:13:29,501225 1 0x00007fa1aadf6700     log KurentoMediaElementImpl   MediaElementImpl.cpp:268 _media_element_pad_added() <kmsrecorderendpoint27:sink_video_default>  Pad added
2022-07-08T10:13:29,501394 1 0x00007fa1aadf6700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1059 performConnection()  Still waiting for src pad b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint:video_src_default_0
2022-07-08T10:13:29,501691 1 0x00007fa1aadf6700     log KurentoServerMethods      ServerMethods.cpp:351 postProcess()  Caching: {
   "id" : 68,
   "jsonrpc" : "2.0",
   "result" : {
      "sessionId" : "4070b3e8-f3c7-4435-b3bf-13f4c6937f48",
      "value" : null
   }
}

2022-07-08T10:13:29,501891 1 0x00007fa1aadf6700   debug KurentoWebSocketTransport WebSocketTransport.cpp:534 processMessage()  Response: {"id":68,"jsonrpc":"2.0","result":{"sessionId":"4070b3e8-f3c7-4435-b3bf-13f4c6937f48","value":null}}
2022-07-08T10:13:29,546328 1 0x00007fa1aa5f5700   debug KurentoWebSocketTransport WebSocketTransport.cpp:532 processMessage()  Message: {"jsonrpc":"2.0","method":"invoke","params":{"object":"b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint","operation":"processOffer","operationParams":{"offer":"v=0\r\no=mozilla...THIS_IS_SDPARTA-99.0 391378943432596964 0 IN IP4 0.0.0.0\r\ns=-\r\nt=0 0\r\na=sendrecv\r\na=fingerprint:sha-256 27:A8:B6:9C:22:11:54:A7:AE:17:73:47:A9:5A:CF:23:21:01:F2:33:91:56:10:5D:B9:E4:24:30:D3:DE:DA:6E\r\na=group:BUNDLE 0 1\r\na=ice-options:trickle\r\na=msid-semantic:WMS *\r\nm=video 9 UDP/TLS/RTP/SAVPF 120 124 121 125 126 127 97 98\r\nc=IN IP4 0.0.0.0\r\na=sendrecv\r\na=extmap:3 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:4 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:5 urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:6/recvonly http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:7 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=fmtp:126 profile-level-id=42e01f;level-asymmetry-allowed=1;packetization-mode=1\r\na=fmtp:97 profile-level-id=42e01f;level-asymmetry-allowed=1\r\na=fmtp:120 max-fs=12288;max-fr=60\r\na=fmtp:124 apt=120\r\na=fmtp:121 max-fs=12288;max-fr=60\r\na=fmtp:125 apt=121\r\na=fmtp:127 apt=126\r\na=fmtp:98 apt=97\r\na=ice-pwd:539e4cb9a49331aed2cb01b0f0f9c2e7\r\na=ice-ufrag:7a4cfa76\r\na=mid:0\r\na=msid:{da00e338-ca0c-4553-8bb3-c91d4f2326f6} {4f159557-3995-4fe7-a1f3-26da1992c912}\r\na=rtcp-fb:120 nack\r\na=rtcp-fb:120 nack pli\r\na=rtcp-fb:120 ccm fir\r\na=rtcp-fb:120 goog-remb\r\na=rtcp-fb:120 transport-cc\r\na=rtcp-fb:121 nack\r\na=rtcp-fb:121 nack pli\r\na=rtcp-fb:121 ccm fir\r\na=rtcp-fb:121 goog-remb\r\na=rtcp-fb:121 transport-cc\r\na=rtcp-fb:126 nack\r\na=rtcp-fb:126 nack pli\r\na=rtcp-fb:126 ccm fir\r\na=rtcp-fb:126 goog-remb\r\na=rtcp-fb:126 transport-cc\r\na=rtcp-fb:97 nack\r\na=rtcp-fb:97 nack pli\r\na=rtcp-fb:97 ccm fir\r\na=rtcp-fb:97 goog-remb\r\na=rtcp-fb:97 transport-cc\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:120 VP8/90000\r\na=rtpmap:124 rtx/90000\r\na=rtpmap:121 VP9/90000\r\na=rtpmap:125 rtx/90000\r\na=rtpmap:126 H264/90000\r\na=rtpmap:127 rtx/90000\r\na=rtpmap:97 H264/90000\r\na=rtpmap:98 rtx/90000\r\na=setup:actpass\r\na=ssrc:680514307 cname:{ff0beb0f-8a08-40af-b69e-8b3d13c2ec16}\r\na=ssrc:274736812 cname:{ff0beb0f-8a08-40af-b69e-8b3d13c2ec16}\r\na=ssrc-group:FID 680514307 274736812\r\nm=audio 9 UDP/TLS/RTP/SAVPF 109 9 0 8 101\r\nc=IN IP4 0.0.0.0\r\na=recvonly\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:2/recvonly urn:ietf:params:rtp-hdrext:csrc-audio-level\r\na=extmap:3 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=fmtp:109 maxplaybackrate=48000;stereo=1;useinbandfec=1\r\na=fmtp:101 0-15\r\na=ice-pwd:539e4cb9a49331aed2cb01b0f0f9c2e7\r\na=ice-ufrag:7a4cfa76\r\na=mid:1\r\na=rtcp-mux\r\na=rtpmap:109 opus/48000/2\r\na=rtpmap:9 G722/8000/1\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:101 telephone-event/8000\r\na=setup:actpass\r\na=ssrc:3936749302 cname:{ff0beb0f-8a08-40af-b69e-8b3d13c2ec16}\r\n"},"sessionId":"4070b3e8-f3c7-4435-b3bf-13f4c6937f48"},"id":69}
2022-07-08T10:13:29,553021 1 0x00007fa1aa5f5700     log KurentoServerMethods      ServerMethods.cpp:351 postProcess()  Caching: {
   "id" : 69,
   "jsonrpc" : "2.0",
   "result" : {
      "sessionId" : "4070b3e8-f3c7-4435-b3bf-13f4c6937f48",
      "value" : "v=0\r\no=- 3866264009 3866264009 IN IP4 0.0.0.0\r\ns=Kurento Media Server\r\nc=IN IP4 0.0.0.0\r\nt=0 0\r\na=sendrecv:\r\na=fingerprint:sha-256 27:A8:B6:9C:22:11:54:A7:AE:17:73:47:A9:5A:CF:23:21:01:F2:33:91:56:10:5D:B9:E4:24:30:D3:DE:DA:6E\r\na=ice-options:trickle\r\na=msid-semantic:WMS *\r\na=group:BUNDLE 0 1\r\nm=video 1 UDP/TLS/RTP/SAVPF 120 126 97\r\na=extmap:4 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=sendrecv\r\na=mid:0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=rtpmap:120 VP8/90000\r\na=rtpmap:126 H264/90000\r\na=rtpmap:97 H264/90000\r\na=rtcp-fb:120 nack\r\na=rtcp-fb:120 nack pli\r\na=rtcp-fb:120 ccm fir\r\na=rtcp-fb:120 goog-remb\r\na=rtcp-fb:126 nack\r\na=rtcp-fb:126 nack pli\r\na=rtcp-fb:126 ccm fir\r\na=rtcp-fb:126 goog-remb\r\na=rtcp-fb:97 nack\r\na=rtcp-fb:97 nack pli\r\na=rtcp-fb:97 ccm fir\r\na=rtcp-fb:97 goog-remb\r\na=fmtp:126 profile-level-id=42e01f;level-asymmetry-allowed=1;packetization-mode=1\r\na=fmtp:97 profile-level-id=42e01f;level-asymmetry-allowed=1\r\na=fmtp:120 max-fs=12288;max-fr=60\r\na=rtcp-mux\r\na=setup:active\r\na=ssrc:2446344570 cname:user341071128@host-f12cde74\r\na=ice-ufrag:pRMP\r\na=ice-pwd:MGOcya2P7tdt/qUHl+/BxX\r\na=fingerprint:sha-256 D5:6D:C7:9A:B5:58:EC:54:33:0E:4B:51:1C:32:78:61:15:C2:7C:03:A7:AA:1B:18:58:C4:95:09:BC:88:51:ED\r\nm=audio 1 UDP/TLS/RTP/SAVPF 109 0\r\na=sendonly\r\na=mid:1\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=rtpmap:109 opus/48000/2\r\na=rtpmap:0 PCMU/8000\r\na=fmtp:109 maxplaybackrate=48000;stereo=1;useinbandfec=1\r\na=rtcp-mux\r\na=setup:active\r\na=ssrc:3496700051 cname:user341071128@host-f12cde74\r\na=ice-ufrag:pRMP\r\na=ice-pwd:MGOcya2P7tdt/qUHl+/BxX\r\na=fingerprint:sha-256 D5:6D:C7:9A:B5:58:EC:54:33:0E:4B:51:1C:32:78:61:15:C2:7C:03:A7:AA:1B:18:58:C4:95:09:BC:88:51:ED\r\n"
   }
}

2022-07-08T10:13:29,553374 1 0x00007fa1aa5f5700   debug KurentoWebSocketTransport WebSocketTransport.cpp:534 processMessage()  Response: {"id":69,"jsonrpc":"2.0","result":{"sessionId":"4070b3e8-f3c7-4435-b3bf-13f4c6937f48","value":"v=0\r\no=- 3866264009 3866264009 IN IP4 0.0.0.0\r\ns=Kurento Media Server\r\nc=IN IP4 0.0.0.0\r\nt=0 0\r\na=sendrecv:\r\na=fingerprint:sha-256 27:A8:B6:9C:22:11:54:A7:AE:17:73:47:A9:5A:CF:23:21:01:F2:33:91:56:10:5D:B9:E4:24:30:D3:DE:DA:6E\r\na=ice-options:trickle\r\na=msid-semantic:WMS *\r\na=group:BUNDLE 0 1\r\nm=video 1 UDP/TLS/RTP/SAVPF 120 126 97\r\na=extmap:4 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=sendrecv\r\na=mid:0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=rtpmap:120 VP8/90000\r\na=rtpmap:126 H264/90000\r\na=rtpmap:97 H264/90000\r\na=rtcp-fb:120 nack\r\na=rtcp-fb:120 nack pli\r\na=rtcp-fb:120 ccm fir\r\na=rtcp-fb:120 goog-remb\r\na=rtcp-fb:126 nack\r\na=rtcp-fb:126 nack pli\r\na=rtcp-fb:126 ccm fir\r\na=rtcp-fb:126 goog-remb\r\na=rtcp-fb:97 nack\r\na=rtcp-fb:97 nack pli\r\na=rtcp-fb:97 ccm fir\r\na=rtcp-fb:97 goog-remb\r\na=fmtp:126 profile-level-id=42e01f;level-asymmetry-allowed=1;packetization-mode=1\r\na=fmtp:97 profile-level-id=42e01f;level-asymmetry-allowed=1\r\na=fmtp:120 max-fs=12288;max-fr=60\r\na=rtcp-mux\r\na=setup:active\r\na=ssrc:2446344570 cname:user341071128@host-f12cde74\r\na=ice-ufrag:pRMP\r\na=ice-pwd:MGOcya2P7tdt/qUHl+/BxX\r\na=fingerprint:sha-256 D5:6D:C7:9A:B5:58:EC:54:33:0E:4B:51:1C:32:78:61:15:C2:7C:03:A7:AA:1B:18:58:C4:95:09:BC:88:51:ED\r\nm=audio 1 UDP/TLS/RTP/SAVPF 109 0\r\na=sendonly\r\na=mid:1\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=rtpmap:109 opus/48000/2\r\na=rtpmap:0 PCMU/8000\r\na=fmtp:109 maxplaybackrate=48000;stereo=1;useinbandfec=1\r\na=rtcp-mux\r\na=setup:active\r\na=ssrc:3496700051 cname:user341071128@host-f12cde74\r\na=ice-ufrag:pRMP\r\na=ice-pwd:MGOcya2P7tdt/qUHl+/BxX\r\na=fingerprint:sha-256 D5:6D:C7:9A:B5:58:EC:54:33:0E:4B:51:1C:32:78:61:15:C2:7C:03:A7:AA:1B:18:58:C4:95:09:BC:88:51:ED\r\n"}}
2022-07-08T10:13:29,558578 1 0x00007fa1ac5f9700   debug KurentoWebSocketTransport WebSocketTransport.cpp:532 processMessage()  Message: {"jsonrpc":"2.0","method":"invoke","params":{"object":"b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint","operation":"gatherCandidates","sessionId":"4070b3e8-f3c7-4435-b3bf-13f4c6937f48"},"id":70}
2022-07-08T10:13:29,587477 1 0x00007fa161ffb700   debug KurentoWebRtcEndpointImpl WebRtcEndpointImpl.cpp:335 newSelectedPairFull() <kmswebrtcendpoint29>  New candidate pair selected, local: 'candidate:1 1 UDP 2015363327 172.17.0.2 50056 typ host', remote: 'candidate:0 1 UDP 2122187007 192.168.8.101 53668 typ host', stream_id: '1', component_id: 1
2022-07-08T10:13:30,559998 1 0x00007fa161ffb700   debug KurentoBaseRtpEndpointImpl BaseRtpEndpointImpl.cpp:190 updateConnectionState() <kmswebrtcendpoint29>  ConnectionState changed to 'CONNECTED'
2022-07-08T10:13:30,560243 1 0x00007fa161ffb700     log KurentoMediaElementImpl   MediaElementImpl.cpp:268 _media_element_pad_added() <kmswebrtcendpoint29:sink_video_default>  Pad added
2022-07-08T10:13:30,560477 1 0x00007fa161ffb700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1059 performConnection()  Still waiting for src pad b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint:video_src_default_1
2022-07-08T10:13:30,560763 1 0x00007fa161ffb700     log KurentoMediaElementImpl   MediaElementImpl.cpp:268 _media_element_pad_added() <kmswebrtcendpoint29:sink_audio_default>  Pad added
2022-07-08T10:13:30,560852 1 0x00007fa161ffb700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1059 performConnection()  Still waiting for src pad b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint:audio_src_default_1
2022-07-08T10:13:30,574629 1 0x00007fa161ffb700   debug KurentoBaseRtpEndpointImpl BaseRtpEndpointImpl.cpp:153 updateMediaState() <kmswebrtcendpoint29>  MediaState changed to 'CONNECTED'
2022-07-08T10:13:30,578329 1 0x00007fa1323d8700     log KurentoMediaElementImpl   MediaElementImpl.cpp:268 _media_element_pad_added() <kmswebrtcendpoint29:video_src_default_1>  Pad added
2022-07-08T10:13:30,578567 1 0x00007fa1323d8700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1070 performConnection()  Linking b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint:video_src_default_1 -> b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint:sink_video_default
2022-07-08T10:13:30,578824 1 0x00007fa1323d8700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1078 performConnection()  Link done
2022-07-08T10:13:30,578941 1 0x00007fa1323d8700     log KurentoMediaElementImpl   MediaElementImpl.cpp:268 _media_element_pad_added() <kmswebrtcendpoint29:video_src_default_0>  Pad added
2022-07-08T10:13:30,579048 1 0x00007fa1323d8700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1070 performConnection()  Linking b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint:video_src_default_0 -> b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/6371117b-7672-46ee-adb8-1e3f3de749ff_kurento.RecorderEndpoint:sink_video_default
2022-07-08T10:13:30,579201 1 0x00007fa1323d8700   debug recorderendpoint          kmsrecorderendpoint.c:1153 link_sinkpad_cb() <kmsrecorderendpoint27:sink_video_default>  linked to <kmswebrtcendpoint29:video_src_default_0>
2022-07-08T10:13:30,579286 1 0x00007fa1323d8700     log KurentoMediaElementImpl   MediaElementImpl.cpp:1078 performConnection()  Link done
2022-07-08T10:13:30,718486 1 0x00007fa1323d8700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:526 mediaFlowOutStateChange() <kmswebrtcendpoint29>  MediaFlowOutStateChange: FLOWING, pad: 'default', type: 'video'
2022-07-08T10:13:30,737958 1 0x00007fa1323d8700   debug agnosticbin               kmsagnosticbin.c:897 input_bin_src_caps_probe() <kmswebrtcendpoint29_kmsagnosticbin2-39>  Set input caps: video/x-vp8, framerate=(fraction)90/1, height=(int)467, width=(int)640, profile=(string)0
2022-07-08T10:13:30,738364 1 0x00007fa1323d8700   debug agnosticbin               kmsagnosticbin.c:772 kms_agnostic_bin2_link_pad() <kmswebrtcendpoint29_kmsagnosticbin2-39>  Upstream provided caps: ANY
2022-07-08T10:13:30,738629 1 0x00007fa1323d8700   debug agnosticbin               kmsagnosticbin.c:781 kms_agnostic_bin2_link_pad() <kmswebrtcendpoint29_kmsagnosticbin2-39>  Downstream wanted caps: video/x-vp8
2022-07-08T10:13:30,738908 1 0x00007fa1323d8700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:601 onMediaTranscodingStateChange() <kmswebrtcendpoint29>  MediaTranscodingStateChange: NOT TRANSCODING, bin: 'kmswebrtcendpoint29_kmsagnosticbin2-39', type: 'video'
2022-07-08T10:13:30,739057 1 0x00007fa1323d8700   debug agnosticbin               kmsagnosticbin.c:741 kms_agnostic_bin2_find_or_create_bin_for_caps() <kmswebrtcendpoint29_kmsagnosticbin2-39>  TRANSCODING INACTIVE for video
2022-07-08T10:13:30,739758 1 0x00007fa1323d8700   debug agnosticbin               kmsagnosticbin.c:772 kms_agnostic_bin2_link_pad() <kmswebrtcendpoint29_kmsagnosticbin2-39>  Upstream provided caps: ANY
2022-07-08T10:13:30,740041 1 0x00007fa1323d8700   debug agnosticbin               kmsagnosticbin.c:781 kms_agnostic_bin2_link_pad() <kmswebrtcendpoint29_kmsagnosticbin2-39>  Downstream wanted caps: video/x-vp8, width=(int)[ 16, 4096 ], height=(int)[ 16, 4096 ], framerate=(fraction)[ 0/1, 2147483647/1 ]
2022-07-08T10:13:30,741132 1 0x00007fa130bd5700   debug recorderendpoint          kmsrecorderendpoint.c:1046 appsink_event_probe() <appsink55:sink>  Processing CAPS event video/x-vp8, framerate=(fraction)90/1, height=(int)467, width=(int)640, profile=(string)0
2022-07-08T10:13:30,741473 1 0x00007fa130bd5700   debug recorderendpoint          kmsrecorderendpoint.c:1054 appsink_event_probe() <appsink55>  Setting caps: video/x-vp8, framerate=(fraction)90/1, height=(int)467, width=(int)640, profile=(string)0
2022-07-08T10:13:30,741789 1 0x00007fa130bd5700   debug recorderendpoint          kmsrecorderendpoint.c:1021 set_appsink_caps() <appsink55>  Setting sink caps video/x-vp8, profile=(string)0
2022-07-08T10:13:30,741964 1 0x00007fa130bd5700   debug recorderendpoint          kmsrecorderendpoint.c:981 set_appsrc_caps() <videoSrc>  Setting source caps video/x-vp8, framerate=(fraction)0/1, height=(int)467, width=(int)640, profile=(string)0
2022-07-08T10:13:30,780646 1 0x00007fa130bd5700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:561 mediaFlowInStateChange() <kmsrecorderendpoint27>  MediaFlowInStateChange: FLOWING, pad: 'default', type: 'video'
2022-07-08T10:13:30,780677 1 0x00007fa1313d6700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:561 mediaFlowInStateChange() <kmswebrtcendpoint29>  MediaFlowInStateChange: FLOWING, pad: 'default', type: 'video'
2022-07-08T10:13:30,781152 1 0x00007fa130bd5700   debug recorderendpoint          kmsrecorderendpoint.c:364 recv_sample() <kmsrecorderendpoint27>  Setting PTS base time to 0:00:01.223486315
2022-07-08T10:13:30,781684 1 0x00007fa130bd5700   debug recorderendpoint          kmsrecorderendpoint.c:371 recv_sample() <kmsrecorderendpoint27>  Setting DTS base time to 0:00:01.223486315
2022-07-08T10:13:34,196074 1 0x00007fa1a9df4700   debug KurentoWebSocketTransport WebSocketTransport.cpp:532 processMessage()  Message: {"jsonrpc":"2.0","method":"ping","id":71}
2022-07-08T10:13:34,196372 1 0x00007fa1a9df4700   trace KurentoServerMethods      ServerMethods.cpp:248 injectSessionId()  Injecting sessionId 4070b3e8-f3c7-4435-b3bf-13f4c6937f48
2022-07-08T10:13:34,196710 1 0x00007fa1a9df4700    info KurentoServerMethods      ServerMethods.cpp:814 ping()  WebSocket Ping/Pong with sessionId 4070b3e8-f3c7-4435-b3bf-13f4c6937f48
2022-07-08T10:13:34,196947 1 0x00007fa1a9df4700     log KurentoServerMethods      ServerMethods.cpp:351 postProcess()  Caching: {
   "id" : 71,
   "jsonrpc" : "2.0",
   "result" : {
      "sessionId" : "4070b3e8-f3c7-4435-b3bf-13f4c6937f48",
      "value" : "pong"
   }
}

2022-07-08T10:13:34,197152 1 0x00007fa1a9df4700   debug KurentoWebSocketTransport WebSocketTransport.cpp:534 processMessage()  Response: {"id":71,"jsonrpc":"2.0","result":{"sessionId":"4070b3e8-f3c7-4435-b3bf-13f4c6937f48","value":"pong"}}
2022-07-08T10:13:34,733519 1 0x00007fa1a8df2700    info KurentoWebSocketTransport WebSocketTransport.cpp:346 keepAliveSessions()  Keep-Alive for session '0dd12f24-36a6-4efd-9901-58cbc952519d'
2022-07-08T10:13:34,733913 1 0x00007fa1a8df2700    info KurentoWebSocketTransport WebSocketTransport.cpp:346 keepAliveSessions()  Keep-Alive for session '4070b3e8-f3c7-4435-b3bf-13f4c6937f48'
2022-07-08T10:14:00,584712 1 0x00007fa1a95f3700   debug KurentoWebSocketTransport WebSocketTransport.cpp:532 processMessage()  Message: {"jsonrpc":"2.0","method":"ping","id":57}
2022-07-08T10:14:00,585277 1 0x00007fa1a95f3700   trace KurentoServerMethods      ServerMethods.cpp:248 injectSessionId()  Injecting sessionId 0dd12f24-36a6-4efd-9901-58cbc952519d
2022-07-08T10:14:00,585628 1 0x00007fa1a95f3700    info KurentoServerMethods      ServerMethods.cpp:814 ping()  WebSocket Ping/Pong with sessionId 0dd12f24-36a6-4efd-9901-58cbc952519d
2022-07-08T10:14:00,585969 1 0x00007fa1a95f3700     log KurentoServerMethods      ServerMethods.cpp:351 postProcess()  Caching: {
   "id" : 57,
   "jsonrpc" : "2.0",
   "result" : {
      "sessionId" : "0dd12f24-36a6-4efd-9901-58cbc952519d",
      "value" : "pong"
   }
}

2022-07-08T10:14:00,586339 1 0x00007fa1a95f3700   debug KurentoWebSocketTransport WebSocketTransport.cpp:534 processMessage()  Response: {"id":57,"jsonrpc":"2.0","result":{"sessionId":"0dd12f24-36a6-4efd-9901-58cbc952519d","value":"pong"}}
2022-07-08T10:14:34,196330 1 0x00007fa1acdfa700   debug KurentoWebSocketTransport WebSocketTransport.cpp:532 processMessage()  Message: {"jsonrpc":"2.0","method":"ping","id":73}
2022-07-08T10:14:34,196702 1 0x00007fa1acdfa700   trace KurentoServerMethods      ServerMethods.cpp:248 injectSessionId()  Injecting sessionId 4070b3e8-f3c7-4435-b3bf-13f4c6937f48
2022-07-08T10:14:34,197157 1 0x00007fa1acdfa700    info KurentoServerMethods      ServerMethods.cpp:814 ping()  WebSocket Ping/Pong with sessionId 4070b3e8-f3c7-4435-b3bf-13f4c6937f48
2022-07-08T10:14:34,197670 1 0x00007fa1acdfa700     log KurentoServerMethods      ServerMethods.cpp:351 postProcess()  Caching: {
   "id" : 73,
   "jsonrpc" : "2.0",
   "result" : {
      "sessionId" : "4070b3e8-f3c7-4435-b3bf-13f4c6937f48",
      "value" : "pong"
   }
}

2022-07-08T10:14:34,198061 1 0x00007fa1acdfa700   debug KurentoWebSocketTransport WebSocketTransport.cpp:534 processMessage()  Response: {"id":73,"jsonrpc":"2.0","result":{"sessionId":"4070b3e8-f3c7-4435-b3bf-13f4c6937f48","value":"pong"}}
2022-07-08T10:14:34,690321 1 0x00007fa1ae5fd700   debug KurentoMediaSet           MediaSet.cpp:121 doGarbageCollection()  Running garbage collector
2022-07-08T10:14:34,690738 1 0x00007fa1ae5fd700 warning KurentoMediaSet           MediaSet.cpp:129 doGarbageCollection()  Removing inactive session: d8ebd919-54f1-440a-99b9-5dad31021c83
2022-07-08T10:14:34,691124 1 0x00007fa1ae5fd700 warning KurentoMediaSet           MediaSet.cpp:129 doGarbageCollection()  Removing inactive session: e122846a-5971-489e-9f88-3c89148fa931
2022-07-08T10:14:34,734277 1 0x00007fa1a8df2700    info KurentoWebSocketTransport WebSocketTransport.cpp:346 keepAliveSessions()  Keep-Alive for session '0dd12f24-36a6-4efd-9901-58cbc952519d'
2022-07-08T10:14:34,734801 1 0x00007fa1a8df2700    info KurentoWebSocketTransport WebSocketTransport.cpp:346 keepAliveSessions()  Keep-Alive for session '4070b3e8-f3c7-4435-b3bf-13f4c6937f48'
2022-07-08T10:14:40,387015 1 0x00007fa161ffb700   debug KurentoBaseRtpEndpointImpl BaseRtpEndpointImpl.cpp:153 updateMediaState() <kmswebrtcendpoint29>  MediaState changed to 'DISCONNECTED'
2022-07-08T10:14:40,409510 1 0x00007fa1addfc700   debug KurentoWebSocketTransport WebSocketTransport.cpp:532 processMessage()  Message: {"jsonrpc":"2.0","method":"release","params":{"object":"b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline","sessionId":"4070b3e8-f3c7-4435-b3bf-13f4c6937f48"},"id":74}
2022-07-08T10:14:40,410006 1 0x00007fa1af5ff700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:1130 disconnect()  Disconnecting b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint - b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/6371117b-7672-46ee-adb8-1e3f3de749ff_kurento.RecorderEndpoint params AUDIO default default
2022-07-08T10:14:40,409989 1 0x00007fa1addfc700     log KurentoServerMethods      ServerMethods.cpp:351 postProcess()  Caching: {
   "id" : 74,
   "jsonrpc" : "2.0",
   "result" : {
      "sessionId" : "4070b3e8-f3c7-4435-b3bf-13f4c6937f48"
   }
}

2022-07-08T10:14:40,410432 1 0x00007fa1af5ff700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:1130 disconnect()  Disconnecting b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint - b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/6371117b-7672-46ee-adb8-1e3f3de749ff_kurento.RecorderEndpoint params DATA default default
2022-07-08T10:14:40,410615 1 0x00007fa1addfc700   debug KurentoWebSocketTransport WebSocketTransport.cpp:534 processMessage()  Response: {"id":74,"jsonrpc":"2.0","result":{"sessionId":"4070b3e8-f3c7-4435-b3bf-13f4c6937f48"}}
2022-07-08T10:14:40,410672 1 0x00007fa1af5ff700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:1130 disconnect()  Disconnecting b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint - b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/6371117b-7672-46ee-adb8-1e3f3de749ff_kurento.RecorderEndpoint params VIDEO default default
2022-07-08T10:14:40,411731 1 0x00007fa1af5ff700   debug KurentoMediaSet           MediaSet.cpp:463 async_delete()  Destroying RecorderEndpoint -> b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/6371117b-7672-46ee-adb8-1e3f3de749ff_kurento.RecorderEndpoint
2022-07-08T10:14:40,411969 1 0x00007fa1b0601700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:1130 disconnect()  Disconnecting b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint - b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint params AUDIO default default
2022-07-08T10:14:40,412954 1 0x00007fa1af5ff700     log KurentoMediaElementImpl   MediaElementImpl.cpp:706 ~MediaElementImpl()  Deleting media element b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/6371117b-7672-46ee-adb8-1e3f3de749ff_kurento.RecorderEndpoint
2022-07-08T10:14:40,413059 1 0x00007fa1b0601700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:1130 disconnect()  Disconnecting b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint - b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint params DATA default default
2022-07-08T10:14:40,413383 1 0x00007fa1b0601700   debug KurentoMediaElementImpl   MediaElementImpl.cpp:1130 disconnect()  Disconnecting b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint - b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint params VIDEO default default
2022-07-08T10:14:40,413472 1 0x00007fa1bf377240   debug recorderendpoint          kmsrecorderendpoint.c:582 kms_recorder_endpoint_dispose() <kmsrecorderendpoint27>  dispose
2022-07-08T10:14:40,413680 1 0x00007fa1b0601700   debug KurentoMediaSet           MediaSet.cpp:463 async_delete()  Destroying WebRtcEndpoint -> b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint
2022-07-08T10:14:40,413689 1 0x00007fa1bf377240 warning recorderendpoint          kmsrecorderendpoint.c:593 kms_recorder_endpoint_dispose() <kmsrecorderendpoint27>  warning: Recorder may have buffers to save
2022-07-08T10:14:40,413812 1 0x00007fa1b0601700     log KurentoMediaElementImpl   MediaElementImpl.cpp:706 ~MediaElementImpl()  Deleting media element b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline/d994b362-a4b0-4ad4-a67d-b037801c6003_kurento.WebRtcEndpoint
2022-07-08T10:14:40,413869 1 0x00007fa1bf377240 warning recorderendpoint          kmsrecorderendpoint.c:593 kms_recorder_endpoint_dispose() <kmsrecorderendpoint27>  warning: Disposing recorder when it isn't stopped.
2022-07-08T10:14:40,417220 1 0x00007fa1b1603700   debug KurentoMediaSet           MediaSet.cpp:463 async_delete()  Destroying MediaPipeline -> b8078aae-fefb-4701-827d-88585c87e2a4_kurento.MediaPipeline
2022-07-08T10:14:40,419110 1 0x00007fa1bf377240   debug uriendpoint               kmsuriendpoint.c:214 kms_uri_endpoint_dispose() <kmsrecorderendpoint27>  dispose
2022-07-08T10:14:40,419381 1 0x00007fa1bf377240   debug recorderendpoint          kmsrecorderendpoint.c:634 kms_recorder_endpoint_finalize() <kmsrecorderendpoint27>  releasing resources...
2022-07-08T10:14:40,419524 1 0x00007fa1bf377240     log basemediamuxer            kmsbasemediamuxer.c:71 kms_base_media_muxer_finalize() <KmsAVMuxer@0x7fa0fc0200d0>  finalize
2022-07-08T10:14:40,419716 1 0x00007fa1bf377240   debug recorderendpoint          kmsrecorderendpoint.c:648 kms_recorder_endpoint_finalize() <kmsrecorderendpoint27>  finalized
2022-07-08T10:14:40,419804 1 0x00007fa1bf377240   debug uriendpoint               kmsuriendpoint.c:224 kms_uri_endpoint_finalize() <kmsrecorderendpoint27>  finalize
github-actions[bot] commented 2 years ago

Hello @RaistGH! :wave: we're sorry you found a bug... so first of all, thank you very much for reporting it.

To know about progress, check in Triage. All issues are considered Backlog Candidates until work priorities align and the issue is selected for development. It will then become part of our official Backlog.

j1elo commented 2 years ago

Related discussion: https://groups.google.com/g/kurento/c/E92YcQqt7kY/

j1elo commented 2 years ago

Please follow the Troubleshooting RecorderEndpoint section of the doc, to see if any of the suggestions help you find the cause of the problem.

RaistGH commented 2 years ago

Thanks for so fast reply! I used the function connect(MediaElement, MediaType) (with MediaType matching 'AUDIO|VIDEO|DATA': yield webRtc.connect(recorder, 'VIDEO'); But it was not enough. Apparently RecorderEndpoint must be also built as video only so parameter mediaProfile must be set and matching 'WEBM|MKV|MP4|WEBM_VIDEO_ONLY|WEBM_AUDIO_ONLY|MKV_VIDEO_ONLY|MKV_AUDIO_ONLY|MP4_VIDEO_ONLY|MP4_AUDIO_ONLY|JPEG_VIDEO_ONLY|KURENTO_SPLIT_RECORDER|FLV'.

So, in addition to connect(recorder, 'VIDEO'), RecorderEndpoint creation would be: var recorder = yield pipeline.create('RecorderEndpoint', {uri: args.file_uri, mediaProfile: 'WEBM_VIDEO_ONLY'});

Alternatively it's also possible to use a Composite Hub which outputs all 'AUDIO', 'VIDEO' and 'DATA' streams even though input is only 'VIDEO', so RecorderEndpoint gets all needed to record (that's why other people in the related discussion weren't having the same issue):

var compositeHub = yield pipeline.create('Composite');
var hubPortInput = yield compositeHub.createHubPort();
var hubPortOutput = yield compositeHub.createHubPort();
yield webRtc.connect(hubPortInput);
var recorder = yield pipeline.create('RecorderEndpoint', {uri: args.file_uri});
yield hubPortOutput.connect(recorder);
yield hubPortOutput.connect(webRtc);
yield recorder.record();

Therefore instead of a functionality bug this can be changed to a Doc Improvement Request. Thanks!

j1elo commented 2 years ago

Happy to see that you managed to make it work. I always take the chance to leave things better than they were, so docs have been revised:

Thanks for the feedback.

RaistGH commented 2 years ago

Thanks! I was trying to modify the docs this morning and do a pull request but I didn't have the time to finish it. Anyway what you wrote is more complete than mine :)