0:00:00.000105671 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m            GST_INIT gst.c:605:init_pre:[00m Initializing GStreamer Core Library version 0.10.35
0:00:00.000200517 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m            GST_INIT gst.c:606:init_pre:[00m Using library installed in /usr/lib
0:00:00.000229012 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m            GST_INIT gst.c:616:init_pre:[00m Linux steph-laptop 3.1.0-0.rc9.git0.0.fc16.i686 #1 SMP Wed Oct 5 16:15:47 UTC 2011 i686
0:00:00.000337547 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m            GST_INIT gstquery.c:107:_gst_query_initialize:[00m init queries
0:00:00.001098895 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m            GST_INIT gstmessage.c:73:_gst_message_initialize:[00m init messages
0:00:00.001499789 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;36m  GST_PLUGIN_LOADING gstplugin.c:350:_gst_plugin_initialize:[00m registering 0 static plugins
0:00:00.001652464 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;36m  GST_PLUGIN_LOADING gstplugin.c:255:gst_plugin_register_static:[00m registered static plugin "staticelements"
0:00:00.001681099 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;36m  GST_PLUGIN_LOADING gstplugin.c:257:gst_plugin_register_static:[00m added static plugin "staticelements", result: 1
0:00:00.002062157 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00m        GST_REGISTRY gstregistry.c:1597:ensure_current_registry:[00m reading registry cache: /home/steph/.gstreamer-0.10/registry.i386.bin
0:00:00.018857224 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00m        GST_REGISTRY gstregistrybinary.c:614:gst_registry_binary_read_cache:[00m loaded /home/steph/.gstreamer-0.10/registry.i386.bin in 0.016763 seconds
0:00:00.018956050 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00m        GST_REGISTRY gstregistry.c:1467:scan_and_update_registry:[00m Validating plugins from registry cache: /home/steph/.gstreamer-0.10/registry.i386.bin
0:00:00.020228852 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00m        GST_REGISTRY gstregistry.c:1559:scan_and_update_registry:[00m Registry cache has not changed
0:00:00.020249316 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00m        GST_REGISTRY gstregistry.c:1626:ensure_current_registry:[00m registry reading and updating done, result = 1
0:00:00.020265240 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m            GST_INIT gst.c:797:init_post:[00m GLib runtime version: 2.30.0
0:00:00.020282212 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m            GST_INIT gst.c:799:init_post:[00m GLib headers version: 2.29.8
0:00:00.020304142 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m            GST_INIT gst.c:450:gst_init_check:[00m initialized GStreamer successfully
0:00:00.040461758 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create:[00m creating element "pipeline" named "Discoverer"
0:00:00.040884582 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;36m  GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file:[00m plugin "/usr/lib/gstreamer-0.10/libgstdecodebin2.so" loaded
0:00:00.040906512 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create:[00m creating element "uridecodebin" named "discoverer-uri"
0:00:00.041128749 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:375:gst_element_factory_create:[00m creating element "decodebin2"
0:00:00.041790853 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;36m  GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file:[00m plugin "/usr/lib/gstreamer-0.10/libgstcoreelements.so" loaded
0:00:00.041812504 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create:[00m creating element "typefind" named "typefind"
0:00:00.041966855 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<GstTypeFindElement@0xa00b800>[00m adding pad 'sink'
0:00:00.042017490 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<GstTypeFindElement@0xa00b800>[00m adding pad 'src'
0:00:00.042049548 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:969:gst_element_get_static_pad:[00m found pad typefind:sink
0:00:00.042156685 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link sink:proxypad0 and typefind:sink
0:00:00.042179244 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked sink:proxypad0 and typefind:sink, successful
0:00:00.042206902 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<GstDecodeBin2@0xa008000>[00m adding pad 'sink'
0:00:00.042249296 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking sink:proxypad0(0xa00d810) and typefind:sink(0xa00c000)
0:00:00.042269620 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked sink:proxypad0 and typefind:sink
0:00:00.042289315 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<decodebin20>[00m removed child "typefind"
0:00:00.042323817 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<typefind>[00m dispose
0:00:00.042341068 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<typefind>[00m removing pad 'src'
0:00:00.042368446 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<typefind>[00m removing pad 'sink'
0:00:00.042390028 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<typefind>[00m parent class dispose
0:00:00.042409793 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<typefind>[00m finalize
0:00:00.042428161 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<typefind>[00m finalize parent
0:00:00.042445273 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<decodebin20>[00m dispose
0:00:00.042477610 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<decodebin20>[00m removing pad 'sink'
0:00:00.042506594 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<decodebin20>[00m parent class dispose
0:00:00.042524544 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<decodebin20>[00m finalize
0:00:00.042541027 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<decodebin20>[00m finalize parent
0:00:00.042585586 [333m 6452[00m  0x9e9d060 [33;01mWARN   [00m [00m          discoverer gstdiscoverer.c:1273:start_discovering:[00m No URI to process
0:00:00.042642856 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;33m                 ges ges-timeline-pipeline.c:127:ges_timeline_pipeline_init:<GESTimelinePipeline@0xa008138>[00m Creating new 'playsink'
0:00:00.043240146 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;36m  GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file:[00m plugin "/usr/lib/gstreamer-0.10/libgstplaybin.so" loaded
0:00:00.043267803 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create:[00m creating element "playsink" named "internal-sinks"
0:00:00.043720380 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;36m  GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file:[00m plugin "/usr/lib/gstreamer-0.10/libgstencodebin.so" loaded
0:00:00.043742031 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create:[00m creating element "encodebin" named "internal-encodebin"
0:00:00.044662410 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<GstEncodeBin@0xa013020>[00m adding pad 'src'
0:00:00.044722335 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<GESTimelinePipeline@0xa008138>[00m completed state change to NULL
0:00:00.106764806 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;36m  GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file:[00m plugin "/usr/lib/gstreamer-0.10/libgnl.so" loaded
0:00:00.106806642 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:375:gst_element_factory_create:[00m creating element "gnlcomposition"
0:00:00.107071273 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:375:gst_element_factory_create:[00m creating element "gnlcomposition"
0:00:03.503739800 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:375:gst_element_factory_create:[00m creating element "gnlsource"
0:00:03.504870823 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;36m  GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file:[00m plugin "/usr/lib/gstreamer-0.10/libgstaudiotestsrc.so" loaded
0:00:03.504937033 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:375:gst_element_factory_create:[00m creating element "audiotestsrc"
0:00:03.505421039 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<GstBaseSrc@0xa0ea1d8>[00m adding pad 'src'
0:00:03.506160248 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:375:gst_element_factory_create:[00m creating element "gnlsource"
0:00:03.506286871 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create:[00m creating element "bin" named "titlesrc-bin"
0:00:03.511593892 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;36m  GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file:[00m plugin "/usr/lib/gstreamer-0.10/libgstvideotestsrc.so" loaded
0:00:03.511616242 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create:[00m creating element "videotestsrc" named "titlesrc-bg"
0:00:03.512285818 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<GstBaseSrc@0xa114088>[00m adding pad 'src'
0:00:03.512719677 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;36m  GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file:[00m plugin "/usr/lib/gstreamer-0.10/libgstpango.so" loaded
0:00:03.512740839 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create:[00m creating element "textoverlay" named "titlsrc-text"
0:00:03.513200330 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<GstTextOverlay@0xa117020>[00m adding pad 'video_sink'
0:00:03.513252362 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<GstTextOverlay@0xa117020>[00m adding pad 'text_sink'
0:00:03.513338338 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<GstTextOverlay@0xa117020>[00m adding pad 'src'
0:00:03.513409437 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstutils.c:1695:gst_element_link_pads_full:[00m trying to link element titlesrc-bg:src to element titlsrc-text:video_sink
0:00:03.513429761 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:969:gst_element_get_static_pad:[00m found pad titlesrc-bg:src
0:00:03.513456860 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:969:gst_element_get_static_pad:[00m found pad titlsrc-text:video_sink
0:00:03.513480397 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstutils.c:1593:prepare_link_maybe_ghosting:[00m titlesrc-bg and titlsrc-text in same bin, no need for ghost pads
0:00:03.513507007 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link titlesrc-bg:src and titlsrc-text:video_sink
0:00:03.513525445 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked titlesrc-bg:src and titlsrc-text:video_sink, successful
0:00:03.513553103 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:969:gst_element_get_static_pad:[00m found pad titlsrc-text:src
0:00:03.513613935 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link titlsrc-text:src and src:proxypad2
0:00:03.513633211 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked titlsrc-text:src and src:proxypad2, successful
0:00:03.513659612 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<titlesrc-bin>[00m adding pad 'src'
0:00:03.514355378 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;04m             default ges-ui.c:353:layer_object_added_cb:[00m layer object added cb 0xa014800 0xa07e100 0xa001628
0:00:09.707079457 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<streamsynchronizer0>[00m completed state change to READY
0:00:09.707195744 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<streamsynchronizer0>[00m posting state-changed NULL to READY
0:00:09.707278297 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<internal-sinks>[00m child 'streamsynchronizer0' changed state to 2(READY) successfully
0:00:09.707339549 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<internal-sinks>[00m completed state change to READY
0:00:09.707386902 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<internal-sinks>[00m posting state-changed NULL to READY
0:00:09.707479443 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestimelinepipeline0>[00m child 'internal-sinks' changed state to 2(READY) successfully
0:00:09.707572123 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gnlcomposition1>[00m child 'gnlsource1' changed state to 2(READY) successfully
0:00:09.707628486 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gnlcomposition1>[00m completed state change to READY
0:00:09.707704544 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gnlcomposition1>[00m posting state-changed NULL to READY
0:00:09.707769217 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestrack1>[00m child 'gnlcomposition1' changed state to 2(READY) successfully
0:00:09.707823345 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gestrack1>[00m completed state change to READY
0:00:09.707868812 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gestrack1>[00m posting state-changed NULL to READY
0:00:09.707929435 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestimeline0>[00m child 'gestrack1' changed state to 2(READY) successfully
0:00:09.708000534 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gnlcomposition0>[00m child 'gnlsource0' changed state to 2(READY) successfully
0:00:09.708054592 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gnlcomposition0>[00m completed state change to READY
0:00:09.708100827 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gnlcomposition0>[00m posting state-changed NULL to READY
0:00:09.708162568 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestrack0>[00m child 'gnlcomposition0' changed state to 2(READY) successfully
0:00:09.708216765 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gestrack0>[00m completed state change to READY
0:00:09.708262512 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gestrack0>[00m posting state-changed NULL to READY
0:00:09.708321738 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestimeline0>[00m child 'gestrack0' changed state to 2(READY) successfully
0:00:09.708374608 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gestimeline0>[00m completed state change to READY
0:00:09.708420285 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gestimeline0>[00m posting state-changed NULL to READY
0:00:09.708501162 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestimelinepipeline0>[00m child 'gestimeline0' changed state to 2(READY) successfully
0:00:09.708559201 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2381:gst_element_continue_state:<gestimelinepipeline0>[00m committing state from NULL to READY, pending PLAYING, next PAUSED
0:00:09.708619196 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2390:gst_element_continue_state:<gestimelinepipeline0>[00m continue state change READY to PAUSED, final PLAYING
0:00:09.708688130 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00m            playsink gstplaysink.c:1035:do_async_start:<internal-sinks>[00m Sending async_start message
0:00:09.708788283 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<streamsynchronizer0>[00m completed state change to PAUSED
0:00:09.708837801 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<streamsynchronizer0>[00m posting state-changed READY to PAUSED
0:00:09.708933834 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<internal-sinks>[00m child 'streamsynchronizer0' changed state to 3(PAUSED) successfully
0:00:09.708995435 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2503:gst_bin_change_state_func:<gestimelinepipeline0>[00m child 'internal-sinks' is changing state asynchronously to PAUSED
0:00:09.709133792 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;34m      gnlcomposition gnlcomposition.c:461:wait_no_more_pads:<gnlsource1>[00m no existing pad, connecting to 'no-more-pads'
0:00:09.709203774 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;34m      gnlcomposition gnlcomposition.c:475:wait_no_more_pads:<gnlcomposition1>[00m the number of waiting pads is now 1
0:00:09.709260485 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;34m           GST_EVENT gstevent.c:1019:gst_event_new_seek:[00m creating seek rate 1.000000, format TIME, flags 3, start_type 2, start 0:00:00.000000000, stop_type 2, stop 0:00:01.000000000
0:00:09.709393115 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<titlsrc-text>[00m completed state change to READY
0:00:09.709442424 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<titlsrc-text>[00m posting state-changed NULL to READY
0:00:09.709536432 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<titlesrc-bin>[00m child 'titlsrc-text' changed state to 2(READY) successfully
0:00:09.709592585 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<titlesrc-bg>[00m completed state change to READY
0:00:09.709639519 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<titlesrc-bg>[00m posting state-changed NULL to READY
0:00:09.709704961 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<titlesrc-bin>[00m child 'titlesrc-bg' changed state to 2(READY) successfully
0:00:09.709759368 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<titlesrc-bin>[00m completed state change to READY
0:00:09.709804905 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<titlesrc-bin>[00m posting state-changed NULL to READY
0:00:09.709868531 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gnlsource1>[00m child 'titlesrc-bin' changed state to 2(READY) successfully
0:00:09.709922169 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2381:gst_element_continue_state:<gnlsource1>[00m committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:09.709986354 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2390:gst_element_continue_state:<gnlsource1>[00m continue state change READY to PAUSED, final PAUSED
0:00:09.710084832 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<titlsrc-text>[00m completed state change to PAUSED
0:00:09.710133302 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<titlsrc-text>[00m posting state-changed READY to PAUSED
0:00:09.710199023 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<titlesrc-bin>[00m child 'titlsrc-text' changed state to 3(PAUSED) successfully
0:00:09.711954714 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;34m           GST_EVENT gstevent.c:599:gst_event_new_new_segment_full:[00m creating newsegment update 0, rate 1.000000, format GST_FORMAT_TIME, start 0:00:00.000000000, stop 99:99:99.999999999, position 0:00:00.000000000
0:00:09.712342756 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<titlesrc-bg>[00m completed state change to PAUSED
0:00:09.712412948 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<titlesrc-bg>[00m posting state-changed READY to PAUSED
0:00:09.712510797 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<titlesrc-bin>[00m child 'titlesrc-bg' changed state to 3(PAUSED) successfully
0:00:09.712579591 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<titlesrc-bin>[00m completed state change to PAUSED
0:00:09.712629668 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<titlesrc-bin>[00m posting state-changed READY to PAUSED
0:00:09.712697275 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gnlsource1>[00m child 'titlesrc-bin' changed state to 3(PAUSED) successfully
0:00:09.712759923 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gnlsource1>[00m completed state change to PAUSED
0:00:09.712808533 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gnlsource1>[00m posting state-changed READY to PAUSED
0:00:09.712874115 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gnlcomposition1>[00m child 'gnlsource1' changed state to 3(PAUSED) successfully
0:00:09.712936903 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gnlcomposition1>[00m completed state change to PAUSED
0:00:09.712985723 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gnlcomposition1>[00m posting state-changed READY to PAUSED
0:00:09.713049139 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestrack1>[00m child 'gnlcomposition1' changed state to 3(PAUSED) successfully
0:00:09.713111857 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gestrack1>[00m completed state change to PAUSED
0:00:09.713161376 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gestrack1>[00m posting state-changed READY to PAUSED
0:00:09.713223884 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestimeline0>[00m child 'gestrack1' changed state to 3(PAUSED) successfully
0:00:09.713320196 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;34m      gnlcomposition gnlcomposition.c:461:wait_no_more_pads:<gnlsource0>[00m no existing pad, connecting to 'no-more-pads'
0:00:09.713384032 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;34m      gnlcomposition gnlcomposition.c:475:wait_no_more_pads:<gnlcomposition0>[00m the number of waiting pads is now 1
0:00:09.713434248 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;34m           GST_EVENT gstevent.c:1019:gst_event_new_seek:[00m creating seek rate 1.000000, format TIME, flags 3, start_type 2, start 0:00:00.000000000, stop_type 2, stop 0:00:01.000000000
0:00:09.713612416 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<audiotestsrc0>[00m completed state change to READY
0:00:09.713664937 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<audiotestsrc0>[00m posting state-changed NULL to READY
0:00:09.713735268 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gnlsource0>[00m child 'audiotestsrc0' changed state to 2(READY) successfully
0:00:09.713794634 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2381:gst_element_continue_state:<gnlsource0>[00m committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:09.713863847 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2390:gst_element_continue_state:<gnlsource0>[00m continue state change READY to PAUSED, final PAUSED
0:00:09.714034262 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;34m           GST_EVENT gstevent.c:599:gst_event_new_new_segment_full:[00m creating newsegment update 0, rate 1.000000, format GST_FORMAT_TIME, start 0:00:00.000000000, stop 99:99:99.999999999, position 0:00:00.000000000
0:00:09.715146916 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<audiotestsrc0>[00m completed state change to PAUSED
0:00:09.715212638 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<audiotestsrc0>[00m posting state-changed READY to PAUSED
0:00:09.715284086 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gnlsource0>[00m child 'audiotestsrc0' changed state to 3(PAUSED) successfully
0:00:09.715347433 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gnlsource0>[00m completed state change to PAUSED
0:00:09.715396043 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gnlsource0>[00m posting state-changed READY to PAUSED
0:00:09.715420557 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link titlesrc-bin:src and src:proxypad3
0:00:09.715507860 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gnlcomposition0>[00m child 'gnlsource0' changed state to 3(PAUSED) successfully
0:00:09.715526019 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked titlesrc-bin:src and src:proxypad3, successful
0:00:09.715579518 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gnlcomposition0>[00m completed state change to PAUSED
0:00:09.715662770 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<gnlsource1>[00m adding pad 'src'
0:00:09.715683792 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gnlcomposition0>[00m posting state-changed READY to PAUSED
0:00:09.715781711 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;34m      gnlcomposition gnlcomposition.c:467:wait_no_more_pads:<gnlsource1>[00m disconnecting from 'no-more-pads'
0:00:09.715791908 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestrack0>[00m child 'gnlcomposition0' changed state to 3(PAUSED) successfully
0:00:09.715849109 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;34m      gnlcomposition gnlcomposition.c:475:wait_no_more_pads:<gnlcomposition1>[00m the number of waiting pads is now 0
0:00:09.715914621 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gestrack0>[00m completed state change to PAUSED
0:00:09.716038101 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gestrack0>[00m posting state-changed READY to PAUSED
0:00:09.716102845 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestimeline0>[00m child 'gestrack0' changed state to 3(PAUSED) successfully
0:00:09.716105988 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link gnlsource1:src and src:proxypad4
0:00:09.716171081 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gestimeline0>[00m completed state change to PAUSED
0:00:09.716213964 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked gnlsource1:src and src:proxypad4, successful
0:00:09.716267253 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gestimeline0>[00m posting state-changed READY to PAUSED
0:00:09.716360422 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<gnlcomposition1>[00m adding pad 'src'
0:00:09.716374111 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestimelinepipeline0>[00m child 'gestimeline0' changed state to 3(PAUSED) successfully
0:00:09.716581054 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link gnlcomposition1:src and src:proxypad5
0:00:09.716646007 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked gnlcomposition1:src and src:proxypad5, successful
0:00:09.716736662 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<gestrack1>[00m adding pad 'src'
0:00:09.716935293 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link gestrack1:src and track_0xa004268_src:proxypad6
0:00:09.716998640 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked gestrack1:src and track_0xa004268_src:proxypad6, successful
0:00:09.717081891 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<gestimeline0>[00m adding pad 'track_0xa004268_src'
0:00:09.717154317 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:375:gst_element_factory_create:[00m creating element "tee"
0:00:09.718513864 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<GstTee@0xa0e5f30>[00m adding pad 'sink'
0:00:09.718837302 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2381:gst_element_continue_state:<tee0>[00m committing state from NULL to READY, pending PLAYING, next PAUSED
0:00:09.718911614 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2390:gst_element_continue_state:<tee0>[00m continue state change READY to PAUSED, final PLAYING
0:00:09.718973005 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2381:gst_element_continue_state:<tee0>[00m committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:00:09.719036422 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2390:gst_element_continue_state:<tee0>[00m continue state change PAUSED to PLAYING, final PLAYING
0:00:09.719107451 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<tee0>[00m completed state change to PLAYING
0:00:09.719155084 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<tee0>[00m posting state-changed PAUSED to PLAYING
0:00:09.719233586 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:969:gst_element_get_static_pad:[00m found pad tee0:sink
0:00:09.719354762 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link gestimeline0:track_0xa004268_src and tee0:sink
0:00:09.719411055 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked gestimeline0:track_0xa004268_src and tee0:sink, successful
0:00:09.719518263 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:966:gst_element_get_static_pad:[00m no such pad 'video_sink' in element "internal-sinks"
0:00:09.719707814 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<internal-sinks>[00m adding pad 'video_sink'
0:00:09.719824101 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<tee0>[00m adding pad 'src0'
0:00:09.719897366 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link tee0:src0 and internal-sinks:video_sink
0:00:09.719952471 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked tee0:src0 and internal-sinks:video_sink, successful
0:00:09.720868799 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;36m  GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file:[00m plugin "/usr/lib/gstreamer-0.10/libgstautodetect.so" loaded
0:00:09.720933263 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create:[00m creating element "autovideosink" named "videosink"
0:00:09.721242035 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<GstAutoVideoSink@0xa0ece20>[00m adding pad 'sink'
0:00:09.721308176 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create:[00m creating element "fakesink" named "tempsink"
0:00:09.721780657 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<GstBaseSink@0xb0e0a020>[00m adding pad 'sink'
0:00:09.721915034 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:969:gst_element_get_static_pad:[00m found pad tempsink:sink
0:00:09.721972933 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link sink:proxypad8 and tempsink:sink
0:00:09.722026362 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked sink:proxypad8 and tempsink:sink, successful
0:00:09.722118553 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<tempsink>[00m completed state change to NULL
0:00:09.722172751 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking sink:proxypad8(0xb0e02298) and tempsink:sink(0xb0e05808)
0:00:09.722229812 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked sink:proxypad8 and tempsink:sink
0:00:09.722307825 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<videosink>[00m removed child "tempsink"
0:00:09.722370613 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<tempsink>[00m dispose
0:00:09.722419712 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<tempsink>[00m removing pad 'sink'
0:00:09.722516723 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<tempsink>[00m parent class dispose
0:00:09.722584400 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<tempsink>[00m finalize
0:00:09.722634337 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<tempsink>[00m finalize parent
0:00:09.726900501 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;36m  GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file:[00m plugin "/usr/lib/gstreamer-0.10/libgstxvimagesink.so" loaded
0:00:09.726950369 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create:[00m creating element "xvimagesink" named "videosink-actual-sink-xvimage"
0:00:09.727256347 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<GstBaseSink@0xb0e0c188>[00m adding pad 'sink'
0:00:09.727300766 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:969:gst_element_get_static_pad:[00m found pad videosink-actual-sink-xvimage:sink
0:00:09.727818855 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link audiotestsrc0:src and src:proxypad9
0:00:09.727847770 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked audiotestsrc0:src and src:proxypad9, successful
0:00:09.727887510 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<gnlsource0>[00m adding pad 'src'
0:00:09.727939682 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;34m      gnlcomposition gnlcomposition.c:467:wait_no_more_pads:<gnlsource0>[00m disconnecting from 'no-more-pads'
0:00:09.727960006 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;34m      gnlcomposition gnlcomposition.c:475:wait_no_more_pads:<gnlcomposition0>[00m the number of waiting pads is now 0
0:00:09.728023283 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link gnlsource0:src and src:proxypad10
0:00:09.728044794 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked gnlsource0:src and src:proxypad10, successful
0:00:09.728081950 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<gnlcomposition0>[00m adding pad 'src'
0:00:09.728147252 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link gnlcomposition0:src and src:proxypad11
0:00:09.728168973 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked gnlcomposition0:src and src:proxypad11, successful
0:00:09.728198586 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<gestrack0>[00m adding pad 'src'
0:00:09.728261444 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link gestrack0:src and track_0xa004160_src:proxypad12
0:00:09.728292105 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked gestrack0:src and track_0xa004160_src:proxypad12, successful
0:00:09.728322416 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<gestimeline0>[00m adding pad 'track_0xa004160_src'
0:00:09.728348258 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:375:gst_element_factory_create:[00m creating element "tee"
0:00:09.728413700 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<GstTee@0xb0e055b8>[00m adding pad 'sink'
0:00:09.728488361 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2381:gst_element_continue_state:<tee1>[00m committing state from NULL to READY, pending PLAYING, next PAUSED
0:00:09.728522025 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2390:gst_element_continue_state:<tee1>[00m continue state change READY to PAUSED, final PLAYING
0:00:09.728544654 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2381:gst_element_continue_state:<tee1>[00m committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:00:09.728568540 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2390:gst_element_continue_state:<tee1>[00m continue state change PAUSED to PLAYING, final PLAYING
0:00:09.728586699 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<tee1>[00m completed state change to PLAYING
0:00:09.728603950 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<tee1>[00m posting state-changed PAUSED to PLAYING
0:00:09.728631817 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:969:gst_element_get_static_pad:[00m found pad tee1:sink
0:00:09.728656122 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link gestimeline0:track_0xa004160_src and tee1:sink
0:00:09.728675957 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked gestimeline0:track_0xa004160_src and tee1:sink, successful
0:00:09.729007148 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:966:gst_element_get_static_pad:[00m no such pad 'audio_sink' in element "internal-sinks"
0:00:09.730858732 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<videosink-actual-sink-xvimage>[00m completed state change to READY
0:00:09.730888275 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<videosink-actual-sink-xvimage>[00m posting state-changed NULL to READY
0:00:09.730931158 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:969:gst_element_get_static_pad:[00m found pad videosink-actual-sink-xvimage:sink
0:00:09.730952320 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link sink:proxypad8 and videosink-actual-sink-xvimage:sink
0:00:09.730972435 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked sink:proxypad8 and videosink-actual-sink-xvimage:sink, successful
0:00:09.731005121 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2221:gst_bin_element_set_state:<videosink-actual-sink-xvimage>[00m skipping transition from READY to  READY, since bin pending is READY : last change state return follows
0:00:09.731033407 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<videosink>[00m child 'videosink-actual-sink-xvimage' changed state to 2(READY) successfully
0:00:09.731054290 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<videosink>[00m completed state change to READY
0:00:09.731071820 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<videosink>[00m posting state-changed NULL to READY
0:00:09.731101293 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00m            playsink gstplaysink.c:968:find_property:<videosink-actual-sink-xvimage>[00m found async with sink property
0:00:09.731133770 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00m            playsink gstplaysink.c:968:find_property:<videosink-actual-sink-xvimage>[00m found ts-offset with sink property
0:00:09.731152837 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create:[00m creating element "bin" named "vbin"
0:00:09.731214158 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create:[00m creating element "queue" named "vqueue"
0:00:09.731377938 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<GstQueue@0xb0e19068>[00m adding pad 'sink'
0:00:09.731429411 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<GstQueue@0xb0e19068>[00m adding pad 'src'
0:00:09.731484936 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstutils.c:1695:gst_element_link_pads_full:[00m trying to link element vqueue:src to element videosink:(any)
0:00:09.731507006 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:969:gst_element_get_static_pad:[00m found pad vqueue:src
0:00:09.731526841 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstutils.c:1029:gst_pad_check_link:[00m trying to link vqueue:src and videosink:sink
0:00:09.731551985 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstutils.c:1593:prepare_link_maybe_ghosting:[00m vqueue and videosink in same bin, no need for ghost pads
0:00:09.731577337 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link vqueue:src and videosink:sink
0:00:09.731602131 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked vqueue:src and videosink:sink, successful
0:00:09.731627204 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:969:gst_element_get_static_pad:[00m found pad vqueue:sink
0:00:09.731679167 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link sink:proxypad13 and vqueue:sink
0:00:09.731698723 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked sink:proxypad13 and vqueue:sink, successful
0:00:09.731717790 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<vbin>[00m adding pad 'sink'
0:00:09.731801879 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<streamsynchronizer0>[00m adding pad 'src_0'
0:00:09.731821924 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<streamsynchronizer0>[00m adding pad 'sink_0'
0:00:09.731849372 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link video_sink:proxypad7 and streamsynchronizer0:sink_0
0:00:09.731868090 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked video_sink:proxypad7 and streamsynchronizer0:sink_0, successful
0:00:09.731910274 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2221:gst_bin_element_set_state:<videosink>[00m skipping transition from READY to  READY, since bin pending is PAUSED : last change state return follows
0:00:09.731929481 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<vbin>[00m child 'videosink' changed state to 2(READY) successfully
0:00:09.731948897 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<vqueue>[00m completed state change to READY
0:00:09.731965100 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<vqueue>[00m posting state-changed NULL to READY
0:00:09.731988986 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<vbin>[00m child 'vqueue' changed state to 2(READY) successfully
0:00:09.732016504 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2381:gst_element_continue_state:<vbin>[00m committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:09.732041577 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2390:gst_element_continue_state:<vbin>[00m continue state change READY to PAUSED, final PAUSED
0:00:09.732096264 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2503:gst_bin_change_state_func:<videosink>[00m child 'videosink-actual-sink-xvimage' is changing state asynchronously to PAUSED
0:00:09.732121267 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2503:gst_bin_change_state_func:<vbin>[00m child 'videosink' is changing state asynchronously to PAUSED
0:00:09.732204868 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<vqueue>[00m completed state change to PAUSED
0:00:09.732224913 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<vqueue>[00m posting state-changed READY to PAUSED
0:00:09.732262837 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<vbin>[00m child 'vqueue' changed state to 3(PAUSED) successfully
0:00:09.732291891 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link streamsynchronizer0:src_0 and vbin:sink
0:00:09.732312146 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked streamsynchronizer0:src_0 and vbin:sink, successful
0:00:09.732340432 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00m            playsink gstplaysink.c:1047:do_async_done:<internal-sinks>[00m Sending async_done message
0:00:09.732371372 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;34m      gnlcomposition gnlcomposition.c:1832:no_more_pads_object_cb:<gnlcomposition1>[00m Sending pending seek on gnlsource1:src
0:00:09.732368927 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create:[00m creating element "tee" named "audiotee"
0:00:09.732418934 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;34m           GST_EVENT gstevent.c:1019:gst_event_new_seek:[00m creating seek rate 1.000000, format TIME, flags 3, start_type 2, start 0:00:00.000000000, stop_type 2, stop 0:00:01.000000000
0:00:09.732485145 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00m               pango gsttextoverlay.c:2227:gst_text_overlay_video_event:<titlsrc-text>[00m video flush start
0:00:09.732483748 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<GstTee@0xb0e05668>[00m adding pad 'sink'
0:00:09.732506796 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:3531:gst_pad_event_default_dispatch:<titlsrc-text:video_sink>[00m Sending event 0xb0e1a0c8 (flush-start) to all internally linked pads
0:00:09.732538225 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:969:gst_element_get_static_pad:[00m found pad audiotee:sink
0:00:09.732568396 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2381:gst_element_continue_state:<audiotee>[00m committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:09.732569095 [333m 6452[00m  0xa12a868 [32;01mINFO   [00m [00m             basesrc gstbasesrc.c:2405:gst_base_src_loop:<titlesrc-bg>[00m pausing after gst_base_src_get_range() = wrong-state
0:00:09.732599267 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2390:gst_element_continue_state:<audiotee>[00m continue state change READY to PAUSED, final PAUSED
0:00:09.732633699 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00m               pango gsttextoverlay.c:2235:gst_text_overlay_video_event:<titlsrc-text>[00m video flush stop
0:00:09.732655559 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<audiotee>[00m completed state change to PAUSED
0:00:09.732670785 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:3531:gst_pad_event_default_dispatch:<titlsrc-text:video_sink>[00m Sending event 0xb0e1a0f0 (flush-stop) to all internally linked pads
0:00:09.732691179 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<audiotee>[00m posting state-changed READY to PAUSED
0:00:09.732717090 [333m 6452[00m 0xb2000d28 [32;01mINFO   [00m [00;01;34m           GST_EVENT gstevent.c:599:gst_event_new_new_segment_full:[00m creating newsegment update 0, rate 1.000000, format GST_FORMAT_TIME, start 0:00:00.000000000, stop 0:00:01.000000000, position 0:00:00.000000000
0:00:09.732772265 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link audio_sink:proxypad14 and audiotee:sink
0:00:09.732793777 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked audio_sink:proxypad14 and audiotee:sink, successful
0:00:09.732815987 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<internal-sinks>[00m adding pad 'audio_sink'
0:00:09.732857612 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<tee1>[00m adding pad 'src0'
0:00:09.732884571 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link tee1:src0 and internal-sinks:audio_sink
0:00:09.732904476 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked tee1:src0 and internal-sinks:audio_sink, successful
0:00:09.732935067 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking video_sink:proxypad7(0xb0e021c0) and streamsynchronizer0:sink_0(0xb0e05b28)
0:00:09.732964401 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked video_sink:proxypad7 and streamsynchronizer0:sink_0
0:00:09.732983747 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link video_sink:proxypad7 and streamsynchronizer0:sink_0
0:00:09.733002255 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked video_sink:proxypad7 and streamsynchronizer0:sink_0, successful
0:00:09.733027049 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link streamsynchronizer0:src_0 and vbin:sink
0:00:09.733045138 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1986:gst_pad_link_prepare:[00m src streamsynchronizer0:src_0 was already linked to vbin:sink
0:00:09.733077615 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create:[00m creating element "autoaudiosink" named "audiosink"
0:00:09.733177699 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<GstAutoAudioSink@0xb0e07460>[00m adding pad 'sink'
0:00:09.733199559 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create:[00m creating element "fakesink" named "tempsink"
0:00:09.733242093 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<GstBaseSink@0xb0e0ad60>[00m adding pad 'sink'
0:00:09.733279179 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:969:gst_element_get_static_pad:[00m found pad tempsink:sink
0:00:09.733313402 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link sink:proxypad15 and tempsink:sink
0:00:09.733332748 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked sink:proxypad15 and tempsink:sink, successful
0:00:09.733363688 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<tempsink>[00m completed state change to NULL
0:00:09.733383244 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking sink:proxypad15(0xa131108) and tempsink:sink(0xb0e05f10)
0:00:09.733403638 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked sink:proxypad15 and tempsink:sink
0:00:09.733424451 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<audiosink>[00m removed child "tempsink"
0:00:09.733457975 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<tempsink>[00m dispose
0:00:09.733478229 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<tempsink>[00m removing pad 'sink'
0:00:09.733500788 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<tempsink>[00m parent class dispose
0:00:09.733520274 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<tempsink>[00m finalize
0:00:09.733538014 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<tempsink>[00m finalize parent
0:00:09.733643825 [333m 6452[00m  0xa12a868 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:3531:gst_pad_event_default_dispatch:<titlsrc-text:video_sink>[00m Sending event 0xb0e1a118 (newsegment) to all internally linked pads
0:00:09.733682448 [333m 6452[00m  0xa12a868 [32;01mINFO   [00m [00;01;34m           GST_EVENT gstevent.c:599:gst_event_new_new_segment_full:[00m creating newsegment update 0, rate 1.000000, format GST_FORMAT_TIME, start 0:00:00.000000000, stop 0:00:01.000000000, position 0:00:00.000000000
0:00:09.733720931 [333m 6452[00m  0xa12a868 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:3531:gst_pad_event_default_dispatch:<tee0:sink>[00m Sending event 0xb2002cf0 (newsegment) to all internally linked pads
0:00:09.733925917 [333m 6452[00m  0xa12a868 [32;01mINFO   [00m [00m             basesrc gstbasesrc.c:2519:gst_base_src_loop:<titlesrc-bg>[00m pausing after gst_pad_push() = not-negotiated
0:00:09.733967333 [333m 6452[00m  0xa12a868 [33;01mWARN   [00m [00m             basesrc gstbasesrc.c:2582:gst_base_src_loop:<titlesrc-bg>[00m error: Internal data flow error.
0:00:09.733995689 [333m 6452[00m  0xa12a868 [33;01mWARN   [00m [00m             basesrc gstbasesrc.c:2582:gst_base_src_loop:<titlesrc-bg>[00m error: streaming task paused, reason not-negotiated (-4)
0:00:09.734041017 [333m 6452[00m  0xa12a868 [32;01mINFO   [00m [00;01;31;47m    GST_ERROR_SYSTEM gstelement.c:1931:gst_element_message_full:<titlesrc-bg>[00m posting message: Internal data flow error.
0:00:09.734074960 [333m 6452[00m  0xa12a868 [32;01mINFO   [00m [00;01;31;47m    GST_ERROR_SYSTEM gstelement.c:1954:gst_element_message_full:<titlesrc-bg>[00m posted error message: Internal data flow error.
0:00:09.734096751 [333m 6452[00m  0xa12a868 [32;01mINFO   [00m [00m               pango gsttextoverlay.c:2220:gst_text_overlay_video_event:<titlsrc-text>[00m video EOS
0:00:09.734113303 [333m 6452[00m  0xa12a868 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:3531:gst_pad_event_default_dispatch:<titlsrc-text:video_sink>[00m Sending event 0xb2002f90 (eos) to all internally linked pads
0:00:09.735318219 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking gnlsource1:src(0xa07fd98) and src:proxypad4(0xa00ded0)
0:00:09.735354048 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked gnlsource1:src and src:proxypad4
0:00:09.735392740 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2221:gst_bin_element_set_state:<titlesrc-bin>[00m skipping transition from PAUSED to  PAUSED, since bin pending is PAUSED : last change state return follows
0:00:09.735413553 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2513:gst_bin_change_state_func:<gnlsource1>[00m child 'titlesrc-bin' failed to go to state 3(PAUSED)
0:00:09.735433738 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2725:gst_element_change_state:<gnlsource1>[00m have FAILURE change_state return
0:00:09.735465586 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2302:gst_element_abort_state:<gnlsource1>[00m aborting state from PAUSED to PAUSED
0:00:09.735490520 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:3531:gst_pad_event_default_dispatch:<tee0:sink>[00m Sending event 0xa119400 (eos) to all internally linked pads
0:00:09.735596330 [333m 6452[00m 0xb0e1b290 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2910:bin_handle_async_done:<videosink>[00m committing state from READY to PAUSED, old pending PAUSED
0:00:09.735633556 [333m 6452[00m 0xb0e1b290 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2930:bin_handle_async_done:<videosink>[00m completed state change, pending VOID
0:00:09.735697252 [333m 6452[00m 0xb0e1b290 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2910:bin_handle_async_done:<vbin>[00m committing state from READY to PAUSED, old pending PAUSED
0:00:09.735726516 [333m 6452[00m 0xb0e1b290 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2930:bin_handle_async_done:<vbin>[00m completed state change, pending VOID
0:00:09.735786161 [333m 6452[00m 0xb0e1b290 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2910:bin_handle_async_done:<internal-sinks>[00m committing state from READY to PAUSED, old pending PAUSED
0:00:09.735805996 [333m 6452[00m 0xb0e1b290 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2930:bin_handle_async_done:<internal-sinks>[00m completed state change, pending VOID
0:00:09.739866196 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;36m  GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file:[00m plugin "/usr/lib/gstreamer-0.10/libgstpulse.so" loaded
0:00:09.739894133 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create:[00m creating element "pulsesink" named "audiosink-actual-sink-pulse"
0:00:09.740209330 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<GstBaseSink@0xa13a000>[00m adding pad 'sink'
0:00:09.740584243 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:969:gst_element_get_static_pad:[00m found pad audiosink-actual-sink-pulse:sink
0:00:09.740634738 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00m               pulse pulsesink.c:2730:gst_pulsesink_change_state:<audiosink-actual-sink-pulse>[00m new pa main loop thread
0:00:09.740753330 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00m               pulse pulsesink.c:470:gst_pulseringbuffer_open_device:<audiosink-actual-sink-pulse>[00m new context with name lt-ges-ui, pbuf=0xa013598, pctx=0xa0b8630
0:00:09.743727416 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<audiosink-actual-sink-pulse>[00m completed state change to READY
0:00:09.743753467 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<audiosink-actual-sink-pulse>[00m posting state-changed NULL to READY
0:00:09.743832249 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:969:gst_element_get_static_pad:[00m found pad audiosink-actual-sink-pulse:sink
0:00:09.743854668 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link sink:proxypad15 and audiosink-actual-sink-pulse:sink
0:00:09.743874503 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked sink:proxypad15 and audiosink-actual-sink-pulse:sink, successful
0:00:09.743902021 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2221:gst_bin_element_set_state:<audiosink-actual-sink-pulse>[00m skipping transition from READY to  READY, since bin pending is READY : last change state return follows
0:00:09.743921297 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<audiosink>[00m child 'audiosink-actual-sink-pulse' changed state to 2(READY) successfully
0:00:09.743941691 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<audiosink>[00m completed state change to READY
0:00:09.743958873 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<audiosink>[00m posting state-changed NULL to READY
0:00:09.743981990 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create:[00m creating element "bin" named "abin"
0:00:09.744048969 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create:[00m creating element "queue" named "aqueue"
0:00:09.744104703 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<GstQueue@0xb0e195d8>[00m adding pad 'sink'
0:00:09.744150938 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<GstQueue@0xb0e195d8>[00m adding pad 'src'
0:00:09.744190050 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00m            playsink gstplaysink.c:968:find_property:<audiosink-actual-sink-pulse>[00m found ts-offset with sink property
0:00:09.744210793 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00m            playsink gstplaysink.c:968:find_property:<audiosink-actual-sink-pulse>[00m found volume with sink property
0:00:09.744234260 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00m            playsink gstplaysink.c:968:find_property:<audiosink-actual-sink-pulse>[00m found mute with sink property
0:00:09.744252558 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstutils.c:1695:gst_element_link_pads_full:[00m trying to link element aqueue:src to element audiosink:(any)
0:00:09.744271067 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:969:gst_element_get_static_pad:[00m found pad aqueue:src
0:00:09.744289365 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstutils.c:1029:gst_pad_check_link:[00m trying to link aqueue:src and audiosink:sink
0:00:09.744310597 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstutils.c:1593:prepare_link_maybe_ghosting:[00m aqueue and audiosink in same bin, no need for ghost pads
0:00:09.744334204 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link aqueue:src and audiosink:sink
0:00:09.744353131 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked aqueue:src and audiosink:sink, successful
0:00:09.744378344 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:969:gst_element_get_static_pad:[00m found pad aqueue:sink
0:00:09.744430586 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link sink:proxypad16 and aqueue:sink
0:00:09.744481990 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked sink:proxypad16 and aqueue:sink, successful
0:00:09.744504898 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<abin>[00m adding pad 'sink'
0:00:09.744589128 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<streamsynchronizer0>[00m adding pad 'src_1'
0:00:09.744610499 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<streamsynchronizer0>[00m adding pad 'sink_1'
0:00:09.744653382 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<audiotee>[00m adding pad 'src0'
0:00:09.744701783 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2221:gst_bin_element_set_state:<audiosink>[00m skipping transition from READY to  READY, since bin pending is PAUSED : last change state return follows
0:00:09.744721269 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<abin>[00m child 'audiosink' changed state to 2(READY) successfully
0:00:09.744740964 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<aqueue>[00m completed state change to READY
0:00:09.744764850 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<aqueue>[00m posting state-changed NULL to READY
0:00:09.744789784 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<abin>[00m child 'aqueue' changed state to 2(READY) successfully
0:00:09.744817860 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2381:gst_element_continue_state:<abin>[00m committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:09.744842515 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2390:gst_element_continue_state:<abin>[00m continue state change READY to PAUSED, final PAUSED
0:00:09.744904884 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2503:gst_bin_change_state_func:<audiosink>[00m child 'audiosink-actual-sink-pulse' is changing state asynchronously to PAUSED
0:00:09.744930306 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2503:gst_bin_change_state_func:<abin>[00m child 'audiosink' is changing state asynchronously to PAUSED
0:00:09.745016072 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<aqueue>[00m completed state change to PAUSED
0:00:09.745039749 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<aqueue>[00m posting state-changed READY to PAUSED
0:00:09.745077533 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<abin>[00m child 'aqueue' changed state to 3(PAUSED) successfully
0:00:09.745106587 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link audiotee:src0 and streamsynchronizer0:sink_1
0:00:09.745126772 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked audiotee:src0 and streamsynchronizer0:sink_1, successful
0:00:09.745157083 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link streamsynchronizer0:src_1 and abin:sink
0:00:09.745176849 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked streamsynchronizer0:src_1 and abin:sink, successful
0:00:09.745232932 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;34m      gnlcomposition gnlcomposition.c:1832:no_more_pads_object_cb:<gnlcomposition0>[00m Sending pending seek on gnlsource0:src
0:00:09.745261777 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;34m           GST_EVENT gstevent.c:1019:gst_event_new_seek:[00m creating seek rate 1.000000, format TIME, flags 3, start_type 2, start 0:00:00.000000000, stop_type 2, stop 0:00:01.000000000
0:00:09.745316952 [333m 6452[00m  0xa12bd28 [32;01mINFO   [00m [00m             basesrc gstbasesrc.c:2405:gst_base_src_loop:<audiotestsrc0>[00m pausing after gst_base_src_get_range() = wrong-state
0:00:09.745367657 [333m 6452[00m 0xb0c00c80 [32;01mINFO   [00m [00;01;34m           GST_EVENT gstevent.c:599:gst_event_new_new_segment_full:[00m creating newsegment update 0, rate 1.000000, format GST_FORMAT_TIME, start 0:00:00.000000000, stop 0:00:01.000000000, position 0:00:00.000000000
0:00:09.745552180 [333m 6452[00m  0xa12bd28 [32;01mINFO   [00m [00;01;34m           GST_EVENT gstevent.c:599:gst_event_new_new_segment_full:[00m creating newsegment update 0, rate 1.000000, format GST_FORMAT_TIME, start 0:00:00.000000000, stop 0:00:01.000000000, position 0:00:00.000000000
0:00:09.745599812 [333m 6452[00m  0xa12bd28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:3531:gst_pad_event_default_dispatch:<tee1:sink>[00m Sending event 0xb0c01c28 (newsegment) to all internally linked pads
0:00:09.745631241 [333m 6452[00m  0xa12bd28 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:3531:gst_pad_event_default_dispatch:<audiotee:sink>[00m Sending event 0xb0c01c28 (newsegment) to all internally linked pads
0:00:09.749299487 [333m 6452[00m  0xa12bd28 [32;01mINFO   [00m [00m             basesrc gstbasesrc.c:2524:gst_base_src_loop:<audiotestsrc0>[00m pausing after end of segment
0:00:09.749442524 [333m 6452[00m  0xa1409f8 [32;01mINFO   [00m [00m               pulse pulsesink.c:820:gst_pulseringbuffer_acquire:<audiosink-actual-sink-pulse>[00m tlength:   17640
0:00:09.749480658 [333m 6452[00m  0xa1409f8 [32;01mINFO   [00m [00m               pulse pulsesink.c:821:gst_pulseringbuffer_acquire:<audiosink-actual-sink-pulse>[00m maxlength: -1
0:00:09.749497071 [333m 6452[00m  0xa1409f8 [32;01mINFO   [00m [00m               pulse pulsesink.c:822:gst_pulseringbuffer_acquire:<audiosink-actual-sink-pulse>[00m prebuf:    0
0:00:09.749512715 [333m 6452[00m  0xa1409f8 [32;01mINFO   [00m [00m               pulse pulsesink.c:823:gst_pulseringbuffer_acquire:<audiosink-actual-sink-pulse>[00m minreq:    882
0:00:09.749643041 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking gnlsource0:src(0xb0e01520) and src:proxypad10(0xb0e02520)
0:00:09.749676984 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked gnlsource0:src and src:proxypad10
0:00:09.749716165 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2221:gst_bin_element_set_state:<audiotestsrc0>[00m skipping transition from PAUSED to  PAUSED, since bin pending is PAUSED : last change state return follows
0:00:09.749736839 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gnlsource0>[00m child 'audiotestsrc0' changed state to 3(PAUSED) successfully
0:00:09.749757721 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gnlsource0>[00m completed state change to PAUSED
0:00:09.749780560 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:3531:gst_pad_event_default_dispatch:<tee1:sink>[00m Sending event 0xa119918 (eos) to all internally linked pads
0:00:09.749802560 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:3531:gst_pad_event_default_dispatch:<audiotee:sink>[00m Sending event 0xa119918 (eos) to all internally linked pads
0:00:09.756638145 [333m 6452[00m  0xa1409f8 [32;01mINFO   [00m [00m               pulse pulsesink.c:890:gst_pulseringbuffer_acquire:<audiosink-actual-sink-pulse>[00m tlength:   9702 (wanted: 17640)
0:00:09.756671809 [333m 6452[00m  0xa1409f8 [32;01mINFO   [00m [00m               pulse pulsesink.c:891:gst_pulseringbuffer_acquire:<audiosink-actual-sink-pulse>[00m maxlength: 4194304
0:00:09.756688920 [333m 6452[00m  0xa1409f8 [32;01mINFO   [00m [00m               pulse pulsesink.c:892:gst_pulseringbuffer_acquire:<audiosink-actual-sink-pulse>[00m prebuf:    0
0:00:09.756705193 [333m 6452[00m  0xa1409f8 [32;01mINFO   [00m [00m               pulse pulsesink.c:894:gst_pulseringbuffer_acquire:<audiosink-actual-sink-pulse>[00m minreq:    882 (wanted 882)
0:00:09.756878890 [333m 6452[00m  0xa1409f8 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2910:bin_handle_async_done:<audiosink>[00m committing state from READY to PAUSED, old pending PAUSED
0:00:09.756901449 [333m 6452[00m  0xa1409f8 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2930:bin_handle_async_done:<audiosink>[00m completed state change, pending VOID
0:00:09.756930574 [333m 6452[00m  0xa1409f8 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2910:bin_handle_async_done:<abin>[00m committing state from READY to PAUSED, old pending PAUSED
0:00:09.756961025 [333m 6452[00m  0xa1409f8 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2930:bin_handle_async_done:<abin>[00m completed state change, pending VOID
0:00:09.756988403 [333m 6452[00m  0xa1409f8 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2910:bin_handle_async_done:<internal-sinks>[00m committing state from PAUSED to PAUSED, old pending PAUSED
0:00:09.757011800 [333m 6452[00m  0xa1409f8 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2930:bin_handle_async_done:<internal-sinks>[00m completed state change, pending VOID
0:00:11.129078149 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<audiosink-actual-sink-pulse>[00m completed state change to READY
0:00:11.129132346 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<audiosink-actual-sink-pulse>[00m posting state-changed PAUSED to READY
0:00:11.129162518 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<audiosink>[00m child 'audiosink-actual-sink-pulse' changed state to 2(READY) successfully
0:00:11.129202188 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<audiosink>[00m completed state change to READY
0:00:11.129220766 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<audiosink>[00m posting state-changed PAUSED to READY
0:00:11.129244023 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<abin>[00m child 'audiosink' changed state to 2(READY) successfully
0:00:11.129548535 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<aqueue>[00m completed state change to READY
0:00:11.129575913 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<aqueue>[00m posting state-changed PAUSED to READY
0:00:11.129606993 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<abin>[00m child 'aqueue' changed state to 2(READY) successfully
0:00:11.129647432 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<abin>[00m completed state change to READY
0:00:11.129665730 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<abin>[00m posting state-changed PAUSED to READY
0:00:11.129689057 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<internal-sinks>[00m child 'abin' changed state to 2(READY) successfully
0:00:11.129762112 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<videosink-actual-sink-xvimage>[00m completed state change to READY
0:00:11.129781808 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<videosink-actual-sink-xvimage>[00m posting state-changed PAUSED to READY
0:00:11.129809116 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<videosink>[00m child 'videosink-actual-sink-xvimage' changed state to 2(READY) successfully
0:00:11.129835377 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<videosink>[00m completed state change to READY
0:00:11.129853256 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<videosink>[00m posting state-changed PAUSED to READY
0:00:11.129937765 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<vbin>[00m child 'videosink' changed state to 2(READY) successfully
0:00:11.130019899 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<vqueue>[00m completed state change to READY
0:00:11.130038896 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<vqueue>[00m posting state-changed PAUSED to READY
0:00:11.130062992 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<vbin>[00m child 'vqueue' changed state to 2(READY) successfully
0:00:11.130093792 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<vbin>[00m completed state change to READY
0:00:11.130116910 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<vbin>[00m posting state-changed PAUSED to READY
0:00:11.130139678 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<internal-sinks>[00m child 'vbin' changed state to 2(READY) successfully
0:00:11.130179698 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<streamsynchronizer0>[00m completed state change to READY
0:00:11.130197997 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<streamsynchronizer0>[00m posting state-changed PAUSED to READY
0:00:11.130220835 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<internal-sinks>[00m child 'streamsynchronizer0' changed state to 2(READY) successfully
0:00:11.130254988 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<audiotee>[00m completed state change to READY
0:00:11.130273007 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<audiotee>[00m posting state-changed PAUSED to READY
0:00:11.130295566 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<internal-sinks>[00m child 'audiotee' changed state to 2(READY) successfully
0:00:11.130330277 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<streamsynchronizer0>[00m removing pad 'src_0'
0:00:11.130348716 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking streamsynchronizer0:src_0(0xb0e05bf0) and vbin:sink(0xb0e01438)
0:00:11.130376094 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked streamsynchronizer0:src_0 and vbin:sink
0:00:11.130406685 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<streamsynchronizer0>[00m removing pad 'sink_0'
0:00:11.130424285 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking video_sink:proxypad7(0xb0e021c0) and streamsynchronizer0:sink_0(0xb0e05b28)
0:00:11.130444749 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked video_sink:proxypad7 and streamsynchronizer0:sink_0
0:00:11.130494267 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<streamsynchronizer0>[00m removing pad 'src_1'
0:00:11.130519061 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking streamsynchronizer0:src_1(0xb0e05f10) and abin:sink(0xa130b10)
0:00:11.130544483 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked streamsynchronizer0:src_1 and abin:sink
0:00:11.130578357 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<streamsynchronizer0>[00m removing pad 'sink_1'
0:00:11.130602522 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking audiotee:src0(0xb0e05808) and streamsynchronizer0:sink_1(0xa133a58)
0:00:11.130626827 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked audiotee:src0 and streamsynchronizer0:sink_1
0:00:11.136078002 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<videosink-actual-sink-xvimage>[00m completed state change to NULL
0:00:11.136101888 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<videosink-actual-sink-xvimage>[00m posting state-changed READY to NULL
0:00:11.136127730 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<videosink>[00m child 'videosink-actual-sink-xvimage' changed state to 1(NULL) successfully
0:00:11.136155038 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<videosink-actual-sink-xvimage>[00m completed state change to NULL
0:00:11.136174175 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking sink:proxypad8(0xb0e02298) and videosink-actual-sink-xvimage:sink(0xb0e058d0)
0:00:11.136195127 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked sink:proxypad8 and videosink-actual-sink-xvimage:sink
0:00:11.136216010 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<videosink>[00m removed child "videosink-actual-sink-xvimage"
0:00:11.136242341 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create:[00m creating element "fakesink" named "tempsink"
0:00:11.136301776 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<GstBaseSink@0xa1440b8>[00m adding pad 'sink'
0:00:11.136339491 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:969:gst_element_get_static_pad:[00m found pad tempsink:sink
0:00:11.136359605 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link sink:proxypad8 and tempsink:sink
0:00:11.136378952 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked sink:proxypad8 and tempsink:sink, successful
0:00:11.136398368 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<videosink>[00m completed state change to NULL
0:00:11.136415409 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<videosink>[00m posting state-changed READY to NULL
0:00:11.136438667 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<vbin>[00m child 'videosink' changed state to 1(NULL) successfully
0:00:11.136501246 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<vqueue>[00m completed state change to NULL
0:00:11.136526180 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<vqueue>[00m posting state-changed READY to NULL
0:00:11.136558167 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<vbin>[00m child 'vqueue' changed state to 1(NULL) successfully
0:00:11.136591203 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<vbin>[00m completed state change to NULL
0:00:11.136615089 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<vbin>[00m posting state-changed READY to NULL
0:00:11.136650708 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<internal-sinks>[00m removed child "vbin"
0:00:11.136805897 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00m               pulse pulsesink.c:2766:gst_pulsesink_change_state:<audiosink-actual-sink-pulse>[00m terminating pa main loop thread
0:00:11.137087710 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<audiosink-actual-sink-pulse>[00m completed state change to NULL
0:00:11.137114529 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<audiosink-actual-sink-pulse>[00m posting state-changed READY to NULL
0:00:11.137149101 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<audiosink>[00m child 'audiosink-actual-sink-pulse' changed state to 1(NULL) successfully
0:00:11.137185349 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<audiosink-actual-sink-pulse>[00m completed state change to NULL
0:00:11.137211330 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking sink:proxypad15(0xa131108) and audiosink-actual-sink-pulse:sink(0xa133800)
0:00:11.137239826 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked sink:proxypad15 and audiosink-actual-sink-pulse:sink
0:00:11.137268391 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<audiosink>[00m removed child "audiosink-actual-sink-pulse"
0:00:11.137300030 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create:[00m creating element "fakesink" named "tempsink"
0:00:11.137358907 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:722:gst_element_add_pad:<GstBaseSink@0xa144408>[00m adding pad 'sink'
0:00:11.137405422 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:969:gst_element_get_static_pad:[00m found pad tempsink:sink
0:00:11.137433009 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1956:gst_pad_link_prepare:[00m trying to link sink:proxypad15 and tempsink:sink
0:00:11.137475194 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2139:gst_pad_link_full:[00m linked sink:proxypad15 and tempsink:sink, successful
0:00:11.137503340 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<audiosink>[00m completed state change to NULL
0:00:11.137526668 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<audiosink>[00m posting state-changed READY to NULL
0:00:11.137569760 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<abin>[00m child 'audiosink' changed state to 1(NULL) successfully
0:00:11.137605100 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<aqueue>[00m completed state change to NULL
0:00:11.137629405 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<aqueue>[00m posting state-changed READY to NULL
0:00:11.137660764 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<abin>[00m child 'aqueue' changed state to 1(NULL) successfully
0:00:11.137693450 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<abin>[00m completed state change to NULL
0:00:11.137717406 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<abin>[00m posting state-changed READY to NULL
0:00:11.137751629 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<internal-sinks>[00m removed child "abin"
0:00:11.137787178 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2381:gst_element_continue_state:<internal-sinks>[00m committing state from PAUSED to READY, pending NULL, next NULL
0:00:11.137818747 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2390:gst_element_continue_state:<internal-sinks>[00m continue state change READY to NULL, final NULL
0:00:11.137859605 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<audiotee>[00m completed state change to NULL
0:00:11.137884259 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<audiotee>[00m posting state-changed READY to NULL
0:00:11.137915339 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<internal-sinks>[00m child 'audiotee' changed state to 1(NULL) successfully
0:00:11.137946837 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<streamsynchronizer0>[00m completed state change to NULL
0:00:11.137971073 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<streamsynchronizer0>[00m posting state-changed READY to NULL
0:00:11.138001384 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<internal-sinks>[00m child 'streamsynchronizer0' changed state to 1(NULL) successfully
0:00:11.138035467 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking vqueue:src(0xb0e05a60) and videosink:sink(0xb0e01350)
0:00:11.138072483 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked vqueue:src and videosink:sink
0:00:11.138112223 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<vbin>[00m removed child "videosink"
0:00:11.138145538 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking aqueue:src(0xa133990) and audiosink:sink(0xa130a28)
0:00:11.138180739 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked aqueue:src and audiosink:sink
0:00:11.138229069 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<abin>[00m removed child "audiosink"
0:00:11.138269857 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2221:gst_bin_element_set_state:<tempsink>[00m skipping transition from NULL to  NULL, since bin pending is NULL : last change state return follows
0:00:11.138296118 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<audiosink>[00m child 'tempsink' changed state to 1(NULL) successfully
0:00:11.138323286 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<audiosink>[00m completed state change to NULL
0:00:11.138354436 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2221:gst_bin_element_set_state:<tempsink>[00m skipping transition from NULL to  NULL, since bin pending is NULL : last change state return follows
0:00:11.138379509 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<videosink>[00m child 'tempsink' changed state to 1(NULL) successfully
0:00:11.138406398 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<videosink>[00m completed state change to NULL
0:00:11.138431471 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<internal-sinks>[00m completed state change to NULL
0:00:11.138467650 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<internal-sinks>[00m posting state-changed READY to NULL
0:00:11.138501035 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestimelinepipeline0>[00m child 'internal-sinks' changed state to 1(NULL) successfully
0:00:11.138532114 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2381:gst_element_continue_state:<tee1>[00m committing state from PLAYING to PAUSED, pending NULL, next READY
0:00:11.138563124 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2390:gst_element_continue_state:<tee1>[00m continue state change PAUSED to READY, final NULL
0:00:11.138607055 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2381:gst_element_continue_state:<tee1>[00m committing state from PAUSED to READY, pending NULL, next NULL
0:00:11.138638833 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2390:gst_element_continue_state:<tee1>[00m continue state change READY to NULL, final NULL
0:00:11.138669424 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<tee1>[00m completed state change to NULL
0:00:11.138693240 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<tee1>[00m posting state-changed READY to NULL
0:00:11.138722923 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestimelinepipeline0>[00m child 'tee1' changed state to 1(NULL) successfully
0:00:11.138752675 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2381:gst_element_continue_state:<tee0>[00m committing state from PLAYING to PAUSED, pending NULL, next READY
0:00:11.138783616 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2390:gst_element_continue_state:<tee0>[00m continue state change PAUSED to READY, final NULL
0:00:11.138815743 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2381:gst_element_continue_state:<tee0>[00m committing state from PAUSED to READY, pending NULL, next NULL
0:00:11.138856531 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2390:gst_element_continue_state:<tee0>[00m continue state change READY to NULL, final NULL
0:00:11.138887261 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<tee0>[00m completed state change to NULL
0:00:11.138910938 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<tee0>[00m posting state-changed READY to NULL
0:00:11.138940970 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestimelinepipeline0>[00m child 'tee0' changed state to 1(NULL) successfully
0:00:11.139001243 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<gnlcomposition1>[00m removing pad 'src'
0:00:11.139026387 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking gnlcomposition1:src(0xa07fe80) and src:proxypad5(0xb0e02010)
0:00:11.139062565 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked gnlcomposition1:src and src:proxypad5
0:00:11.139109499 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<gestrack1>[00m removing pad 'src'
0:00:11.139134083 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking gestrack1:src(0xb0e01098) and track_0xa004268_src:proxypad6(0xb0e020e8)
0:00:11.139168934 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked gestrack1:src and track_0xa004268_src:proxypad6
0:00:11.139211119 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<gestimeline0>[00m removing pad 'track_0xa004268_src'
0:00:11.139235494 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking gestimeline0:track_0xa004268_src(0xb0e01180) and tee0:sink(0xa00c640)
0:00:11.139269297 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked gestimeline0:track_0xa004268_src and tee0:sink
0:00:11.139312390 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking tee0:src0(0xa00c708) and internal-sinks:video_sink(0xb0e01268)
0:00:11.139348009 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked tee0:src0 and internal-sinks:video_sink
0:00:11.139389845 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<internal-sinks>[00m removing pad 'video_sink'
0:00:11.139428397 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:969:gst_element_get_static_pad:[00m found pad tee0:sink
0:00:11.139467579 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking gestimeline0:track_0xa004268_src(0xb0e01180) and tee0:sink(0xa00c640)
0:00:11.139516050 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<tee0>[00m completed state change to NULL
0:00:11.139550412 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<gestimelinepipeline0>[00m removed child "tee0"
0:00:11.139661112 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<titlsrc-text>[00m completed state change to READY
0:00:11.139697639 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<titlsrc-text>[00m posting state-changed PAUSED to READY
0:00:11.139733258 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<titlesrc-bin>[00m child 'titlsrc-text' changed state to 2(READY) successfully
0:00:11.139853806 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<titlesrc-bg>[00m completed state change to READY
0:00:11.139880485 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<titlesrc-bg>[00m posting state-changed PAUSED to READY
0:00:11.139915826 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<titlesrc-bin>[00m child 'titlesrc-bg' changed state to 2(READY) successfully
0:00:11.139949559 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<titlesrc-bin>[00m completed state change to READY
0:00:11.139974353 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<titlesrc-bin>[00m posting state-changed PAUSED to READY
0:00:11.140007947 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gnlsource1>[00m child 'titlesrc-bin' changed state to 2(READY) successfully
0:00:11.140044125 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking titlesrc-bin:src(0xa07f828) and src:proxypad3(0xa00ddf8)
0:00:11.140083167 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked titlesrc-bin:src and src:proxypad3
0:00:11.140125142 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<gnlsource1>[00m removing pad 'src'
0:00:11.140168654 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gnlsource1>[00m completed state change to READY
0:00:11.140193727 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gnlsource1>[00m posting state-changed PAUSED to READY
0:00:11.140225994 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gnlcomposition1>[00m child 'gnlsource1' changed state to 2(READY) successfully
0:00:11.140260845 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gnlcomposition1>[00m completed state change to READY
0:00:11.140285081 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gnlcomposition1>[00m posting state-changed PAUSED to READY
0:00:11.140316859 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestrack1>[00m child 'gnlcomposition1' changed state to 2(READY) successfully
0:00:11.140344935 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2221:gst_bin_element_set_state:<gnlcomposition1>[00m skipping transition from READY to  READY, since bin pending is READY : last change state return follows
0:00:11.140371545 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestrack1>[00m child 'gnlcomposition1' changed state to 2(READY) successfully
0:00:11.140402206 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gestrack1>[00m completed state change to READY
0:00:11.140436498 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gestrack1>[00m posting state-changed PAUSED to READY
0:00:11.140486924 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestimeline0>[00m child 'gestrack1' changed state to 2(READY) successfully
0:00:11.140515350 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2221:gst_bin_element_set_state:<gestrack1>[00m skipping transition from READY to  READY, since bin pending is NULL : last change state return follows
0:00:11.140541750 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestimeline0>[00m child 'gestrack1' changed state to 2(READY) successfully
0:00:11.140598881 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<gnlcomposition0>[00m removing pad 'src'
0:00:11.140624653 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking gnlcomposition0:src(0xb0e01608) and src:proxypad11(0xb0e025f8)
0:00:11.140660622 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked gnlcomposition0:src and src:proxypad11
0:00:11.140703086 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<gestrack0>[00m removing pad 'src'
0:00:11.140727321 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking gestrack0:src(0xb0e016f0) and track_0xa004160_src:proxypad12(0xb0e026d0)
0:00:11.140762032 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked gestrack0:src and track_0xa004160_src:proxypad12
0:00:11.140803798 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<gestimeline0>[00m removing pad 'track_0xa004160_src'
0:00:11.140828033 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking gestimeline0:track_0xa004160_src(0xa130858) and tee1:sink(0xb0e05cb8)
0:00:11.140862186 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked gestimeline0:track_0xa004160_src and tee1:sink
0:00:11.140901158 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking tee1:src0(0xb0e05e48) and internal-sinks:audio_sink(0xa130940)
0:00:11.140936358 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked tee1:src0 and internal-sinks:audio_sink
0:00:11.140974282 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking audio_sink:proxypad14(0xa131030) and audiotee:sink(0xb0e05d80)
0:00:11.141002499 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked audio_sink:proxypad14 and audiotee:sink
0:00:11.141029807 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<internal-sinks>[00m removing pad 'audio_sink'
0:00:11.141065566 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:969:gst_element_get_static_pad:[00m found pad tee1:sink
0:00:11.141090779 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking gestimeline0:track_0xa004160_src(0xa130858) and tee1:sink(0xb0e05cb8)
0:00:11.141146513 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<tee1>[00m completed state change to NULL
0:00:11.141179269 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<gestimelinepipeline0>[00m removed child "tee1"
0:00:11.141363512 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<audiotestsrc0>[00m completed state change to READY
0:00:11.141391658 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<audiotestsrc0>[00m posting state-changed PAUSED to READY
0:00:11.141427767 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gnlsource0>[00m child 'audiotestsrc0' changed state to 2(READY) successfully
0:00:11.141477285 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking audiotestsrc0:src(0xa00c190) and src:proxypad9(0xb0e02448)
0:00:11.141515978 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked audiotestsrc0:src and src:proxypad9
0:00:11.141554740 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<gnlsource0>[00m removing pad 'src'
0:00:11.141599160 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gnlsource0>[00m completed state change to READY
0:00:11.141624163 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gnlsource0>[00m posting state-changed PAUSED to READY
0:00:11.141656570 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gnlcomposition0>[00m child 'gnlsource0' changed state to 2(READY) successfully
0:00:11.141691281 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gnlcomposition0>[00m completed state change to READY
0:00:11.141715517 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gnlcomposition0>[00m posting state-changed PAUSED to READY
0:00:11.141747155 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestrack0>[00m child 'gnlcomposition0' changed state to 2(READY) successfully
0:00:11.141775231 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2221:gst_bin_element_set_state:<gnlcomposition0>[00m skipping transition from READY to  READY, since bin pending is READY : last change state return follows
0:00:11.141801911 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestrack0>[00m child 'gnlcomposition0' changed state to 2(READY) successfully
0:00:11.141832781 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gestrack0>[00m completed state change to READY
0:00:11.141856737 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gestrack0>[00m posting state-changed PAUSED to READY
0:00:11.141887607 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestimeline0>[00m child 'gestrack0' changed state to 2(READY) successfully
0:00:11.141915614 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2221:gst_bin_element_set_state:<gestrack1>[00m skipping transition from READY to  READY, since bin pending is NULL : last change state return follows
0:00:11.141951024 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestimeline0>[00m child 'gestrack1' changed state to 2(READY) successfully
0:00:11.141977215 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2221:gst_bin_element_set_state:<gestrack0>[00m skipping transition from READY to  READY, since bin pending is NULL : last change state return follows
0:00:11.142002567 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestimeline0>[00m child 'gestrack0' changed state to 2(READY) successfully
0:00:11.142033577 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2381:gst_element_continue_state:<gestimeline0>[00m committing state from PAUSED to READY, pending NULL, next NULL
0:00:11.142065844 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2390:gst_element_continue_state:<gestimeline0>[00m continue state change READY to NULL, final NULL
0:00:11.142127724 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<titlsrc-text>[00m completed state change to NULL
0:00:11.142152448 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<titlsrc-text>[00m posting state-changed READY to NULL
0:00:11.142185973 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<titlesrc-bin>[00m child 'titlsrc-text' changed state to 1(NULL) successfully
0:00:11.142218310 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<titlesrc-bg>[00m completed state change to NULL
0:00:11.142242335 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<titlesrc-bg>[00m posting state-changed READY to NULL
0:00:11.142275091 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<titlesrc-bin>[00m child 'titlesrc-bg' changed state to 1(NULL) successfully
0:00:11.142307498 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<titlesrc-bin>[00m completed state change to NULL
0:00:11.142331523 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<titlesrc-bin>[00m posting state-changed READY to NULL
0:00:11.142363721 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gnlsource1>[00m child 'titlesrc-bin' changed state to 1(NULL) successfully
0:00:11.142394800 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gnlsource1>[00m completed state change to NULL
0:00:11.142418617 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gnlsource1>[00m posting state-changed READY to NULL
0:00:11.142463386 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gnlcomposition1>[00m child 'gnlsource1' changed state to 1(NULL) successfully
0:00:11.142499424 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gnlcomposition1>[00m completed state change to NULL
0:00:11.142523520 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gnlcomposition1>[00m posting state-changed READY to NULL
0:00:11.142565565 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestrack1>[00m child 'gnlcomposition1' changed state to 1(NULL) successfully
0:00:11.142597901 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gestrack1>[00m completed state change to NULL
0:00:11.142621857 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gestrack1>[00m posting state-changed READY to NULL
0:00:11.142652308 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestimeline0>[00m child 'gestrack1' changed state to 1(NULL) successfully
0:00:11.142700011 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<audiotestsrc0>[00m completed state change to NULL
0:00:11.142724735 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<audiotestsrc0>[00m posting state-changed READY to NULL
0:00:11.142757281 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gnlsource0>[00m child 'audiotestsrc0' changed state to 1(NULL) successfully
0:00:11.142789129 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gnlsource0>[00m completed state change to NULL
0:00:11.142813225 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gnlsource0>[00m posting state-changed READY to NULL
0:00:11.142845073 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gnlcomposition0>[00m child 'gnlsource0' changed state to 1(NULL) successfully
0:00:11.142878597 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gnlcomposition0>[00m completed state change to NULL
0:00:11.142902483 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gnlcomposition0>[00m posting state-changed READY to NULL
0:00:11.142946344 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestrack0>[00m child 'gnlcomposition0' changed state to 1(NULL) successfully
0:00:11.142978471 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gestrack0>[00m completed state change to NULL
0:00:11.143002217 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gestrack0>[00m posting state-changed READY to NULL
0:00:11.143031970 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestimeline0>[00m child 'gestrack0' changed state to 1(NULL) successfully
0:00:11.143063119 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gestimeline0>[00m completed state change to NULL
0:00:11.143086656 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gestimeline0>[00m posting state-changed READY to NULL
0:00:11.143116688 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestimelinepipeline0>[00m child 'gestimeline0' changed state to 1(NULL) successfully
0:00:11.143145044 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2221:gst_bin_element_set_state:<internal-sinks>[00m skipping transition from NULL to  NULL, since bin pending is NULL : last change state return follows
0:00:11.143178987 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestimelinepipeline0>[00m child 'internal-sinks' changed state to 1(NULL) successfully
0:00:11.143205737 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2221:gst_bin_element_set_state:<gestimeline0>[00m skipping transition from NULL to  NULL, since bin pending is NULL : last change state return follows
0:00:11.143230321 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestimelinepipeline0>[00m child 'gestimeline0' changed state to 1(NULL) successfully
0:00:11.143284868 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<videosink-actual-sink-xvimage>[00m dispose
0:00:11.143311059 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<videosink-actual-sink-xvimage>[00m removing pad 'sink'
0:00:11.143341650 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<videosink-actual-sink-xvimage>[00m parent class dispose
0:00:11.143370145 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<videosink-actual-sink-xvimage>[00m finalize
0:00:11.143395009 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<videosink-actual-sink-xvimage>[00m finalize parent
0:00:11.143426368 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking sink:proxypad13(0xb0e02370) and vqueue:sink(0xb0e05998)
0:00:11.143467016 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked sink:proxypad13 and vqueue:sink
0:00:11.143496979 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<vbin>[00m removed child "vqueue"
0:00:11.143531341 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<vqueue>[00m dispose
0:00:11.143555995 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<vqueue>[00m removing pad 'src'
0:00:11.143587913 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<vqueue>[00m removing pad 'sink'
0:00:11.143619342 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<vqueue>[00m parent class dispose
0:00:11.143646161 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<vqueue>[00m finalize
0:00:11.143670327 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<vqueue>[00m finalize parent
0:00:11.143695051 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<vbin>[00m dispose
0:00:11.143718657 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<vbin>[00m removing pad 'sink'
0:00:11.143753578 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<vbin>[00m parent class dispose
0:00:11.143778791 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<vbin>[00m finalize
0:00:11.143802747 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<vbin>[00m finalize parent
0:00:11.143860716 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<audiosink-actual-sink-pulse>[00m dispose
0:00:11.143886069 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<audiosink-actual-sink-pulse>[00m removing pad 'sink'
0:00:11.143916380 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<audiosink-actual-sink-pulse>[00m parent class dispose
0:00:11.143944736 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<audiosink-actual-sink-pulse>[00m finalize
0:00:11.143969251 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<audiosink-actual-sink-pulse>[00m finalize parent
0:00:11.144000680 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking sink:proxypad16(0xa1311e0) and aqueue:sink(0xa1338c8)
0:00:11.144028547 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked sink:proxypad16 and aqueue:sink
0:00:11.144056064 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<abin>[00m removed child "aqueue"
0:00:11.144089379 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<aqueue>[00m dispose
0:00:11.144113894 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<aqueue>[00m removing pad 'src'
0:00:11.144145253 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<aqueue>[00m removing pad 'sink'
0:00:11.144176751 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<aqueue>[00m parent class dispose
0:00:11.144202872 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<aqueue>[00m finalize
0:00:11.144226968 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<aqueue>[00m finalize parent
0:00:11.144251762 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<abin>[00m dispose
0:00:11.144275229 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<abin>[00m removing pad 'sink'
0:00:11.144309731 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<abin>[00m parent class dispose
0:00:11.144335083 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<abin>[00m finalize
0:00:11.144359039 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<abin>[00m finalize parent
0:00:11.144394519 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<tee1>[00m removing pad 'src0'
0:00:11.144429300 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<tee1>[00m dispose
0:00:11.144481403 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<tee1>[00m removing pad 'sink'
0:00:11.144516324 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<tee1>[00m parent class dispose
0:00:11.144552432 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<tee1>[00m finalize
0:00:11.144576877 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<tee1>[00m finalize parent
0:00:11.144607677 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<tee0>[00m removing pad 'src0'
0:00:11.144641551 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<tee0>[00m dispose
0:00:11.144665996 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<tee0>[00m removing pad 'sink'
0:00:11.144699939 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<tee0>[00m parent class dispose
0:00:11.144723056 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<tee0>[00m finalize
0:00:11.144739749 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<tee0>[00m finalize parent
0:00:11.144780886 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gestimelinepipeline0>[00m completed state change to NULL
0:00:11.144797648 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2421:gst_element_continue_state:<gestimelinepipeline0>[00m posting state-changed READY to NULL
0:00:11.144822721 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<gestimelinepipeline0>[00m removed child "internal-sinks"
0:00:11.144851496 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2221:gst_bin_element_set_state:<tempsink>[00m skipping transition from NULL to  NULL, since bin pending is NULL : last change state return follows
0:00:11.144869236 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<audiosink>[00m child 'tempsink' changed state to 1(NULL) successfully
0:00:11.144887814 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<audiosink>[00m completed state change to NULL
0:00:11.144905554 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<tempsink>[00m completed state change to NULL
0:00:11.144922246 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking sink:proxypad15(0xa131108) and tempsink:sink(0xb0e05f10)
0:00:11.144941313 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked sink:proxypad15 and tempsink:sink
0:00:11.144960031 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<audiosink>[00m removed child "tempsink"
0:00:11.144980285 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<tempsink>[00m dispose
0:00:11.144996837 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<tempsink>[00m removing pad 'sink'
0:00:11.145016882 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<tempsink>[00m parent class dispose
0:00:11.145034762 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<tempsink>[00m finalize
0:00:11.145057809 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<tempsink>[00m finalize parent
0:00:11.145077645 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<audiosink>[00m dispose
0:00:11.145094127 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<audiosink>[00m removing pad 'sink'
0:00:11.145119410 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<audiosink>[00m parent class dispose
0:00:11.145137010 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<audiosink>[00m finalize
0:00:11.145153563 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<audiosink>[00m finalize parent
0:00:11.145175563 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2221:gst_bin_element_set_state:<tempsink>[00m skipping transition from NULL to  NULL, since bin pending is NULL : last change state return follows
0:00:11.145192674 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<videosink>[00m child 'tempsink' changed state to 1(NULL) successfully
0:00:11.145211043 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<videosink>[00m completed state change to NULL
0:00:11.145228503 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<tempsink>[00m completed state change to NULL
0:00:11.145244986 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1728:gst_pad_unlink:[00m unlinking sink:proxypad8(0xb0e02298) and tempsink:sink(0xa00c578)
0:00:11.145263774 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1773:gst_pad_unlink:[00m unlinked sink:proxypad8 and tempsink:sink
0:00:11.145282771 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<videosink>[00m removed child "tempsink"
0:00:11.145303095 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<tempsink>[00m dispose
0:00:11.145319787 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<tempsink>[00m removing pad 'sink'
0:00:11.145340460 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<tempsink>[00m parent class dispose
0:00:11.145358619 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<tempsink>[00m finalize
0:00:11.145375032 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<tempsink>[00m finalize parent
0:00:11.145394378 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<videosink>[00m dispose
0:00:11.145410721 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<videosink>[00m removing pad 'sink'
0:00:11.145434398 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<videosink>[00m parent class dispose
0:00:11.145462684 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<videosink>[00m finalize
0:00:11.145480704 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<videosink>[00m finalize parent
0:00:11.145505497 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<audiotee>[00m removing pad 'src0'
0:00:11.145531828 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<internal-sinks>[00m removed child "audiotee"
0:00:11.145552990 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<audiotee>[00m dispose
0:00:11.145569193 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<audiotee>[00m removing pad 'sink'
0:00:11.145590565 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<audiotee>[00m parent class dispose
0:00:11.145607886 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<audiotee>[00m finalize
0:00:11.145624438 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<audiotee>[00m finalize parent
0:00:11.145642947 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<internal-sinks>[00m removed child "streamsynchronizer0"
0:00:11.145665366 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<streamsynchronizer0>[00m dispose
0:00:11.145681988 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<streamsynchronizer0>[00m parent class dispose
0:00:11.145699519 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<streamsynchronizer0>[00m finalize
0:00:11.145716001 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<streamsynchronizer0>[00m finalize parent
0:00:11.145733043 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<internal-sinks>[00m dispose
0:00:11.145749037 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<internal-sinks>[00m parent class dispose
0:00:11.145766288 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<internal-sinks>[00m finalize
0:00:11.145782840 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<internal-sinks>[00m finalize parent
0:00:11.145844301 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<internal-encodebin>[00m dispose
0:00:11.145862251 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:841:gst_element_remove_pad:<internal-encodebin>[00m removing pad 'src'
0:00:11.145889838 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<internal-encodebin>[00m parent class dispose
0:00:11.145908416 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<internal-encodebin>[00m finalize
0:00:11.145925877 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<internal-encodebin>[00m finalize parent
0:00:11.145946201 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<gestimelinepipeline0>[00m removed child "gestimeline0"
0:00:11.145992297 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2221:gst_bin_element_set_state:<discoverer-uri>[00m skipping transition from NULL to  NULL, since bin pending is NULL : last change state return follows
0:00:11.146020513 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<Discoverer>[00m child 'discoverer-uri' changed state to 1(NULL) successfully
0:00:11.146040837 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<Discoverer>[00m completed state change to NULL
0:00:11.146068844 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2221:gst_bin_element_set_state:<discoverer-uri>[00m skipping transition from NULL to  NULL, since bin pending is NULL : last change state return follows
0:00:11.146086025 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<Discoverer>[00m child 'discoverer-uri' changed state to 1(NULL) successfully
0:00:11.146104184 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<Discoverer>[00m completed state change to NULL
0:00:11.146131492 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<Discoverer>[00m removed child "discoverer-uri"
0:00:11.146157124 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<discoverer-uri>[00m dispose
0:00:11.146174165 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<discoverer-uri>[00m parent class dispose
0:00:11.146196864 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<discoverer-uri>[00m finalize
0:00:11.146213975 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<discoverer-uri>[00m finalize parent
0:00:11.146231087 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<Discoverer>[00m dispose
0:00:11.146248687 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<Discoverer>[00m parent class dispose
0:00:11.146265798 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<Discoverer>[00m finalize
0:00:11.146282351 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<Discoverer>[00m finalize parent
0:00:11.146345488 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<gnlcomposition1>[00m removed child "gnlsource1"
0:00:11.146418752 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<gnlcomposition0>[00m removed child "gnlsource0"
0:00:11.146470506 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;04m             default ges-ui.c:391:layer_object_removed_cb:[00m layer object removed cb 0xa014800 0xa07e100 0xa001628
0:00:11.147080786 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<gestimeline0>[00m removed child "gestrack0"
0:00:11.147111865 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2221:gst_bin_element_set_state:<gnlcomposition0>[00m skipping transition from NULL to  NULL, since bin pending is NULL : last change state return follows
0:00:11.147130373 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestrack0>[00m child 'gnlcomposition0' changed state to 1(NULL) successfully
0:00:11.147149440 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gestrack0>[00m completed state change to NULL
0:00:11.147175631 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<gestrack0>[00m removed child "gnlcomposition0"
0:00:11.147201961 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<gnlcomposition0>[00m dispose
0:00:11.147219143 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<gnlcomposition0>[00m parent class dispose
0:00:11.147236813 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;34m      gnlcomposition gnlcomposition.c:405:gnl_composition_finalize:[00m finalize
0:00:11.147254762 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<gnlcomposition0>[00m finalize
0:00:11.147271594 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<gnlcomposition0>[00m finalize parent
0:00:11.147292058 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<gestrack0>[00m dispose
0:00:11.147308540 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<gestrack0>[00m parent class dispose
0:00:11.147326071 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<gestrack0>[00m finalize
0:00:11.147342693 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<gestrack0>[00m finalize parent
0:00:11.147428529 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<gestimeline0>[00m removed child "gestrack1"
0:00:11.147467292 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2221:gst_bin_element_set_state:<gnlcomposition1>[00m skipping transition from NULL to  NULL, since bin pending is NULL : last change state return follows
0:00:11.147486917 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2497:gst_bin_change_state_func:<gestrack1>[00m child 'gnlcomposition1' changed state to 1(NULL) successfully
0:00:11.147506054 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2408:gst_element_continue_state:<gestrack1>[00m completed state change to NULL
0:00:11.147524562 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1448:gst_bin_remove_func:<gestrack1>[00m removed child "gnlcomposition1"
0:00:11.147549845 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<gnlcomposition1>[00m dispose
0:00:11.147566817 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<gnlcomposition1>[00m parent class dispose
0:00:11.147584138 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;34m      gnlcomposition gnlcomposition.c:405:gnl_composition_finalize:[00m finalize
0:00:11.147601598 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<gnlcomposition1>[00m finalize
0:00:11.147618290 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<gnlcomposition1>[00m finalize parent
0:00:11.147638824 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<gestrack1>[00m dispose
0:00:11.147655377 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<gestrack1>[00m parent class dispose
0:00:11.147679682 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<gestrack1>[00m finalize
0:00:11.147696583 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<gestrack1>[00m finalize parent
0:00:11.147715720 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<gestimeline0>[00m dispose
0:00:11.147732133 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<gestimeline0>[00m parent class dispose
0:00:11.147752247 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<gestimeline0>[00m finalize
0:00:11.147768870 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<gestimeline0>[00m finalize parent
0:00:11.147785911 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3022:gst_element_dispose:<gestimelinepipeline0>[00m dispose
0:00:11.147802394 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3046:gst_element_dispose:<gestimelinepipeline0>[00m parent class dispose
0:00:11.147819156 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3077:gst_element_finalize:<gestimelinepipeline0>[00m finalize
0:00:11.147843950 [333m 6452[00m  0x9e9d060 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3088:gst_element_finalize:<gestimelinepipeline0>[00m finalize parent
