$ GST_DEBUG=3,videoaggregator:6,gl*mixer*:5 ./glvideomixer-dynamic-input 
0:00:00.001797418 32350 0x55bc09840e90 WARN                wildmidi gstwildmidi.c:207:wildmidi_open_config: no config file, can't initialise
0:00:00.001849866 32350 0x55bc09840e90 WARN                wildmidi gstwildmidi.c:1026:plugin_init: Can't initialize wildmidi
0:00:00.001865222 32350 0x55bc09840e90 WARN      GST_PLUGIN_LOADING gstplugin.c:527:gst_plugin_register_func: plugin "/home/matt/Projects/gst/master/gst-plugins-bad/ext/timidity/.libs/libgstwildmidi.so" failed to initialise
0:00:00.148777396 32349 0x55a6c6ae9b60 DEBUG             glmixerbin gstglmixerbin.c:250:gst_gl_mixer_bin_init:<GstGLMixerBin@0x55a6c6b98080> setting target src pad <gldownloadelement0:src>
0:00:00.149184513 32349 0x55a6c6ae9b60 INFO         videoaggregator gstvideoaggregator.c:386:gst_video_aggregator_child_proxy_init: intializing child proxy interface
0:00:00.149545420 32349 0x55a6c6ae9b60 DEBUG        videoaggregator gstvideoaggregator.c:981:gst_video_aggregator_update_qos:<GstVideoAggregator@0x55a6c6ad6970> Updating QoS: proportion 0.500000, diff +0:00:00.000000000, timestamp 99:99:99.999999999
0:00:00.149615008 32349 0x55a6c6ae9b60 DEBUG                glmixer gstglbasemixer.c:439:gst_gl_base_mixer_change_state:<mixer> changing state: NULL => NULL
0:00:00.157907167 32349 0x55a6c6ae9b60 DEBUG                glmixer gstglmixer.c:254:gst_gl_mixer_pad_sink_getcaps:<mixer:sink_0> returning video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)[ 1, 2147483647 ], height=(int)[ 1, 2147483647 ], framerate=(fraction)[ 0/1, 2147483647/1 ]
0:00:00.160189181 32349 0x55a6c6ae9b60 DEBUG                glmixer gstglmixer.c:254:gst_gl_mixer_pad_sink_getcaps:<mixer:sink_0> returning video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)[ 1, 2147483647 ], height=(int)[ 1, 2147483647 ], framerate=(fraction)[ 0/1, 2147483647/1 ]
0:00:00.160248271 32349 0x55a6c6ae9b60 DEBUG             glmixerbin gstglmixerbin.c:315:_create_input_chain:<glvideomixerbin0> setting target sink pad <gluploadelement1:sink>
0:00:00.160486680 32349 0x55a6c6ae9b60 DEBUG                glmixer gstglmixer.c:254:gst_gl_mixer_pad_sink_getcaps:<mixer:sink_0> returning video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)[ 1, 2147483647 ], height=(int)[ 1, 2147483647 ], framerate=(fraction)[ 0/1, 2147483647/1 ]
0:00:00.189016336 32349 0x55a6c6ae9b60 DEBUG                glmixer gstglbasemixer.c:439:gst_gl_base_mixer_change_state:<mixer> changing state: NULL => READY
0:00:00.189135596 32349 0x55a6c6ae9b60 DEBUG                glmixer gstglbasemixer.c:439:gst_gl_base_mixer_change_state:<mixer> changing state: READY => PAUSED
0:00:00.189364673 32349 0x55a6c6a14ad0 FIXME                default gstutils.c:3940:gst_pad_create_stream_id_internal:<videotestsrc0:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:00:00.189450709 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1796:gst_video_aggregator_sink_event:<mixer:sink_0> Got stream-start event on pad mixer:sink_0
0:00:00.189907613 32349 0x55a6c6a14ad0 DEBUG                glmixer gstglmixer.c:254:gst_gl_mixer_pad_sink_getcaps:<mixer:sink_0> returning video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)320, height=(int)240, framerate=(fraction)2/1, multiview-mode=(string){ mono, left, right }, texture-target=(string){ 2D, rectangle, external-oes }
0:00:00.191031014 32349 0x55a6c6a14ad0 DEBUG                glmixer gstglmixer.c:254:gst_gl_mixer_pad_sink_getcaps:<mixer:sink_0> returning video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)320, height=(int)240, framerate=(fraction)2/1, multiview-mode=(string)mono, pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive, texture-target=(string){ 2D, rectangle, external-oes }
0:00:00.191161464 32349 0x55a6c6a14ad0 DEBUG                glmixer gstglmixer.c:254:gst_gl_mixer_pad_sink_getcaps:<mixer:sink_0> returning video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)320, height=(int)240, framerate=(fraction)2/1, multiview-mode=(string)mono, pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive, texture-target=(string){ 2D, rectangle, external-oes }
0:00:00.191201803 32349 0x55a6c6a14ad0 DEBUG                glmixer gstglmixer.c:212:gst_gl_mixer_pad_sink_acceptcaps:<mixer:sink_0> try accept caps of video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)320, height=(int)240, framerate=(fraction)2/1, multiview-mode=(string)mono, pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive, texture-target=(string)2D
0:00:00.191216551 32349 0x55a6c6a14ad0 DEBUG                glmixer gstglmixer.c:219:gst_gl_mixer_pad_sink_acceptcaps:<mixer:sink_0> accepted caps video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)320, height=(int)240, framerate=(fraction)2/1, multiview-mode=(string)mono, pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive, texture-target=(string)2D
0:00:00.191235399 32349 0x55a6c6a14ad0 DEBUG                glmixer gstglmixer.c:212:gst_gl_mixer_pad_sink_acceptcaps:<mixer:sink_0> try accept caps of video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)320, height=(int)240, framerate=(fraction)2/1, multiview-mode=(string)mono, pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive, texture-target=(string)2D
0:00:00.191247684 32349 0x55a6c6a14ad0 DEBUG                glmixer gstglmixer.c:219:gst_gl_mixer_pad_sink_acceptcaps:<mixer:sink_0> accepted caps video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)320, height=(int)240, framerate=(fraction)2/1, multiview-mode=(string)mono, pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive, texture-target=(string)2D
0:00:00.191288187 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1796:gst_video_aggregator_sink_event:<mixer:sink_0> Got caps event on pad mixer:sink_0
0:00:00.191322818 32349 0x55a6c6a14940 INFO         videoaggregator gstvideoaggregator.c:799:gst_video_aggregator_pad_sink_setcaps:<mixer:sink_0> Setting caps video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)320, height=(int)240, framerate=(fraction)2/1, multiview-mode=(string)mono, pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive, texture-target=(string)2D
0:00:00.191363286 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:809:gst_video_aggregator_pad_sink_setcaps:<mixer> Taking EVENT lock from thread 0x55a6c6a14940
0:00:00.191369372 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:809:gst_video_aggregator_pad_sink_setcaps:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:00.191375123 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:851:gst_video_aggregator_pad_sink_setcaps:<mixer> Releasing EVENT lock from thread 0x55a6c6a14940
0:00:00.191381374 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:851:gst_video_aggregator_pad_sink_setcaps:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:00.191425921 32349 0x55a6c6a14940 DEBUG                glmixer gstglmixer.c:165:gst_gl_mixer_propose_allocation:<mixer> create new pool
0:00:00.197659873 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1796:gst_video_aggregator_sink_event:<mixer:sink_0> Got segment event on pad mixer:sink_0
0:00:00.197702222 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:981:gst_video_aggregator_update_qos:<mixer> Updating QoS: proportion 0.500000, diff +0:00:00.000000000, timestamp 99:99:99.999999999
0:00:00.199400600 32349 0x55a6c6a14940 INFO         videoaggregator gstvideoaggregator.c:703:gst_video_aggregator_default_negotiated_src_caps:<mixer:src> set src caps: video/x-raw(memory:GLMemory), width=(int)320, height=(int)240, framerate=(fraction)2/1, format=(string)RGBA, texture-target=(string)2D, pixel-aspect-ratio=(fraction)1/1
0:00:00.199424634 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:728:gst_video_aggregator_default_negotiated_src_caps:<mixer> Resetting frame counter because of framerate change
0:00:00.199428864 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:981:gst_video_aggregator_update_qos:<mixer> Updating QoS: proportion 0.500000, diff +0:00:00.000000000, timestamp 99:99:99.999999999
0:00:00.199494747 32349 0x55a6c6a14940 FIXME               basesink gstbasesink.c:3145:gst_base_sink_default_event:<sink> stream-start event without group-id. Consider implementing group-id handling in the upstream elements
0:00:00.207949000 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1460:gst_video_aggregator_aggregate:<mixer> Taking EVENT lock from thread 0x55a6c6a14940
0:00:00.207964994 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1460:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:00.207968372 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1476:gst_video_aggregator_aggregate:<mixer> New ts offset 0:00:00.000000000
0:00:00.207977090 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1189:gst_video_aggregator_fill_queues:<mixer:sink_0> Taking new buffer with start time 0:00:00.000000000
0:00:00.207983685 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1536:gst_video_aggregator_aggregate:<mixer> Producing buffer for 0:00:00.000000000 to 0:00:00.500000000, running time start 0:00:00.000000000, running time end 0:00:00.500000000
0:00:00.208007062 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1375:gst_video_aggregator_do_qos:<mixer> no observation yet, process frame
0:00:00.212559558 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1563:gst_video_aggregator_aggregate:<mixer> Releasing EVENT lock from thread 0x55a6c6a14940
0:00:00.212577261 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1563:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:00.212580965 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1568:gst_video_aggregator_aggregate:<mixer> Pushing buffer with ts 0:00:00.000000000 and duration 0:00:00.500000000
0:00:00.221665388 32349 0x7f9870002f20 DEBUG                glmixer gstglbasemixer.c:439:gst_gl_base_mixer_change_state:<mixer> changing state: PAUSED => PLAYING
0:00:00.224312738 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1573:gst_video_aggregator_aggregate:<mixer> Taking EVENT lock from thread 0x55a6c6a14940
0:00:00.224410696 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1573:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:00.224431175 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1576:gst_video_aggregator_aggregate:<mixer> Releasing EVENT lock from thread 0x55a6c6a14940
0:00:00.224478269 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1576:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:00.224545662 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1460:gst_video_aggregator_aggregate:<mixer> Taking EVENT lock from thread 0x55a6c6a14940
0:00:00.224569055 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1460:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:00.224604832 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1189:gst_video_aggregator_fill_queues:<mixer:sink_0> Taking new buffer with start time 0:00:00.500000000
0:00:00.224667353 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1536:gst_video_aggregator_aggregate:<mixer> Producing buffer for 0:00:00.500000000 to 0:00:01.000000000, running time start 0:00:00.500000000, running time end 0:00:01.000000000
0:00:00.224713892 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1375:gst_video_aggregator_do_qos:<mixer> no observation yet, process frame
0:00:00.226914688 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1563:gst_video_aggregator_aggregate:<mixer> Releasing EVENT lock from thread 0x55a6c6a14940
0:00:00.226976300 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1563:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:00.227022428 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1568:gst_video_aggregator_aggregate:<mixer> Pushing buffer with ts 0:00:00.500000000 and duration 0:00:00.500000000
0:00:00.723252775 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:981:gst_video_aggregator_update_qos:<mixer> Updating QoS: proportion 0.010867, diff -0:00:00.493835416, timestamp 0:00:00.500000000
0:00:00.723396794 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1573:gst_video_aggregator_aggregate:<mixer> Taking EVENT lock from thread 0x55a6c6a14940
0:00:00.723423545 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1573:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:00.723440140 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1576:gst_video_aggregator_aggregate:<mixer> Releasing EVENT lock from thread 0x55a6c6a14940
0:00:00.723456676 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1576:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:00.723482870 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1460:gst_video_aggregator_aggregate:<mixer> Taking EVENT lock from thread 0x55a6c6a14940
0:00:00.723498393 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1460:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:00.723529360 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1189:gst_video_aggregator_fill_queues:<mixer:sink_0> Taking new buffer with start time 0:00:01.000000000
0:00:00.723572837 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1536:gst_video_aggregator_aggregate:<mixer> Producing buffer for 0:00:01.000000000 to 0:00:01.500000000, running time start 0:00:01.000000000, running time end 0:00:01.500000000
0:00:00.723632427 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1385:gst_video_aggregator_do_qos:<mixer> qostime 0:00:01.000000000, earliest 0:00:00.006164584
0:00:00.723669633 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1393:gst_video_aggregator_do_qos:<mixer> process frame
0:00:00.724680352 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1563:gst_video_aggregator_aggregate:<mixer> Releasing EVENT lock from thread 0x55a6c6a14940
0:00:00.724715720 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1563:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:00.724740054 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1568:gst_video_aggregator_aggregate:<mixer> Pushing buffer with ts 0:00:01.000000000 and duration 0:00:00.500000000
0:00:01.222844002 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:981:gst_video_aggregator_update_qos:<mixer> Updating QoS: proportion 0.010842, diff -0:00:00.496170495, timestamp 0:00:01.000000000
0:00:01.222979228 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1573:gst_video_aggregator_aggregate:<mixer> Taking EVENT lock from thread 0x55a6c6a14940
0:00:01.223012995 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1573:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:01.223037095 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1576:gst_video_aggregator_aggregate:<mixer> Releasing EVENT lock from thread 0x55a6c6a14940
0:00:01.223060522 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1576:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:01.223180324 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1460:gst_video_aggregator_aggregate:<mixer> Taking EVENT lock from thread 0x55a6c6a14940
0:00:01.223207348 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1460:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:01.223239164 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1189:gst_video_aggregator_fill_queues:<mixer:sink_0> Taking new buffer with start time 0:00:01.500000000
0:00:01.223293692 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1536:gst_video_aggregator_aggregate:<mixer> Producing buffer for 0:00:01.500000000 to 0:00:02.000000000, running time start 0:00:01.500000000, running time end 0:00:02.000000000
0:00:01.223356706 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1385:gst_video_aggregator_do_qos:<mixer> qostime 0:00:01.500000000, earliest 0:00:00.503829505
0:00:01.223401301 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1393:gst_video_aggregator_do_qos:<mixer> process frame
0:00:01.224414500 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1563:gst_video_aggregator_aggregate:<mixer> Releasing EVENT lock from thread 0x55a6c6a14940
0:00:01.224452578 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1563:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:01.224471068 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1568:gst_video_aggregator_aggregate:<mixer> Pushing buffer with ts 0:00:01.500000000 and duration 0:00:00.500000000
0:00:01.722735359 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:981:gst_video_aggregator_update_qos:<mixer> Updating QoS: proportion 0.010792, diff -0:00:00.496477590, timestamp 0:00:01.500000000
0:00:01.722889113 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1573:gst_video_aggregator_aggregate:<mixer> Taking EVENT lock from thread 0x55a6c6a14940
0:00:01.722923699 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1573:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:01.722949471 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1576:gst_video_aggregator_aggregate:<mixer> Releasing EVENT lock from thread 0x55a6c6a14940
0:00:01.723031456 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1576:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:01.723074320 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1460:gst_video_aggregator_aggregate:<mixer> Taking EVENT lock from thread 0x55a6c6a14940
0:00:01.723175237 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1460:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:01.723215133 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1189:gst_video_aggregator_fill_queues:<mixer:sink_0> Taking new buffer with start time 0:00:02.000000000
0:00:01.723277043 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1536:gst_video_aggregator_aggregate:<mixer> Producing buffer for 0:00:02.000000000 to 0:00:02.500000000, running time start 0:00:02.000000000, running time end 0:00:02.500000000
0:00:01.723341556 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1385:gst_video_aggregator_do_qos:<mixer> qostime 0:00:02.000000000, earliest 0:00:01.003522410
0:00:01.723390580 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1393:gst_video_aggregator_do_qos:<mixer> process frame
0:00:01.724775571 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1563:gst_video_aggregator_aggregate:<mixer> Releasing EVENT lock from thread 0x55a6c6a14940
0:00:01.724824145 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1563:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:01.724850958 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1568:gst_video_aggregator_aggregate:<mixer> Pushing buffer with ts 0:00:02.000000000 and duration 0:00:00.500000000
0:00:02.222730973 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:981:gst_video_aggregator_update_qos:<mixer> Updating QoS: proportion 0.010727, diff -0:00:00.496105315, timestamp 0:00:02.000000000
0:00:02.222874715 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1573:gst_video_aggregator_aggregate:<mixer> Taking EVENT lock from thread 0x55a6c6a14940
0:00:02.222909377 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1573:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:02.222929546 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1576:gst_video_aggregator_aggregate:<mixer> Releasing EVENT lock from thread 0x55a6c6a14940
0:00:02.222957311 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1576:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:02.222997850 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1460:gst_video_aggregator_aggregate:<mixer> Taking EVENT lock from thread 0x55a6c6a14940
0:00:02.223023958 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1460:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:02.223059404 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1189:gst_video_aggregator_fill_queues:<mixer:sink_0> Taking new buffer with start time 0:00:02.500000000
0:00:02.223203801 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1536:gst_video_aggregator_aggregate:<mixer> Producing buffer for 0:00:02.500000000 to 0:00:03.000000000, running time start 0:00:02.500000000, running time end 0:00:03.000000000
0:00:02.223274540 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1385:gst_video_aggregator_do_qos:<mixer> qostime 0:00:02.500000000, earliest 0:00:01.503894685
0:00:02.223316341 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1393:gst_video_aggregator_do_qos:<mixer> process frame
0:00:02.224276915 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1563:gst_video_aggregator_aggregate:<mixer> Releasing EVENT lock from thread 0x55a6c6a14940
0:00:02.224318666 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1563:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:02.224344982 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1568:gst_video_aggregator_aggregate:<mixer> Pushing buffer with ts 0:00:02.500000000 and duration 0:00:00.500000000
0:00:02.225111813 32349 0x55a6c6a14ad0 DEBUG                glmixer gstglmixer.c:254:gst_gl_mixer_pad_sink_getcaps:<mixer:sink_0> returning video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)640, height=(int)480, framerate=(fraction)2/1, multiview-mode=(string){ mono, left, right }, texture-target=(string){ 2D, rectangle, external-oes }
0:00:02.228933737 32349 0x55a6c6a14ad0 DEBUG                glmixer gstglmixer.c:254:gst_gl_mixer_pad_sink_getcaps:<mixer:sink_0> returning video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)640, height=(int)480, framerate=(fraction)2/1, multiview-mode=(string)mono, pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive, texture-target=(string){ 2D, rectangle, external-oes }
0:00:02.229524719 32349 0x55a6c6a14ad0 DEBUG                glmixer gstglmixer.c:254:gst_gl_mixer_pad_sink_getcaps:<mixer:sink_0> returning video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)640, height=(int)480, framerate=(fraction)2/1, multiview-mode=(string)mono, pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive, texture-target=(string){ 2D, rectangle, external-oes }
0:00:02.229715911 32349 0x55a6c6a14ad0 DEBUG                glmixer gstglmixer.c:212:gst_gl_mixer_pad_sink_acceptcaps:<mixer:sink_0> try accept caps of video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)640, height=(int)480, framerate=(fraction)2/1, multiview-mode=(string)mono, pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive, texture-target=(string)2D
0:00:02.229815506 32349 0x55a6c6a14ad0 DEBUG                glmixer gstglmixer.c:219:gst_gl_mixer_pad_sink_acceptcaps:<mixer:sink_0> accepted caps video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)640, height=(int)480, framerate=(fraction)2/1, multiview-mode=(string)mono, pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive, texture-target=(string)2D
0:00:02.229909239 32349 0x55a6c6a14ad0 DEBUG                glmixer gstglmixer.c:212:gst_gl_mixer_pad_sink_acceptcaps:<mixer:sink_0> try accept caps of video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)640, height=(int)480, framerate=(fraction)2/1, multiview-mode=(string)mono, pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive, texture-target=(string)2D
0:00:02.229961392 32349 0x55a6c6a14ad0 DEBUG                glmixer gstglmixer.c:219:gst_gl_mixer_pad_sink_acceptcaps:<mixer:sink_0> accepted caps video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)640, height=(int)480, framerate=(fraction)2/1, multiview-mode=(string)mono, pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive, texture-target=(string)2D
0:00:02.722817972 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:981:gst_video_aggregator_update_qos:<mixer> Updating QoS: proportion 0.010643, diff -0:00:00.496598332, timestamp 0:00:02.500000000
0:00:02.722964729 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1573:gst_video_aggregator_aggregate:<mixer> Taking EVENT lock from thread 0x55a6c6a14940
0:00:02.722997060 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1573:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:02.723022910 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1576:gst_video_aggregator_aggregate:<mixer> Releasing EVENT lock from thread 0x55a6c6a14940
0:00:02.723046054 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1576:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:02.723173967 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1460:gst_video_aggregator_aggregate:<mixer> Taking EVENT lock from thread 0x55a6c6a14940
0:00:02.723207203 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1460:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:02.723245167 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1189:gst_video_aggregator_fill_queues:<mixer:sink_0> Taking new buffer with start time 0:00:03.000000000
0:00:02.723299745 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1536:gst_video_aggregator_aggregate:<mixer> Producing buffer for 0:00:03.000000000 to 0:00:03.500000000, running time start 0:00:03.000000000, running time end 0:00:03.500000000
0:00:02.723359322 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1385:gst_video_aggregator_do_qos:<mixer> qostime 0:00:03.000000000, earliest 0:00:02.003401668
0:00:02.723404840 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1393:gst_video_aggregator_do_qos:<mixer> process frame
0:00:02.724315570 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1563:gst_video_aggregator_aggregate:<mixer> Releasing EVENT lock from thread 0x55a6c6a14940
0:00:02.724380992 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1563:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:02.724405356 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1568:gst_video_aggregator_aggregate:<mixer> Pushing buffer with ts 0:00:03.000000000 and duration 0:00:00.500000000
0:00:03.222544830 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:981:gst_video_aggregator_update_qos:<mixer> Updating QoS: proportion 0.010546, diff -0:00:00.496519872, timestamp 0:00:03.000000000
0:00:03.222645531 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1573:gst_video_aggregator_aggregate:<mixer> Taking EVENT lock from thread 0x55a6c6a14940
0:00:03.222668148 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1573:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:03.222684773 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1576:gst_video_aggregator_aggregate:<mixer> Releasing EVENT lock from thread 0x55a6c6a14940
0:00:03.222699158 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1576:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:03.222722152 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1796:gst_video_aggregator_sink_event:<mixer:sink_0> Got caps event on pad mixer:sink_0
0:00:03.222739462 32349 0x55a6c6a14940 INFO         videoaggregator gstvideoaggregator.c:799:gst_video_aggregator_pad_sink_setcaps:<mixer:sink_0> Setting caps video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)640, height=(int)480, framerate=(fraction)2/1, multiview-mode=(string)mono, pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive, texture-target=(string)2D
0:00:03.222794857 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:809:gst_video_aggregator_pad_sink_setcaps:<mixer> Taking EVENT lock from thread 0x55a6c6a14940
0:00:03.222807518 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:809:gst_video_aggregator_pad_sink_setcaps:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:03.222821539 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:847:gst_video_aggregator_pad_sink_setcaps:<mixer:sink_0> delaying caps change
0:00:03.222833550 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:851:gst_video_aggregator_pad_sink_setcaps:<mixer> Releasing EVENT lock from thread 0x55a6c6a14940
0:00:03.222848340 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:851:gst_video_aggregator_pad_sink_setcaps:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:03.222886960 32349 0x55a6c6a14940 DEBUG                glmixer gstglmixer.c:165:gst_gl_mixer_propose_allocation:<mixer> create new pool
0:00:03.229270319 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1460:gst_video_aggregator_aggregate:<mixer> Taking EVENT lock from thread 0x55a6c6a14940
0:00:03.229339984 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1460:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:03.229391273 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1189:gst_video_aggregator_fill_queues:<mixer:sink_0> Taking new buffer with start time 0:00:03.500000000
0:00:03.232814738 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1525:gst_video_aggregator_aggregate:<mixer> Need reconfigure
0:00:03.232867096 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1584:gst_video_aggregator_aggregate:<mixer> Releasing EVENT lock from thread 0x55a6c6a14940
0:00:03.232900276 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1584:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:03.244414428 32349 0x55a6c6a14940 INFO         videoaggregator gstvideoaggregator.c:703:gst_video_aggregator_default_negotiated_src_caps:<mixer:src> set src caps: video/x-raw(memory:GLMemory), width=(int)640, height=(int)480, framerate=(fraction)2/1, format=(string)RGBA, texture-target=(string)2D, pixel-aspect-ratio=(fraction)1/1
0:00:03.271271771 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1460:gst_video_aggregator_aggregate:<mixer> Taking EVENT lock from thread 0x55a6c6a14940
0:00:03.271404225 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1460:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:03.271483633 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1204:gst_video_aggregator_fill_queues:<mixer:sink_0> Keeping buffer until 0:00:04.000000000
0:00:03.271517604 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1536:gst_video_aggregator_aggregate:<mixer> Producing buffer for 0:00:03.500000000 to 0:00:04.000000000, running time start 0:00:03.500000000, running time end 0:00:04.000000000
0:00:03.271623760 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1385:gst_video_aggregator_do_qos:<mixer> qostime 0:00:03.500000000, earliest 0:00:02.503480128
0:00:03.271645382 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1393:gst_video_aggregator_do_qos:<mixer> process frame
0:00:03.275859824 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1563:gst_video_aggregator_aggregate:<mixer> Releasing EVENT lock from thread 0x55a6c6a14940
0:00:03.275910149 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1563:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:03.275925132 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1568:gst_video_aggregator_aggregate:<mixer> Pushing buffer with ts 0:00:03.500000000 and duration 0:00:00.500000000
0:00:03.723694402 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:981:gst_video_aggregator_update_qos:<mixer> Updating QoS: proportion 0.011242, diff -0:00:00.445033727, timestamp 0:00:03.500000000
0:00:03.723886666 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1573:gst_video_aggregator_aggregate:<mixer> Taking EVENT lock from thread 0x55a6c6a14940
0:00:03.723929660 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1573:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:03.723959285 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1576:gst_video_aggregator_aggregate:<mixer> Releasing EVENT lock from thread 0x55a6c6a14940
0:00:03.724021911 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1576:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:03.724067333 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1460:gst_video_aggregator_aggregate:<mixer> Taking EVENT lock from thread 0x55a6c6a14940
0:00:03.724097209 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1460:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:03.724134668 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1189:gst_video_aggregator_fill_queues:<mixer:sink_0> Taking new buffer with start time 0:00:04.000000000
0:00:03.724224336 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1536:gst_video_aggregator_aggregate:<mixer> Producing buffer for 0:00:04.000000000 to 0:00:04.500000000, running time start 0:00:04.000000000, running time end 0:00:04.500000000
0:00:03.724296907 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1385:gst_video_aggregator_do_qos:<mixer> qostime 0:00:04.000000000, earliest 0:00:03.054966273
0:00:03.724339246 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1393:gst_video_aggregator_do_qos:<mixer> process frame
0:00:03.725353995 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1563:gst_video_aggregator_aggregate:<mixer> Releasing EVENT lock from thread 0x55a6c6a14940
0:00:03.725397546 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1563:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:03.725427900 32349 0x55a6c6a14940 DEBUG        videoaggregator gstvideoaggregator.c:1568:gst_video_aggregator_aggregate:<mixer> Pushing buffer with ts 0:00:04.000000000 and duration 0:00:00.500000000
0:00:04.190031024 32349 0x55a6c6ae9b60 DEBUG                glmixer gstglbasemixer.c:439:gst_gl_base_mixer_change_state:<mixer> changing state: PLAYING => PAUSED
0:00:04.191240500 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1573:gst_video_aggregator_aggregate:<mixer> Taking EVENT lock from thread 0x55a6c6a14940
0:00:04.191310578 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1573:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:04.191347003 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1576:gst_video_aggregator_aggregate:<mixer> Releasing EVENT lock from thread 0x55a6c6a14940
0:00:04.191371856 32349 0x55a6c6a14940 LOG          videoaggregator gstvideoaggregator.c:1576:gst_video_aggregator_aggregate:<mixer> Took EVENT lock from thread 0x55a6c6a14940
0:00:04.192309213 32349 0x55a6c6ae9b60 DEBUG                glmixer gstglbasemixer.c:439:gst_gl_base_mixer_change_state:<mixer> changing state: PAUSED => READY
0:00:04.192553844 32349 0x55a6c6ae9b60 DEBUG        videoaggregator gstvideoaggregator.c:981:gst_video_aggregator_update_qos:<mixer> Updating QoS: proportion 0.500000, diff +0:00:00.000000000, timestamp 99:99:99.999999999
0:00:04.192654897 32349 0x55a6c6ae9b60 DEBUG           glvideomixer gstglvideomixer.c:1146:gst_gl_video_mixer_reset:<mixer> context:0x55a6c6bc20b0
0:00:04.198823508 32349 0x55a6c6ae9b60 DEBUG                glmixer gstglbasemixer.c:439:gst_gl_base_mixer_change_state:<mixer> changing state: READY => NULL
0:00:04.199545389 32349 0x55a6c6ae9b60 LOG          videoaggregator gstvideoaggregator.c:1891:gst_video_aggregator_release_pad:<mixer> Taking EVENT lock from thread 0x55a6c6ae9b60
0:00:04.199582922 32349 0x55a6c6ae9b60 LOG          videoaggregator gstvideoaggregator.c:1891:gst_video_aggregator_release_pad:<mixer> Took EVENT lock from thread 0x55a6c6ae9b60
0:00:04.199614717 32349 0x55a6c6ae9b60 DEBUG        videoaggregator gstvideoaggregator.c:981:gst_video_aggregator_update_qos:<mixer> Updating QoS: proportion 0.500000, diff +0:00:00.000000000, timestamp 99:99:99.999999999
0:00:04.199854924 32349 0x55a6c6ae9b60 LOG          videoaggregator gstvideoaggregator.c:1910:gst_video_aggregator_release_pad:<mixer> Releasing EVENT lock from thread 0x55a6c6ae9b60
0:00:04.199881885 32349 0x55a6c6ae9b60 LOG          videoaggregator gstvideoaggregator.c:1910:gst_video_aggregator_release_pad:<mixer> Took EVENT lock from thread 0x55a6c6ae9b60
