Bug 290537 – [GStreamer][1.26] Critical warnings in volume element

• Status: RESOLVED
• Resolution: FIXED
• Component: Platform

[Philippe Normand]

When running imported/w3c/web-platform-tests/mixed-content/gen/ tests


Thread 1 (Thread 0x7f26217fa6c0 (LWP 715620)):
#0  g_logv (log_domain=0x7f28ef2509e3 "GStreamer", log_level=G_LOG_LEVEL_CRITICAL, format=<optimized out>, args=args@entry=0x7f26217f94a0) at ../glib/gmessages.c:1422
#1  0x00007f28ef3e07a3 in g_log (log_domain=log_domain@entry=0x7f28ef2509e3 "GStreamer", log_level=log_level@entry=G_LOG_LEVEL_CRITICAL, format=format@entry=0x7f28ef438f92 "%s: assertion '%s' failed") at ../glib/gmessages.c:1460
#2  0x00007f28ef3e361d in g_return_if_fail_warning (log_domain=log_domain@entry=0x7f28ef2509e3 "GStreamer", pretty_function=pretty_function@entry=0x7f28ef27d350 <__func__.8.lto_priv.34> "gst_segment_to_stream_time", expression=expression@entry=0x7f28ef25a5e3 "segment->format == format") at ../glib/gmessages.c:2930
#3  0x00007f28ef209e5a in gst_segment_to_stream_time (segment=segment@entry=0x55bab0a771c0, format=format@entry=GST_FORMAT_TIME, position=<optimized out>) at ../gst/gstsegment.c:528
#4  0x00007f28543810e9 in volume_before_transform (base=0x55bab0a770a0 [GstBaseTransform|volume], buffer=<optimized out>) at ../gst/volume/gstvolume.c:859
#5  0x00007f28ec0c8f73 in gst_base_transform_chain (pad=pad@entry=0x55bab0a77530 [GstPad|sink], parent=parent@entry=0x55bab0a770a0 [GstObject|volume], buffer=buffer@entry=0x7f260802a210) at ../libs/gst/base/gstbasetransform.c:2339
#6  0x00007f28ef1e791c in gst_pad_chain_data_unchecked (pad=pad@entry=0x55bab0a77530 [GstPad|sink], type=type@entry=4112, data=data@entry=0x7f260802a210) at ../gst/gstpad.c:4518
#7  0x00007f28ef1eb634 in gst_pad_push_data (pad=pad@entry=0x55bab0a73e50 [GstPad|src], type=type@entry=4112, data=data@entry=0x7f260802a210) at ../gst/gstpad.c:4811
#8  0x00007f28ef1eba04 in gst_pad_push (pad=0x55bab0a73e50 [GstPad|src], buffer=0x7f260802a210) at ../gst/gstpad.c:4930
#9  0x00007f28ec0c904c in gst_base_transform_chain (pad=pad@entry=0x55bab0a73a40 [GstPad|sink], parent=parent@entry=0x55bab0a734d0 [GstObject|audioresample0], buffer=buffer@entry=0x7f260802a210) at ../libs/gst/base/gstbasetransform.c:2391
#10 0x00007f28ef1e791c in gst_pad_chain_data_unchecked (pad=pad@entry=0x55bab0a73a40 [GstPad|sink], type=type@entry=4112, data=data@entry=0x7f260802a210) at ../gst/gstpad.c:4518
#11 0x00007f28ef1eb634 in gst_pad_push_data (pad=pad@entry=0x55bab0a6f340 [GstPad|src], type=type@entry=4112, data=data@entry=0x7f260802a210) at ../gst/gstpad.c:4811
#12 0x00007f28ef1eba04 in gst_pad_push (pad=0x55bab0a6f340 [GstPad|src], buffer=0x7f260802a210) at ../gst/gstpad.c:4930
#13 0x00007f28ec0c904c in gst_base_transform_chain (pad=pad@entry=0x55bab0a6ef10 [GstPad|sink], parent=parent@entry=0x55bab0a6e960 [GstObject|audioconvert0], buffer=buffer@entry=0x7f260802a210) at ../libs/gst/base/gstbasetransform.c:2391
#14 0x00007f28ef1e791c in gst_pad_chain_data_unchecked (pad=pad@entry=0x55bab0a6ef10 [GstPad|sink], type=type@entry=4112, data=data@entry=0x7f260802a210) at ../gst/gstpad.c:4518
#15 0x00007f28ef1eb634 in gst_pad_push_data (pad=pad@entry=0x55bab0a69390 [GstPad|src], type=type@entry=4112, data=data@entry=0x7f260802a210) at ../gst/gstpad.c:4811
#16 0x00007f28ef1eba04 in gst_pad_push (pad=0x55bab0a69390 [GstPad|src], buffer=buffer@entry=0x7f260802a210) at ../gst/gstpad.c:4930
#17 0x00007f28543de488 in gst_queue_push_one (queue=0x55bab0a68af0 [GstQueue|queue0]) at ../plugins/elements/gstqueue.c:1485
#18 gst_queue_loop (pad=<optimized out>) at ../plugins/elements/gstqueue.c:1640
#19 0x00007f28ef224654 in gst_task_func (task=0x7f26140f5bb0 [GstTask|queue0:src]) at ../gst/gsttask.c:399
#20 0x00007f28ef40e4f2 in g_thread_pool_thread_proxy (data=<optimized out>) at ../glib/gthreadpool.c:350
#21 0x00007f28ef40b862 in g_thread_proxy (data=0x7f28780018e0) at ../glib/gthread.c:831
#22 0x00007f28eebb8e39 in start_thread (arg=<optimized out>) at pthread_create.c:444
#23 0x00007f28eec408c4 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:100

STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.303: ../gst/gstpad.c:4777:gst_pad_push_data:<oggdemux0:src_4deedab9> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.303: ../gst/gstpad.c:4487:gst_pad_chain_data_unchecked:<multiqueue0:sink_0> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.303: ../gst/gstpad.c:4777:gst_pad_push_data:<multiqueue0:src_0> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.303: ../gst/gstpad.c:4777:gst_pad_push_data:<oggdemux0:src_5d3faa93> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.303: ../gst/gstpad.c:4487:gst_pad_chain_data_unchecked:<multiqueue0:sink_1> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.303: ../gst/gstpad.c:4487:gst_pad_chain_data_unchecked:<theoradec0:sink> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.303: ../gst/gstpad.c:4777:gst_pad_push_data:<multiqueue0:src_1> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.303: ../gst/gstpad.c:4487:gst_pad_chain_data_unchecked:<vorbisdec0:sink> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4777:gst_pad_push_data:<vorbisdec0:src> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4487:gst_pad_chain_data_unchecked:<src_1:proxypad9> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4777:gst_pad_push_data:<decodebin0:src_1> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4487:gst_pad_chain_data_unchecked:<src_1:proxypad11> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4777:gst_pad_push_data:<uridecodebin0:src_1> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4487:gst_pad_chain_data_unchecked:<inputselector1:sink_0> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4777:gst_pad_push_data:<inputselector1:src> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4487:gst_pad_chain_data_unchecked:<playsink:audio_sink> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4777:gst_pad_push_data:<audio_sink:proxypad12> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4487:gst_pad_chain_data_unchecked:<audiotee:sink> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4777:gst_pad_push_data:<audiotee:src_0> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4487:gst_pad_chain_data_unchecked:<streamsynchronizer0:sink_1> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4777:gst_pad_push_data:<streamsynchronizer0:src_1> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4487:gst_pad_chain_data_unchecked:<abin:sink> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4777:gst_pad_push_data:<sink:proxypad21> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4487:gst_pad_chain_data_unchecked:<filter-convert:sink> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4777:gst_pad_push_data:<filter-convert:src> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4487:gst_pad_chain_data_unchecked:<scaletempo0:sink> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4777:gst_pad_push_data:<scaletempo0:src> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4487:gst_pad_chain_data_unchecked:<aqueue:sink> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4777:gst_pad_push_data:<aqueue:src> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4487:gst_pad_chain_data_unchecked:<aconv:sink> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4777:gst_pad_push_data:<sink:proxypad19> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4487:gst_pad_chain_data_unchecked:<conv:sink> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4777:gst_pad_push_data:<conv:src> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4487:gst_pad_chain_data_unchecked:<resample:sink> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4777:gst_pad_push_data:<resample:src> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4487:gst_pad_chain_data_unchecked:<src:proxypad20> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4777:gst_pad_push_data:<aconv:src> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4487:gst_pad_chain_data_unchecked:<audio-sink:sink> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4777:gst_pad_push_data:<sink:proxypad1> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4487:gst_pad_chain_data_unchecked:<audioTee:sink> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4777:gst_pad_push_data:<audioTee:src_0> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4487:gst_pad_chain_data_unchecked:<queue0:sink> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4777:gst_pad_push_data:<queue0:src> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4487:gst_pad_chain_data_unchecked:<audioconvert0:sink> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4777:gst_pad_push_data:<audioconvert0:src> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4487:gst_pad_chain_data_unchecked:<audioresample0:sink> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4777:gst_pad_push_data:<audioresample0:src> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-WARNING **: 06:45:02.312: ../gst/gstpad.c:4487:gst_pad_chain_data_unchecked:<volume:sink> Got data flow before segment event
STDERR: 
STDERR: (WPEWebProcess:715487): GStreamer-CRITICAL **: 06:45:02.312: gst_segment_to_stream_time: assertion 'segment->format == format' failed

[Philippe Normand]

Might be related with this issue in oggdemux... :(

(gdb) bt
#0  _g_log_abort (breakpoint=<optimized out>) at ../glib/gmessages.c:431
#1  g_logv (log_domain=0x7fd918d5b2f7 "GStreamer", log_level=G_LOG_LEVEL_WARNING, format=<optimized out>, args=args@entry=0x7fd6737fd060) at ../glib/gmessages.c:1287
#2  0x00007fd918ed3723 in g_log
    (log_domain=log_domain@entry=0x7fd918d5b2f7 "GStreamer", log_level=log_level@entry=G_LOG_LEVEL_WARNING, format=format@entry=0x7fd918d6dfd0 "../gstreamer/subprojects/gstreamer/gst/gstpad.c:4777:%s:<%s:%s> Got data flow before segment event") at ../glib/gmessages.c:1329
#3  0x00007fd918cc170e in gst_pad_push_data (pad=pad@entry=0x7fd664186540 [GstPad|src_4deedab9], type=type@entry=4112, data=data@entry=0x7fd66c039000) at ../gstreamer/subprojects/gstreamer/gst/gstpad.c:4777
#4  0x00007fd918cc8de8 in gst_pad_push (pad=pad@entry=0x7fd664186540 [GstPad|src_4deedab9], buffer=buffer@entry=0x7fd66c039000) at ../gstreamer/subprojects/gstreamer/gst/gstpad.c:4930
#5  0x00007fd868197eae in gst_ogg_demux_chain_peer (pad=pad@entry=0x7fd664186540 [GstOggPad|src_4deedab9], packet=packet@entry=0x7fd6737fd310, push_headers=1)
    at ../gstreamer/subprojects/gst-plugins-base/ext/ogg/gstoggdemux.c:852
#6  0x00007fd86819b590 in gst_ogg_pad_submit_packet (pad=0x7fd664186540 [GstOggPad|src_4deedab9], packet=0x7fd6737fd310) at ../gstreamer/subprojects/gst-plugins-base/ext/ogg/gstoggdemux.c:1277
#7  gst_ogg_pad_stream_out (pad=pad@entry=0x7fd664186540 [GstOggPad|src_4deedab9], npackets=<optimized out>, npackets@entry=0) at ../gstreamer/subprojects/gst-plugins-base/ext/ogg/gstoggdemux.c:1325
#8  0x00007fd86819ce21 in gst_ogg_pad_submit_page (pad=0x7fd664186540 [GstOggPad|src_4deedab9], page=page@entry=0x7fd6737fd4f0) at ../gstreamer/subprojects/gst-plugins-base/ext/ogg/gstoggdemux.c:2078
#9  0x00007fd8681a309f in gst_ogg_demux_handle_page (ogg=ogg@entry=0x7fd66417b760 [GstOggDemux|oggdemux0], page=page@entry=0x7fd6737fd4f0, discont=discont@entry=0)
    at ../gstreamer/subprojects/gst-plugins-base/ext/ogg/gstoggdemux.c:4656
#10 0x00007fd8681a388d in gst_ogg_demux_chain (pad=<optimized out>, parent=0x7fd66417b760 [GstObject|oggdemux0], buffer=<optimized out>) at ../gstreamer/subprojects/gst-plugins-base/ext/ogg/gstoggdemux.c:4735
#11 0x00007fd918cbebae in gst_pad_chain_data_unchecked (pad=pad@entry=0x7fd66417bc00 [GstPad|sink], type=type@entry=4112, data=data@entry=0x7fd66c039570) at ../gstreamer/subprojects/gstreamer/gst/gstpad.c:4518
#12 0x00007fd918cc180a in gst_pad_push_data (pad=pad@entry=0x7fd66c0311f0 [GstPad|src], type=type@entry=4112, data=data@entry=0x7fd66c039570) at ../gstreamer/subprojects/gstreamer/gst/gstpad.c:4811
#13 0x00007fd918cc8de8 in gst_pad_push (pad=0x7fd66c0311f0 [GstPad|src], buffer=0x7fd66c039570) at ../gstreamer/subprojects/gstreamer/gst/gstpad.c:4930
#14 0x00007fd918cbebae in gst_pad_chain_data_unchecked (pad=pad@entry=0x7fd66c004340 [GstPad|sink], type=type@entry=4112, data=data@entry=0x7fd66c039570) at ../gstreamer/subprojects/gstreamer/gst/gstpad.c:4518
#15 0x00007fd918cc180a in gst_pad_push_data (pad=pad@entry=0x7fd66c032c50 [GstPad|proxypad6], type=type@entry=4112, data=data@entry=0x7fd66c039570) at ../gstreamer/subprojects/gstreamer/gst/gstpad.c:4811
#16 0x00007fd918cc8de8 in gst_pad_push (pad=pad@entry=0x7fd66c032c50 [GstPad|proxypad6], buffer=buffer@entry=0x7fd66c039570) at ../gstreamer/subprojects/gstreamer/gst/gstpad.c:4930
#17 0x00007fd918caa024 in gst_proxy_pad_chain_default (pad=0x7fd66c032870 [GstPad|sink], parent=<optimized out>, buffer=0x7fd66c039570) at ../gstreamer/subprojects/gstreamer/gst/gstghostpad.c:127
#18 0x00007fd918cbebae in gst_pad_chain_data_unchecked (pad=pad@entry=0x7fd66c032870 [GstPad|sink], type=type@entry=4112, data=data@entry=0x7fd66c039570) at ../gstreamer/subprojects/gstreamer/gst/gstpad.c:4518
#19 0x00007fd918cc180a in gst_pad_push_data (pad=pad@entry=0x7fd66c036c30 [GstPad|src], type=type@entry=4112, data=data@entry=0x7fd66c039570) at ../gstreamer/subprojects/gstreamer/gst/gstpad.c:4811
#20 0x00007fd918cc8de8 in gst_pad_push (pad=0x7fd66c036c30 [GstPad|src], buffer=buffer@entry=0x7fd66c039570) at ../gstreamer/subprojects/gstreamer/gst/gstpad.c:4930
#21 0x00007fd88c20a84b in gst_queue2_push_one (queue=0x7fd66c036210 [GstQueue2|queue2-0]) at ../gstreamer/subprojects/gstreamer/plugins/elements/gstqueue2.c:3093
#22 gst_queue2_loop (pad=<optimized out>) at ../gstreamer/subprojects/gstreamer/plugins/elements/gstqueue2.c:3218
#23 0x00007fd918cfbc93 in gst_task_func (task=0x7fd66c037750 [GstTask|queue2-0:src]) at ../gstreamer/subprojects/gstreamer/gst/gsttask.c:399
#24 0x00007fd918f053d2 in g_thread_pool_thread_proxy (data=<optimized out>) at ../glib/gthreadpool.c:336
#25 0x00007fd918f041c2 in g_thread_proxy (data=0x7fd8980010d0) at ../glib/gthread.c:890
#26 0x00007fd918730324 in start_thread (arg=<optimized out>) at pthread_create.c:448
#27 0x00007fd9187b3cfc in __GI___clone3 () at ../sysdeps/unix/sysv/linux/x86_64/clone3.S:78

[Philippe Normand]

Indeed, regression introduced by https://gitlab.freedesktop.org/gstreamer/gstreamer/-/merge_requests/937

[Philippe Normand]

Pull request: https://github.com/WebKit/WebKit/pull/43742

[EWS]

Committed 294419@main (97a032c9d850): <https://commits.webkit.org/294419@main>

Reviewed commits have been landed. Closing PR #43742 and removing active labels.

[Radar WebKit Bug Importer]

<rdar://problem/150506616>