$ GST_DEBUG=4 ./test.py 
0:00:00.000036434 16087      0x261e2c0 INFO                GST_INIT gst.c:510:init_pre: Initializing GStreamer Core Library version 1.5.90
0:00:00.000082308 16087      0x261e2c0 INFO                GST_INIT gst.c:511:init_pre: Using library installed in /usr/local/lib
0:00:00.000105237 16087      0x261e2c0 INFO                GST_INIT gst.c:522:init_pre: Linux spock 3.19.0-26-generic #28-Ubuntu SMP Tue Aug 11 14:16:32 UTC 2015 x86_64
0:00:00.000292142 16087      0x261e2c0 INFO                GST_INIT gstmessage.c:119:_priv_gst_message_initialize: init messages
0:00:00.000586854 16087      0x261e2c0 INFO                GST_INIT gstcontext.c:77:_priv_gst_context_initialize: init contexts
0:00:00.000727397 16087      0x261e2c0 INFO      GST_PLUGIN_LOADING gstplugin.c:316:_priv_gst_plugin_initialize: registering 0 static plugins
0:00:00.000806264 16087      0x261e2c0 INFO      GST_PLUGIN_LOADING gstplugin.c:224:gst_plugin_register_static: registered static plugin "staticelements"
0:00:00.000820978 16087      0x261e2c0 INFO      GST_PLUGIN_LOADING gstplugin.c:226:gst_plugin_register_static: added static plugin "staticelements", result: 1
0:00:00.000834252 16087      0x261e2c0 INFO            GST_REGISTRY gstregistry.c:1723:ensure_current_registry: reading registry cache: /home/peter/gst/master/gstreamer/registry.dat
0:00:00.014095493 16087      0x261e2c0 INFO            GST_REGISTRY gstregistrybinary.c:619:priv_gst_registry_binary_read_cache: loaded /home/peter/gst/master/gstreamer/registry.dat in 0.013242 seconds
0:00:00.014172047 16087      0x261e2c0 INFO            GST_REGISTRY gstregistry.c:1579:scan_and_update_registry: Validating plugins from registry cache: /home/peter/gst/master/gstreamer/registry.dat
0:00:00.031612562 16087      0x261e2c0 INFO            GST_REGISTRY gstregistry.c:1681:scan_and_update_registry: Registry cache has not changed
0:00:00.031652575 16087      0x261e2c0 INFO            GST_REGISTRY gstregistry.c:1758:ensure_current_registry: registry reading and updating done, result = 1
0:00:00.031679201 16087      0x261e2c0 INFO                GST_INIT gst.c:720:init_post: GLib runtime version: 2.44.1
0:00:00.031694242 16087      0x261e2c0 INFO                GST_INIT gst.c:722:init_post: GLib headers version: 2.44.1
0:00:00.031706218 16087      0x261e2c0 INFO                GST_INIT gst.c:723:init_post: initialized GStreamer successfully
0:00:00.033088179 16087      0x261e2c0 INFO            GST_PIPELINE gstparse.c:323:gst_parse_launch_full: parsing pipeline description '
videotestsrc pattern=red !
video/x-raw,width=1920,height=1080,format=I420,framerate=25/1,pixel-aspect-ratio=1/1 !
videoscale !
capsfilter name=caps0 caps=video/x-raw !
mix.

compositor name=mix !
video/x-raw,width=1920,height=1080,format=I420,framerate=25/1,pixel-aspect-ratio=1/1 !
xvimagesink
		'
0:00:00.033812398 16087      0x261e2c0 INFO      GST_PLUGIN_LOADING gstplugin.c:842:_priv_gst_plugin_load_file_for_registry: plugin "/home/peter/gst/master/gst-plugins-base/gst/videotestsrc/.libs/libgstvideotestsrc.so" loaded
0:00:00.033847496 16087      0x261e2c0 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "videotestsrc"
0:00:00.034022519 16087      0x261e2c0 INFO        GST_ELEMENT_PADS gstelement.c:646:gst_element_add_pad:<GstBaseSrc@0x28408a0> adding pad 'src'
0:00:00.034333295 16087      0x261e2c0 INFO      GST_PLUGIN_LOADING gstplugin.c:842:_priv_gst_plugin_load_file_for_registry: plugin "/home/peter/gst/master/gst-plugins-base/gst/videoscale/.libs/libgstvideoscale.so" loaded
0:00:00.034363407 16087      0x261e2c0 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "videoscale"
0:00:00.034508416 16087      0x261e2c0 INFO        GST_ELEMENT_PADS gstelement.c:646:gst_element_add_pad:<GstBaseTransform@0x2847dd0> adding pad 'sink'
0:00:00.034541098 16087      0x261e2c0 INFO        GST_ELEMENT_PADS gstelement.c:646:gst_element_add_pad:<GstBaseTransform@0x2847dd0> adding pad 'src'
0:00:00.035028157 16087      0x261e2c0 INFO      GST_PLUGIN_LOADING gstplugin.c:842:_priv_gst_plugin_load_file_for_registry: plugin "/home/peter/gst/master/gstreamer/plugins/elements/.libs/libgstcoreelements.so" loaded
0:00:00.035057748 16087      0x261e2c0 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "capsfilter"
0:00:00.035129948 16087      0x261e2c0 INFO        GST_ELEMENT_PADS gstelement.c:646:gst_element_add_pad:<GstBaseTransform@0x284c170> adding pad 'sink'
0:00:00.035157643 16087      0x261e2c0 INFO        GST_ELEMENT_PADS gstelement.c:646:gst_element_add_pad:<GstBaseTransform@0x284c170> adding pad 'src'
0:00:00.035196542 16087      0x261e2c0 INFO               GST_EVENT gstevent.c:1374:gst_event_new_reconfigure: creating reconfigure event
0:00:00.035763783 16087      0x261e2c0 INFO      GST_PLUGIN_LOADING gstplugin.c:842:_priv_gst_plugin_load_file_for_registry: plugin "/home/peter/gst/master/gst-plugins-bad/gst/compositor/.libs/libgstcompositor.so" loaded
0:00:00.035791272 16087      0x261e2c0 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "compositor"
0:00:00.035841521 16087      0x261e2c0 INFO         videoaggregator gstvideoaggregator.c:382:gst_videoaggregator_child_proxy_init: intializing child proxy interface
0:00:00.035941592 16087      0x261e2c0 INFO        GST_ELEMENT_PADS gstelement.c:646:gst_element_add_pad:<GstAggregator@0x28535f0> adding pad 'src'
0:00:00.036901724 16087      0x261e2c0 INFO      GST_PLUGIN_LOADING gstplugin.c:842:_priv_gst_plugin_load_file_for_registry: plugin "/home/peter/gst/master/gst-plugins-base/sys/xvimage/.libs/libgstxvimagesink.so" loaded
0:00:00.036932986 16087      0x261e2c0 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "xvimagesink"
0:00:00.037091491 16087      0x261e2c0 INFO        GST_ELEMENT_PADS gstelement.c:646:gst_element_add_pad:<GstBaseSink@0x2864220> adding pad 'sink'
0:00:00.037142301 16087      0x261e2c0 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "pipeline"
0:00:00.037236128 16087      0x261e2c0 INFO            GST_PIPELINE grammar.y:571:gst_parse_perform_link: linking videotestsrc0:(any) to videoscale0:(any) (0/0) with caps "video/x-raw, width=(int)1920, height=(int)1080, format=(string)I420, framerate=(fraction)25/1, pixel-aspect-ratio=(fraction)1/1"
0:00:00.037272069 16087      0x261e2c0 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "capsfilter"
0:00:00.037296455 16087      0x261e2c0 INFO        GST_ELEMENT_PADS gstelement.c:646:gst_element_add_pad:<GstBaseTransform@0x284c4a0> adding pad 'sink'
0:00:00.037319220 16087      0x261e2c0 INFO        GST_ELEMENT_PADS gstelement.c:646:gst_element_add_pad:<GstBaseTransform@0x284c4a0> adding pad 'src'
0:00:00.037337658 16087      0x261e2c0 INFO              GST_STATES gstbin.c:1829:gst_bin_get_state_func:<pipeline0> getting state
0:00:00.037361421 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2330:gst_element_continue_state:<capsfilter1> completed state change to NULL
0:00:00.037380320 16087      0x261e2c0 INFO               GST_EVENT gstevent.c:1374:gst_event_new_reconfigure: creating reconfigure event
0:00:00.037395450 16087      0x261e2c0 INFO        GST_ELEMENT_PADS gstutils.c:1571:gst_element_link_pads_full: trying to link element videotestsrc0:(any) to element capsfilter1:sink
0:00:00.037413349 16087      0x261e2c0 INFO        GST_ELEMENT_PADS gstelement.c:897:gst_element_get_static_pad: found pad capsfilter1:sink
0:00:00.037426334 16087      0x261e2c0 INFO                GST_PADS gstutils.c:1444:prepare_link_maybe_ghosting: videotestsrc0 and capsfilter1 in same bin, no need for ghost pads
0:00:00.037445797 16087      0x261e2c0 INFO                GST_PADS gstpad.c:2234:gst_pad_link_prepare: trying to link videotestsrc0:src and capsfilter1:sink
0:00:00.037468943 16087      0x261e2c0 INFO                GST_PADS gstpad.c:3995:gst_pad_peer_query:<capsfilter1:src> pad has no peer
0:00:00.037492105 16087      0x261e2c0 INFO                GST_PADS gstpad.c:2440:gst_pad_link_full: linked videotestsrc0:src and capsfilter1:sink, successful
0:00:00.037508501 16087      0x261e2c0 INFO               GST_EVENT gstevent.c:1374:gst_event_new_reconfigure: creating reconfigure event
0:00:00.037520211 16087      0x261e2c0 INFO               GST_EVENT gstpad.c:5499:gst_pad_send_event_unchecked:<videotestsrc0:src> Received event on flushing pad. Discarding
0:00:00.037537484 16087      0x261e2c0 INFO        GST_ELEMENT_PADS gstutils.c:1571:gst_element_link_pads_full: trying to link element capsfilter1:src to element videoscale0:(any)
0:00:00.037551485 16087      0x261e2c0 INFO        GST_ELEMENT_PADS gstelement.c:897:gst_element_get_static_pad: found pad capsfilter1:src
0:00:00.037565157 16087      0x261e2c0 INFO                GST_PADS gstutils.c:932:gst_pad_check_link: trying to link capsfilter1:src and videoscale0:sink
0:00:00.037594786 16087      0x261e2c0 INFO                GST_PADS gstpad.c:3995:gst_pad_peer_query:<videoscale0:src> pad has no peer
0:00:00.038474491 16087      0x261e2c0 INFO                GST_PADS gstutils.c:1444:prepare_link_maybe_ghosting: capsfilter1 and videoscale0 in same bin, no need for ghost pads
0:00:00.038497172 16087      0x261e2c0 INFO                GST_PADS gstpad.c:2234:gst_pad_link_prepare: trying to link capsfilter1:src and videoscale0:sink
0:00:00.038524523 16087      0x261e2c0 INFO                GST_PADS gstpad.c:3995:gst_pad_peer_query:<videoscale0:src> pad has no peer
0:00:00.039393764 16087      0x261e2c0 INFO                GST_PADS gstpad.c:2440:gst_pad_link_full: linked capsfilter1:src and videoscale0:sink, successful
0:00:00.039412436 16087      0x261e2c0 INFO               GST_EVENT gstevent.c:1374:gst_event_new_reconfigure: creating reconfigure event
0:00:00.039424180 16087      0x261e2c0 INFO               GST_EVENT gstpad.c:5499:gst_pad_send_event_unchecked:<capsfilter1:src> Received event on flushing pad. Discarding
0:00:00.039439763 16087      0x261e2c0 INFO            GST_PIPELINE grammar.y:571:gst_parse_perform_link: linking videoscale0:(any) to caps0:(any) (0/0) with caps "(NULL)"
0:00:00.039455631 16087      0x261e2c0 INFO        GST_ELEMENT_PADS gstutils.c:1571:gst_element_link_pads_full: trying to link element videoscale0:(any) to element caps0:(any)
0:00:00.039470076 16087      0x261e2c0 INFO                GST_PADS gstutils.c:932:gst_pad_check_link: trying to link videoscale0:src and caps0:sink
0:00:00.039528947 16087      0x261e2c0 INFO                GST_PADS gstpad.c:3995:gst_pad_peer_query:<caps0:src> pad has no peer
0:00:00.039546243 16087      0x261e2c0 INFO                GST_PADS gstutils.c:1444:prepare_link_maybe_ghosting: videoscale0 and caps0 in same bin, no need for ghost pads
0:00:00.039562092 16087      0x261e2c0 INFO                GST_PADS gstpad.c:2234:gst_pad_link_prepare: trying to link videoscale0:src and caps0:sink
0:00:00.039614511 16087      0x261e2c0 INFO                GST_PADS gstpad.c:3995:gst_pad_peer_query:<caps0:src> pad has no peer
0:00:00.039630357 16087      0x261e2c0 INFO                GST_PADS gstpad.c:2440:gst_pad_link_full: linked videoscale0:src and caps0:sink, successful
0:00:00.039643323 16087      0x261e2c0 INFO               GST_EVENT gstevent.c:1374:gst_event_new_reconfigure: creating reconfigure event
0:00:00.039654066 16087      0x261e2c0 INFO               GST_EVENT gstpad.c:5499:gst_pad_send_event_unchecked:<videoscale0:src> Received event on flushing pad. Discarding
0:00:00.039669571 16087      0x261e2c0 INFO           GST_PARENTAGE gstbin.c:4177:gst_bin_get_by_name: [pipeline0]: looking up child element mix
0:00:00.039686973 16087      0x261e2c0 INFO            GST_PIPELINE grammar.y:571:gst_parse_perform_link: linking caps0:(any) to mix:mix (0/0) with caps "(NULL)"
0:00:00.039701741 16087      0x261e2c0 INFO        GST_ELEMENT_PADS gstutils.c:1571:gst_element_link_pads_full: trying to link element caps0:(any) to element mix:(any)
0:00:00.039799316 16087      0x261e2c0 INFO        GST_ELEMENT_PADS gstelement.c:646:gst_element_add_pad:<mix> adding pad 'sink_0'
0:00:00.039825412 16087      0x261e2c0 INFO                GST_PADS gstutils.c:1444:prepare_link_maybe_ghosting: caps0 and mix in same bin, no need for ghost pads
0:00:00.039842253 16087      0x261e2c0 INFO                GST_PADS gstpad.c:2234:gst_pad_link_prepare: trying to link caps0:src and mix:sink_0
0:00:00.039915034 16087      0x261e2c0 INFO                GST_PADS gstpad.c:2440:gst_pad_link_full: linked caps0:src and mix:sink_0, successful
0:00:00.039931178 16087      0x261e2c0 INFO               GST_EVENT gstevent.c:1374:gst_event_new_reconfigure: creating reconfigure event
0:00:00.039942542 16087      0x261e2c0 INFO               GST_EVENT gstpad.c:5499:gst_pad_send_event_unchecked:<caps0:src> Received event on flushing pad. Discarding
0:00:00.039956781 16087      0x261e2c0 INFO            GST_PIPELINE grammar.y:571:gst_parse_perform_link: linking mix:(any) to xvimagesink0:(any) (0/0) with caps "video/x-raw, width=(int)1920, height=(int)1080, format=(string)I420, framerate=(fraction)25/1, pixel-aspect-ratio=(fraction)1/1"
0:00:00.039981370 16087      0x261e2c0 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "capsfilter"
0:00:00.040003575 16087      0x261e2c0 INFO        GST_ELEMENT_PADS gstelement.c:646:gst_element_add_pad:<GstBaseTransform@0x284c7d0> adding pad 'sink'
0:00:00.040024651 16087      0x261e2c0 INFO        GST_ELEMENT_PADS gstelement.c:646:gst_element_add_pad:<GstBaseTransform@0x284c7d0> adding pad 'src'
0:00:00.040041827 16087      0x261e2c0 INFO              GST_STATES gstbin.c:1829:gst_bin_get_state_func:<pipeline0> getting state
0:00:00.040060027 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2330:gst_element_continue_state:<capsfilter2> completed state change to NULL
0:00:00.040074191 16087      0x261e2c0 INFO               GST_EVENT gstevent.c:1374:gst_event_new_reconfigure: creating reconfigure event
0:00:00.040087340 16087      0x261e2c0 INFO        GST_ELEMENT_PADS gstutils.c:1571:gst_element_link_pads_full: trying to link element mix:(any) to element capsfilter2:sink
0:00:00.040100448 16087      0x261e2c0 INFO        GST_ELEMENT_PADS gstelement.c:897:gst_element_get_static_pad: found pad capsfilter2:sink
0:00:00.040112091 16087      0x261e2c0 INFO                GST_PADS gstutils.c:1444:prepare_link_maybe_ghosting: mix and capsfilter2 in same bin, no need for ghost pads
0:00:00.040126200 16087      0x261e2c0 INFO                GST_PADS gstpad.c:2234:gst_pad_link_prepare: trying to link mix:src and capsfilter2:sink
0:00:00.040143787 16087      0x261e2c0 INFO                GST_PADS gstpad.c:3995:gst_pad_peer_query:<capsfilter2:src> pad has no peer
0:00:00.040158898 16087      0x261e2c0 INFO                GST_PADS gstpad.c:2440:gst_pad_link_full: linked mix:src and capsfilter2:sink, successful
0:00:00.040171719 16087      0x261e2c0 INFO               GST_EVENT gstevent.c:1374:gst_event_new_reconfigure: creating reconfigure event
0:00:00.040182356 16087      0x261e2c0 INFO               GST_EVENT gstpad.c:5499:gst_pad_send_event_unchecked:<mix:src> Received event on flushing pad. Discarding
0:00:00.040195876 16087      0x261e2c0 INFO        GST_ELEMENT_PADS gstutils.c:1571:gst_element_link_pads_full: trying to link element capsfilter2:src to element xvimagesink0:(any)
0:00:00.040208986 16087      0x261e2c0 INFO        GST_ELEMENT_PADS gstelement.c:897:gst_element_get_static_pad: found pad capsfilter2:src
0:00:00.040221424 16087      0x261e2c0 INFO                GST_PADS gstutils.c:932:gst_pad_check_link: trying to link capsfilter2:src and xvimagesink0:sink
0:00:00.040245160 16087      0x261e2c0 INFO                GST_PADS gstutils.c:1444:prepare_link_maybe_ghosting: capsfilter2 and xvimagesink0 in same bin, no need for ghost pads
0:00:00.040262210 16087      0x261e2c0 INFO                GST_PADS gstpad.c:2234:gst_pad_link_prepare: trying to link capsfilter2:src and xvimagesink0:sink
0:00:00.040284809 16087      0x261e2c0 INFO                GST_PADS gstpad.c:2440:gst_pad_link_full: linked capsfilter2:src and xvimagesink0:sink, successful
0:00:00.040299858 16087      0x261e2c0 INFO               GST_EVENT gstevent.c:1374:gst_event_new_reconfigure: creating reconfigure event
0:00:00.040310513 16087      0x261e2c0 INFO               GST_EVENT gstpad.c:5499:gst_pad_send_event_unchecked:<capsfilter2:src> Received event on flushing pad. Discarding
0:00:00.041271647 16087      0x261e2c0 INFO           GST_PARENTAGE gstbin.c:4177:gst_bin_get_by_name: [pipeline0]: looking up child element caps0
0:00:00.041759380 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<xvimagesink0> current NULL pending VOID_PENDING, desired next READY
0:00:00.043237340 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2330:gst_element_continue_state:<xvimagesink0> completed state change to READY
0:00:00.043274743 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2235:_priv_gst_element_state_changed:<xvimagesink0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.043306127 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2684:gst_bin_change_state_func:<pipeline0> child 'xvimagesink0' changed state to 2(READY) successfully
0:00:00.043328678 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<capsfilter2> current NULL pending VOID_PENDING, desired next READY
0:00:00.043344835 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2330:gst_element_continue_state:<capsfilter2> completed state change to READY
0:00:00.043356450 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2235:_priv_gst_element_state_changed:<capsfilter2> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.043370931 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2684:gst_bin_change_state_func:<pipeline0> child 'capsfilter2' changed state to 2(READY) successfully
0:00:00.043387163 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<mix> current NULL pending VOID_PENDING, desired next READY
0:00:00.043401690 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2330:gst_element_continue_state:<mix> completed state change to READY
0:00:00.043414791 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2235:_priv_gst_element_state_changed:<mix> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.043429313 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2684:gst_bin_change_state_func:<pipeline0> child 'mix' changed state to 2(READY) successfully
0:00:00.043445784 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<caps0> current NULL pending VOID_PENDING, desired next READY
0:00:00.043458915 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2330:gst_element_continue_state:<caps0> completed state change to READY
0:00:00.043470338 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2235:_priv_gst_element_state_changed:<caps0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.043485089 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2684:gst_bin_change_state_func:<pipeline0> child 'caps0' changed state to 2(READY) successfully
0:00:00.043501473 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<videoscale0> current NULL pending VOID_PENDING, desired next READY
0:00:00.043514547 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2330:gst_element_continue_state:<videoscale0> completed state change to READY
0:00:00.043525522 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2235:_priv_gst_element_state_changed:<videoscale0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.043539233 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2684:gst_bin_change_state_func:<pipeline0> child 'videoscale0' changed state to 2(READY) successfully
0:00:00.043553313 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<capsfilter1> current NULL pending VOID_PENDING, desired next READY
0:00:00.043567581 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2330:gst_element_continue_state:<capsfilter1> completed state change to READY
0:00:00.043578378 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2235:_priv_gst_element_state_changed:<capsfilter1> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.043592107 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2684:gst_bin_change_state_func:<pipeline0> child 'capsfilter1' changed state to 2(READY) successfully
0:00:00.043605902 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<videotestsrc0> current NULL pending VOID_PENDING, desired next READY
0:00:00.043619738 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2330:gst_element_continue_state:<videotestsrc0> completed state change to READY
0:00:00.043634669 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2235:_priv_gst_element_state_changed:<videotestsrc0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.043650696 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2684:gst_bin_change_state_func:<pipeline0> child 'videotestsrc0' changed state to 2(READY) successfully
0:00:00.043671660 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2305:gst_element_continue_state:<pipeline0> committing state from NULL to READY, pending PLAYING, next PAUSED
0:00:00.043687704 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2235:_priv_gst_element_state_changed:<pipeline0> notifying about state-changed NULL to READY (PLAYING pending)
0:00:00.043703426 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2312:gst_element_continue_state:<pipeline0> continue state change READY to PAUSED, final PLAYING
0:00:00.043728897 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<xvimagesink0> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.043753806 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2690:gst_bin_change_state_func:<pipeline0> child 'xvimagesink0' is changing state asynchronously to PAUSED
0:00:00.043771898 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<capsfilter2> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.043789409 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2330:gst_element_continue_state:<capsfilter2> completed state change to PAUSED
0:00:00.043804184 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2235:_priv_gst_element_state_changed:<capsfilter2> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.043825606 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2684:gst_bin_change_state_func:<pipeline0> child 'capsfilter2' changed state to 3(PAUSED) successfully
0:00:00.043843006 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<mix> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.043860626 16087      0x261e2c0 INFO              aggregator gstaggregator.c:1754:gst_aggregator_src_pad_activate_mode_func:<mix:src> Activating pad!
0:00:00.043874477 16087      0x261e2c0 INFO              aggregator gstaggregator.c:912:gst_aggregator_start_srcpad_task:<mix> Starting srcpad task
0:00:00.043900374 16087      0x261e2c0 INFO                    task gsttask.c:450:gst_task_set_lock: setting stream lock 0x2842c10 on task 0x2880050
0:00:00.043917302 16087      0x261e2c0 INFO                GST_PADS gstpad.c:5845:gst_pad_start_task:<mix:src> created task 0x2880050
0:00:00.043976403 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2330:gst_element_continue_state:<mix> completed state change to PAUSED
0:00:00.043993944 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2235:_priv_gst_element_state_changed:<mix> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.044009717 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2684:gst_bin_change_state_func:<pipeline0> child 'mix' changed state to 3(PAUSED) successfully
0:00:00.044026297 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<caps0> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.044042783 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2330:gst_element_continue_state:<caps0> completed state change to PAUSED
0:00:00.044055172 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2235:_priv_gst_element_state_changed:<caps0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.044052737 16087      0x286f540 WARN              aggregator gstaggregator.c:1366:gst_aggregator_query_latency_unlocked:<mix> Latency query failed
0:00:00.044071322 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2684:gst_bin_change_state_func:<pipeline0> child 'caps0' changed state to 3(PAUSED) successfully
0:00:00.044120483 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<videoscale0> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.044139052 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2330:gst_element_continue_state:<videoscale0> completed state change to PAUSED
0:00:00.044150961 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2235:_priv_gst_element_state_changed:<videoscale0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.044169855 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2684:gst_bin_change_state_func:<pipeline0> child 'videoscale0' changed state to 3(PAUSED) successfully
0:00:00.044187025 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<capsfilter1> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.044203952 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2330:gst_element_continue_state:<capsfilter1> completed state change to PAUSED
0:00:00.044217191 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2235:_priv_gst_element_state_changed:<capsfilter1> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.044231738 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2684:gst_bin_change_state_func:<pipeline0> child 'capsfilter1' changed state to 3(PAUSED) successfully
0:00:00.044245407 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<videotestsrc0> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.044268175 16087      0x261e2c0 INFO                 basesrc gstbasesrc.c:1339:gst_base_src_do_seek:<videotestsrc0> seeking: time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=99:99:99.999999999, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.000000000, duration 99:99:99.999999999
0:00:00.044302114 16087      0x261e2c0 INFO                    task gsttask.c:450:gst_task_set_lock: setting stream lock 0x28420d0 on task 0x2880170
0:00:00.044317282 16087      0x261e2c0 INFO                GST_PADS gstpad.c:5845:gst_pad_start_task:<videotestsrc0:src> created task 0x2880170
0:00:00.044361829 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2330:gst_element_continue_state:<videotestsrc0> completed state change to PAUSED
0:00:00.044377430 16087      0x261e2c0 INFO              GST_STATES gstelement.c:2235:_priv_gst_element_state_changed:<videotestsrc0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.044393527 16087      0x261e2c0 INFO              GST_STATES gstbin.c:2684:gst_bin_change_state_func:<pipeline0> child 'videotestsrc0' changed state to 3(PAUSED) successfully
0:00:00.044395834 16087      0x286f230 INFO        GST_ELEMENT_PADS gstelement.c:894:gst_element_get_static_pad: no such pad 'sink' in element "videotestsrc0"
0:00:00.044452073 16087      0x286f230 FIXME                default gstutils.c:3766: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.044708805 16087      0x286f230 INFO               GST_EVENT gstevent.c:679:gst_event_new_caps: creating caps event video/x-raw, format=(string)I420, width=(int)1920, height=(int)1080, framerate=(fraction)25/1, pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive
0:00:00.044828102 16087      0x286f230 INFO           basetransform gstbasetransform.c:1367:gst_base_transform_setcaps:<capsfilter1> reuse caps
0:00:00.044850810 16087      0x286f230 INFO               GST_EVENT gstevent.c:679:gst_event_new_caps: creating caps event video/x-raw, format=(string)I420, width=(int)1920, height=(int)1080, framerate=(fraction)25/1, pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive
0:00:00.045010255 16087      0x286f230 INFO           basetransform gstbasetransform.c:1367:gst_base_transform_setcaps:<videoscale0> reuse caps
0:00:00.045041349 16087      0x286f230 INFO               GST_EVENT gstevent.c:679:gst_event_new_caps: creating caps event video/x-raw, format=(string)I420, width=(int)1920, height=(int)1080, framerate=(fraction)25/1, pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive
0:00:00.045134124 16087      0x286f230 INFO           basetransform gstbasetransform.c:1367:gst_base_transform_setcaps:<caps0> reuse caps
0:00:00.045153841 16087      0x286f230 INFO               GST_EVENT gstevent.c:679:gst_event_new_caps: creating caps event video/x-raw, format=(string)I420, width=(int)1920, height=(int)1080, framerate=(fraction)25/1, pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive
0:00:00.045223923 16087      0x286f230 INFO         videoaggregator gstvideoaggregator.c:804:gst_videoaggregator_pad_sink_setcaps:<mix:sink_0> Setting caps video/x-raw, format=(string)I420, width=(int)1920, height=(int)1080, framerate=(fraction)25/1, pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive
0:00:00.054652629 16087      0x286f230 INFO               GST_EVENT gstevent.c:760:gst_event_new_segment: creating segment event time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=99:99:99.999999999, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.000000000, duration 99:99:99.999999999
0:00:00.054790998 16087      0x286f230 INFO                 basesrc gstbasesrc.c:2838:gst_base_src_loop:<videotestsrc0> marking pending DISCONT
0:00:00.054963283 16087      0x286f540 INFO         videoaggregator gstvideoaggregator.c:597:gst_videoaggregator_src_setcaps:<mix:src> set src caps: video/x-raw, format=(string)I420, width=(int)1920, height=(int)1080, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, chroma-site=(string)mpeg2, colorimetry=(string)bt709, framerate=(fraction)25/1
0:00:00.055016129 16087      0x286f540 INFO              aggregator gstaggregator.c:494:gst_aggregator_push_mandatory_events:<mix> pushing stream start
0:00:00.055047079 16087      0x286f540 FIXME               basesink gstbasesink.c:3117:gst_base_sink_default_event:<xvimagesink0> stream-start event without group-id. Consider implementing group-id handling in the upstream elements
0:00:00.055083546 16087      0x286f540 INFO              aggregator gstaggregator.c:506:gst_aggregator_push_mandatory_events:<mix> pushing caps: video/x-raw, format=(string)I420, width=(int)1920, height=(int)1080, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, chroma-site=(string)mpeg2, colorimetry=(string)bt709, framerate=(fraction)25/1
0:00:00.055120751 16087      0x286f540 INFO               GST_EVENT gstevent.c:679:gst_event_new_caps: creating caps event video/x-raw, format=(string)I420, width=(int)1920, height=(int)1080, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, chroma-site=(string)mpeg2, colorimetry=(string)bt709, framerate=(fraction)25/1
0:00:00.055183024 16087      0x286f540 INFO           basetransform gstbasetransform.c:1367:gst_base_transform_setcaps:<capsfilter2> reuse caps
0:00:00.055199304 16087      0x286f540 INFO               GST_EVENT gstevent.c:679:gst_event_new_caps: creating caps event video/x-raw, format=(string)I420, width=(int)1920, height=(int)1080, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, chroma-site=(string)mpeg2, colorimetry=(string)bt709, framerate=(fraction)25/1
0:00:00.055740806 16087      0x286f540 INFO               GST_EVENT gstevent.c:760:gst_event_new_segment: creating segment event time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=99:99:99.999999999, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.000000000, duration 99:99:99.999999999
0:00:00.060003526 16087      0x286f540 INFO        compositor_blend blend.c:430:_blend_i420: Fast copy (alpha == 1.0)
0:00:00.060731684 16087      0x286f540 INFO        compositor_blend blend.c:430:_blend_i420: Fast copy (alpha == 1.0)
0:00:00.060917786 16087      0x286f540 INFO        compositor_blend blend.c:430:_blend_i420: Fast copy (alpha == 1.0)
0:00:00.063678456 16087      0x286f540 INFO              GST_STATES gstbin.c:3158:bin_handle_async_done:<pipeline0> committing state from READY to PAUSED, old pending PLAYING
0:00:00.063724796 16087      0x286f540 INFO              GST_STATES gstbin.c:3187:bin_handle_async_done:<pipeline0> continue state change, pending PLAYING
0:00:00.063747155 16087      0x286f540 INFO              GST_STATES gstelement.c:2235:_priv_gst_element_state_changed:<pipeline0> notifying about state-changed READY to PAUSED (PLAYING pending)
0:00:00.063885995 16087 0x7f05580036d0 INFO              GST_STATES gstbin.c:2981:gst_bin_continue_func:<pipeline0> continue state change PAUSED to PLAYING, final PLAYING
0:00:00.064080407 16087 0x7f05580036d0 INFO               GST_EVENT gstevent.c:1245:gst_event_new_latency: creating latency event 0:00:00.000000000
0:00:00.064147275 16087 0x7f05580036d0 INFO                     bin gstbin.c:2502:gst_bin_do_latency_func:<pipeline0> configured latency of 0:00:00.000000000
0:00:00.064195999 16087 0x7f05580036d0 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<xvimagesink0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.066574840 16087 0x7f05580036d0 INFO              GST_STATES gstelement.c:2330:gst_element_continue_state:<xvimagesink0> completed state change to PLAYING
0:00:00.066642789 16087 0x7f05580036d0 INFO              GST_STATES gstelement.c:2235:_priv_gst_element_state_changed:<xvimagesink0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.066721488 16087 0x7f05580036d0 INFO              GST_STATES gstbin.c:2684:gst_bin_change_state_func:<pipeline0> child 'xvimagesink0' changed state to 4(PLAYING) successfully
0:00:00.066760070 16087 0x7f05580036d0 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<capsfilter2> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.066785182 16087 0x7f05580036d0 INFO              GST_STATES gstelement.c:2330:gst_element_continue_state:<capsfilter2> completed state change to PLAYING
0:00:00.066804603 16087 0x7f05580036d0 INFO              GST_STATES gstelement.c:2235:_priv_gst_element_state_changed:<capsfilter2> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.066855875 16087 0x7f05580036d0 INFO              GST_STATES gstbin.c:2684:gst_bin_change_state_func:<pipeline0> child 'capsfilter2' changed state to 4(PLAYING) successfully
0:00:00.066884537 16087 0x7f05580036d0 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<mix> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.066907862 16087 0x7f05580036d0 INFO              GST_STATES gstelement.c:2330:gst_element_continue_state:<mix> completed state change to PLAYING
0:00:00.066926910 16087 0x7f05580036d0 INFO              GST_STATES gstelement.c:2235:_priv_gst_element_state_changed:<mix> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.066972621 16087 0x7f05580036d0 INFO              GST_STATES gstbin.c:2684:gst_bin_change_state_func:<pipeline0> child 'mix' changed state to 4(PLAYING) successfully
0:00:00.067002979 16087 0x7f05580036d0 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<caps0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.067025187 16087 0x7f05580036d0 INFO              GST_STATES gstelement.c:2330:gst_element_continue_state:<caps0> completed state change to PLAYING
0:00:00.067043433 16087 0x7f05580036d0 INFO              GST_STATES gstelement.c:2235:_priv_gst_element_state_changed:<caps0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.067091314 16087 0x7f05580036d0 INFO              GST_STATES gstbin.c:2684:gst_bin_change_state_func:<pipeline0> child 'caps0' changed state to 4(PLAYING) successfully
0:00:00.067119904 16087 0x7f05580036d0 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<videoscale0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.067141116 16087 0x7f05580036d0 INFO              GST_STATES gstelement.c:2330:gst_element_continue_state:<videoscale0> completed state change to PLAYING
0:00:00.067160713 16087 0x7f05580036d0 INFO              GST_STATES gstelement.c:2235:_priv_gst_element_state_changed:<videoscale0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.067210308 16087 0x7f05580036d0 INFO              GST_STATES gstbin.c:2684:gst_bin_change_state_func:<pipeline0> child 'videoscale0' changed state to 4(PLAYING) successfully
0:00:00.067239697 16087 0x7f05580036d0 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<capsfilter1> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.067262256 16087 0x7f05580036d0 INFO              GST_STATES gstelement.c:2330:gst_element_continue_state:<capsfilter1> completed state change to PLAYING
0:00:00.067280848 16087 0x7f05580036d0 INFO              GST_STATES gstelement.c:2235:_priv_gst_element_state_changed:<capsfilter1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.067327324 16087 0x7f05580036d0 INFO              GST_STATES gstbin.c:2684:gst_bin_change_state_func:<pipeline0> child 'capsfilter1' changed state to 4(PLAYING) successfully
0:00:00.067352179 16087 0x7f05580036d0 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<videotestsrc0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.067376460 16087 0x7f05580036d0 INFO              GST_STATES gstelement.c:2330:gst_element_continue_state:<videotestsrc0> completed state change to PLAYING
0:00:00.067397485 16087 0x7f05580036d0 INFO              GST_STATES gstelement.c:2235:_priv_gst_element_state_changed:<videotestsrc0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.067444662 16087 0x7f05580036d0 INFO              GST_STATES gstbin.c:2684:gst_bin_change_state_func:<pipeline0> child 'videotestsrc0' changed state to 4(PLAYING) successfully
0:00:00.067471851 16087 0x7f05580036d0 INFO              GST_STATES gstelement.c:2330:gst_element_continue_state:<pipeline0> completed state change to PLAYING
0:00:00.067491023 16087 0x7f05580036d0 INFO              GST_STATES gstelement.c:2235:_priv_gst_element_state_changed:<pipeline0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.068584453 16087      0x286f540 INFO         videoaggregator gstvideoaggregator.c:597:gst_videoaggregator_src_setcaps:<mix:src> set src caps: video/x-raw, format=(string)I420, width=(int)1920, height=(int)1080, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, chroma-site=(string)mpeg2, colorimetry=(string)bt709, framerate=(fraction)25/1
0:00:00.073908200 16087      0x286f540 INFO        compositor_blend blend.c:430:_blend_i420: Fast copy (alpha == 1.0)
0:00:00.075038107 16087      0x286f540 INFO        compositor_blend blend.c:430:_blend_i420: Fast copy (alpha == 1.0)
0:00:00.075379494 16087      0x286f540 INFO        compositor_blend blend.c:430:_blend_i420: Fast copy (alpha == 1.0)
...more 'Fast copy' messages...
reconfigure!
0:00:01.043026595 16087      0x261e2c0 INFO               GST_EVENT gstevent.c:1374:gst_event_new_reconfigure: creating reconfigure event
0:00:01.068326565 16087      0x286f540 INFO        compositor_blend blend.c:430:_blend_i420: Fast copy (alpha == 1.0)
0:00:01.068948523 16087      0x286f540 INFO        compositor_blend blend.c:430:_blend_i420: Fast copy (alpha == 1.0)
0:00:01.069153917 16087      0x286f540 INFO        compositor_blend blend.c:430:_blend_i420: Fast copy (alpha == 1.0)
0:00:01.111184105 16087      0x286f540 INFO        compositor_blend blend.c:430:_blend_i420: Fast copy (alpha == 1.0)
0:00:01.111823919 16087      0x286f540 INFO        compositor_blend blend.c:430:_blend_i420: Fast copy (alpha == 1.0)
0:00:01.112013166 16087      0x286f540 INFO        compositor_blend blend.c:430:_blend_i420: Fast copy (alpha == 1.0)
0:00:01.117484848 16087      0x286f230 INFO           basetransform gstbasetransform.c:1367:gst_base_transform_setcaps:<capsfilter1> reuse caps
0:00:01.117924951 16087      0x286f230 INFO               GST_EVENT gstevent.c:679:gst_event_new_caps: creating caps event video/x-raw, format=(string)I420, width=(int)950, height=(int)534, framerate=(fraction)25/1, pixel-aspect-ratio=(fraction)1424/1425, interlace-mode=(string)progressive
0:00:01.118066107 16087      0x286f230 INFO           basetransform gstbasetransform.c:1367:gst_base_transform_setcaps:<caps0> reuse caps
0:00:01.118104738 16087      0x286f230 INFO               GST_EVENT gstevent.c:679:gst_event_new_caps: creating caps event video/x-raw, format=(string)I420, width=(int)950, height=(int)534, framerate=(fraction)25/1, pixel-aspect-ratio=(fraction)1424/1425, interlace-mode=(string)progressive
0:00:01.118176071 16087      0x286f230 INFO         videoaggregator gstvideoaggregator.c:804:gst_videoaggregator_pad_sink_setcaps:<mix:sink_0> Setting caps video/x-raw, format=(string)I420, width=(int)950, height=(int)534, framerate=(fraction)25/1, pixel-aspect-ratio=(fraction)1424/1425, interlace-mode=(string)progressive
0:00:01.118213325 16087      0x286f230 ERROR        videoaggregator gstvideoaggregator.c:822:gst_videoaggregator_pad_sink_setcaps:<mix:sink_0> got input caps video/x-raw, format=(string)I420, width=(int)950, height=(int)534, framerate=(fraction)25/1, pixel-aspect-ratio=(fraction)1424/1425, interlace-mode=(string)progressive, but current caps are video/x-raw, format=(string)I420, width=(int)1920, height=(int)1080, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, chroma-site=(string)mpeg2, colorimetry=(string)bt709, framerate=(fraction)25/1
0:00:01.118310286 16087      0x286f230 INFO         videoaggregator gstvideoaggregator.c:804:gst_videoaggregator_pad_sink_setcaps:<mix:sink_0> Setting caps video/x-raw, format=(string)I420, width=(int)950, height=(int)534, framerate=(fraction)25/1, pixel-aspect-ratio=(fraction)1424/1425, interlace-mode=(string)progressive
0:00:01.118366058 16087      0x286f230 ERROR        videoaggregator gstvideoaggregator.c:822:gst_videoaggregator_pad_sink_setcaps:<mix:sink_0> got input caps video/x-raw, format=(string)I420, width=(int)950, height=(int)534, framerate=(fraction)25/1, pixel-aspect-ratio=(fraction)1424/1425, interlace-mode=(string)progressive, but current caps are video/x-raw, format=(string)I420, width=(int)1920, height=(int)1080, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, chroma-site=(string)mpeg2, colorimetry=(string)bt709, framerate=(fraction)25/1
0:00:01.118403798 16087      0x286f230 WARN                GST_PADS gstpad.c:3989:gst_pad_peer_query:<caps0:src> could not send sticky events
0:00:01.124321661 16087      0x286f230 INFO         videoaggregator gstvideoaggregator.c:804:gst_videoaggregator_pad_sink_setcaps:<mix:sink_0> Setting caps video/x-raw, format=(string)I420, width=(int)950, height=(int)534, framerate=(fraction)25/1, pixel-aspect-ratio=(fraction)1424/1425, interlace-mode=(string)progressive
0:00:01.124394733 16087      0x286f230 ERROR        videoaggregator gstvideoaggregator.c:822:gst_videoaggregator_pad_sink_setcaps:<mix:sink_0> got input caps video/x-raw, format=(string)I420, width=(int)950, height=(int)534, framerate=(fraction)25/1, pixel-aspect-ratio=(fraction)1424/1425, interlace-mode=(string)progressive, but current caps are video/x-raw, format=(string)I420, width=(int)1920, height=(int)1080, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, chroma-site=(string)mpeg2, colorimetry=(string)bt709, framerate=(fraction)25/1
0:00:01.124459457 16087      0x286f230 WARN                 basesrc gstbasesrc.c:2943:gst_base_src_loop:<videotestsrc0> error: Internal data flow error.
0:00:01.124484505 16087      0x286f230 WARN                 basesrc gstbasesrc.c:2943:gst_base_src_loop:<videotestsrc0> error: streaming task paused, reason not-negotiated (-4)
0:00:01.124521205 16087      0x286f230 INFO        GST_ERROR_SYSTEM gstelement.c:1837:gst_element_message_full:<videotestsrc0> posting message: Internal data flow error.
0:00:01.124578922 16087      0x286f230 INFO        GST_ERROR_SYSTEM gstelement.c:1860:gst_element_message_full:<videotestsrc0> posted error message: Internal data flow error.
0:00:01.124643961 16087      0x286f230 INFO         videoaggregator gstvideoaggregator.c:804:gst_videoaggregator_pad_sink_setcaps:<mix:sink_0> Setting caps video/x-raw, format=(string)I420, width=(int)950, height=(int)534, framerate=(fraction)25/1, pixel-aspect-ratio=(fraction)1424/1425, interlace-mode=(string)progressive
0:00:01.124682813 16087      0x286f230 ERROR        videoaggregator gstvideoaggregator.c:822:gst_videoaggregator_pad_sink_setcaps:<mix:sink_0> got input caps video/x-raw, format=(string)I420, width=(int)950, height=(int)534, framerate=(fraction)25/1, pixel-aspect-ratio=(fraction)1424/1425, interlace-mode=(string)progressive, but current caps are video/x-raw, format=(string)I420, width=(int)1920, height=(int)1080, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, chroma-site=(string)mpeg2, colorimetry=(string)bt709, framerate=(fraction)25/1
0:00:01.124752516 16087      0x286f230 INFO         videoaggregator gstvideoaggregator.c:804:gst_videoaggregator_pad_sink_setcaps:<mix:sink_0> Setting caps video/x-raw, format=(string)I420, width=(int)950, height=(int)534, framerate=(fraction)25/1, pixel-aspect-ratio=(fraction)1424/1425, interlace-mode=(string)progressive
0:00:01.124789102 16087      0x286f230 ERROR        videoaggregator gstvideoaggregator.c:822:gst_videoaggregator_pad_sink_setcaps:<mix:sink_0> got input caps video/x-raw, format=(string)I420, width=(int)950, height=(int)534, framerate=(fraction)25/1, pixel-aspect-ratio=(fraction)1424/1425, interlace-mode=(string)progressive, but current caps are video/x-raw, format=(string)I420, width=(int)1920, height=(int)1080, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, chroma-site=(string)mpeg2, colorimetry=(string)bt709, framerate=(fraction)25/1
0:00:01.124841108 16087      0x286f230 INFO                    task gsttask.c:315:gst_task_func:<videotestsrc0:src> Task going to paused
Received Error-Signal on Mixing-Pipeline
Error-Details: #1: gstbasesrc.c(2943): gst_base_src_loop (): /GstPipeline:pipeline0/GstVideoTestSrc:videotestsrc0:
streaming task paused, reason not-negotiated (-4)
0:00:01.184196644 16087      0x286f540 INFO                    task gsttask.c:315:gst_task_func:<mix:src> Task going to paused
