validate.rtsp.playback.seek_backward.bowlerhatdancer_sleepytom_SGP_mjpeg_avi sometimes fails as EOS is received before the scenario finishes
validate.rtsp.playback.seek_backward.bowlerhatdancer_sleepytom_SGP_mjpeg_avi: Failed 'Application returned 18 (critical errors: [The program stopped before some actions were executed])'
You can reproduce with: /builds/thiblahute/gst-ci/gst-build/build/subprojects/gst-devtools/validate/tools/gst-validate-rtsp-server-1.0 file:///builds/thiblahute/gst-ci/validate-output/gst-integration-testsuites/medias/defaults/avi/bowlerhatdancer.sleepytom.SGP.mjpeg.avi --port 51987 & GST_VALIDATE_SCENARIOS_PATH='/builds/thiblahute/gst-ci/gst-build/prefix/share/gstreamer-1.0/validate/scenarios:/builds/thiblahute/gst-ci/gst-build/subprojects/gst-devtools/validate/data/scenarios' GST_GL_XINITTHREADS='1' DISPLAY=':27' GST_VALIDATE_SCENARIO='seek_backward' /builds/thiblahute/gst-ci/gst-build/build/subprojects/gst-devtools/validate/tools/gst-validate-1.0 playbin uri=rtsp://127.0.0.1:51987/test 'audio-sink=fakesink sync=true' 'video-sink=fakevideosink qos=true max-lateness=20000000' --set-media-info /builds/thiblahute/gst-ci/validate-output/gst-integration-testsuites/medias/defaults/avi/bowlerhatdancer.sleepytom.SGP.mjpeg.avi.media_info
Dumping log files on failure
Dumping contents of /builds/thiblahute/gst-ci/validate-output/logs/validate/rtsp/playback/seek_backward/bowlerhatdancer_sleepytom_SGP_mjpeg_avi
=================
Test name: validate.rtsp.playback.seek_backward.bowlerhatdancer_sleepytom_SGP_mjpeg_avi
Command: '/builds/thiblahute/gst-ci/gst-build/build/subprojects/gst-devtools/validate/tools/gst-validate-1.0 playbin uri=rtsp://127.0.0.1:51987/test audio-sink=fakesink sync=true video-sink=fakevideosink qos=true max-lateness=20000000 --set-media-info /builds/thiblahute/gst-ci/validate-output/gst-integration-testsuites/medias/defaults/avi/bowlerhatdancer.sleepytom.SGP.mjpeg.avi.media_info'
=================
=========================================
Running scenario seek_backward on pipeline playbin0
=========================================
Starting pipeline
Prerolling...
Pipeline started
<position: 0:00:00.000000000 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:00.000000000 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:00.000000000 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:00.000000000 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:00.000000000 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:00.000000000 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:00.000000000 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:00.000000000 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:00.155201454 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:00.405882502 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:00.656817988 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:00.907820751 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:01.158356358 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:01.408456372 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:01.658962866 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:01.909313084 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:02.160154770 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:02.411236337 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:02.661707506 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:02.911765340 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:03.162750806 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:03.412799125 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:03.663013558 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:03.913925059 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:04.164981512 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:04.415865591 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:04.666733200 duration: 0:00:19.400000000 speed: 1.000000 />
Executing (subaction)seek (
- name=Backward-seek
- playback-time=0:00:04.850000000
- rate=1
- start=0:00:00.000000000
- flags=accurate+flush
)
-> Action seek done (duration: 0:00:02.015809950)
<position: 0:00:00.032731588 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:00.282802972 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:00.533765603 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:00.784459177 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:01.034592918 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:01.284700140 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:01.535286550 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:01.785553848 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:02.036298952 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:02.286847648 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:02.536981460 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:02.787267165 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:03.038219108 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:03.288515423 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:03.539024533 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:03.789155752 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:04.039346998 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:04.290102933 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:04.540469961 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:04.790729305 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:05.041436394 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:05.291777308 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:05.542160315 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:05.792973696 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:06.043365012 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:06.293498297 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:06.544097954 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:06.794736430 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:07.044984185 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:07.295819520 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:07.546895281 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:07.797202425 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:08.047869269 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:08.298457474 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:08.549181000 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:08.799560121 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:09.050271404 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:09.300384521 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:09.550912332 duration: 0:00:19.400000000 speed: 1.000000 />
Executing (subaction)seek (
- name=Backward-seek
- playback-time=0:00:09.700000000
- rate=1
- start=0:00:04.850000000
- flags=accurate+flush
)
-> Action seek done (duration: 0:00:02.012685025)
<position: 0:00:04.918380035 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:05.169285373 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:05.420209043 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:05.670463402 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:05.921143396 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:06.171425198 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:06.422329527 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:06.672637119 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:06.923406499 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:07.174055247 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:07.424731392 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:07.675731403 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:07.926802217 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:08.177089212 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:08.427292822 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:08.678117153 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:08.928381871 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:09.178699972 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:09.428913149 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:09.679692932 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:09.942084931 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:10.180813083 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:10.431295651 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:10.682055344 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:10.932528869 duration: 0:00:19.400000000 speed: 1.000000 />
<position: 0:00:11.183287077 duration: 0:00:19.400000000 speed: 1.000000 />
Executing stop ( )
seek_backward --> State change request NULL, quiting mainloop
<position: 0:00:11.402724409 duration: 0:00:19.400000000 speed: 1.000000 />
warning : a new segment event has different value than the received one
Detected on <rtpjpegdepay0:src>
Description : when receiving a new segment, an element should push an equivalent segment downstream
warning : received the same caps twice
Detected on <rtpjpegdepay0:sink>
Detected on <jpegdec0:sink>
Detected on <typefind:sink>
warning : Buffer didn't have expected DISCONT flag
Detected on <rtpsession0:send_rtcp_src>
Detected on <udpsink1:sink>
Detected on <udpsrc0:src>
Detected on <rtpsession0:recv_rtp_sink>
Detected on <rtpsession0:recv_rtp_src>
Detected on <rtpstorage0:sink>
Detected on <rtpstorage0:src>
Detected on <rtpssrcdemux0:sink>
Detected on <rtpssrcdemux0:src_88543734>
Detected on <rtpjitterbuffer0:sink>
Detected on <udpsrc1:src>
Detected on <rtpsession0:recv_rtcp_sink>
Detected on <rtpsession0:sync_src>
Detected on <rtpssrcdemux0:rtcp_sink>
Detected on <rtpssrcdemux0:rtcp_src_88543734>
Detected on <rtpjitterbuffer0:sink_rtcp>
Description : Buffers after SEGMENT and FLUSH must have a DISCONT flag
warning : a serialized event received should be pushed in the same 'time' as it was received
Detected on <rtpjpegdepay0:src>
Description : serialized events should be pushed in the same order they are received and serialized with buffers. If an event is received after a buffer with timestamp end 'X', it should be pushed right after buffers with timestamp end 'X'
warning : Query position reported a value outside of the current expected segment
Detected on <seek_backward>
issue : FLUSH_START events that are part of the same pipeline 'operation' should have the same seqnum
Detected on <typefind:src>
Detected on <rtpjpegdepay0:src>
Detected on <jpegdec0:src>
Detected on <inputselector0:src>
Detected on <streamsynchronizer0:src_0>
Detected on <vdconv:src>
Detected on <deinterlace:src>
Detected on <vqueue:src>
Detected on <conv:src>
Detected on <scale:src>
Detected on <videobalance:src>
Detected on <conv2:src>
Description : when events/messages are created from another event/message, they should have their seqnums set to the original event/message seqnum
issue : FLUSH_STOP events that are part of the same pipeline 'operation' should have the same seqnum
Detected on <typefind:src>
Detected on <rtpjpegdepay0:src>
Detected on <jpegdec0:src>
Detected on <inputselector0:src>
Detected on <streamsynchronizer0:src_0>
Detected on <vdconv:src>
Detected on <deinterlace:src>
Detected on <vqueue:src>
Detected on <conv:src>
Detected on <scale:src>
Detected on <videobalance:src>
Detected on <conv2:src>
Description : when events/messages are created from another event/message, they should have their seqnums set to the original event/message seqnum
issue : SEGMENT events that are part of the same pipeline 'operation' should have the same seqnum
Detected on <udpsrc0:src>
Detected on <rtpsession0:recv_rtp_sink>
Detected on <rtpsession0:recv_rtp_src>
Detected on <rtpstorage0:sink>
Detected on <rtpstorage0:src>
Detected on <rtpssrcdemux0:sink>
Detected on <rtpssrcdemux0:src_88543734>
Detected on <rtpjitterbuffer0:sink>
Detected on <rtpjitterbuffer0:src>
Detected on <rtpptdemux0:sink>
Detected on <rtpptdemux0:src_96>
Detected on <typefind:sink>
Detected on <typefind:src>
Detected on <rtpjpegdepay0:sink>
Detected on <rtpjpegdepay0:src>
Detected on <jpegdec0:sink>
Detected on <jpegdec0:src>
Detected on <inputselector0:sink_0>
Detected on <inputselector0:src>
Detected on <streamsynchronizer0:sink_0>
Detected on <streamsynchronizer0:src_0>
Detected on <vdconv:sink>
Detected on <vdconv:src>
Detected on <deinterlace:sink>
Detected on <deinterlace:src>
Detected on <vqueue:sink>
Detected on <vqueue:src>
Detected on <conv:sink>
Detected on <conv:src>
Detected on <scale:sink>
Detected on <scale:src>
Detected on <videobalance:sink>
Detected on <videobalance:src>
Detected on <conv2:sink>
Detected on <conv2:src>
Detected on <sink:sink>
Detected on <udpsrc1:src>
Detected on <rtpsession0:recv_rtcp_sink>
Detected on <rtpsession0:sync_src>
Detected on <rtpssrcdemux0:rtcp_sink>
Detected on <rtpssrcdemux0:rtcp_src_88543734>
Detected on <rtpjitterbuffer0:sink_rtcp>
Description : when events/messages are created from another event/message, they should have their seqnums set to the original event/message seqnum
issue : EOS events that are part of the same pipeline 'operation' should have the same seqnum
Detected on <udpsrc0:src>
Detected on <rtpsession0:recv_rtp_sink>
Detected on <rtpsession0:recv_rtp_src>
Detected on <rtpstorage0:sink>
Detected on <rtpstorage0:src>
Detected on <rtpssrcdemux0:sink>
Detected on <rtpssrcdemux0:src_88543734>
Detected on <rtpjitterbuffer0:sink>
Detected on <rtpsession0:send_rtcp_src>
Detected on <udpsink1:sink>
Detected on <rtpjitterbuffer0:src>
Detected on <rtpptdemux0:sink>
Detected on <rtpptdemux0:src_96>
Detected on <typefind:sink>
Detected on <typefind:src>
Detected on <rtpjpegdepay0:sink>
Detected on <rtpjpegdepay0:src>
Detected on <jpegdec0:sink>
Detected on <jpegdec0:src>
Detected on <inputselector0:sink_0>
Detected on <inputselector0:src>
Detected on <streamsynchronizer0:sink_0>
Description : when events/messages are created from another event/message, they should have their seqnums set to the original event/message seqnum
critical : The program stopped before some actions were executed
Detected on <seek_backward>
Details : 1 actions were not executed:
seek, name=(string)Backward-seek, playback-time=(guint64)14550000000, rate=(double)1, start=(string)"min\(10.0\,\ 2\*\(duration/4\)\)", flags=(string)accurate+flush;
dotfile : no dotfile produced as GST_DEBUG_DUMP_DOT_DIR is not set.
backtrace :
gst_debug_get_stack_trace (gstinfo.c:2886)
gst_validate_report_new (gst-validate-report.c:729)
gst_validate_report_valist (gst-validate-reporter.c:186)
gst_validate_report (gst-validate-reporter.c:303)
message_cb (gst-validate-scenario.c:2764)
ffi_call_unix64 (/usr/lib64/libffi.so.6.0.2:0x7fee645c7aca)
ffi_call (/usr/lib64/libffi.so.6.0.2:0x7fee645c748b)
g_cclosure_marshal_generic (/usr/lib64/libgobject-2.0.so.0.5800.2:0x7fee64c4dea1)
g_closure_invoke (/usr/lib64/libgobject-2.0.so.0.5800.2:0x7fee64c4d3d9)
?? (/usr/lib64/libgobject-2.0.so.0.5800.2:0x7fee64c6097f)
g_signal_emit_valist (/usr/lib64/libgobject-2.0.so.0.5800.2:0x7fee64c69aa6)
g_signal_emit (/usr/lib64/libgobject-2.0.so.0.5800.2:0x7fee64c6a09f)
gst_bus_async_signal_func (gstbus.c:1251)
gst_bus_source_dispatch (gstbus.c:839)
g_main_context_dispatch (/usr/lib64/libglib-2.0.so.0.5800.2:0x7fee64ce1069)
?? (/usr/lib64/libglib-2.0.so.0.5800.2:0x7fee64ce1434)
g_main_loop_run (/usr/lib64/libglib-2.0.so.0.5800.2:0x7fee64ce175e)
main (gst-validate.c:526)
__libc_start_main (/usr/lib64/libc-2.28.so:0x7fee648cc40f)
_start (/builds/thiblahute/gst-ci/gst-build/build/subprojects/gst-devtools/validate/tools/gst-validate-1.0:0x403efa)
==== Got criticals. Return value set to 18 ====
Critical error 1 actions were not executed:
seek, name=(string)Backward-seek, playback-time=(guint64)14550000000, rate=(double)1, start=(string)"min\(10.0\,\ 2\*\(duration/4\)\)", flags=(string)accurate+flush;
Issues found: 10
Returning 18 as errors were found
=======> Test FAILED (Return value: 18)
