edit_while_seeked_with_stop/check_layer_activness_gaps tests sometimes times out and gets gst_object_unref() criticals
See e.g. https://gitlab.freedesktop.org/Knopp/gst-libav/-/jobs/2623088
This seems to be relatively new
check.gst-editing-services.edit_while_seeked_with_stop
Command
GST_STATE_IGNORE_ELEMENTS='' GST_PLUGIN_PATH_1_0='/builds/Knopp/gst-libav/gst-build/build' GST_VALIDATE_LOGSDIR='/builds/Knopp/gst-libav/gst-build/build/subprojects/gst-editing-services/tests/check/edit_while_seeked_with_stop' CK_TIMEOUT_MULTIPLIER='2.0' GST_PLUGIN_SYSTEM_PATH_1_0='' GST_REGISTRY='/builds/Knopp/gst-libav/gst-build/build/subprojects/gst-editing-services/tests/check/scenarios.registry' CK_DEFAULT_TIMEOUT='20' /builds/Knopp/gst-libav/gst-build/build/subprojects/gst-editing-services/tools/ges-launch-1.0 --set-test-file /builds/Knopp/gst-libav/gst-build/subprojects/gst-editing-services/tests/check/scenarios/edit_while_seeked_with_stop.validatetest --mute
ges-launch-1.0 output
**-> Checking expectations file: '/builds/Knopp/gst-libav/gst-build/subprojects/gst-editing-services/tests/check/scenarios/edit_while_seeked_with_stop/flow-expectations/log-videosink-sink-expected'**
**-> Running scenario /builds/Knopp/gst-libav/gst-build/subprojects/gst-editing-services/tests/check/scenarios/edit_while_seeked_with_stop.validatetest on pipeline gespipeline0**
Executing add-clip (
- name=c0
- asset-id=GESTestClip
- layer-priority=0
- type=GESTestClip
- start=0
- duration=1
)
Executing set-child-properties (
- element-name=c0
- pattern=blue
- time-mode=time-code
- valignment=center
- halignment=center
)
Executing add-clip (
- name=c1
- asset-id=GESTestClip
- layer-priority=0
- type=GESTestClip
- start=1
- duration=1
)
Executing set-child-properties (
- element-name=c1
- pattern=red
- time-mode=time-code
- valignment=center
- halignment=center
)
Executing commit ( )
Executing play ( )
-> Action play done (duration: 0:00:00.093473547)
Executing seek (
- start=0
- stop=0.5
- flags=accurate+flush
)
-> Action seek done (duration: 0:00:00.040076513)
Executing edit (
- element-name=c0
- position=0.5
- edge=end
- edit-mode=trim
)
Executing commit ( )
-> Action commit done (duration: 0:00:00.013001284)
Executing crank-clock (
- expected-elapsed-time=0
)
Executing (1/5)crank-clock (
- expected-elapsed-time=0.10000000000000001
)
Executing (2/5)crank-clock (
- expected-elapsed-time=0.10000000000000001
)
Executing (3/5)crank-clock (
- expected-elapsed-time=0.10000000000000001
)
Executing (4/5)crank-clock (
- expected-elapsed-time=0.10000000000000001
)
Executing (5/5)crank-clock (
- expected-elapsed-time=0.10000000000000001
)
<position: 0:00:00.500000000 duration: 0:00:02.000000001 speed: 1.000000 />
Executing check-position (
- on-message=eos
- expected-position=0.5
)
Executing seek (
- start=0.5
- stop=1
- flags=accurate+flush
)
-> Action seek done (duration: 0:00:00.021413964)
Executing edit (
- element-name=c1
- position=5
- edge=end
- edit-mode=trim
)
Executing commit ( )
-> Action commit done (duration: 0:00:00.016182807)
Executing crank-clock (
- expected-elapsed-time=0
)
Executing (1/5)crank-clock (
- expected-elapsed-time=0.10000000000000001
)
Executing (2/5)crank-clock (
- expected-elapsed-time=0.10000000000000001
)
Executing (3/5)crank-clock (
- expected-elapsed-time=0.10000000000000001
)
Executing (4/5)crank-clock (
- expected-elapsed-time=0.10000000000000001
)
Executing (5/5)crank-clock (
- expected-elapsed-time=0.10000000000000001
)
<position: 0:00:01.000000000 duration: 0:00:05.000000001 speed: 1.000000 />
Executing check-position (
- on-message=eos
- expected-position=1
)
Executing edit (
- element-name=c1
- position=3
- edge=end
- edit-mode=trim
)
Executing commit ( )
-> Action commit done (duration: 0:00:00.063422726)
<position: 0:00:01.000000000 duration: 0:00:03.000000001 speed: 1.000000 />
Executing check-position (
- on-message=eos
- expected-position=1
)
Executing seek (
- start=1
- stop=2
- flags=accurate+flush
)
-> Action seek done (duration: 0:00:00.011962700)
Executing check-position (
- expected-position=1
)
Executing edit (
- element-name=c1
- position=1.5
- edge=end
- edit-mode=trim
)
Executing commit ( )
<position: 0:00:01.000000000 duration: 0:00:01.500000001 speed: 1.000000 />
<position: 0:00:01.000000000 duration: 0:00:01.500000001 speed: 1.000000 />
[...]
<position: 0:00:01.000000000 duration: 0:00:01.500000001 speed: 1.000000 />
<position: 0:00:01.000000000 duration: 0:00:01.500000001 speed: 1.000000 />Stack trace
[New LWP 31981]
[New LWP 32013]
[New LWP 32017]
[New LWP 32018]
[New LWP 32052]
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
[Thread debugging using libthread_db enabled]
Using host libthread_db library "/lib64/libthread_db.so.1".
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
warning: Loadable section ".note.gnu.property" outside of ELF segments
0x00007f470fc226f7 in __GI___poll (fds=0x2372610, nfds=3, timeout=10) at ../sysdeps/unix/sysv/linux/poll.c:29
29 return SYSCALL_CANCEL (poll, fds, nfds, timeout);
warning: File "/builds/Knopp/gst-libav/gst-build/.gdbinit" auto-loading has been declined by your `auto-load safe-path' set to "$debugdir:$datadir/auto-load".
To enable execution of this file add
add-auto-load-safe-path /builds/Knopp/gst-libav/gst-build/.gdbinit
line to your configuration file "/root/.gdbinit".
To completely disable this security protection add
set auto-load safe-path /
line to your configuration file "/root/.gdbinit".
For more information about this security protection see the
"Auto-loading safe path" section in the GDB manual. E.g., run from the shell:
info "(gdb)Auto-loading safe path"
Thread 6 (Thread 0x7f4705516700 (LWP 32052)):
#0 0x00007f470fc27e0d in syscall () at ../sysdeps/unix/sysv/linux/x86_64/syscall.S:38
#1 0x00007f470ffc2e53 in g_cond_wait (cond=cond@entry=0x2348b20, mutex=mutex@entry=0x2348b18) at ../glib/gthread-posix.c:1413
#2 0x00007f470fae45b5 in gst_base_sink_wait_preroll (sink=sink@entry=0x23489f0 [GstFakeSink]) at ../subprojects/gstreamer/libs/gst/base/gstbasesink.c:2426
#3 0x00007f470fae4adb in gst_base_sink_do_preroll (sink=sink@entry=0x23489f0 [GstFakeSink], obj=obj@entry=0x7f46f003f000) at ../subprojects/gstreamer/libs/gst/base/gstbasesink.c:2520
#4 0x00007f470fae543c in gst_base_sink_do_sync (basesink=basesink@entry=0x23489f0 [GstFakeSink], obj=obj@entry=0x7f46f003f000, late=late@entry=0x7f4705514458, step_end=step_end@entry=0x7f470551445c) at ../subprojects/gstreamer/libs/gst/base/gstbasesink.c:2728
#5 0x00007f470fae676d in gst_base_sink_chain_unlocked (basesink=basesink@entry=0x23489f0 [GstFakeSink], obj=obj@entry=0x7f46f003f000, is_list=is_list@entry=0, pad=<optimized out>) at ../subprojects/gstreamer/libs/gst/base/gstbasesink.c:3908
#6 0x00007f470fae8850 in gst_base_sink_chain_main (basesink=0x23489f0 [GstFakeSink], pad=<optimized out>, obj=0x7f46f003f000, is_list=0) at ../subprojects/gstreamer/libs/gst/base/gstbasesink.c:4067
#7 0x00007f471008a312 in gst_validate_pad_monitor_chain_func (pad=0x20362a0 [GstPad], parent=0x23489f0 [GstFakeSink], buffer=0x7f46f003f000) at ../subprojects/gst-devtools/validate/gst/validate/gst-validate-pad-monitor.c:2350
#8 0x00007f4710195b11 in gst_pad_chain_data_unchecked (pad=pad@entry=0x20362a0 [GstPad], type=type@entry=4112, data=data@entry=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstpad.c:4399
#9 0x00007f4710197fa1 in gst_pad_push_data (pad=pad@entry=0x20467c0 [GstProxyPad], type=type@entry=4112, data=data@entry=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstpad.c:4655
#10 0x00007f471019fee3 in gst_pad_push (pad=pad@entry=0x20467c0 [GstProxyPad], buffer=buffer@entry=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstpad.c:4774
#11 0x00007f47101829a3 in gst_proxy_pad_chain_default (pad=<optimized out>, parent=<optimized out>, buffer=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstghostpad.c:127
#12 0x00007f471008a312 in gst_validate_pad_monitor_chain_func (pad=0x2038570 [GstGhostPad], parent=0x2349080 [GstFakeVideoSink], buffer=0x7f46f003f000) at ../subprojects/gst-devtools/validate/gst/validate/gst-validate-pad-monitor.c:2350
#13 0x00007f4710195b11 in gst_pad_chain_data_unchecked (pad=pad@entry=0x2038570 [GstGhostPad], type=type@entry=4112, data=data@entry=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstpad.c:4399
#14 0x00007f4710197fa1 in gst_pad_push_data (pad=pad@entry=0x23d8e00 [GstGhostPad], type=type@entry=4112, data=data@entry=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstpad.c:4655
#15 0x00007f471019fee3 in gst_pad_push (pad=pad@entry=0x23d8e00 [GstGhostPad], buffer=buffer@entry=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstpad.c:4774
#16 0x00007f47101829a3 in gst_proxy_pad_chain_default (pad=<optimized out>, parent=<optimized out>, buffer=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstghostpad.c:127
#17 0x00007f4710195b11 in gst_pad_chain_data_unchecked (pad=pad@entry=0x7f47000351c0 [GstProxyPad], type=type@entry=4112, data=data@entry=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstpad.c:4399
#18 0x00007f4710197fa1 in gst_pad_push_data (pad=pad@entry=0x23d0b30 [GstPad], type=type@entry=4112, data=data@entry=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstpad.c:4655
#19 0x00007f471019fee3 in gst_pad_push (pad=0x23d0b30 [GstPad], buffer=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstpad.c:4774
#20 0x00007f470faf442b in gst_base_transform_chain (pad=<optimized out>, parent=0x7f46fc048d60 [GstVideoConvert], buffer=<optimized out>) at ../subprojects/gstreamer/libs/gst/base/gstbasetransform.c:2369
#21 0x00007f471008a312 in gst_validate_pad_monitor_chain_func (pad=0x23d0d80 [GstPad], parent=0x7f46fc048d60 [GstVideoConvert], buffer=0x7f46f003f000) at ../subprojects/gst-devtools/validate/gst/validate/gst-validate-pad-monitor.c:2350
#22 0x00007f4710195b11 in gst_pad_chain_data_unchecked (pad=pad@entry=0x23d0d80 [GstPad], type=type@entry=4112, data=data@entry=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstpad.c:4399
#23 0x00007f4710197fa1 in gst_pad_push_data (pad=pad@entry=0x23d08e0 [GstPad], type=type@entry=4112, data=data@entry=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstpad.c:4655
#24 0x00007f471019fee3 in gst_pad_push (pad=0x23d08e0 [GstPad], buffer=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstpad.c:4774
#25 0x00007f470faf442b in gst_base_transform_chain (pad=<optimized out>, parent=0x7f46fc01f950 [GstVideoBalance], buffer=<optimized out>) at ../subprojects/gstreamer/libs/gst/base/gstbasetransform.c:2369
#26 0x00007f471008a312 in gst_validate_pad_monitor_chain_func (pad=0x23d0690 [GstPad], parent=0x7f46fc01f950 [GstVideoBalance], buffer=0x7f46f003f000) at ../subprojects/gst-devtools/validate/gst/validate/gst-validate-pad-monitor.c:2350
#27 0x00007f4710195b11 in gst_pad_chain_data_unchecked (pad=pad@entry=0x23d0690 [GstPad], type=type@entry=4112, data=data@entry=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstpad.c:4399
#28 0x00007f4710197fa1 in gst_pad_push_data (pad=pad@entry=0x23d0fd0 [GstPad], type=type@entry=4112, data=data@entry=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstpad.c:4655
#29 0x00007f471019fee3 in gst_pad_push (pad=0x23d0fd0 [GstPad], buffer=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstpad.c:4774
#30 0x00007f470faf442b in gst_base_transform_chain (pad=<optimized out>, parent=0x7f46fc041900 [GstVideoScale], buffer=<optimized out>) at ../subprojects/gstreamer/libs/gst/base/gstbasetransform.c:2369
#31 0x00007f471008a312 in gst_validate_pad_monitor_chain_func (pad=0x23d1220 [GstPad], parent=0x7f46fc041900 [GstVideoScale], buffer=0x7f46f003f000) at ../subprojects/gst-devtools/validate/gst/validate/gst-validate-pad-monitor.c:2350
#32 0x00007f4710195b11 in gst_pad_chain_data_unchecked (pad=pad@entry=0x23d1220 [GstPad], type=type@entry=4112, data=data@entry=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstpad.c:4399
#33 0x00007f4710197fa1 in gst_pad_push_data (pad=pad@entry=0x23d16c0 [GstPad], type=type@entry=4112, data=data@entry=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstpad.c:4655
#34 0x00007f471019fee3 in gst_pad_push (pad=0x23d16c0 [GstPad], buffer=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstpad.c:4774
#35 0x00007f470faf442b in gst_base_transform_chain (pad=<optimized out>, parent=0x7f46fc041140 [GstVideoConvert], buffer=<optimized out>) at ../subprojects/gstreamer/libs/gst/base/gstbasetransform.c:2369
#36 0x00007f471008a312 in gst_validate_pad_monitor_chain_func (pad=0x23d1470 [GstPad], parent=0x7f46fc041140 [GstVideoConvert], buffer=0x7f46f003f000) at ../subprojects/gst-devtools/validate/gst/validate/gst-validate-pad-monitor.c:2350
#37 0x00007f4710195b11 in gst_pad_chain_data_unchecked (pad=pad@entry=0x23d1470 [GstPad], type=type@entry=4112, data=data@entry=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstpad.c:4399
#38 0x00007f4710197fa1 in gst_pad_push_data (pad=pad@entry=0x7f4700034f60 [GstProxyPad], type=type@entry=4112, data=data@entry=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstpad.c:4655
#39 0x00007f471019fee3 in gst_pad_push (pad=pad@entry=0x7f4700034f60 [GstProxyPad], buffer=buffer@entry=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstpad.c:4774
#40 0x00007f47101829a3 in gst_proxy_pad_chain_default (pad=<optimized out>, parent=<optimized out>, buffer=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstghostpad.c:127
#41 0x00007f471008a312 in gst_validate_pad_monitor_chain_func (pad=0x23d8b80 [GstGhostPad], parent=0x7f46fc01a050 [GstPlaySinkVideoConvert], buffer=0x7f46f003f000) at ../subprojects/gst-devtools/validate/gst/validate/gst-validate-pad-monitor.c:2350
#42 0x00007f4710195b11 in gst_pad_chain_data_unchecked (pad=pad@entry=0x23d8b80 [GstGhostPad], type=type@entry=4112, data=data@entry=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstpad.c:4399
#43 0x00007f4710197fa1 in gst_pad_push_data (pad=pad@entry=0x20372d0 [GstPad], type=type@entry=4112, data=data@entry=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstpad.c:4655
#44 0x00007f471019fee3 in gst_pad_push (pad=0x20372d0 [GstPad], buffer=buffer@entry=0x7f46f003f000) at ../subprojects/gstreamer/gst/gstpad.c:4774
#45 0x00007f470e4adf1e in gst_queue_push_one (queue=0x7f4700014600 [GstQueue]) at ../subprojects/gstreamer/plugins/elements/gstqueue.c:1384
#46 0x00007f470e4adf1e in gst_queue_loop (pad=<optimized out>) at ../subprojects/gstreamer/plugins/elements/gstqueue.c:1537
#47 0x00007f47101d113f in gst_task_func (task=0x7f46f003fdd0 [GstTask]) at ../subprojects/gstreamer/gst/gsttask.c:328
#48 0x00007f470ffa0ef4 in g_thread_pool_thread_proxy (data=<optimized out>) at ../glib/gthreadpool.c:308
#49 0x00007f470ffa04c2 in g_thread_proxy (data=0x7f46fc001850) at ../glib/gthread.c:805
#50 0x00007f470f3bb4c0 in start_thread (arg=<optimized out>) at pthread_create.c:479
#51 0x00007f470fc2d163 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
Thread 5 (Thread 0x7f470642c700 (LWP 32018)):
#0 0x00007f470fc27e0d in syscall () at ../sysdeps/unix/sysv/linux/x86_64/syscall.S:38
#1 0x00007f470ffc2e53 in g_cond_wait (cond=cond@entry=0x7f46f003f890, mutex=mutex@entry=0x7f46f003f848) at ../glib/gthread-posix.c:1413
#2 0x00007f47101d126b in gst_task_func (task=0x7f46f003f830 [GstTask]) at ../subprojects/gstreamer/gst/gsttask.c:313
#3 0x00007f470ffa0ef4 in g_thread_pool_thread_proxy (data=<optimized out>) at ../glib/gthreadpool.c:308
#4 0x00007f470ffa04c2 in g_thread_proxy (data=0x7f4700017ed0) at ../glib/gthread.c:805
#5 0x00007f470f3bb4c0 in start_thread (arg=<optimized out>) at pthread_create.c:479
#6 0x00007f470fc2d163 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
Thread 4 (Thread 0x7f4706c2d700 (LWP 32017)):
#0 0x00007f470fc27e0d in syscall () at ../sysdeps/unix/sysv/linux/x86_64/syscall.S:38
#1 0x00007f470ffc2e53 in g_cond_wait (cond=cond@entry=0x7f46fc0101d0, mutex=mutex@entry=0x7f46fc010188) at ../glib/gthread-posix.c:1413
#2 0x00007f47101d126b in gst_task_func (task=0x7f46fc010170 [GstTask]) at ../subprojects/gstreamer/gst/gsttask.c:313
#3 0x00007f470ffa0ef4 in g_thread_pool_thread_proxy (data=<optimized out>) at ../glib/gthreadpool.c:308
#4 0x00007f470ffa04c2 in g_thread_proxy (data=0x7f4700017e80) at ../glib/gthread.c:805
#5 0x00007f470f3bb4c0 in start_thread (arg=<optimized out>) at pthread_create.c:479
#6 0x00007f470fc2d163 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
Thread 3 (Thread 0x7f4707c2f700 (LWP 32013)):
#0 0x00007f470fc27e0d in syscall () at ../sysdeps/unix/sysv/linux/x86_64/syscall.S:38
#1 0x00007f470ffc2e53 in g_cond_wait (cond=0x2350140, mutex=0x2350138) at ../glib/gthread-posix.c:1413
#2 0x00007f4707ca945e in _execute_actions (comp=0x23501d0 [NleComposition]) at ../subprojects/gst-editing-services/plugins/nle/nlecomposition.c:386
#3 0x00007f47101d113f in gst_task_func (task=0x204e290 [GstTask]) at ../subprojects/gstreamer/gst/gsttask.c:328
#4 0x00007f470ffa0ef4 in g_thread_pool_thread_proxy (data=<optimized out>) at ../glib/gthreadpool.c:308
#5 0x00007f470ffa04c2 in g_thread_proxy (data=0x2291320) at ../glib/gthread.c:805
#6 0x00007f470f3bb4c0 in start_thread (arg=<optimized out>) at pthread_create.c:479
#7 0x00007f470fc2d163 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
Thread 2 (Thread 0x7f470d42b700 (LWP 31981)):
#0 0x00007f470fc226f7 in __GI___poll (fds=0x1dc26b0, nfds=1, timeout=-1) at ../sysdeps/unix/sysv/linux/poll.c:29
#1 0x00007f470ff771ce in g_main_context_poll (priority=<optimized out>, n_fds=1, fds=0x1dc26b0, timeout=<optimized out>, context=0x22ddd60) at ../glib/gmain.c:4228
#2 0x00007f470ff771ce in g_main_context_iterate (context=context@entry=0x22ddd60, block=block@entry=1, dispatch=dispatch@entry=1, self=<optimized out>) at ../glib/gmain.c:3922
#3 0x00007f470ff77303 in g_main_context_iteration (context=0x22ddd60, may_block=may_block@entry=1) at ../glib/gmain.c:3988
#4 0x00007f470ff77351 in glib_worker_main (data=<optimized out>) at ../glib/gmain.c:5868
#5 0x00007f470ffa04c2 in g_thread_proxy (data=0x228fde0) at ../glib/gthread.c:805
#6 0x00007f470f3bb4c0 in start_thread (arg=<optimized out>) at pthread_create.c:479
#7 0x00007f470fc2d163 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
Thread 1 (Thread 0x7f470eea1500 (LWP 31845)):
#0 0x00007f470fc226f7 in __GI___poll (fds=0x2372610, nfds=3, timeout=10) at ../sysdeps/unix/sysv/linux/poll.c:29
#1 0x00007f470ff771ce in g_main_context_poll (priority=<optimized out>, n_fds=3, fds=0x2372610, timeout=<optimized out>, context=0x1dc80e0) at ../glib/gmain.c:4228
#2 0x00007f470ff771ce in g_main_context_iterate (context=context@entry=0x1dc80e0, block=block@entry=1, dispatch=dispatch@entry=1, self=<optimized out>) at ../glib/gmain.c:3922
#3 0x00007f470ff77303 in g_main_context_iteration (context=context@entry=0x1dc80e0, may_block=may_block@entry=1) at ../glib/gmain.c:3988
#4 0x00007f470fdd2985 in g_application_run (application=0x1dc7140 [GESLauncher], argc=argc@entry=4, argv=argv@entry=0x7fff957e6ab8) at ../gio/gapplication.c:2519
#5 0x0000000000404b10 in main (argc=4, argv=0x7fff957e6ab8) at ../subprojects/gst-editing-services/tools/ges-launch.c:35
[Inferior 1 (process 31845) detached]
```interrupt received.
validateflowoverride0 --> Checking that flow /builds/Knopp/gst-libav/gst-build/subprojects/gst-editing-services/tests/check/scenarios/edit_while_seeked_with_stop/flow-expectations/log-videosink-sink-expected matches expected flow /builds/Knopp/gst-libav/gst-build/build/subprojects/gst-editing-services/tests/check/edit_while_seeked_with_stop/edit_while_seeked_with_stop/log-videosink-sink-actual
$ diff /builds/Knopp/gst-libav/gst-build/subprojects/gst-editing-services/tests/check/scenarios/edit_while_seeked_with_stop/flow-expectations/log-videosink-sink-expected /builds/Knopp/gst-libav/gst-build/build/subprojects/gst-editing-services/tests/check/edit_while_seeked_with_stop/edit_while_seeked_with_stop/log-videosink-sink-actual
0:02:01.773046212 31845 0x1dc1830 ERROR validate gst-validate-reporter.c:197:gst_validate_report_valist: <(null)> 3148 (critical) : validateflow: The recorded log does not match the expectation file. : Mismatch error in pad videosink:sink, line 45. Expected:
buffer: pts=0:00:01.100000000, dur=0:00:00.100000000
Actual:
<nothing>
--- /builds/Knopp/gst-libav/gst-build/subprojects/gst-editing-services/tests/check/scenarios/edit_while_seeked_with_stop/flow-expectations/log-videosink-sink-expected 2020-05-09 19:11:17.495787805 +0000
+++ /builds/Knopp/gst-libav/gst-build/build/subprojects/gst-editing-services/tests/check/edit_while_seeked_with_stop/edit_while_seeked_with_stop/log-videosink-sink-actual 2020-05-09 19:18:05.521578441 +0000
@@ -42,11 +42,3 @@
event flush-stop: GstEventFlushStop, reset-time=(boolean)true;
event segment: format=TIME, start=0:00:01.000000000, offset=0:00:00.000000000, stop=0:00:01.500000000, flags=0x01, time=0:00:01.000000000, base=0:00:00.000000000, position=none
buffer: pts=0:00:01.000000000, dur=0:00:00.100000000
-buffer: pts=0:00:01.100000000, dur=0:00:00.100000000
-buffer: pts=0:00:01.200000000, dur=0:00:00.100000000
-buffer: pts=0:00:01.300000000, dur=0:00:00.100000000
-buffer: pts=0:00:01.400000000, dur=0:00:00.100000000
-event caps: video/x-raw, format=(string)I420, width=(int)1280, height=(int)720, framerate=(fraction)10/1, chroma-site=(string)jpeg, colorimetry=(string)bt601;
-event segment: format=TIME, start=0:00:01.500000000, offset=0:00:00.000000000, stop=0:00:01.500000001, flags=0x01, time=0:00:01.500000000, base=0:00:00.500000000, position=none
-buffer: pts=0:00:01.500000000, dur=0:00:00.000000001
-event eos: (no structure)
issue : FLUSH_STOP events that are part of the same pipeline 'operation' should have the same seqnum
Detected on <video_nlecomposition0: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 <mixing-operation:src>
issue : FLUSH_START events that are part of the same pipeline 'operation' should have the same seqnum
Detected on <outputselector0:src_1>
Detected on <timecodestamper0:src>
Detected on <timeoverlay0:src>
Detected on <mixing-operation:src>
Detected on <outputselector1:src_1>
Detected on <timecodestamper1:src>
Detected on <timeoverlay1:src>
Description : when events/messages are created from another event/message, they should have their seqnums set to the original event/message seqnum
warning : a new segment event has different value than the received one
Detected on <gessmartmixer0-compositor:src>
Description : when receiving a new segment, an element should push an equivalent segment downstream
warning : a serialized event received should be pushed in the same 'time' as it was received
Detected on <gessmartmixer0-compositor: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 : Buffer didn't have expected DISCONT flag
Detected on <gessmartmixer0-compositor:src, identity0:sink, identity0:src>
Detected on <gessmartmixer0:src>
Detected on <mixing-operation:src>
Detected on <video_nlecomposition0:src>
Detected on <video_restriction_capsfilter0:sink, video_restriction_capsfilter0:src, gessmartmixer0:src, mixing-operation:src, video_nlecomposition0:src, video_restriction_capsfilter0:sink>
Detected on <gesvideotrack0:src>
Detected on <gestimeline0:track_0x2038840_src>
Detected on <tee0:sink, tee0:src_0, internal-sinks:video_sink, gesvideotrack0:src, gestimeline0:track_0x2038840_src, tee0:sink>
Detected on <streamsynchronizer0:sink_0, streamsynchronizer0:src_0, vbin:sink, streamsynchronizer0:sink_0>
Detected on <vqueue:sink, vqueue:src, vconv:sink, vqueue:sink>
Detected on <conv:sink, conv:src, scale:sink, scale:src, videobalance:sink, videobalance:src, conv2:sink, conv2:src, conv:sink>
Detected on <vconv:src>
Detected on <videosink:sink>
Detected on <sink:sink, vconv:src, videosink:sink, sink:sink>
Description : Buffers after SEGMENT and FLUSH must have a DISCONT flag
warning : buffer was received before a segment
Detected on <gessmartmixer0:gessmartmixerpad0>
Detected on <gessmartmixer0:gessmartmixerpad1>
Detected on <gessmartmixer0:gessmartmixerpad2>
Detected on <gessmartmixer0:gessmartmixerpad3>
Detected on <gessmartmixer0:gessmartmixerpad4>
Description : in push mode, a segment event must be received before a buffer
issue : We got a g_log issue
Detected on <gespipeline0>
Details : posix_spawn avoided (fd close requested) (child_setup specified)
warning : received the same caps twice
Detected on <identity0:sink>
critical : The recorded log does not match the expectation file.
Detected on <(null)>
Details : Mismatch error in pad videosink:sink, line 45. Expected:
buffer: pts=0:00:01.100000000, dur=0:00:00.100000000
Actual:
<nothing>
backtrace :
gst_debug_get_stack_trace (gstinfo.c:3021)
gst_validate_report_new (gst-validate-report.c:810)
gst_validate_report_valist (gst-validate-reporter.c:188)
gst_validate_report (gst-validate-reporter.c:324)
show_mismatch_error (gstvalidateflow.c:434)
runner_stopping (gstvalidateflow.c:502)
_g_closure_invoke_va (gclosure.c:873)
g_signal_emit_valist (gsignal.c:3300)
g_signal_emit (gsignal.c:3447)
gst_validate_runner_exit (gst-validate-runner.c:899)
ges_validate_clean (ges-validate.c:191)
_shutdown (ges-launcher.c:990)
g_closure_invoke (gclosure.c:810)
signal_emit_unlocked_R (gsignal.c:3673)
g_signal_emit_valist (gsignal.c:3391)
g_signal_emit (gsignal.c:3447)
g_application_run (gapplication.c:2525)
main (ges-launch.c:35)
__libc_start_main (libc-start.c:308)
_start (/builds/Knopp/gst-libav/gst-build/build/subprojects/gst-editing-services/tools/ges-launch-1.0:0x404b6a)
Description : The recorded log does not match the expectation file.
**Got criticals. Return value set to 18**:
* critical error Mismatch error in pad videosink:sink, line 45. Expected:
buffer: pts=0:00:01.100000000, dur=0:00:00.100000000
Actual:
<nothing>
Issues found: 10
Stack trace:
gst_debug_get_stack_trace (gstinfo.c:3021)
gst_validate_default_log_hanlder (gst-validate-reporter.c:269)
g_logv (gmessages.c:1350)
g_log (gmessages.c:1413)
_free_entries (ges-asset.c:619)
g_hash_table_remove_all_nodes.part.0 (ghash.c:706)
g_hash_table_unref (ghash.c:628)
g_hash_table_remove_all_nodes.part.0 (ghash.c:706)
g_hash_table_remove_all (ghash.c:628)
g_hash_table_destroy (ghash.c:1482)
ges_asset_cache_deinit (ges-asset.c:783)
ges_deinit (ges.c:251)
main (ges-launch.c:41)
__libc_start_main (libc-start.c:308)
_start (/builds/Knopp/gst-libav/gst-build/build/subprojects/gst-editing-services/tools/ges-launch-1.0:0x404b6a)
(ges-launch-1.0:31845): GStreamer-CRITICAL **: 19:18:05.611: gst_object_unref: assertion '((GObject *) object)->ref_count > 0' failed
**You can mark the issues as 'known' by adding the following lines to the list of known issues**
``` python
"FIXME 'check.gst-editing-services.edit_while_seeked_with_stop' issues [REPORT A BUG in https://gitlab.freedesktop.org/gstreamer/ or use a proper bug description]": {
"tests": [
"check.gst-editing-services.edit_while_seeked_with_stop"
],
"issues": [
{
'returncode': None,
'sometimes': True,
},
],
},Duration: 121.94826054573059
Edited by Sebastian Dröge