search

mavlink / qgroundcontrol

Cross-platform ground control station for drones (Android, iOS, Mac OS, Linux, Windows)
http://qgroundcontrol.io
3.34k stars 3.66k forks source link

Problem running RTSP on Linux #10627

Open fraviofii opened 1 year ago

fraviofii commented 1 year ago

Expected Behavior

RTSP Video should appear on the QGroundControl screen.

Current Behavior

RTSP Video not showing up for my specific RTSP Server.

Steps to Reproduce:

  1. Setup Video Stream from RTSP Server
  2. Point to the RTSP server (in my case, another computer in my LAN)
  3. Go back, to save and start the configuration

System Information

Detailed Description

I'm having problems to connect to my custom RTSP server using QGroundControl. When using the Bunny test, it works.

This problem happens only in Linux. It works when built for Windows and Android.

I can get the communication using VLC. From there, I got the following information:

Codec: H264 - MPEG-4 AVC (part 10) (h264)
Type: Video
Video resolution: 1280x720
Buffer dimensions: 1280x738
Frame rate: 30
Decoded format: Planar 4:2:0 YUV
Orientation: Top Left
Chrome location: Left

The error that appears on QT Creator console is:

VideoReceiverLog: Gstreamer error: Internal data stream error

I could track where this error happens, and I found that it is called on _onBusMessage (GstVideoReceiver.cc line 1036). This function is a signal handler initalized on start method.

I tried to execute the commands using Gstreamer directly. And it works with the following command:

gst-launch-1.0 rtspsrc location=rtsp://192.168.100.137:34554 ! application/x-rtp ! rtpjitterbuffer ! rtph264depay ! avdec_h264 ! videoconvert ! autovideosink

This command is extracted from VideoReceiver README.

I realized that the method _addVideoSink is never called. Is this signal handler call some kind of problem?

How can this problem be solved?

Log Files and Screenshots

Please help me on how to provide additional information.

zdanek commented 1 year ago

Hi. Enable video logs with option added to QGC executable --logging:VideoAllLog and let's see what happened.

fraviofii commented 1 year ago

Hi @zdanek ,

Here is the output from the log using the command line argument that you advised:

QML debugging is enabled. Only use this in a safe environment.
Warning: Ignoring XDG_SESSION_TYPE=wayland on Gnome. Use QT_QPA_PLATFORM=wayland to run on Wayland anyway.
Settings location "/home/debian/.config/QGroundControl.org/QGroundControl Daily.ini" Is writable?: true
Filter rules "*Log.debug=false\nVideoAllLog.debug=true\nVideoManagerLog.debug=true\nVideoReceiverLog.debug=true\nGStreamerLog.debug=true\nGStreamerAPILog.debug=true\nqt.qml.connections=false"
System reported locale: QLocale(English, Latin, United States) ; Name "en_US" ; Preffered (used in maps):  "en-US"
serialnmea: No known GPS device found. Specify the COM port via QT_NMEA_SERIAL_PORT.
GStreamerLog: Failed to change ranking of feature. Featuer does not exist: bcmdec
VideoManagerLog: New Video Source: "RTSP Video Stream"
VideoManagerLog: Testing configuration for RTSP Stream: "rtsp://192.168.100.118:34554"
MAVLinkLogManagerLog: MAVLink logs directory: "/home/debian/Documents/QGroundControl Daily/Logs"
VideoReceiverLog: Starting "rtsp://192.168.100.118:34554" , buffer 0
VideoReceiverLog: Started "rtsp://192.168.100.118:34554"
Map Cache in: "/home/debian/.cache/QGCMapCache300" / "qgcMapCache.db"
VideoReceiverLog: Linking pad start
VideoReceiverLog: done
VideoReceiverLog: Linking pad end
VideoReceiverLog: Streaming started "rtsp://192.168.100.118:34554"
setCurrentPlanViewSeqNum
setCurrentPlanViewSeqNum
VideoManagerLog: Video 0 Start complete, status:  VideoReceiver::STATUS_OK
qt.positioning.geoclue2: Unable to start the client: "org.freedesktop.DBus.Error.AccessDenied" "Geolocation disabled for UID 1000"
QGCPositionManager error 0
VideoReceiverLog: Starting decoding "rtsp://192.168.100.118:34554"
VideoReceiverLog: Decoding started "rtsp://192.168.100.118:34554"
"v4.2.0"
VideoReceiverLog: Stream timeout, no frames for  9  "rtsp://192.168.100.118:34554"
VideoReceiverLog: Stopping "rtsp://192.168.100.118:34554"
VideoReceiverLog: Streaming stopped "rtsp://192.168.100.118:34554"
VideoReceiverLog: Stopped "rtsp://192.168.100.118:34554"
VideoManagerLog: Video 0 Stop complete, status:  VideoReceiver::STATUS_OK
VideoReceiverLog: Starting "rtsp://192.168.100.118:34554" , buffer 0
VideoReceiverLog: Started "rtsp://192.168.100.118:34554"
VideoManagerLog: Video 0 Start complete, status:  VideoReceiver::STATUS_OK
VideoManagerLog: Video 0 start decoding
VideoReceiverLog: Starting decoding "rtsp://192.168.100.118:34554"
VideoReceiverLog: Linking pad start
VideoReceiverLog: done
VideoReceiverLog: Linking pad end
VideoReceiverLog: Streaming started "rtsp://192.168.100.118:34554"
VideoReceiverLog: Decoding started "rtsp://192.168.100.118:34554"
VideoReceiverLog: GStreamer debug:  ../libs/gst/base/gstbasesrc.c(3127): gst_base_src_loop (): /GstPipeline:receiver/GstBin:sourcebin/GstRTSPSrc:source/GstUDPSrc:udpsrc2:
streaming stopped, reason not-negotiated (-4)
VideoReceiverLog: Fii GStreamer error: Internal data stream error.
VideoReceiverLog: Stopping because of error
VideoReceiverLog: Stopping "rtsp://192.168.100.118:34554"
VideoReceiverLog: Streaming stopped "rtsp://192.168.100.118:34554"
VideoReceiverLog: Stopped "rtsp://192.168.100.118:34554"
VideoManagerLog: Video 0 Stop complete, status:  VideoReceiver::STATUS_OK
VideoReceiverLog: Starting "rtsp://192.168.100.118:34554" , buffer 0
VideoReceiverLog: Started "rtsp://192.168.100.118:34554"
VideoManagerLog: Video 0 Start complete, status:  VideoReceiver::STATUS_OK
VideoManagerLog: Video 0 start decoding
VideoReceiverLog: Starting decoding "rtsp://192.168.100.118:34554"
VideoReceiverLog: Linking pad start
VideoReceiverLog: done
VideoReceiverLog: Linking pad end
VideoReceiverLog: Streaming started "rtsp://192.168.100.118:34554"
VideoReceiverLog: Decoding started "rtsp://192.168.100.118:34554"
VideoReceiverLog: Stream timeout, no frames for  9  "rtsp://192.168.100.118:34554"
VideoReceiverLog: Stopping "rtsp://192.168.100.118:34554"
VideoReceiverLog: Streaming stopped "rtsp://192.168.100.118:34554"
VideoReceiverLog: Stopped "rtsp://192.168.100.118:34554"
VideoManagerLog: Video 0 Stop complete, status:  VideoReceiver::STATUS_OK
VideoReceiverLog: Starting "rtsp://192.168.100.118:34554" , buffer 0
VideoReceiverLog: Started "rtsp://192.168.100.118:34554"
VideoManagerLog: Video 0 Start complete, status:  VideoReceiver::STATUS_OK
VideoManagerLog: Video 0 start decoding
VideoReceiverLog: Starting decoding "rtsp://192.168.100.118:34554"
VideoReceiverLog: Linking pad start
VideoReceiverLog: done
VideoReceiverLog: Linking pad end
VideoReceiverLog: Streaming started "rtsp://192.168.100.118:34554"
VideoReceiverLog: Decoding started "rtsp://192.168.100.118:34554"

Thank you for this advice. The problem is here: VideoReceiverLog: GStreamer debug: ../libs/gst/base/gstbasesrc.c(3127): gst_base_src_loop ()::/GstPipeline:receiver/GstBin:sourcebin/GstRTSPSrc:source/GstUDPSrc:udpsrc2:streaming stopped, reason not-negotiated (-4)

Looking at the issues, I found some similar issue. I tried to add the videoconvert to the GStreamer pipeline, but I could not find the correct place to do that.

In addition, I don't know if it is the correct solution.

Can you help me with that?

zdanek commented 1 year ago

Not enough. Set GST_DEBUG=3 env variable before running QGC. If this will not show any errors, higher debug value 4,5 or even 6 might be needed but it will generate big output so please check if lower debug values would be sufficient.

fraviofii commented 1 year ago

Hello @zdanek ,

I set GST_DEBUG=5 during the execution of QGC.

Here is the output until the first occurrence of the error:

0:00:13.187532716  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:4252:gst_pad_peer_query:<sink:proxypad9> peer query 0x7fff6000b800 (accept-caps)
0:00:13.187544483  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:4125:gst_pad_query:<parsebin0:sink> doing query 0x7fff6000b800 (accept-caps)
0:00:13.187549843  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3195:gst_pad_query_accept_caps_default:<parsebin0:sink> query accept-caps accept-caps query: 0x7fff6000b800, GstQueryAcceptCaps, caps=(GstCaps)"video/x-h264\,\ alignment\=\(string\)au\,\ stream-format\=\(string\)avc\,\ parsed\=\(boolean\)true\,\ width\=\(int\)1280\,\ height\=\(int\)720\,\ framerate\=\(fraction\)30/1\,\ chroma-format\=\(string\)4:2:0\,\ bit-depth-luma\=\(uint\)8\,\ bit-depth-chroma\=\(uint\)8\,\ profile\=\(string\)high\,\ level\=\(string\)4", result=(boolean)false;
0:00:13.187552133  3226 0x7fff78028980 DEBUG               GST_PADS gstutils.c:2782:gst_pad_proxy_query_accept_caps:<parsebin0:sink> proxying accept caps query for parsebin0:sink
0:00:13.187553470  3226 0x7fffa0018640 DEBUG         GST_SCHEDULING gstpad.c:4401:gst_pad_chain_data_unchecked:<manager:recv_rtp_sink_0> called chainfunction &gst_proxy_pad_chain_default with buffer 0x7fff7804ed80, returned ok
0:00:13.187554589  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:4252:gst_pad_peer_query:<sink:proxypad10> peer query 0x7fff6000b800 (accept-caps)
0:00:13.187562826  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:4125:gst_pad_query:<typefind:sink> doing query 0x7fff6000b800 (accept-caps)
0:00:13.187565688  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:1673:gst_pad_check_reconfigure:<udpsrc1:src> remove RECONFIGURE flag
0:00:13.187567871  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3195:gst_pad_query_accept_caps_default:<typefind:sink> query accept-caps accept-caps query: 0x7fff6000b800, GstQueryAcceptCaps, caps=(GstCaps)"video/x-h264\,\ alignment\=\(string\)au\,\ stream-format\=\(string\)avc\,\ parsed\=\(boolean\)true\,\ width\=\(int\)1280\,\ height\=\(int\)720\,\ framerate\=\(fraction\)30/1\,\ chroma-format\=\(string\)4:2:0\,\ bit-depth-luma\=\(uint\)8\,\ bit-depth-chroma\=\(uint\)8\,\ profile\=\(string\)high\,\ level\=\(string\)4", result=(boolean)false;
0:00:13.187576310  3226 0x7fff78028980 DEBUG        GST_PERFORMANCE gstpad.c:3212:gst_pad_query_accept_caps_default:<typefind:sink> fallback ACCEPT_CAPS query, consider implementing a specialized version
0:00:13.187576186  3226 0x7fffa0018640 DEBUG                basesrc gstbasesrc.c:3439:gst_base_src_negotiate_unlocked:<udpsrc1> starting negotiation
0:00:13.187580821  3226 0x7fff78028980 DEBUG               GST_CAPS gstutils.c:3102:gst_pad_query_caps:<typefind:sink> get pad caps with filter video/x-h264, alignment=(string)au, stream-format=(string)avc, parsed=(boolean)true, width=(int)1280, height=(int)720, framerate=(fraction)30/1, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, profile=(string)high, level=(string)4
0:00:13.187581931  3226 0x7fffa0018640 DEBUG               GST_CAPS gstutils.c:3102:gst_pad_query_caps:<udpsrc1:src> get pad caps with filter (NULL)
0:00:13.187585152  3226 0x7fff78028980 DEBUG                  query gstquery.c:679:gst_query_new_custom: creating new query 0x7fff6000b770 caps
0:00:13.187587853  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:4125:gst_pad_query:<typefind:sink> doing query 0x7fff6000b770 (caps)
0:00:13.187589004  3226 0x7fffa0018640 DEBUG                  query gstquery.c:679:gst_query_new_custom: creating new query 0x7fff7804f4f0 caps
0:00:13.187593367  3226 0x7fff78028980 DEBUG               GST_CAPS gstpad.c:3254:gst_pad_query_caps_default:<typefind:sink> query caps caps query: 0x7fff6000b770, GstQueryCaps, filter=(GstCaps)"video/x-h264\,\ alignment\=\(string\)au\,\ stream-format\=\(string\)avc\,\ parsed\=\(boolean\)true\,\ width\=\(int\)1280\,\ height\=\(int\)720\,\ framerate\=\(fraction\)30/1\,\ chroma-format\=\(string\)4:2:0\,\ bit-depth-luma\=\(uint\)8\,\ bit-depth-chroma\=\(uint\)8\,\ profile\=\(string\)high\,\ level\=\(string\)4", caps=(GstCaps)"NULL";
0:00:13.187594020  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4125:gst_pad_query:<udpsrc1:src> doing query 0x7fff7804f4f0 (caps)
0:00:13.187595402  3226 0x7fff78028980 DEBUG               GST_CAPS gstpad.c:3278:gst_pad_query_caps_default:<typefind:sink> trying pad template caps
0:00:13.187601875  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:3887:do_probe_callbacks:<udpsrc1:src> data is passed
0:00:13.187606820  3226 0x7fff78028980 DEBUG               GST_CAPS gstpad.c:3300:gst_pad_query_caps_default:<typefind:sink> using caps 0x7fffdc00acf0 ANY with filter 0x7fff6000b850 video/x-h264, alignment=(string)au, stream-format=(string)avc, parsed=(boolean)true, width=(int)1280, height=(int)720, framerate=(fraction)30/1, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, profile=(string)high, level=(string)4
0:00:13.187611234  3226 0x7fff78028980 DEBUG               GST_CAPS gstpad.c:3304:gst_pad_query_caps_default:<typefind:sink> result 0x7fff6000b850 video/x-h264, alignment=(string)au, stream-format=(string)avc, parsed=(boolean)true, width=(int)1280, height=(int)720, framerate=(fraction)30/1, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, profile=(string)high, level=(string)4
0:00:13.187610487  3226 0x7fffa0018640 DEBUG                basesrc gstbasesrc.c:1381:gst_base_src_default_query:<udpsrc1> query caps returns 1
0:00:13.187619319  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3509:gst_pad_query_default:<typefind:sink> not forwarding 0x7fff6000b770 (caps) query
0:00:13.187627772  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:4148:gst_pad_query:<typefind:sink> sent query 0x7fff6000b770 (caps), result 1
0:00:13.187628087  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4148:gst_pad_query:<udpsrc1:src> sent query 0x7fff7804f4f0 (caps), result 1
0:00:13.187632266  3226 0x7fff78028980 DEBUG               GST_CAPS gstutils.c:3109:gst_pad_query_caps:<typefind:sink> query returned video/x-h264, alignment=(string)au, stream-format=(string)avc, parsed=(boolean)true, width=(int)1280, height=(int)720, framerate=(fraction)30/1, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, profile=(string)high, level=(string)4
0:00:13.187638975  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3225:gst_pad_query_accept_caps_default:<typefind:sink> allowed caps subset video/x-h264, alignment=(string)au, stream-format=(string)avc, parsed=(boolean)true, width=(int)1280, height=(int)720, framerate=(fraction)30/1, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, profile=(string)high, level=(string)4, caps video/x-h264, alignment=(string)au, stream-format=(string)avc, parsed=(boolean)true, width=(int)1280, height=(int)720, framerate=(fraction)30/1, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, profile=(string)high, level=(string)4
0:00:13.187643751  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3509:gst_pad_query_default:<typefind:sink> not forwarding 0x7fff6000b800 (accept-caps) query
0:00:13.187646170  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:4148:gst_pad_query:<typefind:sink> sent query 0x7fff6000b800 (accept-caps), result 1
0:00:13.187649429  3226 0x7fff78028980 DEBUG               GST_PADS gstutils.c:2793:gst_pad_proxy_query_accept_caps:<parsebin0:sink> proxying accept caps query: 1
0:00:13.187648609  3226 0x7fffa0018640 DEBUG               GST_CAPS gstutils.c:3109:gst_pad_query_caps:<udpsrc1:src> query returned application/x-rtp, media=(string)video, payload=(int)96, clock-rate=(int)90000, encoding-name=(string)H264, packetization-mode=(string)1, profile-level-id=(string)420029, a-framerate=(string)30.0, a-transform=(string)"1\,0\,0\;0\,1\,0\;0\,0\,1", ssrc=(uint)846930886, npt-start=(guint64)0, play-speed=(double)1, play-scale=(double)1, onvif-mode=(boolean)false
0:00:13.187659775  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3225:gst_pad_query_accept_caps_default:<parsebin0:sink> allowed caps subset ANY, caps video/x-h264, alignment=(string)au, stream-format=(string)avc, parsed=(boolean)true, width=(int)1280, height=(int)720, framerate=(fraction)30/1, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, profile=(string)high, level=(string)4
0:00:13.187662422  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3509:gst_pad_query_default:<parsebin0:sink> not forwarding 0x7fff6000b800 (accept-caps) query
0:00:13.187664061  3226 0x7fffa0018640 DEBUG                basesrc gstbasesrc.c:3369:gst_base_src_default_negotiate:<udpsrc1> caps of src: application/x-rtp, media=(string)video, payload=(int)96, clock-rate=(int)90000, encoding-name=(string)H264, packetization-mode=(string)1, profile-level-id=(string)420029, a-framerate=(string)30.0, a-transform=(string)"1\,0\,0\;0\,1\,0\;0\,0\,1", ssrc=(uint)846930886, npt-start=(guint64)0, play-speed=(double)1, play-scale=(double)1, onvif-mode=(boolean)false
0:00:13.187665508  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:4148:gst_pad_query:<parsebin0:sink> sent query 0x7fff6000b800 (accept-caps), result 1
0:00:13.187672666  3226 0x7fff78028980 DEBUG               GST_PADS gstutils.c:2793:gst_pad_proxy_query_accept_caps:<decodebin3-0:sink> proxying accept caps query: 1
0:00:13.187676710  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3225:gst_pad_query_accept_caps_default:<decodebin3-0:sink> allowed caps subset ANY, caps video/x-h264, alignment=(string)au, stream-format=(string)avc, parsed=(boolean)true, width=(int)1280, height=(int)720, framerate=(fraction)30/1, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, profile=(string)high, level=(string)4
0:00:13.187679015  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3509:gst_pad_query_default:<decodebin3-0:sink> not forwarding 0x7fff6000b800 (accept-caps) query
0:00:13.187678394  3226 0x7fffa0018640 DEBUG               GST_CAPS gstutils.c:3148:gst_pad_peer_query_caps:<udpsrc1:src> get pad peer caps with filter application/x-rtp, media=(string)video, payload=(int)96, clock-rate=(int)90000, encoding-name=(string)H264, packetization-mode=(string)1, profile-level-id=(string)420029, a-framerate=(string)30.0, a-transform=(string)"1\,0\,0\;0\,1\,0\;0\,0\,1", ssrc=(uint)846930886, npt-start=(guint64)0, play-speed=(double)1, play-scale=(double)1, onvif-mode=(boolean)false
0:00:13.187681191  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:4148:gst_pad_query:<decodebin3-0:sink> sent query 0x7fff6000b800 (accept-caps), result 1
0:00:13.187683479  3226 0x7fff78028980 DEBUG               GST_PADS gstutils.c:2793:gst_pad_proxy_query_accept_caps:<valve0:sink> proxying accept caps query: 1
0:00:13.187683689  3226 0x7fffa0018640 DEBUG                  query gstquery.c:679:gst_query_new_custom: creating new query 0x7fff7804f4a0 caps
0:00:13.187687774  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3225:gst_pad_query_accept_caps_default:<valve0:sink> allowed caps subset ANY, caps video/x-h264, alignment=(string)au, stream-format=(string)avc, parsed=(boolean)true, width=(int)1280, height=(int)720, framerate=(fraction)30/1, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, profile=(string)high, level=(string)4
0:00:13.187687838  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4252:gst_pad_peer_query:<udpsrc1:src> peer query 0x7fff7804f4a0 (caps)
0:00:13.187690256  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3509:gst_pad_query_default:<valve0:sink> not forwarding 0x7fff6000b800 (accept-caps) query
0:00:13.187691519  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:3887:do_probe_callbacks:<udpsrc1:src> data is passed
0:00:13.187692667  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:4148:gst_pad_query:<valve0:sink> sent query 0x7fff6000b800 (accept-caps), result 1
0:00:13.187699820  3226 0x7fff78028980 DEBUG               GST_PADS gstutils.c:2793:gst_pad_proxy_query_accept_caps:<queue0:sink> proxying accept caps query: 1
0:00:13.187699654  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4125:gst_pad_query:<manager:recv_rtp_sink_0> doing query 0x7fff7804f4a0 (caps)
0:00:13.187703959  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3225:gst_pad_query_accept_caps_default:<queue0:sink> allowed caps subset ANY, caps video/x-h264, alignment=(string)au, stream-format=(string)avc, parsed=(boolean)true, width=(int)1280, height=(int)720, framerate=(fraction)30/1, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, profile=(string)high, level=(string)4
0:00:13.187706704  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3509:gst_pad_query_default:<queue0:sink> not forwarding 0x7fff6000b800 (accept-caps) query
0:00:13.187708950  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:4148:gst_pad_query:<queue0:sink> sent query 0x7fff6000b800 (accept-caps), result 1
0:00:13.187711527  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:4252:gst_pad_peer_query:<tee0:src_1> peer query 0x7fff6000b800 (accept-caps)
0:00:13.187713617  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:4125:gst_pad_query:<queue1:sink> doing query 0x7fff6000b800 (accept-caps)
0:00:13.187717721  3226 0x7fffa0018640 DEBUG               GST_CAPS gstpad.c:3254:gst_pad_query_caps_default:<manager:recv_rtp_sink_0> query caps caps query: 0x7fff7804f4a0, GstQueryCaps, filter=(GstCaps)"application/x-rtp\,\ media\=\(string\)video\,\ payload\=\(int\)96\,\ clock-rate\=\(int\)90000\,\ encoding-name\=\(string\)H264\,\ packetization-mode\=\(string\)1\,\ profile-level-id\=\(string\)420029\,\ a-framerate\=\(string\)30.0\,\ a-transform\=\(string\)\"1\\\,0\\\,0\\\;0\\\,1\\\,0\\\;0\\\,0\\\,1\"\,\ ssrc\=\(uint\)846930886\,\ npt-start\=\(guint64\)0\,\ play-speed\=\(double\)1\,\ play-scale\=\(double\)1\,\ onvif-mode\=\(boolean\)false", caps=(GstCaps)"NULL";
0:00:13.187719380  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3195:gst_pad_query_accept_caps_default:<queue1:sink> query accept-caps accept-caps query: 0x7fff6000b800, GstQueryAcceptCaps, caps=(GstCaps)"video/x-h264\,\ alignment\=\(string\)au\,\ stream-format\=\(string\)avc\,\ parsed\=\(boolean\)true\,\ width\=\(int\)1280\,\ height\=\(int\)720\,\ framerate\=\(fraction\)30/1\,\ chroma-format\=\(string\)4:2:0\,\ bit-depth-luma\=\(uint\)8\,\ bit-depth-chroma\=\(uint\)8\,\ profile\=\(string\)high\,\ level\=\(string\)4", result=(boolean)true;
0:00:13.187722931  3226 0x7fff78028980 DEBUG               GST_PADS gstutils.c:2782:gst_pad_proxy_query_accept_caps:<queue1:sink> proxying accept caps query for queue1:sink
0:00:13.187724655  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:2963:gst_pad_iterate_internal_links_default:<queue1:sink> Making iterator
0:00:13.187723853  3226 0x7fffa0018640 DEBUG               GST_PADS gstutils.c:2851:gst_pad_proxy_query_caps:<manager:recv_rtp_sink_0> proxying caps query for manager:recv_rtp_sink_0
0:00:13.187732434  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:4252:gst_pad_peer_query:<queue1:src> peer query 0x7fff6000b800 (accept-caps)
0:00:13.187734614  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:4125:gst_pad_query:<valve1:sink> doing query 0x7fff6000b800 (accept-caps)
0:00:13.187735531  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4252:gst_pad_peer_query:<recv_rtp_sink_0:proxypad1> peer query 0x7fff7804f4a0 (caps)
0:00:13.187740340  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3195:gst_pad_query_accept_caps_default:<valve1:sink> query accept-caps accept-caps query: 0x7fff6000b800, GstQueryAcceptCaps, caps=(GstCaps)"video/x-h264\,\ alignment\=\(string\)au\,\ stream-format\=\(string\)avc\,\ parsed\=\(boolean\)true\,\ width\=\(int\)1280\,\ height\=\(int\)720\,\ framerate\=\(fraction\)30/1\,\ chroma-format\=\(string\)4:2:0\,\ bit-depth-luma\=\(uint\)8\,\ bit-depth-chroma\=\(uint\)8\,\ profile\=\(string\)high\,\ level\=\(string\)4", result=(boolean)true;
0:00:13.187740493  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4125:gst_pad_query:<rtpsession0:recv_rtp_sink> doing query 0x7fff7804f4a0 (caps)
0:00:13.187742671  3226 0x7fff78028980 DEBUG               GST_PADS gstutils.c:2782:gst_pad_proxy_query_accept_caps:<valve1:sink> proxying accept caps query for valve1:sink
0:00:13.187744384  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:2963:gst_pad_iterate_internal_links_default:<valve1:sink> Making iterator
0:00:13.187746455  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:4252:gst_pad_peer_query:<valve1:src> peer query 0x7fff6000b800 (accept-caps)
0:00:13.187748014  3226 0x7fff78028980 INFO                GST_PADS gstpad.c:4309:gst_pad_peer_query:<valve1:src> pad has no peer
0:00:13.187749982  3226 0x7fff78028980 DEBUG               GST_PADS gstutils.c:2793:gst_pad_proxy_query_accept_caps:<valve1:sink> proxying accept caps query: 1
0:00:13.187754207  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3225:gst_pad_query_accept_caps_default:<valve1:sink> allowed caps subset ANY, caps video/x-h264, alignment=(string)au, stream-format=(string)avc, parsed=(boolean)true, width=(int)1280, height=(int)720, framerate=(fraction)30/1, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, profile=(string)high, level=(string)4
0:00:13.187754282  3226 0x7fffa0018640 DEBUG               GST_CAPS gstpad.c:3254:gst_pad_query_caps_default:<rtpsession0:recv_rtp_sink> query caps caps query: 0x7fff7804f4a0, GstQueryCaps, filter=(GstCaps)"application/x-rtp\,\ media\=\(string\)video\,\ payload\=\(int\)96\,\ clock-rate\=\(int\)90000\,\ encoding-name\=\(string\)H264\,\ packetization-mode\=\(string\)1\,\ profile-level-id\=\(string\)420029\,\ a-framerate\=\(string\)30.0\,\ a-transform\=\(string\)\"1\\\,0\\\,0\\\;0\\\,1\\\,0\\\;0\\\,0\\\,1\"\,\ ssrc\=\(uint\)846930886\,\ npt-start\=\(guint64\)0\,\ play-speed\=\(double\)1\,\ play-scale\=\(double\)1\,\ onvif-mode\=\(boolean\)false", caps=(GstCaps)"NULL";
0:00:13.187756489  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3509:gst_pad_query_default:<valve1:sink> not forwarding 0x7fff6000b800 (accept-caps) query
0:00:13.187758895  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:4148:gst_pad_query:<valve1:sink> sent query 0x7fff6000b800 (accept-caps), result 1
0:00:13.187758288  3226 0x7fffa0018640 DEBUG               GST_CAPS gstpad.c:3278:gst_pad_query_caps_default:<rtpsession0:recv_rtp_sink> trying pad template caps
0:00:13.187761161  3226 0x7fff78028980 DEBUG               GST_PADS gstutils.c:2793:gst_pad_proxy_query_accept_caps:<queue1:sink> proxying accept caps query: 1
0:00:13.187765930  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3225:gst_pad_query_accept_caps_default:<queue1:sink> allowed caps subset ANY, caps video/x-h264, alignment=(string)au, stream-format=(string)avc, parsed=(boolean)true, width=(int)1280, height=(int)720, framerate=(fraction)30/1, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, profile=(string)high, level=(string)4
0:00:13.187768207  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3509:gst_pad_query_default:<queue1:sink> not forwarding 0x7fff6000b800 (accept-caps) query
0:00:13.187770323  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:4148:gst_pad_query:<queue1:sink> sent query 0x7fff6000b800 (accept-caps), result 1
0:00:13.187770102  3226 0x7fffa0018640 DEBUG               GST_CAPS gstpad.c:3300:gst_pad_query_caps_default:<rtpsession0:recv_rtp_sink> using caps 0x7fffa0022400 application/x-rtp with filter 0x7fffa004b4a0 application/x-rtp, media=(string)video, payload=(int)96, clock-rate=(int)90000, encoding-name=(string)H264, packetization-mode=(string)1, profile-level-id=(string)420029, a-framerate=(string)30.0, a-transform=(string)"1\,0\,0\;0\,1\,0\;0\,0\,1", ssrc=(uint)846930886, npt-start=(guint64)0, play-speed=(double)1, play-scale=(double)1, onvif-mode=(boolean)false
0:00:13.187772306  3226 0x7fff78028980 DEBUG               GST_PADS gstutils.c:2793:gst_pad_proxy_query_accept_caps:<tee0:sink> proxying accept caps query: 1
0:00:13.187776321  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3225:gst_pad_query_accept_caps_default:<tee0:sink> allowed caps subset ANY, caps video/x-h264, alignment=(string)au, stream-format=(string)avc, parsed=(boolean)true, width=(int)1280, height=(int)720, framerate=(fraction)30/1, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, profile=(string)high, level=(string)4
0:00:13.187778717  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3509:gst_pad_query_default:<tee0:sink> not forwarding 0x7fff6000b800 (accept-caps) query
0:00:13.187780887  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:4148:gst_pad_query:<tee0:sink> sent query 0x7fff6000b800 (accept-caps), result 1
0:00:13.187783298  3226 0x7fff78028980 DEBUG               GST_PADS gstutils.c:2793:gst_pad_proxy_query_accept_caps:<src_0:proxypad7> proxying accept caps query: 1
0:00:13.187785584  3226 0x7fffa0018640 DEBUG               GST_CAPS gstpad.c:3304:gst_pad_query_caps_default:<rtpsession0:recv_rtp_sink> result 0x7fff7804f370 application/x-rtp, media=(string)video, payload=(int)96, clock-rate=(int)90000, encoding-name=(string)H264, packetization-mode=(string)1, profile-level-id=(string)420029, a-framerate=(string)30.0, a-transform=(string)"1\,0\,0\;0\,1\,0\;0\,0\,1", ssrc=(uint)846930886, npt-start=(guint64)0, play-speed=(double)1, play-scale=(double)1, onvif-mode=(boolean)false
0:00:13.187787086  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3225:gst_pad_query_accept_caps_default:<src_0:proxypad7> allowed caps subset ANY, caps video/x-h264, alignment=(string)au, stream-format=(string)avc, parsed=(boolean)true, width=(int)1280, height=(int)720, framerate=(fraction)30/1, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, profile=(string)high, level=(string)4
0:00:13.187794471  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3509:gst_pad_query_default:<src_0:proxypad7> not forwarding 0x7fff6000b800 (accept-caps) query
0:00:13.187795608  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:3509:gst_pad_query_default:<rtpsession0:recv_rtp_sink> not forwarding 0x7fff7804f4a0 (caps) query
0:00:13.187797849  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:4148:gst_pad_query:<src_0:proxypad7> sent query 0x7fff6000b800 (accept-caps), result 1
0:00:13.187800535  3226 0x7fff78028980 DEBUG               GST_PADS gstutils.c:2793:gst_pad_proxy_query_accept_caps:<src_0:proxypad6> proxying accept caps query: 1
0:00:13.187800759  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4148:gst_pad_query:<rtpsession0:recv_rtp_sink> sent query 0x7fff7804f4a0 (caps), result 1
0:00:13.187804347  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3225:gst_pad_query_accept_caps_default:<src_0:proxypad6> allowed caps subset ANY, caps video/x-h264, alignment=(string)au, stream-format=(string)avc, parsed=(boolean)true, width=(int)1280, height=(int)720, framerate=(fraction)30/1, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, profile=(string)high, level=(string)4
0:00:13.187806976  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3509:gst_pad_query_default:<src_0:proxypad6> not forwarding 0x7fff6000b800 (accept-caps) query
0:00:13.187809465  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:4148:gst_pad_query:<src_0:proxypad6> sent query 0x7fff6000b800 (accept-caps), result 1
0:00:13.187809740  3226 0x7fffa0018640 DEBUG                default gstutils.c:2814:query_caps_func:<recv_rtp_sink_0:proxypad1> intersect with result application/x-rtp, media=(string)video, payload=(int)96, clock-rate=(int)90000, encoding-name=(string)H264, packetization-mode=(string)1, profile-level-id=(string)420029, a-framerate=(string)30.0, a-transform=(string)"1\,0\,0\;0\,1\,0\;0\,0\,1", ssrc=(uint)846930886, npt-start=(guint64)0, play-speed=(double)1, play-scale=(double)1, onvif-mode=(boolean)false
0:00:13.187811652  3226 0x7fff78028980 DEBUG                default gstutils.c:3191:gst_pad_query_accept_caps:<src_0:proxypad6> query returned 1
0:00:13.187819930  3226 0x7fff78028980 DEBUG          basetransform gstbasetransform.c:1199:gst_base_transform_find_transform:<capsfilter0> Input caps were video/x-h264, stream-format=(string)avc, alignment=(string)au, width=(int)1280, height=(int)720, framerate=(fraction)30/1, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, parsed=(boolean)true, profile=(string)high, level=(string)4, and got final caps video/x-h264, alignment=(string)au, stream-format=(string)avc, parsed=(boolean)true, width=(int)1280, height=(int)720, framerate=(fraction)30/1, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, profile=(string)high, level=(string)4
0:00:13.187823927  3226 0x7fff78028980 INFO           basetransform gstbasetransform.c:1325:gst_base_transform_setcaps:<capsfilter0> reuse caps
0:00:13.187823347  3226 0x7fffa0018640 DEBUG                default gstutils.c:2816:query_caps_func:<recv_rtp_sink_0:proxypad1> intersected application/x-rtp, media=(string)video, payload=(int)96, clock-rate=(int)90000, encoding-name=(string)H264, packetization-mode=(string)1, profile-level-id=(string)420029, a-framerate=(string)30.0, a-transform=(string)"1\,0\,0\;0\,1\,0\;0\,0\,1", ssrc=(uint)846930886, npt-start=(guint64)0, play-speed=(double)1, play-scale=(double)1, onvif-mode=(boolean)false
0:00:13.187829666  3226 0x7fff78028980 DEBUG               GST_CAPS gstpad.c:2732:gst_pad_get_current_caps:<capsfilter0:sink> get current pad caps video/x-h264, stream-format=(string)avc, alignment=(string)au, width=(int)1280, height=(int)720, framerate=(fraction)30/1, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, parsed=(boolean)true, profile=(string)high, level=(string)4
0:00:13.187834138  3226 0x7fff78028980 DEBUG               GST_CAPS gstpad.c:2732:gst_pad_get_current_caps:<capsfilter0:src> get current pad caps video/x-h264, stream-format=(string)avc, alignment=(string)au, width=(int)1280, height=(int)720, framerate=(fraction)30/1, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, parsed=(boolean)true, profile=(string)high, level=(string)4
0:00:13.187835343  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:3509:gst_pad_query_default:<manager:recv_rtp_sink_0> not forwarding 0x7fff7804f4a0 (caps) query
0:00:13.187840065  3226 0x7fff78028980 DEBUG          basetransform gstbasetransform.c:1334:gst_base_transform_setcaps:<capsfilter0> New caps equal to old ones: video/x-h264, stream-format=(string)avc, alignment=(string)au, width=(int)1280, height=(int)720, framerate=(fraction)30/1, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, parsed=(boolean)true, profile=(string)high, level=(string)4 -> video/x-h264, stream-format=(string)avc, alignment=(string)au, width=(int)1280, height=(int)720, framerate=(fraction)30/1, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, parsed=(boolean)true, profile=(string)high, level=(string)4
0:00:13.187840216  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4148:gst_pad_query:<manager:recv_rtp_sink_0> sent query 0x7fff7804f4a0 (caps), result 1
0:00:13.187842041  3226 0x7fff78028980 DEBUG          basetransform gstbasetransform.c:938:gst_base_transform_do_bufferpool:<capsfilter0> we're passthough, delay bufferpool
0:00:13.187847137  3226 0x7fff78028980 DEBUG          basetransform gstbasetransform.c:2038:default_submit_input_buffer:<capsfilter0> handling buffer 0x7fff680d0240 of size 499, PTS 99:99:99.999999999 and offset 3269
0:00:13.187849216  3226 0x7fff78028980 DEBUG                default gstsegment.c:744:gst_segment_to_running_time_full: invalid position (-1)
0:00:13.187849370  3226 0x7fffa0018640 DEBUG               GST_CAPS gstutils.c:3155:gst_pad_peer_query_caps:<udpsrc1:src> peer query returned application/x-rtp, media=(string)video, payload=(int)96, clock-rate=(int)90000, encoding-name=(string)H264, packetization-mode=(string)1, profile-level-id=(string)420029, a-framerate=(string)30.0, a-transform=(string)"1\,0\,0\;0\,1\,0\;0\,0\,1", ssrc=(uint)846930886, npt-start=(guint64)0, play-speed=(double)1, play-scale=(double)1, onvif-mode=(boolean)false
0:00:13.187851335  3226 0x7fff78028980 DEBUG          basetransform gstbasetransform.c:2158:default_generate_output:<capsfilter0> calling prepare buffer
0:00:13.187856410  3226 0x7fff78028980 DEBUG          basetransform gstbasetransform.c:2164:default_generate_output:<capsfilter0> using allocated buffer in 0x7fff680d0240, out 0x7fff680d0240
0:00:13.187858108  3226 0x7fff78028980 DEBUG          basetransform gstbasetransform.c:2182:default_generate_output:<capsfilter0> doing inplace transform
0:00:13.187860993  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3887:do_probe_callbacks:<src_0:proxypad6> data is passed
0:00:13.187866571  3226 0x7fff78028980 DEBUG         GST_SCHEDULING gstpad.c:4395:gst_pad_chain_data_unchecked:<src_0:proxypad6> calling chainfunction &gst_proxy_pad_chain_default with buffer buffer: 0x7fff680d0240, pts 99:99:99.999999999, dts 99:99:99.999999999, dur 0:00:00.033333333, size 499, offset 3269, offset_end none, flags 0x2200
0:00:13.187867159  3226 0x7fffa0018640 DEBUG                basesrc gstbasesrc.c:3379:gst_base_src_default_negotiate:<udpsrc1> caps of peer: application/x-rtp, media=(string)video, payload=(int)96, clock-rate=(int)90000, encoding-name=(string)H264, packetization-mode=(string)1, profile-level-id=(string)420029, a-framerate=(string)30.0, a-transform=(string)"1\,0\,0\;0\,1\,0\;0\,0\,1", ssrc=(uint)846930886, npt-start=(guint64)0, play-speed=(double)1, play-scale=(double)1, onvif-mode=(boolean)false
0:00:13.187877614  3226 0x7fff78028980 DEBUG         GST_SCHEDULING gstpad.c:4395:gst_pad_chain_data_unchecked:<src_0:proxypad7> calling chainfunction &gst_proxy_pad_chain_default with buffer buffer: 0x7fff680d0240, pts 99:99:99.999999999, dts 99:99:99.999999999, dur 0:00:00.033333333, size 499, offset 3269, offset_end none, flags 0x2200
0:00:13.187879699  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3887:do_probe_callbacks:<sourcebin:src_0> data is passed
0:00:13.187880777  3226 0x7fffa0018640 DEBUG                basesrc gstbasesrc.c:3390:gst_base_src_default_negotiate:<udpsrc1> have caps: application/x-rtp, media=(string)video, payload=(int)96, clock-rate=(int)90000, encoding-name=(string)H264, packetization-mode=(string)1, profile-level-id=(string)420029, a-framerate=(string)30.0, a-transform=(string)"1\,0\,0\;0\,1\,0\;0\,0\,1", ssrc=(uint)846930886, npt-start=(guint64)0, play-speed=(double)1, play-scale=(double)1, onvif-mode=(boolean)false
0:00:13.187881674  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3887:do_probe_callbacks:<tee0:sink> data is passed
0:00:13.187888364  3226 0x7fffa0018640 DEBUG                basesrc gstbasesrc.c:1050:gst_base_src_default_fixate:<udpsrc1> using default caps fixate function
0:00:13.187890294  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:3674:probe_hook_marshal:<tee0:sink> probe returned OK
0:00:13.187893877  3226 0x7fff78028980 DEBUG         GST_SCHEDULING gstpad.c:4395:gst_pad_chain_data_unchecked:<tee0:sink> calling chainfunction &gst_tee_chain with buffer buffer: 0x7fff680d0240, pts 99:99:99.999999999, dts 99:99:99.999999999, dur 0:00:00.033333333, size 499, offset 3269, offset_end none, flags 0x2200
0:00:13.187896306  3226 0x7fff78028980 DEBUG                    tee gsttee.c:1022:gst_tee_chain:<tee0> received buffer 0x7fff680d0240
0:00:13.187897897  3226 0x7fffa0018640 DEBUG                basesrc gstbasesrc.c:3398:gst_base_src_default_negotiate:<udpsrc1> fixated to: application/x-rtp, media=(string)video, payload=(int)96, clock-rate=(int)90000, encoding-name=(string)H264, packetization-mode=(string)1, profile-level-id=(string)420029, a-framerate=(string)30.0, a-transform=(string)"1\,0\,0\;0\,1\,0\;0\,0\,1", ssrc=(uint)846930886, npt-start=(guint64)0, play-speed=(double)1, play-scale=(double)1, onvif-mode=(boolean)false
0:00:13.187901202  3226 0x7fff78028980 DEBUG         GST_SCHEDULING gstpad.c:4395:gst_pad_chain_data_unchecked:<queue0:sink> calling chainfunction &gst_queue_chain with buffer buffer: 0x7fff680d0240, pts 99:99:99.999999999, dts 99:99:99.999999999, dur 0:00:00.033333333, size 499, offset 3269, offset_end none, flags 0x2200
0:00:13.187904805  3226 0x7fff78028980 DEBUG         GST_SCHEDULING gstpad.c:4401:gst_pad_chain_data_unchecked:<queue0:sink> called chainfunction &gst_queue_chain with buffer 0x7fff680d0240, returned not-negotiated
0:00:13.187907511  3226 0x7fff78028980 DEBUG                    tee gsttee.c:1003:gst_tee_handle_data:<tee0> received error not-negotiated
0:00:13.187908383  3226 0x7fffa0018640 DEBUG               GST_CAPS gstpad.c:2732:gst_pad_get_current_caps:<udpsrc1:src> get current pad caps application/x-rtp, media=(string)video, payload=(int)96, clock-rate=(int)90000, encoding-name=(string)H264, packetization-mode=(string)1, profile-level-id=(string)420029, a-framerate=(string)30.0, a-transform=(string)"1\,0\,0\;0\,1\,0\;0\,0\,1", ssrc=(uint)846930886, npt-start=(guint64)0, play-speed=(double)1, play-scale=(double)1, onvif-mode=(boolean)false
0:00:13.187910292  3226 0x7fff78028980 DEBUG             GST_MEMORY gstmemory.c:89:_gst_memory_free: free memory 0x7fff680d82a0
0:00:13.187913320  3226 0x7fff78028980 DEBUG                    tee gsttee.c:1026:gst_tee_chain:<tee0> handled buffer not-negotiated
0:00:13.187915473  3226 0x7fff78028980 DEBUG         GST_SCHEDULING gstpad.c:4401:gst_pad_chain_data_unchecked:<tee0:sink> called chainfunction &gst_tee_chain with buffer 0x7fff680d0240, returned not-negotiated
0:00:13.187917957  3226 0x7fff78028980 DEBUG         GST_SCHEDULING gstpad.c:4401:gst_pad_chain_data_unchecked:<src_0:proxypad7> called chainfunction &gst_proxy_pad_chain_default with buffer 0x7fff680d0240, returned not-negotiated
0:00:13.187919005  3226 0x7fffa0018640 DEBUG                basesrc gstbasesrc.c:1003:gst_base_src_set_caps:<udpsrc1> New caps equal to old ones: application/x-rtp, media=(string)video, payload=(int)96, clock-rate=(int)90000, encoding-name=(string)H264, packetization-mode=(string)1, profile-level-id=(string)420029, a-framerate=(string)30.0, a-transform=(string)"1\,0\,0\;0\,1\,0\;0\,0\,1", ssrc=(uint)846930886, npt-start=(guint64)0, play-speed=(double)1, play-scale=(double)1, onvif-mode=(boolean)false
0:00:13.187920176  3226 0x7fff78028980 DEBUG         GST_SCHEDULING gstpad.c:4401:gst_pad_chain_data_unchecked:<src_0:proxypad6> called chainfunction &gst_proxy_pad_chain_default with buffer 0x7fff680d0240, returned not-negotiated
0:00:13.187927707  3226 0x7fff78028980 DEBUG         GST_SCHEDULING gstpad.c:4401:gst_pad_chain_data_unchecked:<capsfilter0:sink> called chainfunction &gst_base_transform_chain with buffer 0x7fff680d0240, returned not-negotiated
0:00:13.187932422  3226 0x7fff78028980 DEBUG         GST_SCHEDULING gstpad.c:4401:gst_pad_chain_data_unchecked:<h264parse0:sink> called chainfunction &gst_base_parse_chain with buffer 0x7fff78039d80, returned not-negotiated
0:00:13.187932805  3226 0x7fffa0018640 DEBUG               GST_CAPS gstpad.c:2732:gst_pad_get_current_caps:<udpsrc1:src> get current pad caps application/x-rtp, media=(string)video, payload=(int)96, clock-rate=(int)90000, encoding-name=(string)H264, packetization-mode=(string)1, profile-level-id=(string)420029, a-framerate=(string)30.0, a-transform=(string)"1\,0\,0\;0\,1\,0\;0\,0\,1", ssrc=(uint)846930886, npt-start=(guint64)0, play-speed=(double)1, play-scale=(double)1, onvif-mode=(boolean)false
0:00:13.187938791  3226 0x7fffa0018640 DEBUG                  query gstquery.c:679:gst_query_new_custom: creating new query 0x7fff7804f320 allocation
0:00:13.187940580  3226 0x7fff78028980 DEBUG             GST_BUFFER gstbuffer.c:2505:gst_buffer_foreach_meta: remove metadata 0x7fff7800bc40 (GstNetAddressMeta)
0:00:13.187942918  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4252:gst_pad_peer_query:<udpsrc1:src> peer query 0x7fff7804f320 (allocation)
0:00:13.187944285  3226 0x7fff78028980 DEBUG             GST_BUFFER gstbuffer.c:1457:gst_buffer_is_memory_range_writable: idx 0, length -1
0:00:13.187951967  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:3887:do_probe_callbacks:<udpsrc1:src> data is passed
0:00:13.187954900  3226 0x7fff78028980 DEBUG         GST_SCHEDULING gstpad.c:4401:gst_pad_chain_data_unchecked:<rtph264depay0:sink> called chainfunction &0x7fffec133390 with buffer 0x7fff7804e120, returned not-negotiated
0:00:13.187956999  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4125:gst_pad_query:<manager:recv_rtp_sink_0> doing query 0x7fff7804f320 (allocation)
0:00:13.187958359  3226 0x7fff78028980 DEBUG         GST_SCHEDULING gstpad.c:4401:gst_pad_chain_data_unchecked:<typefind:sink> called chainfunction &gst_type_find_element_chain with buffer 0x7fff7804e120, returned not-negotiated
0:00:13.187965554  3226 0x7fff78028980 DEBUG         GST_SCHEDULING gstpad.c:4401:gst_pad_chain_data_unchecked:<parser:sink> called chainfunction &gst_proxy_pad_chain_default with buffer 0x7fff7804e120, returned not-negotiated
0:00:13.187965350  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:3509:gst_pad_query_default:<manager:recv_rtp_sink_0> forwarding 0x7fff7804f320 (allocation) query
0:00:13.187968236  3226 0x7fff78028980 DEBUG         GST_SCHEDULING gstpad.c:4401:gst_pad_chain_data_unchecked:<recv_rtp_src_0_846930886_96:proxypad5> called chainfunction &gst_proxy_pad_chain_default with buffer 0x7fff7804e120, returned not-negotiated
0:00:13.187971071  3226 0x7fff78028980 DEBUG         GST_SCHEDULING gstpad.c:4401:gst_pad_chain_data_unchecked:<recv_rtp_src_0_846930886_96:proxypad4> called chainfunction &gst_proxy_pad_chain_default with buffer 0x7fff7804e120, returned not-negotiated
0:00:13.187971197  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4252:gst_pad_peer_query:<recv_rtp_sink_0:proxypad1> peer query 0x7fff7804f320 (allocation)
0:00:13.187974630  3226 0x7fff78028980 DEBUG         GST_SCHEDULING gstpad.c:4401:gst_pad_chain_data_unchecked:<rtpptdemux0:sink> called chainfunction &gst_rtp_pt_demux_chain with buffer 0x7fff7804e120, returned not-negotiated
0:00:13.187975531  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4125:gst_pad_query:<rtpsession0:recv_rtp_sink> doing query 0x7fff7804f320 (allocation)
0:00:13.187978190  3226 0x7fff78028980 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:4162:gst_rtp_jitter_buffer_loop:<rtpjitterbuffer0> pausing task, reason not-negotiated
0:00:13.187980332  3226 0x7fff78028980 DEBUG               GST_PADS gstpad.c:6282:gst_pad_pause_task:<rtpjitterbuffer0:src> pause task
0:00:13.187979915  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:3509:gst_pad_query_default:<rtpsession0:recv_rtp_sink> forwarding 0x7fff7804f320 (allocation) query
0:00:13.187983637  3226 0x7fff78028980 DEBUG                   task gsttask.c:729:gst_task_set_state_unlocked:<rtpjitterbuffer0:src> Changing task 0x7fff8000a4d0 to state 2
0:00:13.187986562  3226 0x7fff78028980 INFO                    task gsttask.c:368:gst_task_func:<rtpjitterbuffer0:src> Task going to paused
0:00:13.187986467  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4252:gst_pad_peer_query:<rtpsession0:recv_rtp_src> peer query 0x7fff7804f320 (allocation)
0:00:13.187992245  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4125:gst_pad_query:<rtpstorage0:sink> doing query 0x7fff7804f320 (allocation)
0:00:13.187996465  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:3509:gst_pad_query_default:<rtpstorage0:sink> forwarding 0x7fff7804f320 (allocation) query
0:00:13.187999735  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:2963:gst_pad_iterate_internal_links_default:<rtpstorage0:sink> Making iterator
0:00:13.188004231  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4252:gst_pad_peer_query:<rtpstorage0:src> peer query 0x7fff7804f320 (allocation)
0:00:13.188008215  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4125:gst_pad_query:<rtpssrcdemux0:sink> doing query 0x7fff7804f320 (allocation)
0:00:13.188012603  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:3509:gst_pad_query_default:<rtpssrcdemux0:sink> not forwarding 0x7fff7804f320 (allocation) query
0:00:13.188037361  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4148:gst_pad_query:<rtpssrcdemux0:sink> sent query 0x7fff7804f320 (allocation), result 0
0:00:13.188041324  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4194:gst_pad_query:<rtpssrcdemux0:sink> query failed
0:00:13.188044609  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4315:gst_pad_peer_query:<rtpstorage0:src> query failed
0:00:13.188049329  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4148:gst_pad_query:<rtpstorage0:sink> sent query 0x7fff7804f320 (allocation), result 0
0:00:13.188052511  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4194:gst_pad_query:<rtpstorage0:sink> query failed
0:00:13.188055585  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4315:gst_pad_peer_query:<rtpsession0:recv_rtp_src> query failed
0:00:13.188059926  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4148:gst_pad_query:<rtpsession0:recv_rtp_sink> sent query 0x7fff7804f320 (allocation), result 0
0:00:13.188062935  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4194:gst_pad_query:<rtpsession0:recv_rtp_sink> query failed
0:00:13.188066021  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4315:gst_pad_peer_query:<recv_rtp_sink_0:proxypad1> query failed
0:00:13.188070288  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4148:gst_pad_query:<manager:recv_rtp_sink_0> sent query 0x7fff7804f320 (allocation), result 0
0:00:13.188073366  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4194:gst_pad_query:<manager:recv_rtp_sink_0> query failed
0:00:13.188076423  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4315:gst_pad_peer_query:<udpsrc1:src> query failed
0:00:13.188079661  3226 0x7fffa0018640 DEBUG                basesrc gstbasesrc.c:3309:gst_base_src_prepare_allocation:<udpsrc1> peer ALLOCATION query failed
0:00:13.188089708  3226 0x7fffa0018640 DEBUG               GST_POLL gstpoll.c:681:gst_poll_new: 0x7fff7804f450: new controllable : 1
0:00:13.188114743  3226 0x7fffa0018640 DEBUG               GST_POLL gstpoll.c:848:gst_poll_add_fd_unlocked: 0x7fff7804f450: fd (fd:48, idx:0)
0:00:13.188120669  3226 0x7fffa0018640 DEBUG               GST_POLL gstpoll.c:1014:gst_poll_fd_ctl_read_unlocked: 0x7fff7804f450: fd (fd:48, idx:0), active : 1
0:00:13.188132622  3226 0x7fffa0018640 DEBUG             bufferpool gstbufferpool.c:180:gst_buffer_pool_init:<GstBufferPool@0x7fff7804ef30> created
0:00:13.188142081  3226 0x7fffa0018640 DEBUG             bufferpool gstbufferpool.c:218:gst_buffer_pool_new:<bufferpool5> created new buffer pool
0:00:13.188165981  3226 0x7fffa0018640 WARN               structure gststructure.c:2069:priv_gst_structure_append_to_gstring: No value transform to serialize field 'params' of type 'GstAllocationParams'
0:00:13.188170902  3226 0x7fffa0018640 DEBUG             bufferpool gstbufferpool.c:609:default_set_config:<bufferpool5> config GstBufferPoolConfig, caps=(GstCaps)"application/x-rtp\,\ media\=\(string\)video\,\ payload\=\(int\)96\,\ clock-rate\=\(int\)90000\,\ encoding-name\=\(string\)H264\,\ packetization-mode\=\(string\)1\,\ profile-level-id\=\(string\)420029\,\ a-framerate\=\(string\)30.0\,\ a-transform\=\(string\)\"1\\\,0\\\,0\\\;0\\\,1\\\,0\\\;0\\\,0\\\,1\"\,\ ssrc\=\(uint\)846930886\,\ npt-start\=\(guint64\)0\,\ play-speed\=\(double\)1\,\ play-scale\=\(double\)1\,\ onvif-mode\=\(boolean\)false", size=(uint)1492, min-buffers=(uint)0, max-buffers=(uint)0, allocator=(GstAllocator)"NULL", params=(GstAllocationParams)NULL;
0:00:13.188185149  3226 0x7fffa0018640 WARN               structure gststructure.c:2069:priv_gst_structure_append_to_gstring: No value transform to serialize field 'pool' of type 'GArray'
0:00:13.188206599  3226 0x7fffa0018640 DEBUG                basesrc gstbasesrc.c:3315:gst_base_src_prepare_allocation:<udpsrc1> ALLOCATION (1) params: allocation query: 0x7fff7804f320, GstQueryAllocation, caps=(GstCaps)"application/x-rtp\,\ media\=\(string\)video\,\ payload\=\(int\)96\,\ clock-rate\=\(int\)90000\,\ encoding-name\=\(string\)H264\,\ packetization-mode\=\(string\)1\,\ profile-level-id\=\(string\)420029\,\ a-framerate\=\(string\)30.0\,\ a-transform\=\(string\)\"1\\\,0\\\,0\\\;0\\\,1\\\,0\\\;0\\\,0\\\,1\"\,\ ssrc\=\(uint\)846930886\,\ npt-start\=\(guint64\)0\,\ play-speed\=\(double\)1\,\ play-scale\=\(double\)1\,\ onvif-mode\=\(boolean\)false", need-pool=(boolean)true, pool=(GArray)NULL;
0:00:13.188214417  3226 0x7fffa0018640 DEBUG                basesrc gstbasesrc.c:3143:gst_base_src_set_allocation:<udpsrc1> activate pool
0:00:13.188218334  3226 0x7fffa0018640 DEBUG                basesrc gstbasesrc.c:3169:gst_base_src_set_allocation:<udpsrc1> deactivate old pool
0:00:13.188225115  3226 0x7fffa0018640 DEBUG                basesrc gstbasesrc.c:2579:gst_base_src_get_range:<udpsrc1> calling create offset 18446744073709551615 length 4096, time 0
0:00:13.188231947  3226 0x7fffa0018640 DEBUG             GST_MEMORY gstmemory.c:139:gst_memory_init: new memory 0x7fff78055fc0, maxsize:1499 offset:0 size:1492
0:00:13.188259007  3226 0x7fffa0018640 DEBUG             GST_BUFFER gstbuffer.c:2307:gst_buffer_add_meta: alloc metadata 0x7fffc80038d0 (GstNetAddressMeta) of size 24
0:00:13.188267059  3226 0x7fffa0018640 DEBUG              GST_CLOCK gstclock.c:1091:gst_clock_get_internal_time:<GstSystemClock> internal time 1:48:59.825067061
0:00:13.188271855  3226 0x7fffa0018640 DEBUG              GST_CLOCK gstclock.c:1136:gst_clock_get_time:<GstSystemClock> adjusted time 1:48:59.825067061
0:00:13.188275000  3226 0x7fffa0018640 DEBUG                basesrc gstbasesrc.c:2435:gst_base_src_do_sync:<udpsrc1> no sync needed
0:00:13.236035947  3226 0x7fffa0018640 DEBUG                basesrc gstbasesrc.c:2672:gst_base_src_get_range:<udpsrc1> buffer ok
0:00:13.236057595  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:3887:do_probe_callbacks:<udpsrc1:src> data is passed
0:00:13.236073463  3226 0x7fffa0018640 DEBUG         GST_SCHEDULING gstpad.c:4395:gst_pad_chain_data_unchecked:<manager:recv_rtp_sink_0> calling chainfunction &gst_proxy_pad_chain_default with buffer buffer: 0x7fff9c0145a0, pts 0:00:11.515337080, dts 0:00:11.515337080, dur 99:99:99.999999999, size 518, offset none, offset_end none, flags 0x0
0:00:13.236080467  3226 0x7fffa0018640 DEBUG         GST_SCHEDULING gstpad.c:4395:gst_pad_chain_data_unchecked:<rtpsession0:recv_rtp_sink> calling chainfunction &gst_rtp_session_chain_recv_rtp with buffer buffer: 0x7fff9c0145a0, pts 0:00:11.515337080, dts 0:00:11.515337080, dur 99:99:99.999999999, size 518, offset none, offset_end none, flags 0x0
0:00:13.236088897  3226 0x7fffa0018640 DEBUG              GST_CLOCK gstclock.c:1091:gst_clock_get_internal_time:<GstSystemClock> internal time 1:48:59.872888806
0:00:13.236092286  3226 0x7fffa0018640 DEBUG              GST_CLOCK gstclock.c:1136:gst_clock_get_time:<GstSystemClock> adjusted time 1:48:59.872888806
0:00:13.236112324  3226 0x7fffa0018640 DEBUG         GST_SCHEDULING gstpad.c:4395:gst_pad_chain_data_unchecked:<rtpstorage0:sink> calling chainfunction &gst_rtp_storage_chain with buffer buffer: 0x7fff9c0145a0, pts 0:00:11.515337080, dts 0:00:11.515337080, dur 99:99:99.999999999, size 518, offset none, offset_end none, flags 0x0
0:00:13.236118400  3226 0x7fffa0018640 DEBUG         GST_SCHEDULING gstpad.c:4395:gst_pad_chain_data_unchecked:<rtpssrcdemux0:sink> calling chainfunction &gst_rtp_ssrc_demux_chain with buffer buffer: 0x7fff9c0145a0, pts 0:00:11.515337080, dts 0:00:11.515337080, dur 99:99:99.999999999, size 518, offset none, offset_end none, flags 0x0
0:00:13.236121701  3226 0x7fffa0018640 DEBUG           rtpssrcdemux gstrtpssrcdemux.c:668:gst_rtp_ssrc_demux_chain:<rtpssrcdemux0> received buffer of SSRC 327b23c6
0:00:13.236126955  3226 0x7fffa0018640 DEBUG         GST_SCHEDULING gstpad.c:4395:gst_pad_chain_data_unchecked:<rtpjitterbuffer0:sink> calling chainfunction &gst_rtp_jitter_buffer_chain with buffer buffer: 0x7fff9c0145a0, pts 0:00:11.515337080, dts 0:00:11.515337080, dur 99:99:99.999999999, size 518, offset none, offset_end none, flags 0x0
0:00:13.236131719  3226 0x7fffa0018640 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2900:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0> Received packet #47474 at time 0:00:11.515337080, discont 0, rtx 0
0:00:13.236134999  3226 0x7fffa0018640 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:3246:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0> flushing not-negotiated
0:00:13.236141373  3226 0x7fffa0018640 DEBUG             GST_BUFFER gstbuffer.c:2505:gst_buffer_foreach_meta: remove metadata 0x7fffc80038d0 (GstNetAddressMeta)
0:00:13.236145341  3226 0x7fffa0018640 DEBUG             GST_BUFFER gstbuffer.c:1457:gst_buffer_is_memory_range_writable: idx 0, length -1
0:00:13.236149515  3226 0x7fffa0018640 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2176:update_current_timer:<rtpjitterbuffer0> no more timers
0:00:13.236152672  3226 0x7fffa0018640 DEBUG         GST_SCHEDULING gstpad.c:4401:gst_pad_chain_data_unchecked:<rtpjitterbuffer0:sink> called chainfunction &gst_rtp_jitter_buffer_chain with buffer 0x7fff9c0145a0, returned not-negotiated
0:00:13.236158060  3226 0x7fffa0018640 DEBUG         GST_SCHEDULING gstpad.c:4401:gst_pad_chain_data_unchecked:<rtpssrcdemux0:sink> called chainfunction &gst_rtp_ssrc_demux_chain with buffer 0x7fff9c0145a0, returned not-negotiated
0:00:13.236160805  3226 0x7fffa0018640 DEBUG         GST_SCHEDULING gstpad.c:4401:gst_pad_chain_data_unchecked:<rtpstorage0:sink> called chainfunction &gst_rtp_storage_chain with buffer 0x7fff9c0145a0, returned not-negotiated
0:00:13.236163873  3226 0x7fffa0018640 DEBUG             rtpsession gstrtpsession.c:2020:gst_rtp_session_chain_recv_rtp:<rtpsession0> process returned not-negotiated
0:00:13.236166749  3226 0x7fffa0018640 DEBUG         GST_SCHEDULING gstpad.c:4401:gst_pad_chain_data_unchecked:<rtpsession0:recv_rtp_sink> called chainfunction &gst_rtp_session_chain_recv_rtp with buffer 0x7fff9c0145a0, returned not-negotiated
0:00:13.236169223  3226 0x7fffa0018640 DEBUG         GST_SCHEDULING gstpad.c:4401:gst_pad_chain_data_unchecked:<manager:recv_rtp_sink_0> called chainfunction &gst_proxy_pad_chain_default with buffer 0x7fff9c0145a0, returned not-negotiated
0:00:13.236176477  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:1648:gst_pad_needs_reconfigure:<udpsrc1:src> peeking RECONFIGURE flag 0
0:00:13.236178985  3226 0x7fffa0018640 DEBUG                basesrc gstbasesrc.c:3065:gst_base_src_loop:<udpsrc1> Not negotiated
0:00:13.236181505  3226 0x7fffa0018640 DEBUG                basesrc gstbasesrc.c:3080:gst_base_src_loop:<udpsrc1> pausing task, reason not-negotiated
0:00:13.236183604  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:6282:gst_pad_pause_task:<udpsrc1:src> pause task
0:00:13.236187586  3226 0x7fffa0018640 DEBUG                   task gsttask.c:729:gst_task_set_state_unlocked:<udpsrc1:src> Changing task 0x7fffdc035830 to state 2
0:00:13.236193061  3226 0x7fffa0018640 DEBUG              GST_EVENT gstevent.c:310:gst_event_new_custom: creating new event 0x7fff7804c240 eos 28174
0:00:13.236199176  3226 0x7fffa0018640 WARN                 basesrc gstbasesrc.c:3127:gst_base_src_loop:<udpsrc1> error: Internal data stream error.
0:00:13.236201219  3226 0x7fffa0018640 WARN                 basesrc gstbasesrc.c:3127:gst_base_src_loop:<udpsrc1> error: streaming stopped, reason not-negotiated (-4)
0:00:13.236354234  3226 0x7fffa0018640 DEBUG            GST_MESSAGE gstelement.c:2204:gst_element_message_full_with_details:<udpsrc1> start
0:00:13.236369033  3226 0x7fffa0018640 INFO        GST_ERROR_SYSTEM gstelement.c:2234:gst_element_message_full_with_details:<udpsrc1> posting message: Internal data stream error.
0:00:13.236389175  3226 0x7fffa0018640 WARN               structure gststructure.c:2069:priv_gst_structure_append_to_gstring: No value transform to serialize field 'gerror' of type 'GError'
0:00:13.236403685  3226 0x7fffa0018640 DEBUG                GST_BUS gstbus.c:340:gst_bus_post:<bus2> [msg 0x7fff68001e40] posting on bus error message: 0x7fff68001e40, time 99:99:99.999999999, seq-num 294, element 'udpsrc1', GstMessageError, gerror=(GError)NULL, debug=(string)"../libs/gst/base/gstbasesrc.c\(3127\):\ gst_base_src_loop\ \(\):\ /GstPipeline:receiver/GstBin:sourcebin/GstRTSPSrc:source/GstUDPSrc:udpsrc1:\012streaming\ stopped\,\ reason\ not-negotiated\ \(-4\)", details=(structure)"details\,\ flow-return\=\(int\)-4\;";
0:00:13.236410574  3226 0x7fffa0018640 DEBUG                rtspsrc gstrtspsrc.c:8984:gst_rtspsrc_handle_message:<source> got error from udpsrc1
0:00:13.236415227  3226 0x7fffa0018640 DEBUG                rtspsrc gstrtspsrc.c:8999:gst_rtspsrc_handle_message:<source> combined flows: not-linked
0:00:13.236419558  3226 0x7fffa0018640 DEBUG                    bin gstbin.c:3735:gst_bin_handle_message_func:<source> [msg 0x7fff68001e40] handling child udpsrc1 message of type error
0:00:13.236422642  3226 0x7fffa0018640 DEBUG                    bin gstbin.c:3744:gst_bin_handle_message_func:<source> got ERROR message, unlocking state change
0:00:13.236425825  3226 0x7fffa0018640 DEBUG                    bin gstbin.c:4071:gst_bin_handle_message_func:<source> posting message upward
0:00:13.236430938  3226 0x7fffa0018640 WARN               structure gststructure.c:2069:priv_gst_structure_append_to_gstring: No value transform to serialize field 'gerror' of type 'GError'
0:00:13.236437392  3226 0x7fffa0018640 DEBUG                GST_BUS gstbus.c:340:gst_bus_post:<bus3> [msg 0x7fff68001e40] posting on bus error message: 0x7fff68001e40, time 99:99:99.999999999, seq-num 294, element 'udpsrc1', GstMessageError, gerror=(GError)NULL, debug=(string)"../libs/gst/base/gstbasesrc.c\(3127\):\ gst_base_src_loop\ \(\):\ /GstPipeline:receiver/GstBin:sourcebin/GstRTSPSrc:source/GstUDPSrc:udpsrc1:\012streaming\ stopped\,\ reason\ not-negotiated\ \(-4\)", details=(structure)"details\,\ flow-return\=\(int\)-4\;";
0:00:13.236440264  3226 0x7fffa0018640 DEBUG                    bin gstbin.c:3735:gst_bin_handle_message_func:<sourcebin> [msg 0x7fff68001e40] handling child udpsrc1 message of type error
0:00:13.236441925  3226 0x7fffa0018640 DEBUG                    bin gstbin.c:3744:gst_bin_handle_message_func:<sourcebin> got ERROR message, unlocking state change
0:00:13.236443746  3226 0x7fffa0018640 DEBUG                    bin gstbin.c:4071:gst_bin_handle_message_func:<sourcebin> posting message upward
0:00:13.236470725  3226 0x7fffa0018640 WARN               structure gststructure.c:2069:priv_gst_structure_append_to_gstring: No value transform to serialize field 'gerror' of type 'GError'
0:00:13.236475128  3226 0x7fffa0018640 DEBUG                GST_BUS gstbus.c:340:gst_bus_post:<bus0> [msg 0x7fff68001e40] posting on bus error message: 0x7fff68001e40, time 99:99:99.999999999, seq-num 294, element 'udpsrc1', GstMessageError, gerror=(GError)NULL, debug=(string)"../libs/gst/base/gstbasesrc.c\(3127\):\ gst_base_src_loop\ \(\):\ /GstPipeline:receiver/GstBin:sourcebin/GstRTSPSrc:source/GstUDPSrc:udpsrc1:\012streaming\ stopped\,\ reason\ not-negotiated\ \(-4\)", details=(structure)"details\,\ flow-return\=\(int\)-4\;";
0:00:13.236478176  3226 0x7fffa0018640 DEBUG                    bin gstbin.c:3735:gst_bin_handle_message_func:<receiver> [msg 0x7fff68001e40] handling child udpsrc1 message of type error
0:00:13.236479802  3226 0x7fffa0018640 DEBUG                    bin gstbin.c:3744:gst_bin_handle_message_func:<receiver> got ERROR message, unlocking state change
0:00:13.236482461  3226 0x7fffa0018640 DEBUG                    bin gstbin.c:4071:gst_bin_handle_message_func:<receiver> posting message upward
0:00:13.236485123  3226 0x7fffa0018640 WARN               structure gststructure.c:2069:priv_gst_structure_append_to_gstring: No value transform to serialize field 'gerror' of type 'GError'
0:00:13.236501980  3226 0x7fffa0018640 DEBUG                GST_BUS gstbus.c:340:gst_bus_post:<bus1> [msg 0x7fff68001e40] posting on bus error message: 0x7fff68001e40, time 99:99:99.999999999, seq-num 294, element 'udpsrc1', GstMessageError, gerror=(GError)NULL, debug=(string)"../libs/gst/base/gstbasesrc.c\(3127\):\ gst_base_src_loop\ \(\):\ /GstPipeline:receiver/GstBin:sourcebin/GstRTSPSrc:source/GstUDPSrc:udpsrc1:\012streaming\ stopped\,\ reason\ not-negotiated\ \(-4\)", details=(structure)"details\,\ flow-return\=\(int\)-4\;";
VideoReceiverLog: GStreamer debug:  ../libs/gst/base/gstbasesrc.c(3127): gst_base_src_loop (): /GstPipeline:receiver/GstBin:sourcebin/GstRTSPSrc:source/GstUDPSrc:udpsrc1:
streaming stopped, reason not-negotiated (-4)
VideoReceiverLog: Fii GStreamer error: Internal data stream error.
0:00:13.236603066  3226 0x7fffa0018640 DEBUG                GST_BUS gstbus.c:383:gst_bus_post:<bus1> [msg 0x7fff68001e40] pushing on async queue
0:00:13.236606811  3226 0x7fffa0018640 DEBUG                GST_BUS gstbus.c:386:gst_bus_post:<bus1> [msg 0x7fff68001e40] pushed on async queue
0:00:13.236608984  3226 0x7fffa0018640 DEBUG                GST_BUS gstbus.c:379:gst_bus_post:<bus0> [msg 0x7fff68001e40] dropped
0:00:13.236610819  3226 0x7fffa0018640 DEBUG                GST_BUS gstbus.c:379:gst_bus_post:<bus3> [msg 0x7fff68001e40] dropped
0:00:13.236612450  3226 0x7fffa0018640 DEBUG                GST_BUS gstbus.c:379:gst_bus_post:<bus2> [msg 0x7fff68001e40] dropped
0:00:13.236615168  3226 0x7fffa0018640 INFO        GST_ERROR_SYSTEM gstelement.c:2261:gst_element_message_full_with_details:<udpsrc1> posted error message: Internal data stream error.
0:00:13.236619441  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4057:check_sticky:<udpsrc1:src> pushing all sticky events
0:00:13.236622602  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:3986:push_sticky:<udpsrc1:src> event stream-start was already received
0:00:13.236624876  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:3986:push_sticky:<udpsrc1:src> event caps was already received
0:00:13.236626873  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:3986:push_sticky:<udpsrc1:src> event segment was already received
0:00:13.236629134  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:3887:do_probe_callbacks:<udpsrc1:src> data is passed
0:00:13.236631504  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:3674:probe_hook_marshal:<udpsrc1:src> probe returned OK
0:00:13.236635886  3226 0x7fffa0018640 DEBUG              GST_EVENT gstpad.c:5774:gst_pad_send_event_unchecked:<manager:recv_rtp_sink_0> have event type eos event: 0x7fff7804c240, time 99:99:99.999999999, seq-num 127, (NULL)
0:00:13.236642036  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:4057:check_sticky:<recv_rtp_sink_0:proxypad1> pushing all sticky events
0:00:13.236644103  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:3986:push_sticky:<recv_rtp_sink_0:proxypad1> event stream-start was already received
0:00:13.236646935  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:3986:push_sticky:<recv_rtp_sink_0:proxypad1> event caps was already received
0:00:13.236648837  3226 0x7fffa0018640 DEBUG               GST_PADS gstpad.c:3986:push_sticky:<recv_rtp_sink_0:proxypad1> event segment was already received
VideoReceiverLog: Stopping because of error
0:00:13.236651789  3226 0x7fffa0018640 DEBUG              GST_EVENT gstpad.c:5774:gst_pad_send_event_unchecked:<rtpsession0:recv_rtp_sink> have event type eos event: 0x7fff7804c240, time 99:99:99.999999999, seq-num 127, (NULL)
0:00:13.236667359  3226 0x7fffa0018640 DEBUG             rtpsession gstrtpsession.c:1734:gst_rtp_session_event_recv_rtp_sink:<rtpsession0> received event eos
VideoReceiverLog: Stopping "rtsp://192.168.100.118:34554"
fraviofii commented 1 year ago

It took me some time to finally find what is the error that is happening:

GStreamerAPILog: (NULL) No start code is found.
GStreamerAPILog: (NULL) Error splitting the input into NAL units.

Is there a way to solve, or workaround, this issue?