Skip to content
GitLab
Projects Groups Snippets
  • /
  • Help
    • Help
    • Support
    • Community forum
    • Submit feedback
    • Contribute to GitLab
  • Sign in / Register
  • gst-plugins-good gst-plugins-good
  • Project information
    • Project information
    • Activity
    • Labels
    • Members
  • Repository
    • Repository
    • Files
    • Commits
    • Branches
    • Tags
    • Contributors
    • Graph
    • Compare
  • Issues 646
    • Issues 646
    • List
    • Boards
    • Service Desk
    • Milestones
  • Merge requests 71
    • Merge requests 71
  • CI/CD
    • CI/CD
    • Pipelines
    • Jobs
    • Schedules
  • Deployments
    • Deployments
    • Environments
    • Releases
  • Packages and registries
    • Packages and registries
    • Container Registry
  • Monitor
    • Monitor
    • Incidents
  • Analytics
    • Analytics
    • Value stream
    • CI/CD
    • Repository
  • Snippets
    • Snippets
  • Activity
  • Graph
  • Create a new issue
  • Jobs
  • Commits
  • Issue Boards
Collapse sidebar
  • GStreamerGStreamer
  • gst-plugins-goodgst-plugins-good
  • Issues
  • #563
Closed
Open
Issue created Feb 01, 2019 by Thibault Saunier@thiblahute🌵Maintainer

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)
Assignee
Assign to
Time tracking