GStreamer-devel

New RTP Bug in head/master (h265 rtspsrc)

classic Classic list List threaded Threaded
♦
♦
 2 messages Options
logidelic
Reply | Threaded
Open this post in threaded view
♦
♦
|

New RTP Bug in head/master (h265 rtspsrc)

logidelic
Hey all,

I recently rebuilt everything from HEAD / master and noticed that my rtspsrc pipeline stopped working for some h265 sources. I had previously built everything with the latest in November 2019.

I was able to isolate the problem to one of the libgstrtp* libraries. If I use the older RTP libraries, everything works fine, but if I use the latest, my pipeline won't play and ends with this error:

udpsrc0
Internal data stream error.
../subprojects/gstreamer/libs/gst/base/gstbasesrc.c(3070):
gst_base_src_loop ():
/GstPipeline:pipeline2/GstRTSPSrc:rtspsrc1/GstUDPSrc:udpsrc0:
streaming stopped, reason not-negotiated (-4)

Log below. Any pointers for how I can further investigate this? It's 100% repro for me. It could be something particular to my RTSP camera, but I have a few that are showing the same issue...

2020-05-22T20:44:11.138Z - gstbus - state-changed el:appsink2 from:NULL to:READY
2020-05-22T20:44:11.138Z - gstbus - state-changed el:capsfilter3 from:NULL to:READY
2020-05-22T20:44:11.138Z - gstbus - state-changed el:videoconvert1 from:NULL to:READY
2020-05-22T20:44:11.138Z - gstbus - state-changed el:capsfilter2 from:NULL to:READY
2020-05-22T20:44:11.138Z - gstbus - state-changed el:nvvconv1 from:NULL to:READY
2020-05-22T20:44:11.139Z - gstbus - state-changed el:omxh265dec-omxh265dec1 from:NULL to:READY
2020-05-22T20:44:11.139Z - gstbus - state-changed el:h265parse1 from:NULL to:READY
2020-05-22T20:44:11.139Z - gstbus - state-changed el:rtph265depay1 from:NULL to:READY
2020-05-22T20:44:11.139Z - gstbus - state-changed el:rtspsrc1 from:NULL to:READY
2020-05-22T20:44:11.139Z - gstbus - state-changed el:pipeline2 from:NULL to:READY
2020-05-22T20:44:11.139Z - gstbus - state-changed el:capsfilter3 from:READY to:PAUSED
2020-05-22T20:44:11.139Z - gstbus - state-changed el:videoconvert1 from:READY to:PAUSED
2020-05-22T20:44:11.139Z - gstbus - state-changed el:capsfilter2 from:READY to:PAUSED
2020-05-22T20:44:11.140Z - gstbus - state-changed el:nvvconv1 from:READY to:PAUSED
2020-05-22T20:44:11.140Z - gstbus - state-changed el:omxh265dec-omxh265dec1 from:READY to:PAUSED
2020-05-22T20:44:11.141Z - gstbus - state-changed el:h265parse1 from:READY to:PAUSED
2020-05-22T20:44:11.141Z - gstbus - state-changed el:rtph265depay1 from:READY to:PAUSED
2020-05-22T20:44:11.141Z - gstbus - progress:start - code:open - Opening Stream
2020-05-22T20:44:11.141Z - gstbus - state-changed el:rtspsrc1 from:READY to:PAUSED
2020-05-22T20:44:11.141Z - gstbus - state-changed el:pipeline2 from:READY to:PAUSED
2020-05-22T20:44:11.141Z - gstbus - new-clock
2020-05-22T20:44:11.141Z - gstbus - state-changed el:capsfilter3 from:PAUSED to:PLAYING
2020-05-22T20:44:11.141Z - gstbus - progress:continue - code:connect - Connecting to rtsp://removed_url
2020-05-22T20:44:11.141Z - gstbus - state-changed el:videoconvert1 from:PAUSED to:PLAYING
2020-05-22T20:44:11.141Z - gstbus - state-changed el:capsfilter2 from:PAUSED to:PLAYING
2020-05-22T20:44:11.141Z - gstbus - state-changed el:nvvconv1 from:PAUSED to:PLAYING
2020-05-22T20:44:11.141Z - gstbus - state-changed el:omxh265dec-omxh265dec1 from:PAUSED to:PLAYING
2020-05-22T20:44:11.141Z - gstbus - state-changed el:h265parse1 from:PAUSED to:PLAYING
2020-05-22T20:44:11.141Z - gstbus - state-changed el:rtph265depay1 from:PAUSED to:PLAYING
2020-05-22T20:44:11.141Z - gstbus - progress:start - code:request - Sending PLAY request
2020-05-22T20:44:11.141Z - gstbus - state-changed el:rtspsrc1 from:PAUSED to:PLAYING

2020-05-22T20:44:11.148Z - gstbus - progress:continue - code:open - Retrieving server options
2020-05-22T20:44:11.730Z - gstbus - progress:continue - code:open - Retrieving media info
2020-05-22T20:44:11.843Z - gstbus - progress:continue - code:request - SETUP stream 0
2020-05-22T20:44:11.949Z - gstbus - state-changed el:manager from:NULL to:READY
2020-05-22T20:44:11.949Z - gstbus - state-changed el:manager from:READY to:PAUSED
2020-05-22T20:44:11.949Z - gstbus - state-changed el:rtpssrcdemux1 from:NULL to:READY
2020-05-22T20:44:11.949Z - gstbus - state-changed el:rtpssrcdemux1 from:READY to:PAUSED
2020-05-22T20:44:11.949Z - gstbus - state-changed el:rtpsession1 from:NULL to:READY
2020-05-22T20:44:11.949Z - gstbus - state-changed el:rtpsession1 from:READY to:PAUSED
2020-05-22T20:44:11.949Z - gstbus - state-changed el:rtpstorage1 from:NULL to:READY
2020-05-22T20:44:11.949Z - gstbus - state-changed el:rtpstorage1 from:READY to:PAUSED
2020-05-22T20:44:11.953Z - gstbus - progress:complete - code:open - Opened Stream
2020-05-22T20:44:11.953Z - gstbus - state-changed el:udpsink0 from:NULL to:READY
2020-05-22T20:44:11.953Z - gstbus - state-changed el:udpsink0 from:READY to:PAUSED
2020-05-22T20:44:11.953Z - gstbus - state-changed el:udpsink0 from:PAUSED to:PLAYING
2020-05-22T20:44:11.953Z - gstbus - state-changed el:fakesrc0 from:NULL to:READY
2020-05-22T20:44:11.953Z - gstbus - stream-status:create el:src
2020-05-22T20:44:11.953Z - gstbus - state-changed el:fakesrc0 from:READY to:PAUSED
2020-05-22T20:44:11.953Z - gstbus - state-changed el:fakesrc0 from:PAUSED to:PLAYING
2020-05-22T20:44:11.953Z - gstbus - progress:continue - code:request - Sending PLAY request
2020-05-22T20:44:11.953Z - gstbus - stream-status:enter el:src
2020-05-22T20:44:12.075Z - gstbus - state-changed el:rtpssrcdemux1 from:PAUSED to:PLAYING
2020-05-22T20:44:12.075Z - gstbus - state-changed el:rtpstorage1 from:PAUSED to:PLAYING
2020-05-22T20:44:12.075Z - gstbus - state-changed el:rtpsession1 from:PAUSED to:PLAYING
2020-05-22T20:44:12.075Z - gstbus - state-changed el:manager from:PAUSED to:PLAYING
2020-05-22T20:44:12.075Z - gstbus - stream-status:create el:src
2020-05-22T20:44:12.075Z - gstbus - state-changed el:udpsrc0 from:READY to:PAUSED
2020-05-22T20:44:12.075Z - gstbus - state-changed el:udpsrc0 from:PAUSED to:PLAYING
2020-05-22T20:44:12.075Z - gstbus - stream-status:create el:src
2020-05-22T20:44:12.075Z - gstbus - stream-status:enter el:src
2020-05-22T20:44:12.076Z - gstbus - state-changed el:udpsrc1 from:READY to:PAUSED
2020-05-22T20:44:12.076Z - gstbus - state-changed el:udpsrc1 from:PAUSED to:PLAYING
2020-05-22T20:44:12.076Z - gstbus - progress:complete - code:request - Sent PLAY request
2020-05-22T20:44:12.076Z - gstbus - stream-status:enter el:src
2020-05-22T20:44:12.196Z - gstbus - state-changed el:rtpjitterbuffer1 from:NULL to:READY
2020-05-22T20:44:12.196Z - gstbus - stream-status:create el:src
2020-05-22T20:44:12.196Z - gstbus - state-changed el:rtpjitterbuffer1 from:READY to:PAUSED
2020-05-22T20:44:12.196Z - gstbus - state-changed el:rtpjitterbuffer1 from:PAUSED to:PLAYING
2020-05-22T20:44:12.197Z - gstbus - stream-status:enter el:src
2020-05-22T20:44:12.197Z - gstbus - latency
2020-05-22T20:44:12.197Z - gstbus - state-changed el:rtpptdemux1 from:NULL to:READY
2020-05-22T20:44:12.197Z - gstbus - state-changed el:rtpptdemux1 from:READY to:PAUSED
2020-05-22T20:44:12.197Z - gstbus - state-changed el:rtpptdemux1 from:PAUSED to:PLAYING

2020-05-22T20:44:12.696Z - on_pad_added - recv_rtp_src_0_1804289383_96
2020-05-22T20:44:12.696Z - on_pad_added - recv_rtp_src_0_1804289383_96 result:OK

2020-05-22T20:44:12.696Z - gstbus - stream-start

2020-05-22T20:44:12.878Z - gstbus - ERROR - src:udpsrc0 msg:Internal data stream error. dbg:../subprojects/gstreamer/libs/gst/base/gstbasesrc.c(3070): gst_base_src_loop (): /GstPipeline:pipeline2/GstRTSPSrc:rtspsrc1/GstUDPSrc:udpsrc0:\nstreaming stopped, reason not-negotiated (-4)
2020-05-22T20:44:12.878Z - onBusMsg - Pipeline play failed

- Bill

_______________________________________________
gstreamer-devel mailing list
[hidden email]
https://lists.freedesktop.org/mailman/listinfo/gstreamer-devel
Nicolas Dufresne-5
Reply | Threaded
Open this post in threaded view
♦
♦
|

Re: New RTP Bug in head/master (h265 rtspsrc)

Nicolas Dufresne-5


Le sam. 23 mai 2020 10 h 15, Bill Klein <[hidden email]> a écrit :
Hey all,

I recently rebuilt everything from HEAD / master and noticed that my rtspsrc pipeline stopped working for some h265 sources. I had previously built everything with the latest in November 2019.

I was able to isolate the problem to one of the libgstrtp* libraries. If I use the older RTP libraries, everything works fine, but if I use the latest, my pipeline won't play and ends with this error:

udpsrc0
Internal data stream error.
../subprojects/gstreamer/libs/gst/base/gstbasesrc.c(3070):
gst_base_src_loop ():
/GstPipeline:pipeline2/GstRTSPSrc:rtspsrc1/GstUDPSrc:udpsrc0:
streaming stopped, reason not-negotiated (-4)

Log below. Any pointers for how I can further investigate this? It's 100% repro for me. It could be something particular to my RTSP camera, but I have a few that are showing the same issue...

Anyways you could wrap a minimal example that would make this 100% reproducible by others ? That would ideal.

As this is a not-negotiated error, I can already speculate that an unfortunate new field be have been added causing a non backward compatible break. But this is as much as we can deduce from now.

Perhaps try to catch this through GAT_CAPS log level ?

export GST_DEBUG=GST_CAPS:7

I believe it would be nice to file an issue, so this is tracked properly. I would file it against gst-plugins-good, though I would not be surprise if the issue was introduced but a change elsewhere, like in h265 parser element. When you file the issue, if you don't have an example, make sure to describe your pipelines, as this will greatly help.

2020-05-22T20:44:11.138Z - gstbus - state-changed el:appsink2 from:NULL to:READY
2020-05-22T20:44:11.138Z - gstbus - state-changed el:capsfilter3 from:NULL to:READY
2020-05-22T20:44:11.138Z - gstbus - state-changed el:videoconvert1 from:NULL to:READY
2020-05-22T20:44:11.138Z - gstbus - state-changed el:capsfilter2 from:NULL to:READY
2020-05-22T20:44:11.138Z - gstbus - state-changed el:nvvconv1 from:NULL to:READY
2020-05-22T20:44:11.139Z - gstbus - state-changed el:omxh265dec-omxh265dec1 from:NULL to:READY
2020-05-22T20:44:11.139Z - gstbus - state-changed el:h265parse1 from:NULL to:READY
2020-05-22T20:44:11.139Z - gstbus - state-changed el:rtph265depay1 from:NULL to:READY
2020-05-22T20:44:11.139Z - gstbus - state-changed el:rtspsrc1 from:NULL to:READY
2020-05-22T20:44:11.139Z - gstbus - state-changed el:pipeline2 from:NULL to:READY
2020-05-22T20:44:11.139Z - gstbus - state-changed el:capsfilter3 from:READY to:PAUSED
2020-05-22T20:44:11.139Z - gstbus - state-changed el:videoconvert1 from:READY to:PAUSED
2020-05-22T20:44:11.139Z - gstbus - state-changed el:capsfilter2 from:READY to:PAUSED
2020-05-22T20:44:11.140Z - gstbus - state-changed el:nvvconv1 from:READY to:PAUSED
2020-05-22T20:44:11.140Z - gstbus - state-changed el:omxh265dec-omxh265dec1 from:READY to:PAUSED
2020-05-22T20:44:11.141Z - gstbus - state-changed el:h265parse1 from:READY to:PAUSED
2020-05-22T20:44:11.141Z - gstbus - state-changed el:rtph265depay1 from:READY to:PAUSED
2020-05-22T20:44:11.141Z - gstbus - progress:start - code:open - Opening Stream
2020-05-22T20:44:11.141Z - gstbus - state-changed el:rtspsrc1 from:READY to:PAUSED
2020-05-22T20:44:11.141Z - gstbus - state-changed el:pipeline2 from:READY to:PAUSED
2020-05-22T20:44:11.141Z - gstbus - new-clock
2020-05-22T20:44:11.141Z - gstbus - state-changed el:capsfilter3 from:PAUSED to:PLAYING
2020-05-22T20:44:11.141Z - gstbus - progress:continue - code:connect - Connecting to rtsp://removed_url
2020-05-22T20:44:11.141Z - gstbus - state-changed el:videoconvert1 from:PAUSED to:PLAYING
2020-05-22T20:44:11.141Z - gstbus - state-changed el:capsfilter2 from:PAUSED to:PLAYING
2020-05-22T20:44:11.141Z - gstbus - state-changed el:nvvconv1 from:PAUSED to:PLAYING
2020-05-22T20:44:11.141Z - gstbus - state-changed el:omxh265dec-omxh265dec1 from:PAUSED to:PLAYING
2020-05-22T20:44:11.141Z - gstbus - state-changed el:h265parse1 from:PAUSED to:PLAYING
2020-05-22T20:44:11.141Z - gstbus - state-changed el:rtph265depay1 from:PAUSED to:PLAYING
2020-05-22T20:44:11.141Z - gstbus - progress:start - code:request - Sending PLAY request
2020-05-22T20:44:11.141Z - gstbus - state-changed el:rtspsrc1 from:PAUSED to:PLAYING

2020-05-22T20:44:11.148Z - gstbus - progress:continue - code:open - Retrieving server options
2020-05-22T20:44:11.730Z - gstbus - progress:continue - code:open - Retrieving media info
2020-05-22T20:44:11.843Z - gstbus - progress:continue - code:request - SETUP stream 0
2020-05-22T20:44:11.949Z - gstbus - state-changed el:manager from:NULL to:READY
2020-05-22T20:44:11.949Z - gstbus - state-changed el:manager from:READY to:PAUSED
2020-05-22T20:44:11.949Z - gstbus - state-changed el:rtpssrcdemux1 from:NULL to:READY
2020-05-22T20:44:11.949Z - gstbus - state-changed el:rtpssrcdemux1 from:READY to:PAUSED
2020-05-22T20:44:11.949Z - gstbus - state-changed el:rtpsession1 from:NULL to:READY
2020-05-22T20:44:11.949Z - gstbus - state-changed el:rtpsession1 from:READY to:PAUSED
2020-05-22T20:44:11.949Z - gstbus - state-changed el:rtpstorage1 from:NULL to:READY
2020-05-22T20:44:11.949Z - gstbus - state-changed el:rtpstorage1 from:READY to:PAUSED
2020-05-22T20:44:11.953Z - gstbus - progress:complete - code:open - Opened Stream
2020-05-22T20:44:11.953Z - gstbus - state-changed el:udpsink0 from:NULL to:READY
2020-05-22T20:44:11.953Z - gstbus - state-changed el:udpsink0 from:READY to:PAUSED
2020-05-22T20:44:11.953Z - gstbus - state-changed el:udpsink0 from:PAUSED to:PLAYING
2020-05-22T20:44:11.953Z - gstbus - state-changed el:fakesrc0 from:NULL to:READY
2020-05-22T20:44:11.953Z - gstbus - stream-status:create el:src
2020-05-22T20:44:11.953Z - gstbus - state-changed el:fakesrc0 from:READY to:PAUSED
2020-05-22T20:44:11.953Z - gstbus - state-changed el:fakesrc0 from:PAUSED to:PLAYING
2020-05-22T20:44:11.953Z - gstbus - progress:continue - code:request - Sending PLAY request
2020-05-22T20:44:11.953Z - gstbus - stream-status:enter el:src
2020-05-22T20:44:12.075Z - gstbus - state-changed el:rtpssrcdemux1 from:PAUSED to:PLAYING
2020-05-22T20:44:12.075Z - gstbus - state-changed el:rtpstorage1 from:PAUSED to:PLAYING
2020-05-22T20:44:12.075Z - gstbus - state-changed el:rtpsession1 from:PAUSED to:PLAYING
2020-05-22T20:44:12.075Z - gstbus - state-changed el:manager from:PAUSED to:PLAYING
2020-05-22T20:44:12.075Z - gstbus - stream-status:create el:src
2020-05-22T20:44:12.075Z - gstbus - state-changed el:udpsrc0 from:READY to:PAUSED
2020-05-22T20:44:12.075Z - gstbus - state-changed el:udpsrc0 from:PAUSED to:PLAYING
2020-05-22T20:44:12.075Z - gstbus - stream-status:create el:src
2020-05-22T20:44:12.075Z - gstbus - stream-status:enter el:src
2020-05-22T20:44:12.076Z - gstbus - state-changed el:udpsrc1 from:READY to:PAUSED
2020-05-22T20:44:12.076Z - gstbus - state-changed el:udpsrc1 from:PAUSED to:PLAYING
2020-05-22T20:44:12.076Z - gstbus - progress:complete - code:request - Sent PLAY request
2020-05-22T20:44:12.076Z - gstbus - stream-status:enter el:src
2020-05-22T20:44:12.196Z - gstbus - state-changed el:rtpjitterbuffer1 from:NULL to:READY
2020-05-22T20:44:12.196Z - gstbus - stream-status:create el:src
2020-05-22T20:44:12.196Z - gstbus - state-changed el:rtpjitterbuffer1 from:READY to:PAUSED
2020-05-22T20:44:12.196Z - gstbus - state-changed el:rtpjitterbuffer1 from:PAUSED to:PLAYING
2020-05-22T20:44:12.197Z - gstbus - stream-status:enter el:src
2020-05-22T20:44:12.197Z - gstbus - latency
2020-05-22T20:44:12.197Z - gstbus - state-changed el:rtpptdemux1 from:NULL to:READY
2020-05-22T20:44:12.197Z - gstbus - state-changed el:rtpptdemux1 from:READY to:PAUSED
2020-05-22T20:44:12.197Z - gstbus - state-changed el:rtpptdemux1 from:PAUSED to:PLAYING

2020-05-22T20:44:12.696Z - on_pad_added - recv_rtp_src_0_1804289383_96
2020-05-22T20:44:12.696Z - on_pad_added - recv_rtp_src_0_1804289383_96 result:OK

2020-05-22T20:44:12.696Z - gstbus - stream-start

2020-05-22T20:44:12.878Z - gstbus - ERROR - src:udpsrc0 msg:Internal data stream error. dbg:../subprojects/gstreamer/libs/gst/base/gstbasesrc.c(3070): gst_base_src_loop (): /GstPipeline:pipeline2/GstRTSPSrc:rtspsrc1/GstUDPSrc:udpsrc0:\nstreaming stopped, reason not-negotiated (-4)
2020-05-22T20:44:12.878Z - onBusMsg - Pipeline play failed

- Bill
_______________________________________________
gstreamer-devel mailing list
[hidden email]
https://lists.freedesktop.org/mailman/listinfo/gstreamer-devel

_______________________________________________
gstreamer-devel mailing list
[hidden email]
https://lists.freedesktop.org/mailman/listinfo/gstreamer-devel
Free forum by Nabble Edit this page