ges: `check.gst-editing-services.check_layer_activness_gaps_it` race leading to wrong source used after commit
Command
CK_TIMEOUT_MULTIPLIER='1.0' GST_GL_XINITTHREADS='1' GST_PLUGIN_SCANNER_1_0='/var/home/thiblahute/devel/gstreamer/gstreamer/build/subprojects/gstreamer/libs/gst/helpers/gst-plugin-scanner' GST_VALIDATE_LOGSDIR='/var/home/thiblahute/devel/gstreamer/gstreamer/build/subprojects/gst-editing-services/tests/check/check_layer_activness_gaps' GST_REGISTRY='/var/home/thiblahute/devel/gstreamer/gstreamer/build/registry.dat' GST_STATE_IGNORE_ELEMENTS='' GST_PLUGIN_SYSTEM_PATH_1_0='' CK_DEFAULT_TIMEOUT='20' /var/home/thiblahute/devel/gstreamer/gstreamer/build/subprojects/gst-editing-services/tools/ges-launch-1.0 --no-interactive --set-scenario /var/home/thiblahute/devel/gstreamer/gstreamer/subprojects/gst-editing-services/tests/check/scenarios/check_layer_activness_gaps.scenario
ges-launch-1.0 output
**-> Running scenario /var/home/thiblahute/devel/gstreamer/gstreamer/subprojects/gst-editing-services/tests/check/scenarios/check_layer_activness_gaps.scenario on pipeline gespipeline0**
Executing `add-clip` at check_layer_activness_gaps.scenario:8 (
- name=clip
- asset-id="framerate\=30/1"
- layer-priority=0
- type=GESTestClip
- pattern=blue
- duration=5000
)
⇨ Action `add-clip` at check_layer_activness_gaps.scenario:8 done 'OK' (duration: 0:00:00.005577927)
Executing `set-layer-active` at check_layer_activness_gaps.scenario:9 (
- tracks={ (string)gesvideotrack0 }
- active=false
- layer-priority=0
)
⇨ Action `set-layer-active` at check_layer_activness_gaps.scenario:9 done 'OK' (duration: 0:00:00.000018555)
Executing `pause` at check_layer_activness_gaps.scenario:11 ( )
⇨ Action `pause` at check_layer_activness_gaps.scenario:11 done 'ASYNC' (duration: 0:00:00.079797039)
Executing `check-property` at check_layer_activness_gaps.scenario:14 (
- target-element-factory-name=videotestsrc
- property-name=pattern
- property-value="100\%\ Black"
)
⇨ Action `check-property` at check_layer_activness_gaps.scenario:14 done 'OK' (duration: 0:00:00.000055063)
Executing `check-property` at check_layer_activness_gaps.scenario:15 (
- target-element-factory-name=audiotestsrc
- property-name=wave
- property-value=Sine
)
⇨ Action `check-property` at check_layer_activness_gaps.scenario:15 done 'OK' (duration: 0:00:00.000026289)
Executing `set-layer-active` at check_layer_activness_gaps.scenario:17 (
- tracks={ (string)gesvideotrack0 }
- active=true
- layer-priority=0
)
⇨ Action `set-layer-active` at check_layer_activness_gaps.scenario:17 done 'OK' (duration: 0:00:00.000057798)
Executing `set-layer-active` at check_layer_activness_gaps.scenario:18 (
- tracks={ (string)gesaudiotrack0 }
- active=false
- layer-priority=0
)
⇨ Action `set-layer-active` at check_layer_activness_gaps.scenario:18 done 'OK' (duration: 0:00:00.000016211)
Executing `commit` at check_layer_activness_gaps.scenario:19 ( )
⇨ Action `commit` at check_layer_activness_gaps.scenario:19 done 'ASYNC' (duration: 0:00:00.019953054)
Executing `check-property` at check_layer_activness_gaps.scenario:22 (
- target-element-factory-name=videotestsrc
- property-name=pattern
- property-value=Blue
)
0:00:00.329193173 [34m2255451[00m 0x1c0ee30 [31;01mERROR [00m [00m validate gst-validate-reporter.c:198:gst_validate_report_valist:[00m <check_layer_activness_gaps.scenario> 3068 (critical) : scenario: The execution of an action did not properly happen :
> check_layer_activness_gaps.scenario:22
22 | check-property, target-element-factory-name=videotestsrc, property-name=pattern, property-value="Blue"
>
> <src>::pattern expected value: '(gchararray)Blue' different than observed: '(gchararray)"100\%\ Black"'
> Error:
(null)> > check_layer_activness_gaps.scenario:22
(null)> 22 | check-property, target-element-factory-name=videotestsrc, property-name=pattern, property-value="Blue"
(null)> >
(null)> > <src>::pattern expected value: '(gchararray)Blue' different than observed: '(gchararray)"100\%\ Black"'
⇨ Action `check-property` at check_layer_activness_gaps.scenario:22 done 'ERROR(reported)' (duration: 0:00:00.011187863)
Executing `check-property` at check_layer_activness_gaps.scenario:23 (
- target-element-factory-name=audiotestsrc
- property-name=wave
- property-value=Silence
)
⇨ Action `check-property` at check_layer_activness_gaps.scenario:23 done 'OK' (duration: 0:00:00.000038071)
Executing `stop` at check_layer_activness_gaps.scenario:25 ( )
⇨ Action `stop` at check_layer_activness_gaps.scenario:25 done 'OK' (duration: 0:00:00.000013796)
check_layer_activness_gaps.scenario --> State change request NULL, quitting application
warning : Buffer didn't have expected DISCONT flag
Detected on <nlesource8:src>
Description : Buffers after SEGMENT and FLUSH must have a DISCONT flag
issue : FLUSH_START events that are part of the same pipeline 'operation' should have the same seqnum
Detected on <nlesource5:src>
Detected on <video_nlecomposition0:src>
Detected on <GESAudioTestSource:nlesource3:src>
Detected on <audio_nlecomposition1:src>
Detected on <nlesource8:src>
Detected on <GESVideoTestSource:nlesource2:src>
Description : when events/messages are created from another event/message, they should have their seqnums set to the original event/message seqnum
warning : received an unexpected flush stop event
Detected on <GESAudioTestSource:nlesource3:src>
Detected on <nlesource5:src>
Detected on <nlesource8:src>
Detected on <GESVideoTestSource:nlesource2:src>
issue : SEGMENT events that are part of the same pipeline 'operation' should have the same seqnum
Detected on <audio_nlecomposition1:src>
Detected on <audio_restriction_capsfilter2:sink, audio_restriction_capsfilter2:src>
Detected on <gesaudiotrack0:src>
Detected on <gestimeline0:track_0x220c440_src>
Detected on <tee1:sink, tee1:src_0, internal-sinks:audio_sink>
Detected on <audiotee:sink, audiotee:src_0, streamsynchronizer0:sink_1, streamsynchronizer0:src_1, abin:sink>
Detected on <aqueue:sink, aqueue:src, aconv:sink>
Detected on <conv:sink, conv:src, resample:sink, resample:src, volume:sink, volume:src>
Detected on <aconv:src>
Detected on <fakeaudiosink0:sink>
Detected on <sink:sink>
Detected on <video_nlecomposition0:src>
Detected on <video_restriction_capsfilter0:sink, video_restriction_capsfilter0:src>
Detected on <gesvideotrack0:src>
Description : when events/messages are created from another event/message, they should have their seqnums set to the original event/message seqnum
critical : The execution of an action did not properly happen
Detected on <check_layer_activness_gaps.scenario>
Details :
> check_layer_activness_gaps.scenario:22
22 | check-property, target-element-factory-name=videotestsrc, property-name=pattern, property-value="Blue"
>
> <src>::pattern expected value: '(gchararray)Blue' different than observed: '(gchararray)"100\%\ Black"'
dotfile : /var/home/thiblahute/devel/gstreamer/gstreamer/build/subprojects/gst-integration-testsuites/logs/9/check/gst-editing-services/check_layer_activness_gaps_it9.pipelines_dot_files/0:00:00.159441881-validate-report-critical-on-check_layer_activness_gaps.scenario-scenario::execution-error.dot
**Got criticals. Return value set to 18**:
* critical error
> check_layer_activness_gaps.scenario:22
22 | check-property, target-element-factory-name=videotestsrc, property-name=pattern, property-value="Blue"
>
> <src>::pattern expected value: '(gchararray)Blue' different than observed: '(gchararray)"100\%\ Black"'
Issues found: 5
You can mark the issues as 'known' by adding the following lines to the list of known issues
"FIXME 'check.gst-editing-services.check_layer_activness_gaps_it9' issues [REPORT A BUG in https://gitlab.freedesktop.org/gstreamer/ or use a proper bug description]": {
"tests": [
"check.gst-editing-services.check_layer_activness_gaps_it9"
],
"issues": [
{
'returncode': 18,
'sometimes': True,
},
{
"issue-id": "scenario::execution-error",
"summary": "The execution of an action did not properly happen",
"level": "critical",
"detected-on": "check_layer_activness_gaps.scenario",
# "details": "\n> check_layer_activness_gaps.scenario:22\n 22 | check-property, target-element-factory-name=videotestsrc, property-name=pattern, property-value="Blue"\n >\n > <src>::pattern expected value: '(gchararray)Blue' different than observed: '(gchararray)"100\%\ Black"'",
},
],
},
Duration: 0.41796231269836426