0:00:00.000104355 15760  0x9ab0490 INFO              GST_INIT gst.c:610:init_pre: Initializing GStreamer Core Library version 0.10.22
0:00:00.000241611 15760  0x9ab0490 INFO              GST_INIT gst.c:611:init_pre: Using library installed in /usr/lib
0:00:00.000262585 15760  0x9ab0490 INFO              GST_INIT gst.c:621:init_pre: Linux morryis-desktop 2.6.28-11-generic #35-Ubuntu SMP Wed Mar 18 21:39:57 UTC 2009 i686
0:00:00.002105436 15760  0x9ab0490 INFO              GST_INIT gstquery.c:106:_gst_query_initialize: init queries
0:00:00.002885945 15760  0x9ab0490 INFO              GST_INIT gstmessage.c:75:_gst_message_initialize: init messages
0:00:00.003468392 15760  0x9ab0490 INFO    GST_PLUGIN_LOADING gstplugin.c:265:_gst_plugin_initialize: registering 0 static plugins
0:00:00.003653208 15760  0x9ab0490 INFO    GST_PLUGIN_LOADING gstplugin.c:250:gst_plugin_register_static: registered static plugin "staticelements"
0:00:00.003671398 15760  0x9ab0490 INFO    GST_PLUGIN_LOADING gstplugin.c:252:gst_plugin_register_static: added static plugin "staticelements", result: 1
0:00:00.004273021 15760  0x9ab0490 INFO              GST_INIT gst.c:932:ensure_current_registry: reading registry cache: /home/morryis/.gstreamer-0.10/registry.i486.bin
0:00:00.017364278 15760  0x9ab0490 INFO          GST_REGISTRY gstregistrybinary.c:1221:gst_registry_binary_read_cache: loaded /home/morryis/.gstreamer-0.10/registry.i486.bin in 0,013064 seconds
0:00:00.017996281 15761  0x9ab0490 INFO              GST_INIT gst.c:673:scan_and_update_registry: Validating registry cache: /home/morryis/.gstreamer-0.10/registry.i486.bin
0:00:00.020759090 15761  0x9ab0490 INFO                ladspa gstladspa.c:809:ladspa_plugin_path_search: 3 dirs in search paths "/usr/lib/ladspa:/usr/local/lib/ladspa:/usr/lib/ladspa"
0:00:00.020777336 15761  0x9ab0490 INFO                ladspa gstladspa.c:754:ladspa_plugin_directory_search: scanning directory for plugins "/usr/lib/ladspa"
0:00:00.020800722 15761  0x9ab0490 INFO                ladspa gstladspa.c:754:ladspa_plugin_directory_search: scanning directory for plugins "/usr/local/lib/ladspa"
0:00:00.020823216 15761  0x9ab0490 WARN    GST_PLUGIN_LOADING gstplugin.c:344:gst_plugin_register_func: plugin "/usr/lib/gstreamer-0.10/libgstladspa.so" failed to initialise
0:00:00.021251681 15761  0x9ab0490 INFO              GST_INIT gst.c:768:scan_and_update_registry: Registry cache has not changed
0:00:00.021639031 15760  0x9ab0490 INFO              GST_INIT gst.c:979:ensure_current_registry: registry reading and updating done, result = 1
0:00:00.289365701 15760  0x9ab0490 INFO    GST_PLUGIN_LOADING gstplugin.c:570:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstplaybin.so" loaded
0:00:00.289414755 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "playbin" named "play"
0:00:00.289902918 15760  0x9ab0490 INFO    GST_PLUGIN_LOADING gstplugin.c:570:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstgconfelements.so" loaded
0:00:00.289923271 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "gconfaudiosink" named "audio-sink"
0:00:00.290006661 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstSwitchSink@0x9f081a0> adding pad 'sink'
0:00:00.291329538 15760  0x9ab0490 INFO    GST_PLUGIN_LOADING gstplugin.c:570:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstcoreelements.so" loaded
0:00:00.291350899 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "fakesink" named "testsink"
0:00:00.291393014 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseSink@0x9f2a008> adding pad 'sink'
0:00:00.291442248 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<testsink> completed state change to NULL
0:00:00.291472345 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad testsink:sink
0:00:00.291498467 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link sink:proxypad0 and testsink:sink
0:00:00.291520502 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked sink:proxypad0 and testsink:sink, successful
0:00:00.291996035 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "gconfvideosink" named "video-sink"
0:00:00.292054501 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstGConfVideoSink@0x9c48620> adding pad 'sink'
0:00:00.292067881 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "fakesink" named "testsink"
0:00:00.292095314 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseSink@0x9f2a278> adding pad 'sink'
0:00:00.292116983 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad testsink:sink
0:00:00.292127721 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link sink:proxypad1 and testsink:sink
0:00:00.292140462 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked sink:proxypad1 and testsink:sink, successful
0:00:00.294877150 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<testsink> completed state change to NULL
0:00:00.294892348 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad1(0x9f15108) and testsink:sink(0x9c38340)
0:00:00.294904607 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked sink:proxypad1 and testsink:sink
0:00:00.294917396 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<video-sink> removed child "testsink"
0:00:00.294929273 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<testsink> dispose
0:00:00.294938871 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<testsink> removing pad 'sink'
0:00:00.294949449 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<testsink> parent class dispose
0:00:00.294962073 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<testsink> finalize
0:00:00.294973588 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<testsink> finalize parent
0:00:00.294989668 15760  0x9ab0490 INFO          GST_PIPELINE gstparse.c:291:gst_parse_launch_full: parsing pipeline description 'bin.( autovideosink )'
0:00:00.295445790 15760  0x9ab0490 INFO    GST_PLUGIN_LOADING gstplugin.c:570:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstautodetect.so" loaded
0:00:00.295467979 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "autovideosink"
0:00:00.295528470 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstAutoVideoSink@0x9c32830> adding pad 'sink'
0:00:00.295542255 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "fakesink" named "tempsink"
0:00:00.295570081 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseSink@0x9f2a4e8> adding pad 'sink'
0:00:00.295592005 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad tempsink:sink
0:00:00.295602961 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link sink:proxypad2 and tempsink:sink
0:00:00.295616000 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked sink:proxypad2 and tempsink:sink, successful
0:00:00.295666113 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "bin"
0:00:00.295758686 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link sink:proxypad3 and autovideosink0:sink
0:00:00.295788027 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked sink:proxypad3 and autovideosink0:sink, successful
0:00:00.295800315 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<bin0> adding pad 'sink'
0:00:00.295828153 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<tempsink> completed state change to NULL
0:00:00.295839346 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<autovideosink0> child 'tempsink' changed state to 1(NULL) successfully
0:00:00.295852478 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autovideosink0> completed state change to NULL
0:00:00.295862525 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<bin0> child 'autovideosink0' changed state to 1(NULL) successfully
0:00:00.295873082 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<bin0> completed state change to NULL
0:00:00.295891665 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad bin0:sink
0:00:00.295902156 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad1(0x9f15108) and '':sink(0x9c38340)
0:00:00.295915188 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link sink:proxypad1 and bin0:sink
0:00:00.295928692 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked sink:proxypad1 and bin0:sink, successful
0:00:00.295947454 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<tempsink> completed state change to NULL
0:00:00.295958224 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad2(0x9f151d8) and tempsink:sink(0x9c38400)
0:00:00.295968893 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked sink:proxypad2 and tempsink:sink
0:00:00.295979585 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<autovideosink0> removed child "tempsink"
0:00:00.295990283 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<tempsink> dispose
0:00:00.295999476 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<tempsink> removing pad 'sink'
0:00:00.296009871 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<tempsink> parent class dispose
0:00:00.296020467 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<tempsink> finalize
0:00:00.296029833 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<tempsink> finalize parent
0:00:00.296875612 15760  0x9ab0490 INFO    GST_PLUGIN_LOADING gstplugin.c:570:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstxvimagesink.so" loaded
0:00:00.296895259 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "xvimagesink" named "autovideosink0-actual-sink-xvimage"
0:00:00.296927789 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseSink@0x9f36018> adding pad 'sink'
0:00:00.296950626 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad autovideosink0-actual-sink-xvimage:sink
0:00:00.317872116 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autovideosink0-actual-sink-xvimage> completed state change to READY
0:00:00.317905237 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<autovideosink0-actual-sink-xvimage> posting state-changed NULL to READY
0:00:00.317942126 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad autovideosink0-actual-sink-xvimage:sink
0:00:00.317954342 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad2(0x9f151d8) and '':sink(0x9c38400)
0:00:00.317978787 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link sink:proxypad2 and autovideosink0-actual-sink-xvimage:sink
0:00:00.318021784 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked sink:proxypad2 and autovideosink0-actual-sink-xvimage:sink, successful
0:00:00.318040879 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autovideosink0-actual-sink-xvimage> completed state change to READY
0:00:00.318051875 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<autovideosink0> child 'autovideosink0-actual-sink-xvimage' changed state to 2(READY) successfully
0:00:00.318063983 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autovideosink0> completed state change to READY
0:00:00.318073550 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<autovideosink0> posting state-changed NULL to READY
0:00:00.318088578 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<bin0> child 'autovideosink0' changed state to 2(READY) successfully
0:00:00.318100284 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<bin0> completed state change to READY
0:00:00.318109626 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<bin0> posting state-changed NULL to READY
0:00:00.318127309 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<video-sink> child 'bin0' changed state to 2(READY) successfully
0:00:00.318139978 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<video-sink> completed state change to READY
0:00:00.318149434 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<video-sink> posting state-changed NULL to READY
0:00:00.318179723 15760  0x9ab0490 INFO          GST_PIPELINE gstparse.c:291:gst_parse_launch_full: parsing pipeline description 'bin.( autoaudiosink )'
0:00:00.318195623 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "autoaudiosink"
0:00:00.318259270 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstAutoAudioSink@0x9c32908> adding pad 'sink'
0:00:00.318273742 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "fakesink" named "tempsink"
0:00:00.318303377 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseSink@0x9f2a758> adding pad 'sink'
0:00:00.318326301 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad tempsink:sink
0:00:00.318337572 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link sink:proxypad4 and tempsink:sink
0:00:00.318350574 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked sink:proxypad4 and tempsink:sink, successful
0:00:00.318420455 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "bin"
0:00:00.318493624 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link sink:proxypad5 and autoaudiosink0:sink
0:00:00.318509281 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked sink:proxypad5 and autoaudiosink0:sink, successful
0:00:00.318521233 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<bin1> adding pad 'sink'
0:00:00.318547460 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<tempsink> completed state change to NULL
0:00:00.318559692 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad4(0x9f15378) and tempsink:sink(0x9c38580)
0:00:00.318578865 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked sink:proxypad4 and tempsink:sink
0:00:00.318591153 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<autoaudiosink0> removed child "tempsink"
0:00:00.318602716 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<tempsink> dispose
0:00:00.318612607 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<tempsink> removing pad 'sink'
0:00:00.318623455 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<tempsink> parent class dispose
0:00:00.318634672 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<tempsink> finalize
0:00:00.318644639 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<tempsink> finalize parent
0:00:00.320679911 15760  0x9ab0490 INFO    GST_PLUGIN_LOADING gstplugin.c:570:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstpulse.so" loaded
0:00:00.320704986 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "pulsesink" named "autoaudiosink0-actual-sink-pulse"
0:00:00.320747845 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseSink@0x9f4c030> adding pad 'sink'
0:00:00.320830611 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad autoaudiosink0-actual-sink-pulse:sink
0:00:00.322303709 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autoaudiosink0-actual-sink-pulse> completed state change to READY
0:00:00.322318994 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<autoaudiosink0-actual-sink-pulse> posting state-changed NULL to READY
0:00:00.322350978 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad autoaudiosink0-actual-sink-pulse:sink
0:00:00.322363024 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad4(0x9f15378) and '':sink(0x9c38580)
0:00:00.322376962 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link sink:proxypad4 and autoaudiosink0-actual-sink-pulse:sink
0:00:00.322439232 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked sink:proxypad4 and autoaudiosink0-actual-sink-pulse:sink, successful
0:00:00.322458165 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autoaudiosink0-actual-sink-pulse> completed state change to READY
0:00:00.322468776 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<autoaudiosink0> child 'autoaudiosink0-actual-sink-pulse' changed state to 2(READY) successfully
0:00:00.322480750 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autoaudiosink0> completed state change to READY
0:00:00.322490440 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<autoaudiosink0> posting state-changed NULL to READY
0:00:00.322504048 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<bin1> child 'autoaudiosink0' changed state to 2(READY) successfully
0:00:00.322515647 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<bin1> completed state change to READY
0:00:00.322525238 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<bin1> posting state-changed NULL to READY
0:00:00.322553534 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<testsink> completed state change to NULL
0:00:00.322565292 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad0(0x9f15038) and testsink:sink(0x9c38280)
0:00:00.322576611 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked sink:proxypad0 and testsink:sink
0:00:00.322598503 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<audio-sink> removed child "testsink"
0:00:00.322610347 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<testsink> dispose
0:00:00.322620031 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<testsink> removing pad 'sink'
0:00:00.322630756 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<testsink> parent class dispose
0:00:00.322641824 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<testsink> finalize
0:00:00.322651793 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<testsink> finalize parent
0:00:00.322661621 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad bin1:sink
0:00:00.322671059 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad0(0x9f15038) and '':sink(0x9c38280)
0:00:00.322684251 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link sink:proxypad0 and bin1:sink
0:00:00.322754617 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked sink:proxypad0 and bin1:sink, successful
0:00:00.322777457 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autoaudiosink0-actual-sink-pulse> completed state change to READY
0:00:00.322788248 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<autoaudiosink0> child 'autoaudiosink0-actual-sink-pulse' changed state to 2(READY) successfully
0:00:00.322799844 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autoaudiosink0> completed state change to READY
0:00:00.322809450 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<bin1> child 'autoaudiosink0' changed state to 2(READY) successfully
0:00:00.322820442 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<bin1> completed state change to READY
0:00:00.322829973 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<audio-sink> child 'bin1' changed state to 2(READY) successfully
0:00:00.322840861 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<audio-sink> completed state change to READY
0:00:00.322850002 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<audio-sink> posting state-changed NULL to READY
0:00:00.322926234 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autoaudiosink0-actual-sink-pulse> completed state change to NULL
0:00:00.322937940 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<autoaudiosink0-actual-sink-pulse> posting state-changed READY to NULL
0:00:00.322953144 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<autoaudiosink0> child 'autoaudiosink0-actual-sink-pulse' changed state to 1(NULL) successfully
0:00:00.322969237 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autoaudiosink0-actual-sink-pulse> completed state change to NULL
0:00:00.322980130 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad4(0x9f15378) and autoaudiosink0-actual-sink-pulse:sink(0x9c38400)
0:00:00.322991083 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked sink:proxypad4 and autoaudiosink0-actual-sink-pulse:sink
0:00:00.323002064 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<autoaudiosink0> removed child "autoaudiosink0-actual-sink-pulse"
0:00:00.323021780 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<autoaudiosink0-actual-sink-pulse> dispose
0:00:00.323032212 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<autoaudiosink0-actual-sink-pulse> removing pad 'sink'
0:00:00.323050218 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<autoaudiosink0-actual-sink-pulse> parent class dispose
0:00:00.323103289 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<autoaudiosink0-actual-sink-pulse> finalize
0:00:00.323114729 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<autoaudiosink0-actual-sink-pulse> finalize parent
0:00:00.323127461 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "fakesink" named "tempsink"
0:00:00.323158053 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseSink@0x9f2a008> adding pad 'sink'
0:00:00.323181330 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad tempsink:sink
0:00:00.323191816 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad4(0x9f15378) and '':sink(0x9c38400)
0:00:00.323206156 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link sink:proxypad4 and tempsink:sink
0:00:00.323220166 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked sink:proxypad4 and tempsink:sink, successful
0:00:00.323231243 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autoaudiosink0> completed state change to NULL
0:00:00.323240564 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<autoaudiosink0> posting state-changed READY to NULL
0:00:00.323255162 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<bin1> child 'autoaudiosink0' changed state to 1(NULL) successfully
0:00:00.323270061 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<bin1> completed state change to NULL
0:00:00.323279655 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<bin1> posting state-changed READY to NULL
0:00:00.323292999 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<audio-sink> child 'bin1' changed state to 1(NULL) successfully
0:00:00.323308365 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "fakesink" named "testsink"
0:00:00.323336152 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseSink@0x9f2a758> adding pad 'sink'
0:00:00.323368004 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<testsink> completed state change to NULL
0:00:00.323392269 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<tempsink> completed state change to NULL
0:00:00.323403150 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<autoaudiosink0> child 'tempsink' changed state to 1(NULL) successfully
0:00:00.323414409 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autoaudiosink0> completed state change to NULL
0:00:00.323423934 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<bin1> child 'autoaudiosink0' changed state to 1(NULL) successfully
0:00:00.323434816 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<bin1> completed state change to NULL
0:00:00.323445127 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad0(0x9f15038) and bin1:sink(0x9f2c2a0)
0:00:00.323456812 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked sink:proxypad0 and bin1:sink
0:00:00.323468000 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<audio-sink> removed child "bin1"
0:00:00.323481365 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad5(0x9f15448) and autoaudiosink0:sink(0x9f2c1c0)
0:00:00.323499872 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked sink:proxypad5 and autoaudiosink0:sink
0:00:00.323511669 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<bin1> removed child "autoaudiosink0"
0:00:00.323526960 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<tempsink> completed state change to NULL
0:00:00.323537883 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad4(0x9f15378) and tempsink:sink(0x9c38340)
0:00:00.323548501 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked sink:proxypad4 and tempsink:sink
0:00:00.323559445 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<autoaudiosink0> removed child "tempsink"
0:00:00.323570359 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<tempsink> dispose
0:00:00.323579896 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<tempsink> removing pad 'sink'
0:00:00.323590550 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<tempsink> parent class dispose
0:00:00.323601335 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<tempsink> finalize
0:00:00.323611118 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<tempsink> finalize parent
0:00:00.323623413 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<autoaudiosink0> dispose
0:00:00.323633094 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<autoaudiosink0> removing pad 'sink'
0:00:00.323643603 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<autoaudiosink0> parent class dispose
0:00:00.323653971 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<autoaudiosink0> finalize
0:00:00.323663422 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<autoaudiosink0> finalize parent
0:00:00.323673508 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<bin1> dispose
0:00:00.323682709 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<bin1> removing pad 'sink'
0:00:00.323693029 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<bin1> parent class dispose
0:00:00.323703055 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<bin1> finalize
0:00:00.323712347 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<bin1> finalize parent
0:00:00.323721764 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad testsink:sink
0:00:00.323731145 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad0(0x9f15038) and '':sink(0x9f2c2a0)
0:00:00.323741238 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad5(0x9f15448) and '':sink(0x9f2c1c0)
0:00:00.323751114 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad4(0x9f15378) and '':sink(0x9c38340)
0:00:00.323775078 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link sink:proxypad0 and testsink:sink
0:00:00.323788738 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked sink:proxypad0 and testsink:sink, successful
0:00:00.323800264 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<audio-sink> completed state change to NULL
0:00:00.323809606 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<audio-sink> posting state-changed READY to NULL
0:00:00.323824805 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "capsfilter" named "audiofilter"
0:00:00.323865243 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseTransform@0x9f131c0> adding pad 'sink'
0:00:00.323892321 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseTransform@0x9f131c0> adding pad 'src'
0:00:00.323964878 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "bin" named "audiosinkbin"
0:00:00.324006345 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstutils.c:1430:gst_element_link_pads: trying to link element audiofilter:src to element audio-sink:sink
0:00:00.324018105 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad audiofilter:src
0:00:00.324027250 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad audio-sink:sink
0:00:00.324038392 15760  0x9ab0490 INFO              GST_PADS gstutils.c:1335:prepare_link_maybe_ghosting: audiofilter and audio-sink in same bin, no need for ghost pads
0:00:00.324058904 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link audiofilter:src and audio-sink:sink
0:00:00.324077834 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked audiofilter:src and audio-sink:sink, successful
0:00:00.324097539 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad audiofilter:sink
0:00:00.324145714 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link sink:proxypad6 and audiofilter:sink
0:00:00.324168574 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked sink:proxypad6 and audiofilter:sink, successful
0:00:00.324182075 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<audiosinkbin> adding pad 'sink'
0:00:00.324217022 15760  0x9ab0490 INFO            GST_STATES gstbin.c:1659:gst_bin_get_state_func:<video-sink> getting state
0:00:00.324529066 15760  0x9ab0490 INFO                 totem /build/buildd/totem-2.26.0/src/backend/bacon-video-widget-gst-missing-plugins.c:328:bacon_video_widget_gst_missing_plugins_setup: Set up support for automatic missing plugin installation
/var/lib/python-support/python2.6/gdata/tlslite/utils/cryptomath.py:9: DeprecationWarning: the sha module is deprecated; use the hashlib module instead
  import sha
0:00:03.869282698 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2123:gst_element_continue_state:<play> committing state from NULL to READY, pending PAUSED
0:00:03.869471340 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2132:gst_element_continue_state:<play> continue state change READY to PAUSED, final PAUSED
0:00:03.869497072 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "fakesink" named "test"
0:00:03.869552579 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseSink@0x9f2a4e8> adding pad 'sink'
0:00:03.869675462 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "filesrc" named "source"
0:00:03.869727634 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseSrc@0xa7010b0> adding pad 'src'
0:00:03.870352229 15760  0x9ab0490 INFO    GST_PLUGIN_LOADING gstplugin.c:570:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstdecodebin.so" loaded
0:00:03.870379808 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "decodebin"
0:00:03.871044490 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "typefind" named "typefind"
0:00:03.871103352 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstTypeFindElement@0x9e230a8> adding pad 'sink'
0:00:03.871173067 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstTypeFindElement@0x9e230a8> adding pad 'src'
0:00:03.871203551 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad typefind:sink
0:00:03.871269901 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link sink:proxypad7 and typefind:sink
0:00:03.871290169 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked sink:proxypad7 and typefind:sink, successful
0:00:03.871308977 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstDecodeBin@0x9f649a8> adding pad 'sink'
0:00:03.871329116 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "fakesink" named "fakesink"
0:00:03.871369542 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseSink@0x9f2a278> adding pad 'sink'
0:00:03.871451813 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstutils.c:1430:gst_element_link_pads: trying to link element source:(any) to element decodebin0:(any)
0:00:03.871470942 15760  0x9ab0490 INFO              GST_PADS gstutils.c:2125:gst_pad_can_link: trying to link source:src and decodebin0:sink
0:00:03.871489647 15760  0x9ab0490 INFO              GST_PADS gstutils.c:1335:prepare_link_maybe_ghosting: source and decodebin0 in same bin, no need for ghost pads
0:00:03.871512787 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link source:src and decodebin0:sink
0:00:03.871532089 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked source:src and decodebin0:sink, successful
0:00:03.871569581 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2123:gst_element_continue_state:<test> committing state from NULL to READY, pending PAUSED
0:00:03.871596612 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2132:gst_element_continue_state:<test> continue state change READY to PAUSED, final PAUSED
0:00:03.871629028 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2361:gst_bin_change_state_func:<play> child 'test' is changing state asynchronously to PAUSED
0:00:03.871654856 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<fakesink> completed state change to READY
0:00:03.871669805 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<fakesink> posting state-changed NULL to READY
0:00:03.871693847 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<decodebin0> child 'fakesink' changed state to 2(READY) successfully
0:00:03.871713147 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<typefind> completed state change to READY
0:00:03.871726875 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<typefind> posting state-changed NULL to READY
0:00:03.871749760 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<decodebin0> child 'typefind' changed state to 2(READY) successfully
0:00:03.871767733 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2123:gst_element_continue_state:<decodebin0> committing state from NULL to READY, pending PAUSED
0:00:03.871790768 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2132:gst_element_continue_state:<decodebin0> continue state change READY to PAUSED, final PAUSED
0:00:03.871832346 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2361:gst_bin_change_state_func:<decodebin0> child 'fakesink' is changing state asynchronously to PAUSED
0:00:03.871863952 15760  0x9ab0490 INFO               filesrc gstfilesrc.c:975:gst_file_src_start:<source> opening file /media/Archiv/Video/Filme/Break/Break.avi
0:00:03.871901579 15760  0x9ab0490 INFO               filesrc gstfilesrc.c:975:gst_file_src_start:<source> opening file /media/Archiv/Video/Filme/Break/Break.avi
0:00:03.872348596 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-ms-asf
0:00:03.872391170 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-musepack
0:00:03.872423964 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-au
0:00:03.872454937 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-msvideo
0:00:03.872484833 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/qcelp
0:00:03.872514284 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-cdxa
0:00:03.872543916 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-vcd
0:00:03.872573224 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-imelody
0:00:03.872603710 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/midi
0:00:03.872633714 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-fli
0:00:03.872663832 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-id3v2
0:00:03.872693140 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-id3v1
0:00:03.872723501 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-apetag
0:00:03.872756945 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-ttafile
0:00:03.872796192 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-mod
0:00:03.872833636 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/mpeg
0:00:03.872864957 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-ac3
0:00:03.872904063 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/mpeg-sys
0:00:03.872939143 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/mpegts
0:00:03.872969702 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/ogg
0:00:03.873002472 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/mpeg-elementary
0:00:03.873031740 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/mpeg4
0:00:03.873060202 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-h264
0:00:03.873094235 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-nuv
0:00:03.873130825 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-m4a
0:00:03.873161615 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-3gp
0:00:03.873191489 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/quicktime
0:00:03.873232203 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/x-quicktime
0:00:03.873268987 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/jp2
0:00:03.873299528 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/mj2
0:00:03.873329196 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for text/html
0:00:03.873359227 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/vnd.rn-realmedia
0:00:03.873392057 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-pn-realaudio
0:00:03.873422819 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-shockwave-flash
0:00:03.873452340 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-flv
0:00:03.873482104 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for text/plain
0:00:03.873511892 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for text/uri-list
0:00:03.873541610 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/sdp
0:00:03.873571149 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/smil
0:00:03.873601051 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/xml
0:00:03.873630137 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-wav
0:00:03.873659789 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-aiff
0:00:03.873695685 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-svx
0:00:03.873726223 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-paris
0:00:03.873755873 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-nist
0:00:03.873788201 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-voc
0:00:03.873817845 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-sds
0:00:03.873847528 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-ircam
0:00:03.873883232 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-w64
0:00:03.873913437 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-shorten
0:00:03.873943074 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-ape
0:00:03.873972715 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/jpeg
0:00:03.874002614 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/gif
0:00:03.874031769 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/png
0:00:03.874082023 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/bmp
0:00:03.874116212 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/tiff
0:00:03.874148414 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-matroska
0:00:03.874178586 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/mxf
0:00:03.874723385 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-mve
0:00:03.874758198 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-dv
0:00:03.874789549 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-amr-nb-sh
0:00:03.874819645 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-amr-wb-sh
0:00:03.874854413 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/iLBC-sh
0:00:03.874883988 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-sid
0:00:03.874913380 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/x-xcf
0:00:03.874942634 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-mng
0:00:03.874971923 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/x-jng
0:00:03.875001282 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/x-xpixmap
0:00:03.875030899 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/x-sun-raster
0:00:03.875060420 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-bzip
0:00:03.875099853 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-gzip
0:00:03.875133315 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/zip
0:00:03.875163367 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-compress
0:00:03.875195792 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-kate
0:00:03.875225994 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-flac
0:00:03.875255592 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-vorbis
0:00:03.875284831 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-theora
0:00:03.875314010 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-ogm-video
0:00:03.875348451 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-ogm-audio
0:00:03.875378269 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-ogm-text
0:00:03.875407675 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-speex
0:00:03.875450267 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-celt
0:00:03.875482566 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-ogg-skeleton
0:00:03.875512543 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for text/x-cmml
0:00:03.875547890 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-executable
0:00:03.875582934 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for adts_mpeg_stream
0:00:03.875613681 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-spc
0:00:03.875645464 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-wavpack
0:00:03.875677847 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-wavpack-correction
0:00:03.875707569 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-rar
0:00:03.875736979 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-tar
0:00:03.875766679 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-ar
0:00:03.875796671 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-ms-dos-executable
0:00:03.875827488 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-dirac
0:00:03.875856931 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for multipart/x-mixed-replace
0:00:03.875890286 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-mmsh
0:00:03.875920199 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/vivo
0:00:03.875949618 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-nsf
0:00:03.875984977 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/pdf
0:00:03.876018143 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/msword
0:00:03.876047786 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/octet-stream
0:00:03.876076944 15760  0x9ab0490 INFO    GST_PLUGIN_LOADING gstplugin.c:570:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgsttypefindfunctions.so" loaded
0:00:03.880601222 15760  0x9ab0490 INFO               default gstbluetooth.c:74:plugin_init: Bluetooth plugin 4.32
0:00:03.880712933 15760  0x9ab0490 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for sbc
0:00:03.881691075 15760  0x9ab0490 INFO    GST_PLUGIN_LOADING gstplugin.c:570:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstbluetooth.so" loaded
0:00:03.882352991 15760  0x9ab0490 INFO              typefind gsttypefindelement.c:165:gst_type_find_element_have_type:<typefind> found caps video/x-msvideo
0:00:03.882393876 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad typefind:src
0:00:03.887354373 15760  0x9ab0490 INFO    GST_PLUGIN_LOADING gstplugin.c:570:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstavi.so" loaded
0:00:03.887399444 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "avidemux"
0:00:03.887458134 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstAviDemux@0xa72b270> adding pad 'sink'
0:00:03.887495962 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad avidemux0:sink
0:00:03.887527970 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<avidemux0> completed state change to READY
0:00:03.887543909 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<avidemux0> posting state-changed NULL to READY
0:00:03.887576838 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link typefind:src and avidemux0:sink
0:00:03.887598307 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked typefind:src and avidemux0:sink, successful
0:00:03.887628718 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:882:gst_element_get_static_pad: no such pad 'audio_%02d' in element "avidemux0"
0:00:03.887644112 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:882:gst_element_get_static_pad: no such pad 'video_%02d' in element "avidemux0"
0:00:03.887656706 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:882:gst_element_get_static_pad: no such pad 'subtitle_%02d' in element "avidemux0"
0:00:03.887683458 15760  0x9ab0490 INFO               filesrc gstfilesrc.c:975:gst_file_src_start:<source> opening file /media/Archiv/Video/Filme/Break/Break.avi
0:00:03.887715942 15760  0x9ab0490 INFO               filesrc gstfilesrc.c:975:gst_file_src_start:<source> opening file /media/Archiv/Video/Filme/Break/Break.avi
0:00:03.887790893 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<avidemux0> completed state change to PAUSED
0:00:03.887809917 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<avidemux0> posting state-changed READY to PAUSED
0:00:03.887843077 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<typefind> completed state change to PAUSED
0:00:03.887859418 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<typefind> posting state-changed READY to PAUSED
0:00:03.887885780 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<decodebin0> child 'typefind' changed state to 3(PAUSED) successfully
0:00:03.887902655 15760  0x9aec520 INFO              avidemux gstavidemux.c:841:gst_avi_demux_parse_avih:<avidemux0> avih tag found:
0:00:03.887913569 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<avidemux0> completed state change to PAUSED
0:00:03.887946609 15760  0x9aec520 INFO              avidemux gstavidemux.c:842:gst_avi_demux_parse_avih:<avidemux0>  us_frame    40000
0:00:03.887961595 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<decodebin0> child 'avidemux0' changed state to 3(PAUSED) successfully
0:00:03.887974858 15760  0x9aec520 INFO              avidemux gstavidemux.c:843:gst_avi_demux_parse_avih:<avidemux0>  max_bps     0
0:00:03.887992925 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<typefind> completed state change to PAUSED
0:00:03.888001610 15760  0x9aec520 INFO              avidemux gstavidemux.c:844:gst_avi_demux_parse_avih:<avidemux0>  pad_gran    0
0:00:03.888021260 15760  0x9aec520 INFO              avidemux gstavidemux.c:845:gst_avi_demux_parse_avih:<avidemux0>  flags       0x00000110
0:00:03.888024512 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<decodebin0> child 'typefind' changed state to 3(PAUSED) successfully
0:00:03.888038268 15760  0x9aec520 INFO              avidemux gstavidemux.c:846:gst_avi_demux_parse_avih:<avidemux0>  tot_frames  133502
0:00:03.888054447 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2361:gst_bin_change_state_func:<decodebin0> child 'fakesink' is changing state asynchronously to PAUSED
0:00:03.888085189 15760  0x9aec520 INFO              avidemux gstavidemux.c:847:gst_avi_demux_parse_avih:<avidemux0>  init_frames 0
0:00:03.888105691 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2361:gst_bin_change_state_func:<play> child 'decodebin0' is changing state asynchronously to PAUSED
0:00:03.888111046 15760  0x9aec520 INFO              avidemux gstavidemux.c:848:gst_avi_demux_parse_avih:<avidemux0>  streams     2
0:00:03.888130148 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2123:gst_element_continue_state:<source> committing state from NULL to READY, pending PAUSED
0:00:03.888154043 15760  0x9aec520 INFO              avidemux gstavidemux.c:849:gst_avi_demux_parse_avih:<avidemux0>  bufsize     0
0:00:03.888161823 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2132:gst_element_continue_state:<source> continue state change READY to PAUSED, final PAUSED
0:00:03.888186510 15760  0x9aec520 INFO              avidemux gstavidemux.c:850:gst_avi_demux_parse_avih:<avidemux0>  width       592
0:00:03.888187887 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<source> completed state change to PAUSED
0:00:03.888205972 15760  0x9aec520 INFO              avidemux gstavidemux.c:851:gst_avi_demux_parse_avih:<avidemux0>  height      320
0:00:03.888220036 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<source> posting state-changed READY to PAUSED
0:00:03.888233743 15760  0x9aec520 INFO              avidemux gstavidemux.c:852:gst_avi_demux_parse_avih:<avidemux0>  scale       0
0:00:03.888257189 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<play> child 'source' changed state to 3(PAUSED) successfully
0:00:03.888258644 15760  0x9aec520 INFO              avidemux gstavidemux.c:853:gst_avi_demux_parse_avih:<avidemux0>  rate        0
0:00:03.888290748 15760  0x9aec520 INFO              avidemux gstavidemux.c:854:gst_avi_demux_parse_avih:<avidemux0>  start       0
0:00:03.888303768 15760  0x9aec520 INFO              avidemux gstavidemux.c:855:gst_avi_demux_parse_avih:<avidemux0>  length      0
0:00:03.888329791 15760  0x9aec520 INFO                  riff riff-read.c:298:gst_riff_parse_strh:<avidemux0> strh tag found:
0:00:03.888347182 15760  0x9aec520 INFO                  riff riff-read.c:300:gst_riff_parse_strh:<avidemux0>  type        vids
0:00:03.888360758 15760  0x9aec520 INFO                  riff riff-read.c:302:gst_riff_parse_strh:<avidemux0>  fcc_handler xvid
0:00:03.888373847 15760  0x9aec520 INFO                  riff riff-read.c:303:gst_riff_parse_strh:<avidemux0>  flags       0x00000000
0:00:03.888386963 15760  0x9aec520 INFO                  riff riff-read.c:304:gst_riff_parse_strh:<avidemux0>  priority    0
0:00:03.888399942 15760  0x9aec520 INFO                  riff riff-read.c:305:gst_riff_parse_strh:<avidemux0>  init_frames 0
0:00:03.888412821 15760  0x9aec520 INFO                  riff riff-read.c:306:gst_riff_parse_strh:<avidemux0>  scale       1
0:00:03.888425553 15760  0x9aec520 INFO                  riff riff-read.c:307:gst_riff_parse_strh:<avidemux0>  rate        25
0:00:03.888438397 15760  0x9aec520 INFO                  riff riff-read.c:308:gst_riff_parse_strh:<avidemux0>  start       0
0:00:03.888451078 15760  0x9aec520 INFO                  riff riff-read.c:309:gst_riff_parse_strh:<avidemux0>  length      133502
0:00:03.888464092 15760  0x9aec520 INFO                  riff riff-read.c:310:gst_riff_parse_strh:<avidemux0>  bufsize     89047
0:00:03.888476921 15760  0x9aec520 INFO                  riff riff-read.c:311:gst_riff_parse_strh:<avidemux0>  quality     10000
0:00:03.888489737 15760  0x9aec520 INFO                  riff riff-read.c:312:gst_riff_parse_strh:<avidemux0>  samplesize  0
0:00:03.888519966 15760  0x9aec520 INFO                  riff riff-read.c:389:gst_riff_parse_strf_vids:<avidemux0> strf tag found in context vids:
0:00:03.888534129 15760  0x9aec520 INFO                  riff riff-read.c:390:gst_riff_parse_strf_vids:<avidemux0>  size        40
0:00:03.888546933 15760  0x9aec520 INFO                  riff riff-read.c:391:gst_riff_parse_strf_vids:<avidemux0>  width       592
0:00:03.888559771 15760  0x9aec520 INFO                  riff riff-read.c:392:gst_riff_parse_strf_vids:<avidemux0>  height      320
0:00:03.888572311 15760  0x9aec520 INFO                  riff riff-read.c:393:gst_riff_parse_strf_vids:<avidemux0>  planes      1
0:00:03.888584857 15760  0x9aec520 INFO                  riff riff-read.c:394:gst_riff_parse_strf_vids:<avidemux0>  bit_cnt     24
0:00:03.888597434 15760  0x9aec520 INFO                  riff riff-read.c:396:gst_riff_parse_strf_vids:<avidemux0>  compression XVID
0:00:03.888610130 15760  0x9aec520 INFO                  riff riff-read.c:397:gst_riff_parse_strf_vids:<avidemux0>  image_size  1136640
0:00:03.888623015 15760  0x9aec520 INFO                  riff riff-read.c:398:gst_riff_parse_strf_vids:<avidemux0>  xpels_meter 0
0:00:03.888635643 15760  0x9aec520 INFO                  riff riff-read.c:399:gst_riff_parse_strf_vids:<avidemux0>  ypels_meter 0
0:00:03.888648255 15760  0x9aec520 INFO                  riff riff-read.c:400:gst_riff_parse_strf_vids:<avidemux0>  num_colors  0
0:00:03.888660771 15760  0x9aec520 INFO                  riff riff-read.c:401:gst_riff_parse_strf_vids:<avidemux0>  imp_colors  0
0:00:03.888755055 15760  0x9aec520 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<avidemux0> adding pad 'video_00'
0:00:03.889155773 15760  0x9aec520 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "queue"
0:00:03.889226878 15760  0x9aec520 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstQueue@0xb4933080> adding pad 'sink'
0:00:03.889279796 15760  0x9aec520 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstQueue@0xb4933080> adding pad 'src'
0:00:03.889342740 15760  0x9aec520 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<queue0> completed state change to READY
0:00:03.889359937 15760  0x9aec520 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<queue0> posting state-changed NULL to READY
0:00:03.889393643 15760  0x9aec520 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad queue0:sink
0:00:03.889409288 15760  0x9aec520 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad queue0:src
0:00:03.889431525 15760  0x9aec520 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link avidemux0:video_00 and queue0:sink
0:00:03.889543185 15760  0x9aec520 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked avidemux0:video_00 and queue0:sink, successful
0:00:03.890167155 15760  0x9ab0490 INFO            GST_STATES gstbin.c:1659:gst_bin_get_state_func:<play> getting state
0:00:03.909906419 15760  0x9aec520 WARN                ffmpeg gstffmpegenc.c:1027:gst_ffmpegenc_register: Couldn't get source caps for encoder jpegls
0:00:03.922047219 15760  0x9aec520 WARN                ffmpeg gstffmpegenc.c:1027:gst_ffmpegenc_register: Couldn't get source caps for encoder sgi
0:00:03.923345095 15760  0x9aec520 WARN                ffmpeg gstffmpegenc.c:1027:gst_ffmpegenc_register: Couldn't get source caps for encoder targa
0:00:03.923363860 15760  0x9aec520 WARN                ffmpeg gstffmpegenc.c:1027:gst_ffmpegenc_register: Couldn't get source caps for encoder tiff
0:00:03.926185259 15760  0x9aec520 WARN                ffmpeg gstffmpegenc.c:1027:gst_ffmpegenc_register: Couldn't get source caps for encoder pcm_f32be
0:00:03.926204105 15760  0x9aec520 WARN                ffmpeg gstffmpegenc.c:1027:gst_ffmpegenc_register: Couldn't get source caps for encoder pcm_f32le
0:00:03.926217633 15760  0x9aec520 WARN                ffmpeg gstffmpegenc.c:1027:gst_ffmpegenc_register: Couldn't get source caps for encoder pcm_f64be
0:00:03.926246364 15760  0x9aec520 WARN                ffmpeg gstffmpegenc.c:1027:gst_ffmpegenc_register: Couldn't get source caps for encoder pcm_f64le
0:00:03.926259840 15760  0x9aec520 WARN                ffmpeg gstffmpegenc.c:1027:gst_ffmpegenc_register: Couldn't get source caps for encoder pcm_zork
0:00:03.927117244 15760  0x9aec520 WARN                ffmpeg gstffmpegenc.c:1027:gst_ffmpegenc_register: Couldn't get source caps for encoder dvbsub
0:00:03.927135293 15760  0x9aec520 WARN                ffmpeg gstffmpegenc.c:1027:gst_ffmpegenc_register: Couldn't get source caps for encoder dvdsub
0:00:03.927260540 15760  0x9aec520 WARN                ffmpeg gstffmpegenc.c:1027:gst_ffmpegenc_register: Couldn't get source caps for encoder libgsm
0:00:03.927277388 15760  0x9aec520 WARN                ffmpeg gstffmpegenc.c:1027:gst_ffmpegenc_register: Couldn't get source caps for encoder libgsm_ms
0:00:03.927399884 15760  0x9aec520 WARN                ffmpeg gstffmpegenc.c:1027:gst_ffmpegenc_register: Couldn't get source caps for encoder libschroedinger
0:00:03.929166356 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'bethsoftvid'
0:00:03.929184209 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'bfi'
0:00:03.929319045 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'c93'
0:00:03.929950720 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'dsicinvideo'
0:00:03.930077296 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'dxa'
0:00:03.930094657 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'eacmv'
0:00:03.930108254 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'eatgq'
0:00:03.930121062 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'eatgv'
0:00:03.930133413 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'eatqi'
0:00:03.930255250 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder '8svx_exp'
0:00:03.930271681 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder '8svx_fib'
0:00:03.930284716 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'escape124'
0:00:03.932095463 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'jpegls'
0:00:03.932932553 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'motionpixels'
0:00:03.932951106 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'mpegvideo_xvmc'
0:00:03.934502734 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'pcx'
0:00:03.934939503 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'ptx'
0:00:03.935290275 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'rl2'
0:00:03.935968724 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'sgi'
0:00:03.935986523 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'smackvid'
0:00:03.936332663 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'sunrast'
0:00:03.936568670 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'targa'
0:00:03.936695909 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'thp'
0:00:03.936712776 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'tiertexseqvideo'
0:00:03.936726564 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'tiff'
0:00:03.937074288 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'txd'
0:00:03.937203876 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'vb'
0:00:03.940961676 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'dsicinaudio'
0:00:03.942765200 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'smackaud'
0:00:03.943527954 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'pcm_dvd'
0:00:03.943541232 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'pcm_f32be'
0:00:03.943550733 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'pcm_f32le'
0:00:03.943559611 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'pcm_f64be'
0:00:03.943568500 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'pcm_f64le'
0:00:03.943577125 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'pcm_s16le_planar'
0:00:03.943586260 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'pcm_zork'
0:00:03.946791362 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'dvbsub'
0:00:03.946804461 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'dvdsub'
0:00:03.946814214 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'xsub'
0:00:03.946823733 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'libgsm'
0:00:03.946832469 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'libgsm_ms'
0:00:03.946841635 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'libschroedinger'
0:00:03.946850770 15760  0x9aec520 WARN                ffmpeg gstffmpegdec.c:2585:gst_ffmpegdec_register: Couldn't get sink caps for decoder 'libspeex'
0:00:03.947275986 15760  0x9aec520 INFO    GST_PLUGIN_LOADING gstplugin.c:570:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstffmpeg.so" loaded
0:00:03.947310267 15760  0x9aec520 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "ffdec_mpeg4"
0:00:03.947343148 15760  0x9aec520 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<ffdec_mpeg4@0xa73d090> adding pad 'sink'
0:00:03.947369069 15760  0x9aec520 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<ffdec_mpeg4@0xa73d090> adding pad 'src'
0:00:03.947567425 15760  0x9aec520 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad ffdec_mpeg40:sink
0:00:03.947597609 15760  0x9aec520 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<ffdec_mpeg40> completed state change to READY
0:00:03.947609120 15760  0x9aec520 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<ffdec_mpeg40> posting state-changed NULL to READY
0:00:03.947648430 15760  0x9aec520 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link queue0:src and ffdec_mpeg40:sink
0:00:03.947738651 15760  0x9aec520 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked queue0:src and ffdec_mpeg40:sink, successful
0:00:03.947765304 15760  0x9aec520 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad ffdec_mpeg40:src
0:00:03.947834297 15760  0x9aec520 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link ffdec_mpeg40:src and src0:proxypad8
0:00:03.947854757 15760  0x9aec520 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked ffdec_mpeg40:src and src0:proxypad8, successful
0:00:03.947875818 15760  0x9aec520 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<decodebin0> adding pad 'src0'
0:00:03.947925745 15760  0x9aec520 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstStreamSelector@0x9f4ae78> adding pad 'src'
0:00:03.947962646 15760  0x9aec520 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "queue" named "preroll_video_src0"
0:00:03.948003705 15760  0x9aec520 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstQueue@0xb4933220> adding pad 'sink'
0:00:03.948040000 15760  0x9aec520 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstQueue@0xb4933220> adding pad 'src'
0:00:03.948068303 15760  0x9aec520 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad preroll_video_src0:src
0:00:03.948088523 15760  0x9aec520 INFO      GST_ELEMENT_PADS gstutils.c:1430:gst_element_link_pads: trying to link element selector_video_src0:(any) to element preroll_video_src0:(any)
0:00:03.948101019 15760  0x9aec520 INFO              GST_PADS gstutils.c:2125:gst_pad_can_link: trying to link selector_video_src0:src and preroll_video_src0:src
0:00:03.948110742 15760  0x9aec520 INFO              GST_PADS gstutils.c:2145:gst_pad_can_link: Sink pad preroll_video_src0:src is not sink pad, failed
0:00:03.948120099 15760  0x9aec520 INFO              GST_PADS gstutils.c:2125:gst_pad_can_link: trying to link selector_video_src0:src and preroll_video_src0:sink
0:00:03.948132606 15760  0x9aec520 INFO              GST_PADS gstutils.c:1335:prepare_link_maybe_ghosting: selector_video_src0 and preroll_video_src0 in same bin, no need for ghost pads
0:00:03.948148510 15760  0x9aec520 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link selector_video_src0:src and preroll_video_src0:sink
0:00:03.948161644 15760  0x9aec520 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked selector_video_src0:src and preroll_video_src0:sink, successful
0:00:03.948181927 15760  0x9aec520 INFO            GST_STATES gstelement.c:2123:gst_element_continue_state:<selector_video_src0> committing state from NULL to READY, pending PAUSED
0:00:03.948200426 15760  0x9aec520 INFO            GST_STATES gstelement.c:2132:gst_element_continue_state:<selector_video_src0> continue state change READY to PAUSED, final PAUSED
0:00:03.948213908 15760  0x9aec520 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<selector_video_src0> completed state change to PAUSED
0:00:03.948223722 15760  0x9aec520 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<selector_video_src0> posting state-changed READY to PAUSED
0:00:03.948240861 15760  0x9aec520 INFO            GST_STATES gstelement.c:2123:gst_element_continue_state:<preroll_video_src0> committing state from NULL to READY, pending PAUSED
0:00:03.948267508 15760  0x9aec520 INFO            GST_STATES gstelement.c:2132:gst_element_continue_state:<preroll_video_src0> continue state change READY to PAUSED, final PAUSED
0:00:03.948280423 15760  0x9aec520 INFO                 queue gstqueue.c:1302:gst_queue_src_activate_push:<preroll_video_src0> not starting task as pad is not linked
0:00:03.948292879 15760  0x9aec520 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<preroll_video_src0> completed state change to PAUSED
0:00:03.948302546 15760  0x9aec520 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<preroll_video_src0> posting state-changed READY to PAUSED
0:00:03.948351327 15760  0x9aec520 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<selector_video_src0> adding pad 'sink0'
0:00:03.948373260 15760  0x9aec520 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link decodebin0:src0 and selector_video_src0:sink0
0:00:03.948392290 15760  0x9aec520 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked decodebin0:src0 and selector_video_src0:sink0, successful
0:00:03.948421837 15760  0x9aec520 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<ffdec_mpeg40> completed state change to PAUSED
0:00:03.948433214 15760  0x9aec520 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<ffdec_mpeg40> posting state-changed READY to PAUSED
0:00:03.948594768 15760  0x9aec520 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<queue0> completed state change to PAUSED
0:00:03.948607248 15760  0x9aec520 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<queue0> posting state-changed READY to PAUSED
0:00:03.948644431 15760  0x9aec520 INFO                  riff riff-read.c:298:gst_riff_parse_strh:<avidemux0> strh tag found:
0:00:03.948655789 15760  0x9aec520 INFO                  riff riff-read.c:300:gst_riff_parse_strh:<avidemux0>  type        auds
0:00:03.948665258 15760  0x9aec520 INFO                  riff riff-read.c:302:gst_riff_parse_strh:<avidemux0>  fcc_handler 
0:00:03.948674471 15760  0x9aec520 INFO                  riff riff-read.c:303:gst_riff_parse_strh:<avidemux0>  flags       0x00000000
0:00:03.948683900 15760  0x9aec520 INFO                  riff riff-read.c:304:gst_riff_parse_strh:<avidemux0>  priority    0
0:00:03.948692930 15760  0x9aec520 INFO                  riff riff-read.c:305:gst_riff_parse_strh:<avidemux0>  init_frames 1
0:00:03.948701946 15760  0x9aec520 INFO                  riff riff-read.c:306:gst_riff_parse_strh:<avidemux0>  scale       1152
0:00:03.948710910 15760  0x9aec520 INFO                  riff riff-read.c:307:gst_riff_parse_strh:<avidemux0>  rate        48000
0:00:03.948719892 15760  0x9aec520 INFO                  riff riff-read.c:308:gst_riff_parse_strh:<avidemux0>  start       0
0:00:03.948728805 15760  0x9aec520 INFO                  riff riff-read.c:309:gst_riff_parse_strh:<avidemux0>  length      222491
0:00:03.948737805 15760  0x9aec520 INFO                  riff riff-read.c:310:gst_riff_parse_strh:<avidemux0>  bufsize     960
0:00:03.948746740 15760  0x9aec520 INFO                  riff riff-read.c:311:gst_riff_parse_strh:<avidemux0>  quality     -1
0:00:03.948755632 15760  0x9aec520 INFO                  riff riff-read.c:312:gst_riff_parse_strh:<avidemux0>  samplesize  0
0:00:03.948766714 15760  0x9aec520 INFO                  riff riff-read.c:483:gst_riff_parse_strf_auds:<avidemux0> strf tag found in context auds:
0:00:03.948776300 15760  0x9aec520 INFO                  riff riff-read.c:484:gst_riff_parse_strf_auds:<avidemux0>  format      85
0:00:03.948785204 15760  0x9aec520 INFO                  riff riff-read.c:485:gst_riff_parse_strf_auds:<avidemux0>  channels    2
0:00:03.948794045 15760  0x9aec520 INFO                  riff riff-read.c:486:gst_riff_parse_strf_auds:<avidemux0>  rate        48000
0:00:03.948802964 15760  0x9aec520 INFO                  riff riff-read.c:487:gst_riff_parse_strf_auds:<avidemux0>  av_bps      16460
0:00:03.948820530 15760  0x9aec520 INFO                  riff riff-read.c:488:gst_riff_parse_strf_auds:<avidemux0>  blockalign  1152
0:00:03.948829932 15760  0x9aec520 INFO                  riff riff-read.c:489:gst_riff_parse_strf_auds:<avidemux0>  size        0
0:00:03.948838782 15760  0x9aec520 INFO                  riff riff-read.c:491:gst_riff_parse_strf_auds:<avidemux0>  12 bytes extradata
0:00:03.948880114 15760  0x9aec520 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<avidemux0> adding pad 'audio_00'
0:00:03.949079556 15760  0x9aec520 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "queue"
0:00:03.949120519 15760  0x9aec520 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstQueue@0xb49333c0> adding pad 'sink'
0:00:03.949156637 15760  0x9aec520 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstQueue@0xb49333c0> adding pad 'src'
0:00:03.949191933 15760  0x9aec520 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<queue2> completed state change to READY
0:00:03.949203474 15760  0x9aec520 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<queue2> posting state-changed NULL to READY
0:00:03.949220566 15760  0x9aec520 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad queue2:sink
0:00:03.949231414 15760  0x9aec520 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad queue2:src
0:00:03.949245433 15760  0x9aec520 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link avidemux0:audio_00 and queue2:sink
0:00:03.949266227 15760  0x9aec520 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked avidemux0:audio_00 and queue2:sink, successful
0:00:03.949762539 15760  0x9aec520 INFO    GST_PLUGIN_LOADING gstplugin.c:570:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstmpegaudioparse.so" loaded
0:00:03.949782006 15760  0x9aec520 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "mp3parse"
0:00:03.949825543 15760  0x9aec520 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstMPEGAudioParse@0xa814c08> adding pad 'sink'
0:00:03.949865063 15760  0x9aec520 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstMPEGAudioParse@0xa814c08> adding pad 'src'
0:00:03.949886610 15760  0x9aec520 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad mpegaudioparse0:sink
0:00:03.949907577 15760  0x9aec520 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<mpegaudioparse0> completed state change to READY
0:00:03.949918755 15760  0x9aec520 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<mpegaudioparse0> posting state-changed NULL to READY
0:00:03.949939876 15760  0x9aec520 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link queue2:src and mpegaudioparse0:sink
0:00:03.949965646 15760  0x9aec520 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked queue2:src and mpegaudioparse0:sink, successful
0:00:03.949989218 15760  0x9aec520 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad mpegaudioparse0:src
0:00:03.950015604 15760  0x9aec520 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<mpegaudioparse0> completed state change to PAUSED
0:00:03.950026680 15760  0x9aec520 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<mpegaudioparse0> posting state-changed READY to PAUSED
0:00:03.950085992 15760  0x9aec520 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<queue2> completed state change to PAUSED
0:00:03.950097800 15760  0x9aec520 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<queue2> posting state-changed READY to PAUSED
0:00:03.950122167 15760  0x9aec520 INFO              avidemux gstavidemux.c:1712:gst_avi_demux_parse_odml:<avidemux0> dmlh tag found:
0:00:03.950133373 15760  0x9aec520 INFO              avidemux gstavidemux.c:1713:gst_avi_demux_parse_odml:<avidemux0>  totalframes: 133502
0:00:03.955069209 15760  0x9aec520 INFO              avidemux gstavidemux.c:1992:gst_avi_demux_stream_index: will parse index chunk size 5695888 for tag idx1
0:00:03.955193317 15760  0x9aec520 INFO              avidemux gstavidemux.c:1791:gst_avi_demux_parse_index: Parsing index, nr_entries = 355993
0:00:04.026840464 15760  0x9aec520 INFO              avidemux gstavidemux.c:1906:gst_avi_demux_parse_index: Parsed index, 355993 entries, 224165 keyframes, entry size = 48, total size =   17087664
0:00:04.115306134 15760  0x9aec520 INFO              avidemux gstavidemux.c:2683:gst_avi_demux_calculate_durations_from_index: Stream 0 duration according to header: 1:29:00.080000000
0:00:04.115445770 15760  0x9aec520 INFO              avidemux gstavidemux.c:2703:gst_avi_demux_calculate_durations_from_index: Stream 0 duration according to index: 1:29:00.080000000
0:00:04.115458445 15760  0x9aec520 INFO              avidemux gstavidemux.c:2683:gst_avi_demux_calculate_durations_from_index: Stream 1 duration according to header: 1:28:59.784000000
0:00:04.115468066 15760  0x9aec520 INFO              avidemux gstavidemux.c:2703:gst_avi_demux_calculate_durations_from_index: Stream 1 duration according to index: 1:28:59.784000000
0:00:04.115477492 15760  0x9aec520 INFO              avidemux gstavidemux.c:2733:gst_avi_demux_calculate_durations_from_index: Setting total duration to: 1:29:00.080000000
0:00:04.115494443 15760  0x9aec520 INFO             GST_EVENT gstevent.c:619:gst_event_new_new_segment_full: creating newsegment update 0, rate 1,000000, format GST_FORMAT_TIME, start 0:00:00.000000000, stop 1:29:00.080000000, position 0:00:00.000000000
0:00:04.115694698 15760  0xa740b60 INFO            GST_STATES gstbin.c:2738:bin_handle_async_done:<decodebin0> committing state from READY to PAUSED, old pending PAUSED
0:00:04.115720951 15760  0xa740b60 INFO            GST_STATES gstbin.c:2753:bin_handle_async_done:<decodebin0> completed state change, pending VOID
0:00:04.115745717 15760  0xa740b60 INFO            GST_STATES gstelement.c:2123:gst_element_continue_state:<fakesink> committing state from READY to READY, pending NULL
0:00:04.115765428 15760  0xa740b60 INFO            GST_STATES gstelement.c:2132:gst_element_continue_state:<fakesink> continue state change READY to NULL, final NULL
0:00:04.115780041 15760  0xa740b60 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<fakesink> completed state change to NULL
0:00:04.115790193 15760  0xa740b60 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<fakesink> posting state-changed READY to NULL
0:00:04.115811098 15760  0xa740b60 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<decodebin0> removed child "fakesink"
0:00:04.116290255 15760  0xa740b60 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<fakesink> dispose
0:00:04.116304055 15760  0xa740b60 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<fakesink> removing pad 'sink'
0:00:04.116320907 15760  0xa740b60 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<fakesink> parent class dispose
0:00:04.116332943 15760  0xa740b60 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<fakesink> finalize
0:00:04.116343026 15760  0xa740b60 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<fakesink> finalize parent
0:00:04.127682866 15760  0xa8159f8 INFO    GST_PLUGIN_LOADING gstplugin.c:570:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstmad.so" loaded
0:00:04.127806808 15760  0xa8159f8 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "mad"
0:00:04.127872987 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstMad@0xb59bab0> adding pad 'sink'
0:00:04.127914985 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstMad@0xb59bab0> adding pad 'src'
0:00:04.127939768 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad mad0:sink
0:00:04.127983380 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<mad0> completed state change to READY
0:00:04.127995123 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<mad0> posting state-changed NULL to READY
0:00:04.128036884 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link mpegaudioparse0:src and mad0:sink
0:00:04.128063176 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked mpegaudioparse0:src and mad0:sink, successful
0:00:04.128087813 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad mad0:src
0:00:04.128180014 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link mad0:src and src1:proxypad9
0:00:04.128216114 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked mad0:src and src1:proxypad9, successful
0:00:04.128237547 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<decodebin0> adding pad 'src1'
0:00:04.128301220 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstStreamSelector@0xa740578> adding pad 'src'
0:00:04.128358189 15760  0xa8159f8 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "queue" named "preroll_audio_src1"
0:00:04.128400139 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstQueue@0xb4933700> adding pad 'sink'
0:00:04.128436389 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstQueue@0xb4933700> adding pad 'src'
0:00:04.128464502 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad preroll_audio_src1:src
0:00:04.128486043 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstutils.c:1430:gst_element_link_pads: trying to link element selector_audio_src1:(any) to element preroll_audio_src1:(any)
0:00:04.128498773 15760  0xa8159f8 INFO              GST_PADS gstutils.c:2125:gst_pad_can_link: trying to link selector_audio_src1:src and preroll_audio_src1:src
0:00:04.128508454 15760  0xa8159f8 INFO              GST_PADS gstutils.c:2145:gst_pad_can_link: Sink pad preroll_audio_src1:src is not sink pad, failed
0:00:04.128517625 15760  0xa8159f8 INFO              GST_PADS gstutils.c:2125:gst_pad_can_link: trying to link selector_audio_src1:src and preroll_audio_src1:sink
0:00:04.128530783 15760  0xa8159f8 INFO              GST_PADS gstutils.c:1335:prepare_link_maybe_ghosting: selector_audio_src1 and preroll_audio_src1 in same bin, no need for ghost pads
0:00:04.128546789 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link selector_audio_src1:src and preroll_audio_src1:sink
0:00:04.128560352 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked selector_audio_src1:src and preroll_audio_src1:sink, successful
0:00:04.128581155 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2123:gst_element_continue_state:<selector_audio_src1> committing state from NULL to READY, pending PAUSED
0:00:04.128604501 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2132:gst_element_continue_state:<selector_audio_src1> continue state change READY to PAUSED, final PAUSED
0:00:04.128618752 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<selector_audio_src1> completed state change to PAUSED
0:00:04.128630515 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<selector_audio_src1> posting state-changed READY to PAUSED
0:00:04.128652742 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2123:gst_element_continue_state:<preroll_audio_src1> committing state from NULL to READY, pending PAUSED
0:00:04.128677064 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2132:gst_element_continue_state:<preroll_audio_src1> continue state change READY to PAUSED, final PAUSED
0:00:04.128699552 15760  0xa8159f8 INFO                 queue gstqueue.c:1302:gst_queue_src_activate_push:<preroll_audio_src1> not starting task as pad is not linked
0:00:04.128712885 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<preroll_audio_src1> completed state change to PAUSED
0:00:04.128722551 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<preroll_audio_src1> posting state-changed READY to PAUSED
0:00:04.128764939 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<selector_audio_src1> adding pad 'sink0'
0:00:04.128789777 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link decodebin0:src1 and selector_audio_src1:sink0
0:00:04.128835780 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked decodebin0:src1 and selector_audio_src1:sink0, successful
0:00:04.128870968 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<mad0> completed state change to PAUSED
0:00:04.128882359 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<mad0> posting state-changed READY to PAUSED
0:00:04.128913089 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad preroll_audio_src1:sink
0:00:04.128926826 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad preroll_video_src0:sink
0:00:04.128947749 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad preroll_audio_src1:src
0:00:04.128958363 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad preroll_audio_src1:sink
0:00:04.128967666 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad selector_audio_src1:src
0:00:04.128983600 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad preroll_video_src0:src
0:00:04.128993926 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad preroll_video_src0:sink
0:00:04.129003088 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad selector_video_src0:src
0:00:04.129073218 15760  0xa8159f8 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "bin" named "abin"
0:00:04.129690151 15760  0xa8159f8 INFO    GST_PLUGIN_LOADING gstplugin.c:570:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstaudioconvert.so" loaded
0:00:04.129709720 15760  0xa8159f8 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "audioconvert" named "aconv"
0:00:04.129752360 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseTransform@0xb5b0010> adding pad 'sink'
0:00:04.129778626 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseTransform@0xb5b0010> adding pad 'src'
0:00:04.156935624 15760  0xa8159f8 INFO    GST_PLUGIN_LOADING gstplugin.c:570:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstaudioresample.so" loaded
0:00:04.157017229 15760  0xa8159f8 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "audioresample" named "aresample"
0:00:04.157052768 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseTransform@0xb5b2020> adding pad 'sink'
0:00:04.157080699 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseTransform@0xb5b2020> adding pad 'src'
0:00:04.157735174 15760  0xa8159f8 INFO    GST_PLUGIN_LOADING gstplugin.c:570:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstvolume.so" loaded
0:00:04.157754297 15760  0xa8159f8 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "volume" named "volume"
0:00:04.157803078 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseTransform@0xb5b6120> adding pad 'sink'
0:00:04.157829275 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseTransform@0xb5b6120> adding pad 'src'
0:00:04.157868969 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstutils.c:1430:gst_element_link_pads: trying to link element aconv:src to element aresample:sink
0:00:04.157881047 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad aconv:src
0:00:04.157890797 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad aresample:sink
0:00:04.157899560 15760  0xa8159f8 INFO              GST_PADS gstutils.c:1335:prepare_link_maybe_ghosting: aconv and aresample in same bin, no need for ghost pads
0:00:04.157914477 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link aconv:src and aresample:sink
0:00:04.158132840 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked aconv:src and aresample:sink, successful
0:00:04.158152800 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstutils.c:1430:gst_element_link_pads: trying to link element aresample:src to element volume:sink
0:00:04.158163585 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad aresample:src
0:00:04.158172957 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad volume:sink
0:00:04.158181571 15760  0xa8159f8 INFO              GST_PADS gstutils.c:1335:prepare_link_maybe_ghosting: aresample and volume in same bin, no need for ghost pads
0:00:04.158195347 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link aresample:src and volume:sink
0:00:04.158559883 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked aresample:src and volume:sink, successful
0:00:04.158579306 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstutils.c:1430:gst_element_link_pads: trying to link element volume:src to element audiosinkbin:(any)
0:00:04.158590133 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad volume:src
0:00:04.158600534 15760  0xa8159f8 INFO              GST_PADS gstutils.c:2125:gst_pad_can_link: trying to link volume:src and audiosinkbin:sink
0:00:04.159016769 15760  0xa8159f8 INFO              GST_PADS gstutils.c:1335:prepare_link_maybe_ghosting: volume and audiosinkbin in same bin, no need for ghost pads
0:00:04.159033188 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link volume:src and audiosinkbin:sink
0:00:04.159443142 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked volume:src and audiosinkbin:sink, successful
0:00:04.159462691 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad aconv:sink
0:00:04.159512240 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link sink:proxypad10 and aconv:sink
0:00:04.160600413 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked sink:proxypad10 and aconv:sink, successful
0:00:04.160615104 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<abin> adding pad 'sink'
0:00:04.160647727 15760  0xa8159f8 INFO          GST_PIPELINE gstparse.c:291:gst_parse_launch_full: parsing pipeline description 'bin.( autoaudiosink )'
0:00:04.160674509 15760  0xa8159f8 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "autoaudiosink"
0:00:04.160736181 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstAutoAudioSink@0x9c32e18> adding pad 'sink'
0:00:04.160759003 15760  0xa8159f8 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "fakesink" named "tempsink"
0:00:04.160788760 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseSink@0x9f2a9c8> adding pad 'sink'
0:00:04.160821653 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad tempsink:sink
0:00:04.160833057 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link sink:proxypad11 and tempsink:sink
0:00:04.160846035 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked sink:proxypad11 and tempsink:sink, successful
0:00:04.160892713 15760  0xa8159f8 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "bin"
0:00:04.160964463 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link sink:proxypad12 and autoaudiosink1:sink
0:00:04.160979955 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked sink:proxypad12 and autoaudiosink1:sink, successful
0:00:04.160991662 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<bin4> adding pad 'sink'
0:00:04.161018656 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<tempsink> completed state change to NULL
0:00:04.161031323 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad11(0xa73edd0) and tempsink:sink(0xa70e248)
0:00:04.161042777 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked sink:proxypad11 and tempsink:sink
0:00:04.161054108 15760  0xa8159f8 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<autoaudiosink1> removed child "tempsink"
0:00:04.161065625 15760  0xa8159f8 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<tempsink> dispose
0:00:04.161075526 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<tempsink> removing pad 'sink'
0:00:04.161086326 15760  0xa8159f8 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<tempsink> parent class dispose
0:00:04.161097609 15760  0xa8159f8 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<tempsink> finalize
0:00:04.161107896 15760  0xa8159f8 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<tempsink> finalize parent
0:00:04.161223507 15760  0xa8159f8 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "pulsesink" named "autoaudiosink1-actual-sink-pulse"
0:00:04.161254741 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseSink@0x9f4cc50> adding pad 'sink'
0:00:04.161332992 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad autoaudiosink1-actual-sink-pulse:sink
0:00:04.162580170 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autoaudiosink1-actual-sink-pulse> completed state change to READY
0:00:04.162595692 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<autoaudiosink1-actual-sink-pulse> posting state-changed NULL to READY
0:00:04.162632929 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad autoaudiosink1-actual-sink-pulse:sink
0:00:04.162645109 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad11(0xa73edd0) and '':sink(0xa70e248)
0:00:04.162658892 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link sink:proxypad11 and autoaudiosink1-actual-sink-pulse:sink
0:00:04.162721194 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked sink:proxypad11 and autoaudiosink1-actual-sink-pulse:sink, successful
0:00:04.162739243 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autoaudiosink1-actual-sink-pulse> completed state change to READY
0:00:04.162750415 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<autoaudiosink1> child 'autoaudiosink1-actual-sink-pulse' changed state to 2(READY) successfully
0:00:04.162771655 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autoaudiosink1> completed state change to READY
0:00:04.162781661 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<autoaudiosink1> posting state-changed NULL to READY
0:00:04.162799490 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<bin4> child 'autoaudiosink1' changed state to 2(READY) successfully
0:00:04.162811620 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<bin4> completed state change to READY
0:00:04.162821070 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<bin4> posting state-changed NULL to READY
0:00:04.162853558 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<testsink> completed state change to NULL
0:00:04.162865507 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad0(0x9f15038) and testsink:sink(0x9c38400)
0:00:04.162876988 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked sink:proxypad0 and testsink:sink
0:00:04.162888530 15760  0xa8159f8 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<audio-sink> removed child "testsink"
0:00:04.162899627 15760  0xa8159f8 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<testsink> dispose
0:00:04.162909401 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<testsink> removing pad 'sink'
0:00:04.162920219 15760  0xa8159f8 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<testsink> parent class dispose
0:00:04.162931203 15760  0xa8159f8 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<testsink> finalize
0:00:04.162941031 15760  0xa8159f8 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<testsink> finalize parent
0:00:04.162950937 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad bin4:sink
0:00:04.162960429 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad0(0x9f15038) and '':sink(0x9c38400)
0:00:04.162973513 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link sink:proxypad0 and bin4:sink
0:00:04.164237955 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked sink:proxypad0 and bin4:sink, successful
0:00:04.164263245 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autoaudiosink1-actual-sink-pulse> completed state change to READY
0:00:04.164274771 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<autoaudiosink1> child 'autoaudiosink1-actual-sink-pulse' changed state to 2(READY) successfully
0:00:04.164286877 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autoaudiosink1> completed state change to READY
0:00:04.164296771 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<bin4> child 'autoaudiosink1' changed state to 2(READY) successfully
0:00:04.164307934 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<bin4> completed state change to READY
0:00:04.164317550 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<audio-sink> child 'bin4' changed state to 2(READY) successfully
0:00:04.164328458 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<audio-sink> completed state change to READY
0:00:04.164337959 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<audio-sink> posting state-changed NULL to READY
0:00:04.164352723 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<audiosinkbin> child 'audio-sink' changed state to 2(READY) successfully
0:00:04.164373333 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<audiofilter> completed state change to READY
0:00:04.164383203 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<audiofilter> posting state-changed NULL to READY
0:00:04.164397226 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<audiosinkbin> child 'audiofilter' changed state to 2(READY) successfully
0:00:04.164408950 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<audiosinkbin> completed state change to READY
0:00:04.164418361 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<audiosinkbin> posting state-changed NULL to READY
0:00:04.164431567 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<abin> child 'audiosinkbin' changed state to 2(READY) successfully
0:00:04.164444870 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<volume> completed state change to READY
0:00:04.164454581 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<volume> posting state-changed NULL to READY
0:00:04.164467932 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<abin> child 'volume' changed state to 2(READY) successfully
0:00:04.164480610 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<aresample> completed state change to READY
0:00:04.164490258 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<aresample> posting state-changed NULL to READY
0:00:04.164503563 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<abin> child 'aresample' changed state to 2(READY) successfully
0:00:04.164515972 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<aconv> completed state change to READY
0:00:04.164525578 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<aconv> posting state-changed NULL to READY
0:00:04.164538997 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<abin> child 'aconv' changed state to 2(READY) successfully
0:00:04.164550622 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<abin> completed state change to READY
0:00:04.164559824 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<abin> posting state-changed NULL to READY
0:00:04.164572811 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad preroll_audio_src1:src
0:00:04.164636422 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2361:gst_bin_change_state_func:<autoaudiosink1> child 'autoaudiosink1-actual-sink-pulse' is changing state asynchronously to PAUSED
0:00:04.164652520 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2361:gst_bin_change_state_func:<bin4> child 'autoaudiosink1' is changing state asynchronously to PAUSED
0:00:04.164666357 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2361:gst_bin_change_state_func:<audio-sink> child 'bin4' is changing state asynchronously to PAUSED
0:00:04.164680031 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2361:gst_bin_change_state_func:<audiosinkbin> child 'audio-sink' is changing state asynchronously to PAUSED
0:00:04.164695157 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<audiofilter> completed state change to PAUSED
0:00:04.164705108 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<audiofilter> posting state-changed READY to PAUSED
0:00:04.164728491 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<audiosinkbin> child 'audiofilter' changed state to 3(PAUSED) successfully
0:00:04.164743305 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2361:gst_bin_change_state_func:<abin> child 'audiosinkbin' is changing state asynchronously to PAUSED
0:00:04.164768677 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<volume> completed state change to PAUSED
0:00:04.164779525 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<volume> posting state-changed READY to PAUSED
0:00:04.164801558 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<abin> child 'volume' changed state to 3(PAUSED) successfully
0:00:04.164819456 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<aresample> completed state change to PAUSED
0:00:04.164829507 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<aresample> posting state-changed READY to PAUSED
0:00:04.164850897 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<abin> child 'aresample' changed state to 3(PAUSED) successfully
0:00:04.164867476 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<aconv> completed state change to PAUSED
0:00:04.164877736 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<aconv> posting state-changed READY to PAUSED
0:00:04.164899042 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<abin> child 'aconv' changed state to 3(PAUSED) successfully
0:00:04.164913251 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad abin:sink
0:00:04.164929982 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link preroll_audio_src1:src and abin:sink
0:00:04.165697159 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked preroll_audio_src1:src and abin:sink, successful
0:00:04.165748844 15760  0xa8159f8 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "bin" named "vbin"
0:00:04.166882724 15760  0xa8159f8 INFO    GST_PLUGIN_LOADING gstplugin.c:570:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstffmpegcolorspace.so" loaded
0:00:04.166904072 15760  0xa8159f8 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "ffmpegcolorspace" named "vconv"
0:00:04.166935042 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseTransform@0xb5b2218> adding pad 'sink'
0:00:04.166961514 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseTransform@0xb5b2218> adding pad 'src'
0:00:04.168061774 15760  0xa8159f8 INFO    GST_PLUGIN_LOADING gstplugin.c:570:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstvideoscale.so" loaded
0:00:04.168080921 15760  0xa8159f8 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "videoscale" named "vscale"
0:00:04.168117570 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseTransform@0xb5c8078> adding pad 'sink'
0:00:04.168143574 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseTransform@0xb5c8078> adding pad 'src'
0:00:04.168180169 15760  0xa8159f8 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "identity" named "id"
0:00:04.168218294 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseTransform@0xb5ca1b0> adding pad 'sink'
0:00:04.168245157 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseTransform@0xb5ca1b0> adding pad 'src'
0:00:04.168272698 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstutils.c:1430:gst_element_link_pads: trying to link element id:src to element vconv:sink
0:00:04.168284107 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad id:src
0:00:04.168293638 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad vconv:sink
0:00:04.168310918 15760  0xa8159f8 INFO              GST_PADS gstutils.c:1335:prepare_link_maybe_ghosting: id and vconv in same bin, no need for ghost pads
0:00:04.168326447 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link id:src and vconv:sink
0:00:04.169477021 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked id:src and vconv:sink, successful
0:00:04.169497158 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstutils.c:1430:gst_element_link_pads: trying to link element vconv:src to element vscale:sink
0:00:04.169507964 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad vconv:src
0:00:04.169517339 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad vscale:sink
0:00:04.169525727 15760  0xa8159f8 INFO              GST_PADS gstutils.c:1335:prepare_link_maybe_ghosting: vconv and vscale in same bin, no need for ghost pads
0:00:04.169539333 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link vconv:src and vscale:sink
0:00:04.179489894 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked vconv:src and vscale:sink, successful
0:00:04.179555074 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstutils.c:1430:gst_element_link_pads: trying to link element vscale:src to element video-sink:(any)
0:00:04.179568277 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad vscale:src
0:00:04.179579125 15760  0xa8159f8 INFO              GST_PADS gstutils.c:2125:gst_pad_can_link: trying to link vscale:src and video-sink:sink
0:00:04.190385074 15760  0xa8159f8 INFO              GST_PADS gstutils.c:1335:prepare_link_maybe_ghosting: vscale and video-sink in same bin, no need for ghost pads
0:00:04.190444689 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link vscale:src and video-sink:sink
0:00:04.201311112 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked vscale:src and video-sink:sink, successful
0:00:04.201375255 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad id:sink
0:00:04.201426584 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link sink:proxypad13 and id:sink
0:00:04.205689332 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked sink:proxypad13 and id:sink, successful
0:00:04.205705125 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<vbin> adding pad 'sink'
0:00:04.205734849 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autovideosink0-actual-sink-xvimage> completed state change to READY
0:00:04.205746121 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<autovideosink0> child 'autovideosink0-actual-sink-xvimage' changed state to 2(READY) successfully
0:00:04.205758061 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autovideosink0> completed state change to READY
0:00:04.205767898 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<bin0> child 'autovideosink0' changed state to 2(READY) successfully
0:00:04.205778789 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<bin0> completed state change to READY
0:00:04.205788542 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<video-sink> child 'bin0' changed state to 2(READY) successfully
0:00:04.205799477 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<video-sink> completed state change to READY
0:00:04.205809119 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<vbin> child 'video-sink' changed state to 2(READY) successfully
0:00:04.205821174 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<vscale> completed state change to READY
0:00:04.205838637 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<vscale> posting state-changed NULL to READY
0:00:04.205853826 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<vbin> child 'vscale' changed state to 2(READY) successfully
0:00:04.205866829 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<vconv> completed state change to READY
0:00:04.205876378 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<vconv> posting state-changed NULL to READY
0:00:04.205890055 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<vbin> child 'vconv' changed state to 2(READY) successfully
0:00:04.205902394 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<id> completed state change to READY
0:00:04.205911851 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<id> posting state-changed NULL to READY
0:00:04.205925264 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<vbin> child 'id' changed state to 2(READY) successfully
0:00:04.205937135 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<vbin> completed state change to READY
0:00:04.205946442 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<vbin> posting state-changed NULL to READY
0:00:04.205959879 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad preroll_video_src0:src
0:00:04.206117473 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2361:gst_bin_change_state_func:<autovideosink0> child 'autovideosink0-actual-sink-xvimage' is changing state asynchronously to PAUSED
0:00:04.206134514 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2361:gst_bin_change_state_func:<bin0> child 'autovideosink0' is changing state asynchronously to PAUSED
0:00:04.206148389 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2361:gst_bin_change_state_func:<video-sink> child 'bin0' is changing state asynchronously to PAUSED
0:00:04.206161970 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2361:gst_bin_change_state_func:<vbin> child 'video-sink' is changing state asynchronously to PAUSED
0:00:04.206177877 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<vscale> completed state change to PAUSED
0:00:04.206188197 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<vscale> posting state-changed READY to PAUSED
0:00:04.206210460 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<vbin> child 'vscale' changed state to 3(PAUSED) successfully
0:00:04.206226985 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<vconv> completed state change to PAUSED
0:00:04.206238052 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<vconv> posting state-changed READY to PAUSED
0:00:04.206260010 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<vbin> child 'vconv' changed state to 3(PAUSED) successfully
0:00:04.206276540 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<id> completed state change to PAUSED
0:00:04.206286863 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<id> posting state-changed READY to PAUSED
0:00:04.206308248 15760  0xa8159f8 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<vbin> child 'id' changed state to 3(PAUSED) successfully
0:00:04.206322627 15760  0xa8159f8 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad vbin:sink
0:00:04.206339116 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link preroll_video_src0:src and vbin:sink
0:00:04.210710086 15760  0xa8159f8 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked preroll_video_src0:src and vbin:sink, successful
0:00:04.210796749 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2123:gst_element_continue_state:<test> committing state from READY to READY, pending NULL
0:00:04.210816526 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2132:gst_element_continue_state:<test> continue state change READY to NULL, final NULL
0:00:04.210831433 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<test> completed state change to NULL
0:00:04.210842023 15760  0xa8159f8 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<test> posting state-changed READY to NULL
0:00:04.210862610 15760  0xa8159f8 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<play> removed child "test"
0:00:04.210974819 15760  0xa8159f8 INFO              GST_PADS gstpad.c:3235:gst_pad_event_default_dispatch:<mad0:sink> Sending event 0xb49322f0 (tag) to all internally linked pads
0:00:04.211006806 15760  0xa8159f8 INFO              GST_PADS gstpad.c:3235:gst_pad_event_default_dispatch:<mad0:sink> Sending event 0xb4932340 (tag) to all internally linked pads
0:00:04.211837175 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<test> dispose
0:00:04.211851246 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<test> removing pad 'sink'
0:00:04.211867863 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<test> parent class dispose
0:00:04.211880134 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<test> finalize
0:00:04.211891189 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<test> finalize parent
0:00:04.212017549 15760  0x9ab0490 WARN              GST_PADS gstpad.c:3005:gst_pad_iterate_internal_links_default:<selector_video_src0:src> Making unsafe iterator
0:00:04.212062139 15760  0x9ab0490 WARN              GST_PADS gstpad.c:3005:gst_pad_iterate_internal_links_default:<selector_audio_src1:src> Making unsafe iterator
0:00:04.216682893 15760 0xb4920000 INFO          audioconvert audioconvert.c:459:audio_convert_prepare_context: use int mixing
0:00:04.216721567 15760 0xb4920000 INFO          audioconvert audioconvert.c:465:audio_convert_prepare_context: unitsizes: 8 -> 8
0:00:04.216735682 15760 0xb4920000 INFO          audioconvert audioconvert.c:475:audio_convert_prepare_context: in default 1, mix passthrough 1, out default 1
0:00:04.219011352 15760 0xb4920000 WARN                 pulse pulsesink.c:836:gst_pulsesink_prepare:<autoaudiosink1-actual-sink-pulse> error: Failed to connect stream: Invalid argument
0:00:04.219057619 15760 0xb4920000 INFO      GST_ERROR_SYSTEM gstelement.c:1675:gst_element_message_full:<autoaudiosink1-actual-sink-pulse> posting message: Failed to connect stream: Invalid argument
0:00:04.219115179 15760 0xb4920000 INFO      GST_ERROR_SYSTEM gstelement.c:1698:gst_element_message_full:<autoaudiosink1-actual-sink-pulse> posted error message: Failed to connect stream: Invalid argument
0:00:04.219184243 15760  0x9ab0490 ERROR                totem /build/buildd/totem-2.26.0/src/backend/bacon-video-widget-gst-0.10.c:327:bvw_error_msg: message = Failed to connect stream: Invalid argument
0:00:04.219208307 15760  0x9ab0490 ERROR                totem /build/buildd/totem-2.26.0/src/backend/bacon-video-widget-gst-0.10.c:329:bvw_error_msg: domain  = 2890 (gst-resource-error-quark)
0:00:04.219221909 15760  0x9ab0490 ERROR                totem /build/buildd/totem-2.26.0/src/backend/bacon-video-widget-gst-0.10.c:330:bvw_error_msg: code    = 1
0:00:04.219233786 15760  0x9ab0490 ERROR                totem /build/buildd/totem-2.26.0/src/backend/bacon-video-widget-gst-0.10.c:331:bvw_error_msg: debug   = pulsesink.c(836): gst_pulsesink_prepare (): /GstPlayBin:play/GstBin:abin/GstBin:audiosinkbin/GstGConfAudioSink:audio-sink/GstBin:bin4/GstAutoAudioSink:autoaudiosink1/GstPulseSink:autoaudiosink1-actual-sink-pulse
0:00:04.219275799 15760  0x9ab0490 ERROR                totem /build/buildd/totem-2.26.0/src/backend/bacon-video-widget-gst-0.10.c:332:bvw_error_msg: source  = <autoaudiosink1-actual-sink-pulse>
0:00:04.219294835 15760  0x9ab0490 ERROR                totem /build/buildd/totem-2.26.0/src/backend/bacon-video-widget-gst-0.10.c:333:bvw_error_msg: uri     = file:///media/Archiv/Video/Filme/Break/Break.avi
** Message: Error: Failed to connect stream: Invalid argument
pulsesink.c(836): gst_pulsesink_prepare (): /GstPlayBin:play/GstBin:abin/GstBin:audiosinkbin/GstGConfAudioSink:audio-sink/GstBin:bin4/GstAutoAudioSink:autoaudiosink1/GstPulseSink:autoaudiosink1-actual-sink-pulse

0:00:04.220499836 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autovideosink0-actual-sink-xvimage> completed state change to READY
0:00:04.220526180 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<autovideosink0-actual-sink-xvimage> posting state-changed READY to READY
0:00:04.220554480 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<autovideosink0> child 'autovideosink0-actual-sink-xvimage' changed state to 2(READY) successfully
0:00:04.220578882 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autovideosink0> completed state change to READY
0:00:04.220593187 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<autovideosink0> posting state-changed READY to READY
0:00:04.220617691 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<bin0> child 'autovideosink0' changed state to 2(READY) successfully
0:00:04.220639760 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<bin0> completed state change to READY
0:00:04.220653524 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<bin0> posting state-changed READY to READY
0:00:04.220679826 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<video-sink> child 'bin0' changed state to 2(READY) successfully
0:00:04.220703443 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<video-sink> completed state change to READY
0:00:04.220717444 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<video-sink> posting state-changed READY to READY
0:00:04.220745606 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<vbin> child 'video-sink' changed state to 2(READY) successfully
0:00:04.220895826 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<vscale> completed state change to READY
0:00:04.220941934 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<vscale> posting state-changed PAUSED to READY
0:00:04.220969795 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<vbin> child 'vscale' changed state to 2(READY) successfully
0:00:04.221006630 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<vconv> completed state change to READY
0:00:04.221021835 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<vconv> posting state-changed PAUSED to READY
0:00:04.221046018 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<vbin> child 'vconv' changed state to 2(READY) successfully
0:00:04.221072125 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<id> completed state change to READY
0:00:04.221086423 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<id> posting state-changed PAUSED to READY
0:00:04.221110859 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<vbin> child 'id' changed state to 2(READY) successfully
0:00:04.228234610 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<vbin> completed state change to READY
0:00:04.228252223 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<vbin> posting state-changed READY to READY
0:00:04.228277655 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<play> child 'vbin' changed state to 2(READY) successfully
0:00:04.228369766 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autoaudiosink1-actual-sink-pulse> completed state change to READY
0:00:04.228386774 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<autoaudiosink1-actual-sink-pulse> posting state-changed READY to READY
0:00:04.228411954 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<autoaudiosink1> child 'autoaudiosink1-actual-sink-pulse' changed state to 2(READY) successfully
0:00:04.228434751 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autoaudiosink1> completed state change to READY
0:00:04.228449064 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<autoaudiosink1> posting state-changed READY to READY
0:00:04.228473386 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<bin4> child 'autoaudiosink1' changed state to 2(READY) successfully
0:00:04.228495688 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<bin4> completed state change to READY
0:00:04.228509753 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<bin4> posting state-changed READY to READY
0:00:04.228533801 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<audio-sink> child 'bin4' changed state to 2(READY) successfully
0:00:04.228556053 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<audio-sink> completed state change to READY
0:00:04.228570018 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<audio-sink> posting state-changed READY to READY
0:00:04.228593506 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<audiosinkbin> child 'audio-sink' changed state to 2(READY) successfully
0:00:04.228629504 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<audiofilter> completed state change to READY
0:00:04.228644129 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<audiofilter> posting state-changed PAUSED to READY
0:00:04.228667911 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<audiosinkbin> child 'audiofilter' changed state to 2(READY) successfully
0:00:04.228696184 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<audiosinkbin> completed state change to READY
0:00:04.228710839 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<audiosinkbin> posting state-changed READY to READY
0:00:04.228733930 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<abin> child 'audiosinkbin' changed state to 2(READY) successfully
0:00:04.228768711 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<volume> completed state change to READY
0:00:04.228783585 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<volume> posting state-changed PAUSED to READY
0:00:04.228807234 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<abin> child 'volume' changed state to 2(READY) successfully
0:00:04.228843829 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<aresample> completed state change to READY
0:00:04.228870236 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<aresample> posting state-changed PAUSED to READY
0:00:04.228894621 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<abin> child 'aresample' changed state to 2(READY) successfully
0:00:04.228930351 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<aconv> completed state change to READY
0:00:04.228945058 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<aconv> posting state-changed PAUSED to READY
0:00:04.228968122 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<abin> child 'aconv' changed state to 2(READY) successfully
0:00:04.228995396 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<abin> completed state change to READY
0:00:04.229010051 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<abin> posting state-changed READY to READY
0:00:04.229032723 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<play> child 'abin' changed state to 2(READY) successfully
0:00:04.229053118 15760  0x9aec520 INFO              avidemux gstavidemux.c:4120:gst_avi_demux_loop: stream_movi flow: wrong-state
0:00:04.229116905 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<preroll_video_src0> completed state change to READY
0:00:04.229136058 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<preroll_video_src0> posting state-changed PAUSED to READY
0:00:04.229159947 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<play> child 'preroll_video_src0' changed state to 2(READY) successfully
0:00:04.229215416 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<preroll_audio_src1> completed state change to READY
0:00:04.229231340 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<preroll_audio_src1> posting state-changed PAUSED to READY
0:00:04.229254975 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<play> child 'preroll_audio_src1' changed state to 2(READY) successfully
0:00:04.229288144 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<selector_video_src0> completed state change to READY
0:00:04.229302940 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<selector_video_src0> posting state-changed PAUSED to READY
0:00:04.229325719 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<play> child 'selector_video_src0' changed state to 2(READY) successfully
0:00:04.229358483 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<selector_audio_src1> completed state change to READY
0:00:04.229373142 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<selector_audio_src1> posting state-changed PAUSED to READY
0:00:04.229395759 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<play> child 'selector_audio_src1' changed state to 2(READY) successfully
0:00:04.229477946 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<mad0> completed state change to READY
0:00:04.229493904 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<mad0> posting state-changed PAUSED to READY
0:00:04.229518477 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<decodebin0> child 'mad0' changed state to 2(READY) successfully
0:00:04.229556914 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<mpegaudioparse0> completed state change to READY
0:00:04.229572272 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<mpegaudioparse0> posting state-changed PAUSED to READY
0:00:04.229607494 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<decodebin0> child 'mpegaudioparse0' changed state to 2(READY) successfully
0:00:04.229746823 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<queue2> completed state change to READY
0:00:04.229772300 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<queue2> posting state-changed PAUSED to READY
0:00:04.229797204 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<decodebin0> child 'queue2' changed state to 2(READY) successfully
0:00:04.229910483 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<ffdec_mpeg40> completed state change to READY
0:00:04.229930038 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<ffdec_mpeg40> posting state-changed PAUSED to READY
0:00:04.229955304 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<decodebin0> child 'ffdec_mpeg40' changed state to 2(READY) successfully
0:00:04.230080483 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<queue0> completed state change to READY
0:00:04.230126438 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<queue0> posting state-changed PAUSED to READY
0:00:04.230153777 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<decodebin0> child 'queue0' changed state to 2(READY) successfully
0:00:04.230229313 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<avidemux0> removing pad 'video_00'
0:00:04.230245346 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking avidemux0:video_00(0xa6fe4d8) and queue0:sink(0xa6fe598)
0:00:04.230274966 15760  0x9ab0490 WARN              GST_PADS gstpad.c:3215:gst_pad_get_internal_links:<queue0:sink> Calling unsafe internal links
0:00:04.230290140 15760  0x9ab0490 WARN              GST_PADS gstpad.c:3121:gst_pad_get_internal_links_default:<queue0:sink> Unsafe internal links used
0:00:04.230310993 15760  0x9ab0490 WARN              GST_PADS gstpad.c:3215:gst_pad_get_internal_links:<ffdec_mpeg40:sink> Calling unsafe internal links
0:00:04.230324935 15760  0x9ab0490 WARN              GST_PADS gstpad.c:3121:gst_pad_get_internal_links_default:<ffdec_mpeg40:sink> Unsafe internal links used
0:00:04.230341543 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<decodebin0> removing pad 'src0'
0:00:04.230355106 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking decodebin0:src0(0x9f2c460) and selector_video_src0:sink0(0x9bb2bf0)
0:00:04.230428974 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "fakesrc"
0:00:04.230483984 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseSrc@0xb0f0a000> adding pad 'src'
0:00:04.230526861 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad fakesrc0:src
0:00:04.230557210 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link fakesrc0:src and selector_video_src0:sink0
0:00:04.236655303 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked fakesrc0:src and selector_video_src0:sink0, successful
0:00:04.236693515 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked decodebin0:src0 and selector_video_src0:sink0
0:00:04.236729648 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<ffdec_mpeg40> completed state change to NULL
0:00:04.236745587 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<ffdec_mpeg40> posting state-changed READY to NULL
0:00:04.236783487 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking ffdec_mpeg40:src(0xb4931838) and src0:proxypad8(0x9f155e8)
0:00:04.236807602 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked ffdec_mpeg40:src and src0:proxypad8
0:00:04.236832592 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking queue0:src(0xa6fe658) and ffdec_mpeg40:sink(0xa6fe718)
0:00:04.236854457 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked queue0:src and ffdec_mpeg40:sink
0:00:04.236881886 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<decodebin0> removed child "ffdec_mpeg40"
0:00:04.236910642 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<queue0> completed state change to NULL
0:00:04.236925403 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<queue0> posting state-changed READY to NULL
0:00:04.236953093 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<decodebin0> removed child "queue0"
0:00:04.236975090 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:882:gst_element_get_static_pad: no such pad 'audio_%02d' in element "avidemux0"
0:00:04.236998220 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked avidemux0:video_00 and queue0:sink
0:00:04.237032475 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<avidemux0> removing pad 'audio_00'
0:00:04.237047014 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking avidemux0:audio_00(0xb4931b38) and queue2:sink(0xb4931bf8)
0:00:04.237072748 15760  0x9ab0490 WARN              GST_PADS gstpad.c:3215:gst_pad_get_internal_links:<queue2:sink> Calling unsafe internal links
0:00:04.237087419 15760  0x9ab0490 WARN              GST_PADS gstpad.c:3121:gst_pad_get_internal_links_default:<queue2:sink> Unsafe internal links used
0:00:04.237104636 15760  0x9ab0490 WARN              GST_PADS gstpad.c:3215:gst_pad_get_internal_links:<mpegaudioparse0:sink> Calling unsafe internal links
0:00:04.237117992 15760  0x9ab0490 WARN              GST_PADS gstpad.c:3121:gst_pad_get_internal_links_default:<mpegaudioparse0:sink> Unsafe internal links used
0:00:04.237133731 15760  0x9ab0490 WARN              GST_PADS gstpad.c:3215:gst_pad_get_internal_links:<mad0:sink> Calling unsafe internal links
0:00:04.237146685 15760  0x9ab0490 WARN              GST_PADS gstpad.c:3121:gst_pad_get_internal_links_default:<mad0:sink> Unsafe internal links used
0:00:04.237162487 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<decodebin0> removing pad 'src1'
0:00:04.237175678 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking decodebin0:src1(0xa73e2a0) and selector_audio_src1:sink0(0xa811308)
0:00:04.237214694 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "fakesrc"
0:00:04.237258717 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseSrc@0xb0f0a220> adding pad 'src'
0:00:04.237293341 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad fakesrc1:src
0:00:04.237321905 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link fakesrc1:src and selector_audio_src1:sink0
0:00:04.238364584 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked fakesrc1:src and selector_audio_src1:sink0, successful
0:00:04.238399658 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked decodebin0:src1 and selector_audio_src1:sink0
0:00:04.238434954 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<mad0> completed state change to NULL
0:00:04.238450608 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<mad0> posting state-changed READY to NULL
0:00:04.238486390 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking mad0:src(0xa813248) and src1:proxypad9(0xa73ec30)
0:00:04.238510241 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked mad0:src and src1:proxypad9
0:00:04.238534976 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking mpegaudioparse0:src(0xb4931e38) and mad0:sink(0xa813188)
0:00:04.238561098 15760  0x9ab0490 WARN              GST_PADS gstpad.c:3215:gst_pad_get_internal_links:<mad0:sink> Calling unsafe internal links
0:00:04.238575933 15760  0x9ab0490 WARN              GST_PADS gstpad.c:3121:gst_pad_get_internal_links_default:<mad0:sink> Unsafe internal links used
0:00:04.238592677 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<mad0> completed state change to NULL
0:00:04.238607335 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1373:gst_bin_remove_func:<decodebin0> already removing child
0:00:04.238623099 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked mpegaudioparse0:src and mad0:sink
0:00:04.238650237 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<decodebin0> removed child "mad0"
0:00:04.238677814 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<mpegaudioparse0> completed state change to NULL
0:00:04.238692769 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<mpegaudioparse0> posting state-changed READY to NULL
0:00:04.238717100 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking queue2:src(0xb4931cb8) and mpegaudioparse0:sink(0xb4931d78)
0:00:04.238740600 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked queue2:src and mpegaudioparse0:sink
0:00:04.238767687 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<decodebin0> removed child "mpegaudioparse0"
0:00:04.238796353 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<queue2> completed state change to NULL
0:00:04.238811008 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<queue2> posting state-changed READY to NULL
0:00:04.238837649 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<decodebin0> removed child "queue2"
0:00:04.238858883 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:882:gst_element_get_static_pad: no such pad 'audio_%02d' in element "avidemux0"
0:00:04.238876404 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked avidemux0:audio_00 and queue2:sink
0:00:04.241302118 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<avidemux0> completed state change to READY
0:00:04.241352713 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<avidemux0> posting state-changed PAUSED to READY
0:00:04.241380880 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<decodebin0> child 'avidemux0' changed state to 2(READY) successfully
0:00:04.241405046 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<avidemux0> completed state change to READY
0:00:04.241419713 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<decodebin0> child 'avidemux0' changed state to 2(READY) successfully
0:00:04.241449228 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<typefind> completed state change to READY
0:00:04.241464009 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<typefind> posting state-changed PAUSED to READY
0:00:04.241487749 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<decodebin0> child 'typefind' changed state to 2(READY) successfully
0:00:04.241512163 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad typefind:src
0:00:04.241546241 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<avidemux0> completed state change to NULL
0:00:04.241560711 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<avidemux0> posting state-changed READY to NULL
0:00:04.241584663 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking typefind:src(0xa6fe118) and avidemux0:sink(0xa6fe298)
0:00:04.241610083 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked typefind:src and avidemux0:sink
0:00:04.241642283 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<decodebin0> removed child "avidemux0"
0:00:04.241669841 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<decodebin0> completed state change to READY
0:00:04.241684542 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<decodebin0> posting state-changed PAUSED to READY
0:00:04.241707700 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<play> child 'decodebin0' changed state to 2(READY) successfully
0:00:04.241756349 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autovideosink0-actual-sink-xvimage> completed state change to READY
0:00:04.241771544 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<autovideosink0> child 'autovideosink0-actual-sink-xvimage' changed state to 2(READY) successfully
0:00:04.241787834 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autovideosink0> completed state change to READY
0:00:04.241801695 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<bin0> child 'autovideosink0' changed state to 2(READY) successfully
0:00:04.241817091 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<bin0> completed state change to READY
0:00:04.241830519 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<video-sink> child 'bin0' changed state to 2(READY) successfully
0:00:04.241845970 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<video-sink> completed state change to READY
0:00:04.241859536 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<vbin> child 'video-sink' changed state to 2(READY) successfully
0:00:04.241876316 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<vscale> completed state change to READY
0:00:04.241890329 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<vbin> child 'vscale' changed state to 2(READY) successfully
0:00:04.241907043 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<vconv> completed state change to READY
0:00:04.241920855 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<vbin> child 'vconv' changed state to 2(READY) successfully
0:00:04.241936998 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<id> completed state change to READY
0:00:04.241950492 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<vbin> child 'id' changed state to 2(READY) successfully
0:00:04.241965817 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<vbin> completed state change to READY
0:00:04.241979173 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<play> child 'vbin' changed state to 2(READY) successfully
0:00:04.242020697 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autoaudiosink1-actual-sink-pulse> completed state change to READY
0:00:04.242035635 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<autoaudiosink1> child 'autoaudiosink1-actual-sink-pulse' changed state to 2(READY) successfully
0:00:04.242061807 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autoaudiosink1> completed state change to READY
0:00:04.242075812 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<bin4> child 'autoaudiosink1' changed state to 2(READY) successfully
0:00:04.242091037 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<bin4> completed state change to READY
0:00:04.242104580 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<audio-sink> child 'bin4' changed state to 2(READY) successfully
0:00:04.242119979 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<audio-sink> completed state change to READY
0:00:04.242133632 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<audiosinkbin> child 'audio-sink' changed state to 2(READY) successfully
0:00:04.242149827 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<audiofilter> completed state change to READY
0:00:04.242163642 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<audiosinkbin> child 'audiofilter' changed state to 2(READY) successfully
0:00:04.242178951 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<audiosinkbin> completed state change to READY
0:00:04.242192284 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<abin> child 'audiosinkbin' changed state to 2(READY) successfully
0:00:04.242209585 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<volume> completed state change to READY
0:00:04.242223373 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<abin> child 'volume' changed state to 2(READY) successfully
0:00:04.242239826 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<aresample> completed state change to READY
0:00:04.242253587 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<abin> child 'aresample' changed state to 2(READY) successfully
0:00:04.242269608 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<aconv> completed state change to READY
0:00:04.242283381 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<abin> child 'aconv' changed state to 2(READY) successfully
0:00:04.242298633 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<abin> completed state change to READY
0:00:04.242312155 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<play> child 'abin' changed state to 2(READY) successfully
0:00:04.242328934 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<preroll_video_src0> completed state change to READY
0:00:04.242343134 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<play> child 'preroll_video_src0' changed state to 2(READY) successfully
0:00:04.242360033 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<preroll_audio_src1> completed state change to READY
0:00:04.242373860 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<play> child 'preroll_audio_src1' changed state to 2(READY) successfully
0:00:04.242390370 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<selector_video_src0> completed state change to READY
0:00:04.242403999 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<play> child 'selector_video_src0' changed state to 2(READY) successfully
0:00:04.242420452 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<selector_audio_src1> completed state change to READY
0:00:04.242443162 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<play> child 'selector_audio_src1' changed state to 2(READY) successfully
0:00:04.242459485 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<fakesrc0> completed state change to READY
0:00:04.242472857 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<fakesrc0> posting state-changed NULL to READY
0:00:04.242496701 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<play> child 'fakesrc0' changed state to 2(READY) successfully
0:00:04.242514231 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<fakesrc1> completed state change to READY
0:00:04.242527731 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<fakesrc1> posting state-changed NULL to READY
0:00:04.242550250 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<play> child 'fakesrc1' changed state to 2(READY) successfully
0:00:04.242574154 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<typefind> completed state change to READY
0:00:04.242588702 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<decodebin0> child 'typefind' changed state to 2(READY) successfully
0:00:04.242604281 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<decodebin0> completed state change to READY
0:00:04.242617779 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<play> child 'decodebin0' changed state to 2(READY) successfully
0:00:04.242640138 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<source> completed state change to READY
0:00:04.242654754 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<source> posting state-changed PAUSED to READY
0:00:04.242677924 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<play> child 'source' changed state to 2(READY) successfully
0:00:04.242716958 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<typefind> completed state change to NULL
0:00:04.242731802 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<typefind> posting state-changed READY to NULL
0:00:04.242755446 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<decodebin0> child 'typefind' changed state to 1(NULL) successfully
0:00:04.242775423 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad typefind:src
0:00:04.242798833 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<decodebin0> completed state change to NULL
0:00:04.242812336 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<decodebin0> posting state-changed READY to NULL
0:00:04.242835125 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking source:src(0x9c38700) and decodebin0:sink(0x9f2c1c0)
0:00:04.242859999 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked source:src and decodebin0:sink
0:00:04.242888529 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<play> removed child "decodebin0"
0:00:04.242914348 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<fakesrc1> completed state change to NULL
0:00:04.242929060 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<fakesrc1> posting state-changed READY to NULL
0:00:04.242952347 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking fakesrc1:src(0xa6fe418) and selector_audio_src1:sink0(0xa811308)
0:00:04.242985840 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked fakesrc1:src and selector_audio_src1:sink0
0:00:04.243014275 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<play> removed child "fakesrc1"
0:00:04.243037108 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<preroll_audio_src1> completed state change to NULL
0:00:04.243051535 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<preroll_audio_src1> posting state-changed READY to NULL
0:00:04.243078872 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<selector_audio_src1> completed state change to NULL
0:00:04.243093581 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<selector_audio_src1> posting state-changed READY to NULL
0:00:04.243116454 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking preroll_audio_src1:src(0xa813488) and abin:sink(0xa73e380)
0:00:04.243140404 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked preroll_audio_src1:src and abin:sink
0:00:04.243182756 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking selector_audio_src1:src(0xa813308) and preroll_audio_src1:sink(0xa8133c8)
0:00:04.243206216 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked selector_audio_src1:src and preroll_audio_src1:sink
0:00:04.243233709 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<play> removed child "preroll_audio_src1"
0:00:04.243254199 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<play> removed child "selector_audio_src1"
0:00:04.243275800 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<fakesrc0> completed state change to NULL
0:00:04.243289966 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<fakesrc0> posting state-changed READY to NULL
0:00:04.243312887 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking fakesrc0:src(0xa6fe358) and selector_video_src0:sink0(0x9bb2bf0)
0:00:04.243336309 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked fakesrc0:src and selector_video_src0:sink0
0:00:04.243363900 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<play> removed child "fakesrc0"
0:00:04.243386074 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<preroll_video_src0> completed state change to NULL
0:00:04.243400333 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<preroll_video_src0> posting state-changed READY to NULL
0:00:04.243427580 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<selector_video_src0> completed state change to NULL
0:00:04.243442301 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<selector_video_src0> posting state-changed READY to NULL
0:00:04.243464760 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking preroll_video_src0:src(0xb4931a78) and vbin:sink(0xa73e620)
0:00:04.243488899 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked preroll_video_src0:src and vbin:sink
0:00:04.243514372 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking selector_video_src0:src(0xb49318f8) and preroll_video_src0:sink(0xb49319b8)
0:00:04.243536885 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked selector_video_src0:src and preroll_video_src0:sink
0:00:04.243563501 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<play> removed child "preroll_video_src0"
0:00:04.243583383 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<play> removed child "selector_video_src0"
0:00:04.243616228 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking ffdec_mpeg40:src(0xb4931838) and src0:proxypad8(0x9f155e8)
0:00:04.243660119 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking mad0:src(0xa813248) and src1:proxypad9(0xa73ec30)
0:00:04.243707166 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<source> completed state change to NULL
0:00:04.243722682 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<source> posting state-changed READY to NULL
0:00:04.243748354 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<play> removed child "source"
0:00:04.243880162 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autoaudiosink1-actual-sink-pulse> completed state change to NULL
0:00:04.243897071 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<autoaudiosink1-actual-sink-pulse> posting state-changed READY to NULL
0:00:04.243924222 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<autoaudiosink1> child 'autoaudiosink1-actual-sink-pulse' changed state to 1(NULL) successfully
0:00:04.243948549 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autoaudiosink1-actual-sink-pulse> completed state change to NULL
0:00:04.243963991 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad11(0xa73edd0) and autoaudiosink1-actual-sink-pulse:sink(0xa70e308)
0:00:04.243979957 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked sink:proxypad11 and autoaudiosink1-actual-sink-pulse:sink
0:00:04.243995893 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<autoaudiosink1> removed child "autoaudiosink1-actual-sink-pulse"
0:00:04.244029041 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "fakesink" named "tempsink"
0:00:04.244073193 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseSink@0x9f2a4e8> adding pad 'sink'
0:00:04.244107274 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad tempsink:sink
0:00:04.244122343 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad11(0xa73edd0) and autoaudiosink1-actual-sink-pulse:sink(0xa70e308)
0:00:04.244137284 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link sink:proxypad11 and tempsink:sink
0:00:04.245846793 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked sink:proxypad11 and tempsink:sink, successful
0:00:04.245866885 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autoaudiosink1> completed state change to NULL
0:00:04.245881021 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<autoaudiosink1> posting state-changed READY to NULL
0:00:04.245907524 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<bin4> child 'autoaudiosink1' changed state to 1(NULL) successfully
0:00:04.245929469 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<bin4> completed state change to NULL
0:00:04.245943285 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<bin4> posting state-changed READY to NULL
0:00:04.245967258 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<audio-sink> child 'bin4' changed state to 1(NULL) successfully
0:00:04.246002674 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "fakesink" named "testsink"
0:00:04.246044852 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseSink@0x9f2a008> adding pad 'sink'
0:00:04.246101875 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<testsink> completed state change to NULL
0:00:04.246137287 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<tempsink> completed state change to NULL
0:00:04.246152891 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<autoaudiosink1> child 'tempsink' changed state to 1(NULL) successfully
0:00:04.246169292 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autoaudiosink1> completed state change to NULL
0:00:04.246183063 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<bin4> child 'autoaudiosink1' changed state to 1(NULL) successfully
0:00:04.246198204 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<bin4> completed state change to NULL
0:00:04.246212808 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad0(0x9f15038) and bin4:sink(0xa73e540)
0:00:04.246229381 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked sink:proxypad0 and bin4:sink
0:00:04.246245365 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<audio-sink> removed child "bin4"
0:00:04.246262459 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad testsink:sink
0:00:04.246276206 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad0(0x9f15038) and bin4:sink(0xa73e540)
0:00:04.246290537 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link sink:proxypad0 and testsink:sink
0:00:04.247986397 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked sink:proxypad0 and testsink:sink, successful
0:00:04.248010755 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<audio-sink> completed state change to NULL
0:00:04.248026004 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<audio-sink> posting state-changed READY to NULL
0:00:04.248052291 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<audiosinkbin> child 'audio-sink' changed state to 1(NULL) successfully
0:00:04.248075859 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<audiofilter> completed state change to NULL
0:00:04.248089831 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<audiofilter> posting state-changed READY to NULL
0:00:04.248113570 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<audiosinkbin> child 'audiofilter' changed state to 1(NULL) successfully
0:00:04.248134547 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<audiosinkbin> completed state change to NULL
0:00:04.248148239 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<audiosinkbin> posting state-changed READY to NULL
0:00:04.248170932 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<abin> child 'audiosinkbin' changed state to 1(NULL) successfully
0:00:04.248194323 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<volume> completed state change to NULL
0:00:04.248208532 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<volume> posting state-changed READY to NULL
0:00:04.248231854 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<abin> child 'volume' changed state to 1(NULL) successfully
0:00:04.248254649 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<aresample> completed state change to NULL
0:00:04.248268572 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<aresample> posting state-changed READY to NULL
0:00:04.248301681 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<abin> child 'aresample' changed state to 1(NULL) successfully
0:00:04.248324215 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<aconv> completed state change to NULL
0:00:04.248338237 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<aconv> posting state-changed READY to NULL
0:00:04.248361373 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<abin> child 'aconv' changed state to 1(NULL) successfully
0:00:04.248382116 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<abin> completed state change to NULL
0:00:04.248395848 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<abin> posting state-changed READY to NULL
0:00:04.248420652 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<play> removed child "abin"
0:00:04.248441911 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad abin:sink
0:00:04.274167182 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autovideosink0-actual-sink-xvimage> completed state change to NULL
0:00:04.274251679 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<autovideosink0-actual-sink-xvimage> posting state-changed READY to NULL
0:00:04.274284875 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<autovideosink0> child 'autovideosink0-actual-sink-xvimage' changed state to 1(NULL) successfully
0:00:04.274310438 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autovideosink0-actual-sink-xvimage> completed state change to NULL
0:00:04.274326597 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad2(0x9f151d8) and autovideosink0-actual-sink-xvimage:sink(0x9c384c0)
0:00:04.274342845 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked sink:proxypad2 and autovideosink0-actual-sink-xvimage:sink
0:00:04.274358737 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<autovideosink0> removed child "autovideosink0-actual-sink-xvimage"
0:00:04.274394528 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "fakesink" named "tempsink"
0:00:04.274441719 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseSink@0x9f2b388> adding pad 'sink'
0:00:04.274475344 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad tempsink:sink
0:00:04.274490383 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad2(0x9f151d8) and autovideosink0-actual-sink-xvimage:sink(0x9c384c0)
0:00:04.274505572 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link sink:proxypad2 and tempsink:sink
0:00:04.290004207 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked sink:proxypad2 and tempsink:sink, successful
0:00:04.290089089 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autovideosink0> completed state change to NULL
0:00:04.290106487 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<autovideosink0> posting state-changed READY to NULL
0:00:04.290133976 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<bin0> child 'autovideosink0' changed state to 1(NULL) successfully
0:00:04.290156468 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<bin0> completed state change to NULL
0:00:04.290170382 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<bin0> posting state-changed READY to NULL
0:00:04.290205327 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<video-sink> child 'bin0' changed state to 1(NULL) successfully
0:00:04.290237629 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<tempsink> completed state change to NULL
0:00:04.290252839 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<autovideosink0> child 'tempsink' changed state to 1(NULL) successfully
0:00:04.290268695 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<autovideosink0> completed state change to NULL
0:00:04.290282435 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<bin0> child 'autovideosink0' changed state to 1(NULL) successfully
0:00:04.290297396 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<bin0> completed state change to NULL
0:00:04.290312181 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad1(0x9f15108) and bin0:sink(0x9f2c0e0)
0:00:04.290329242 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked sink:proxypad1 and bin0:sink
0:00:04.290345277 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<video-sink> removed child "bin0"
0:00:04.290377390 15760  0x9ab0490 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "fakesink" named "testsink"
0:00:04.290434302 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:639:gst_element_add_pad:<GstBaseSink@0x9f2b5f8> adding pad 'sink'
0:00:04.291179590 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad testsink:sink
0:00:04.291198109 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad1(0x9f15108) and bin0:sink(0x9f2c0e0)
0:00:04.291213997 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1805:gst_pad_link_prepare: trying to link sink:proxypad1 and testsink:sink
0:00:04.306773073 15760  0x9ab0490 INFO              GST_PADS gstpad.c:1942:gst_pad_link: linked sink:proxypad1 and testsink:sink, successful
0:00:04.306859166 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<video-sink> completed state change to NULL
0:00:04.306876473 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<video-sink> posting state-changed READY to NULL
0:00:04.306903216 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<vbin> child 'video-sink' changed state to 1(NULL) successfully
0:00:04.306927826 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<vscale> completed state change to NULL
0:00:04.306942190 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<vscale> posting state-changed READY to NULL
0:00:04.306965966 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<vbin> child 'vscale' changed state to 1(NULL) successfully
0:00:04.306988706 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<vconv> completed state change to NULL
0:00:04.307002722 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<vconv> posting state-changed READY to NULL
0:00:04.307025880 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<vbin> child 'vconv' changed state to 1(NULL) successfully
0:00:04.307060210 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<id> completed state change to NULL
0:00:04.307074988 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<id> posting state-changed READY to NULL
0:00:04.307098230 15760  0x9ab0490 INFO            GST_STATES gstbin.c:2355:gst_bin_change_state_func:<vbin> child 'id' changed state to 1(NULL) successfully
0:00:04.307130451 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<vbin> completed state change to NULL
0:00:04.307144425 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<vbin> posting state-changed READY to NULL
0:00:04.307169059 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<play> removed child "vbin"
0:00:04.307189117 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad vbin:sink
0:00:04.307204832 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2123:gst_element_continue_state:<play> committing state from READY to READY, pending NULL
0:00:04.307226576 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2132:gst_element_continue_state:<play> continue state change READY to NULL, final NULL
0:00:04.307283563 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<ffdec_mpeg40> dispose
0:00:04.307298674 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<ffdec_mpeg40> removing pad 'src'
0:00:04.307318582 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<ffdec_mpeg40> removing pad 'sink'
0:00:04.307337126 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<ffdec_mpeg40> parent class dispose
0:00:04.307353104 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<ffdec_mpeg40> finalize
0:00:04.307367148 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<ffdec_mpeg40> finalize parent
0:00:04.307383723 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<queue0> dispose
0:00:04.307397266 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<queue0> removing pad 'src'
0:00:04.307417597 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<queue0> removing pad 'sink'
0:00:04.307437523 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<queue0> parent class dispose
0:00:04.307455692 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<queue0> finalize
0:00:04.307469534 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<queue0> finalize parent
0:00:04.307486836 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<mad0> dispose
0:00:04.307500489 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<mad0> removing pad 'src'
0:00:04.307522381 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<mad0> removing pad 'sink'
0:00:04.307544326 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<mad0> parent class dispose
0:00:04.307559345 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<mad0> finalize
0:00:04.307572800 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<mad0> finalize parent
0:00:04.307593839 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<mpegaudioparse0> dispose
0:00:04.307607868 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<mpegaudioparse0> removing pad 'src'
0:00:04.307629882 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<mpegaudioparse0> removing pad 'sink'
0:00:04.307650697 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<mpegaudioparse0> parent class dispose
0:00:04.307665772 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<mpegaudioparse0> finalize
0:00:04.307679297 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<mpegaudioparse0> finalize parent
0:00:04.307695485 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<queue2> dispose
0:00:04.307719225 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<queue2> removing pad 'src'
0:00:04.307739832 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<queue2> removing pad 'sink'
0:00:04.307761214 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<queue2> parent class dispose
0:00:04.307779133 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<queue2> finalize
0:00:04.307792952 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<queue2> finalize parent
0:00:04.307811207 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<avidemux0> dispose
0:00:04.307824626 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<avidemux0> removing pad 'sink'
0:00:04.307844469 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<avidemux0> parent class dispose
0:00:04.307862298 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<avidemux0> finalize
0:00:04.307876156 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<avidemux0> finalize parent
0:00:04.308443293 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad7(0x9f15448) and typefind:sink(0xa6fe058)
0:00:04.308461323 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked sink:proxypad7 and typefind:sink
0:00:04.308477329 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<decodebin0> removed child "typefind"
0:00:04.308496733 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<typefind> dispose
0:00:04.308510717 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<typefind> removing pad 'src'
0:00:04.308531432 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<typefind> removing pad 'sink'
0:00:04.308547141 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<typefind> parent class dispose
0:00:04.308562564 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<typefind> finalize
0:00:04.308576100 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<typefind> finalize parent
0:00:04.308590399 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<decodebin0> dispose
0:00:04.308603662 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<decodebin0> removing pad 'sink'
0:00:04.308617201 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad7(0x9f15448) and '':sink(0xa6fe058)
0:00:04.308645708 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<decodebin0> parent class dispose
0:00:04.308662230 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<decodebin0> finalize
0:00:04.308675952 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<decodebin0> finalize parent
0:00:04.308692965 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<fakesrc1> dispose
0:00:04.308706400 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<fakesrc1> removing pad 'src'
0:00:04.308724220 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<fakesrc1> parent class dispose
0:00:04.308744054 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<fakesrc1> finalize
0:00:04.308757869 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<fakesrc1> finalize parent
0:00:04.308774156 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<preroll_audio_src1> dispose
0:00:04.308787789 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<preroll_audio_src1> removing pad 'src'
0:00:04.308818884 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<preroll_audio_src1> removing pad 'sink'
0:00:04.308839669 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<preroll_audio_src1> parent class dispose
0:00:04.308856154 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<preroll_audio_src1> finalize
0:00:04.308870174 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<preroll_audio_src1> finalize parent
0:00:04.308887106 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<selector_audio_src1> dispose
0:00:04.308900588 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<selector_audio_src1> removing pad 'sink0'
0:00:04.308920806 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<selector_audio_src1> removing pad 'src'
0:00:04.308938951 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<selector_audio_src1> parent class dispose
0:00:04.308954005 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<selector_audio_src1> finalize
0:00:04.308967508 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<selector_audio_src1> finalize parent
0:00:04.308983457 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<fakesrc0> dispose
0:00:04.308996819 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<fakesrc0> removing pad 'src'
0:00:04.309014798 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<fakesrc0> parent class dispose
0:00:04.309030861 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<fakesrc0> finalize
0:00:04.309044475 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<fakesrc0> finalize parent
0:00:04.309060544 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<preroll_video_src0> dispose
0:00:04.309074041 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<preroll_video_src0> removing pad 'src'
0:00:04.309094504 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<preroll_video_src0> removing pad 'sink'
0:00:04.309118631 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<preroll_video_src0> parent class dispose
0:00:04.309135900 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<preroll_video_src0> finalize
0:00:04.309149628 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<preroll_video_src0> finalize parent
0:00:04.309166590 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<selector_video_src0> dispose
0:00:04.309180261 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<selector_video_src0> removing pad 'sink0'
0:00:04.309199312 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<selector_video_src0> removing pad 'src'
0:00:04.309217654 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<selector_video_src0> parent class dispose
0:00:04.309232631 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<selector_video_src0> finalize
0:00:04.309246146 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<selector_video_src0> finalize parent
0:00:04.309262314 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<source> dispose
0:00:04.309275736 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<source> removing pad 'src'
0:00:04.309293254 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<source> parent class dispose
0:00:04.309319504 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<source> finalize
0:00:04.309333443 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<source> finalize parent
0:00:04.309353159 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad12(0xa73eea0) and autoaudiosink1:sink(0xa73e460)
0:00:04.309370010 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked sink:proxypad12 and autoaudiosink1:sink
0:00:04.309385668 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<bin4> removed child "autoaudiosink1"
0:00:04.309407253 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<tempsink> completed state change to NULL
0:00:04.309422902 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad11(0xa73edd0) and tempsink:sink(0xb4931b38)
0:00:04.309438157 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked sink:proxypad11 and tempsink:sink
0:00:04.309453488 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<autoaudiosink1> removed child "tempsink"
0:00:04.309468551 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<tempsink> dispose
0:00:04.309481835 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<tempsink> removing pad 'sink'
0:00:04.309496883 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<tempsink> parent class dispose
0:00:04.309512385 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<tempsink> finalize
0:00:04.309526089 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<tempsink> finalize parent
0:00:04.309543718 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<autoaudiosink1> dispose
0:00:04.309557251 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<autoaudiosink1> removing pad 'sink'
0:00:04.309572029 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<autoaudiosink1> parent class dispose
0:00:04.309586403 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<autoaudiosink1> finalize
0:00:04.309599564 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<autoaudiosink1> finalize parent
0:00:04.309613740 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<bin4> dispose
0:00:04.309626697 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<bin4> removing pad 'sink'
0:00:04.309640146 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad12(0xa73eea0) and '':sink(0xa73e460)
0:00:04.309654837 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad11(0xa73edd0) and '':sink(0xb4931b38)
0:00:04.309687505 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<bin4> parent class dispose
0:00:04.309702770 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<bin4> finalize
0:00:04.309715907 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<bin4> finalize parent
0:00:04.309739371 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<autovideosink0-actual-sink-xvimage> dispose
0:00:04.309753165 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<autovideosink0-actual-sink-xvimage> removing pad 'sink'
0:00:04.309771058 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<autovideosink0-actual-sink-xvimage> parent class dispose
0:00:04.309788134 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<autovideosink0-actual-sink-xvimage> finalize
0:00:04.309812071 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<autovideosink0-actual-sink-xvimage> finalize parent
0:00:04.309831230 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad3(0x9f152a8) and autovideosink0:sink(0x9f2c000)
0:00:04.309847770 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked sink:proxypad3 and autovideosink0:sink
0:00:04.309863310 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<bin0> removed child "autovideosink0"
0:00:04.309884553 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<tempsink> completed state change to NULL
0:00:04.309900052 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad2(0x9f151d8) and tempsink:sink(0x9c38640)
0:00:04.309915028 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1621:gst_pad_unlink: unlinked sink:proxypad2 and tempsink:sink
0:00:04.309930376 15760  0x9ab0490 INFO         GST_PARENTAGE gstbin.c:1338:gst_bin_remove_func:<autovideosink0> removed child "tempsink"
0:00:04.309945482 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<tempsink> dispose
0:00:04.309958757 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<tempsink> removing pad 'sink'
0:00:04.309973437 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<tempsink> parent class dispose
0:00:04.309988599 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<tempsink> finalize
0:00:04.310002264 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<tempsink> finalize parent
0:00:04.310019869 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<autovideosink0> dispose
0:00:04.310033468 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<autovideosink0> removing pad 'sink'
0:00:04.310048211 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<autovideosink0> parent class dispose
0:00:04.310062641 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<autovideosink0> finalize
0:00:04.310075826 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<autovideosink0> finalize parent
0:00:04.310090076 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<bin0> dispose
0:00:04.310103001 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<bin0> removing pad 'sink'
0:00:04.310116574 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad3(0x9f152a8) and '':sink(0x9f2c000)
0:00:04.310131164 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstpad.c:1578:gst_pad_unlink: unlinking sink:proxypad2(0x9f151d8) and '':sink(0x9c38640)
0:00:04.310163219 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<bin0> parent class dispose
0:00:04.310178586 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<bin0> finalize
0:00:04.310191954 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<bin0> finalize parent
0:00:04.310215367 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad abin:sink
0:00:04.310230382 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:885:gst_element_get_static_pad: found pad vbin:sink
0:00:04.310244701 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2150:gst_element_continue_state:<play> completed state change to NULL
0:00:04.310257710 15760  0x9ab0490 INFO            GST_STATES gstelement.c:2163:gst_element_continue_state:<play> posting state-changed READY to NULL
0:00:04.357157969 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2724:gst_element_dispose:<autoaudiosink1-actual-sink-pulse> dispose
0:00:04.357301650 15760  0x9ab0490 INFO      GST_ELEMENT_PADS gstelement.c:757:gst_element_remove_pad:<autoaudiosink1-actual-sink-pulse> removing pad 'sink'
0:00:04.357328599 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2748:gst_element_dispose:<autoaudiosink1-actual-sink-pulse> parent class dispose
0:00:04.357401813 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2771:gst_element_finalize:<autoaudiosink1-actual-sink-pulse> finalize
0:00:04.357418989 15760  0x9ab0490 INFO       GST_REFCOUNTING gstelement.c:2782:gst_element_finalize:<autoaudiosink1-actual-sink-pulse> finalize parent