gst-editing-services issueshttps://gitlab.freedesktop.org/gstreamer/gst-editing-services/-/issues2020-05-19T13:53:46Zhttps://gitlab.freedesktop.org/gstreamer/gst-editing-services/-/issues/108edit_while_seeked_with_stop/check_layer_activness_gaps tests sometimes times ...2020-05-19T13:53:46ZSebastian Drögeedit_while_seeked_with_stop/check_layer_activness_gaps tests sometimes times out and gets gst_object_unref() criticalsSee 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
``` bash
GST_STATE_IGNORE_ELEMENTS='' GST_PLUGIN_PATH_1_0='/bu...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
``` bash
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.94826054573059https://gitlab.freedesktop.org/gstreamer/gst-editing-services/-/issues/27Seeking in paused might be unreliable and lead to a sensible position offset2021-09-24T12:21:00ZBugzilla Migration UserSeeking in paused might be unreliable and lead to a sensible position offset## Submitted by Thibault Saunier `@thiblahute`
**[Link to original bug (#771122)](https://bugzilla.gnome.org/show_bug.cgi?id=771122)**
## Description
When seeking on files containing mp3 stream (it seems to happen only in that case,...## Submitted by Thibault Saunier `@thiblahute`
**[Link to original bug (#771122)](https://bugzilla.gnome.org/show_bug.cgi?id=771122)**
## Description
When seeking on files containing mp3 stream (it seems to happen only in that case, but that might be wrong) position sometimes is not accurate and lead to the following error in validate:
==== Got criticals, Return value set to 18 ====
Critical error Reported position after accurate seek in PAUSED state should be exactly what the user asked for 0:00:00.250000000 != 0:00:00.200000000
https://ci.gstreamer.net/job/GStreamer-master-validate/3710/testReport/junit/ges.playback.scrub_forward_seeking.test_mixing/audio_only/mp3_h264_mp4/