New RTP Bug in head/master (h265 rtspsrc)

[Bill Klein]

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 libgst*rtp** 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
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.freedesktop.org/archives/gstreamer-devel/attachments/20200523/d5190bfa/attachment.htm>