root@193.167.244.115:/opt/mpeg2test# GST_DEBUG=3 ./avividdectest /data/kiss.avi 1
0:00:00.003932000 1180 0x16cc8 INFO GST_INIT gst.c:542:init_pre: Initializing GStreamer Core Library version 0.10.11
0:00:00.007517000 1180 0x16cc8 INFO GST_INIT gst.c:543:init_pre: Using library installed in /opt/gstreamer/lib
0:00:00.010208000 1180 0x16cc8 INFO GST_INIT gstquery.c:101:_gst_query_initialize: init queries
0:00:00.020319000 1180 0x16cc8 INFO GST_INIT gstmessage.c:72:_gst_message_initialize: init messages
0:00:00.041878000 1180 0x16cc8 INFO GST_PLUGIN_LOADING gstplugin.c:182:_gst_plugin_register_static: loaded static plugin "staticelements"
0:00:01.453979000 1181 0x16cc8 INFO GST_REGISTRY gstregistryxml.c:576:gst_registry_xml_read_cache: loaded /root/.gstreamer-0.10/registry.arm.xml in 1.364817 seconds
0:00:01.513979000 1181 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libSM.so.6: cannot open shared object file: No such file or directory
0:00:01.546286000 1181 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libasound.so.2: cannot open shared object file: No such file or directory
0:00:01.557062000 1181 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libASFParser.so: cannot open shared object file: No such file or directory
0:00:01.583863000 1181 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libSM.so.6: cannot open shared object file: No such file or directory
0:00:01.622112000 1181 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libSM.so.6: cannot open shared object file: No such file or directory
0:00:01.758895000 1181 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libSM.so.6: cannot open shared object file: No such file or directory
0:00:02.119326000 1181 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libSM.so.6: cannot open shared object file: No such file or directory
0:00:02.134162000 1181 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libasound.so.2: cannot open shared object file: No such file or directory
0:00:02.152441000 1181 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libASFParser.so: cannot open shared object file: No such file or directory
0:00:02.176329000 1181 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libSM.so.6: cannot open shared object file: No such file or directory
0:00:02.198521000 1181 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libSM.so.6: cannot open shared object file: No such file or directory
0:00:02.224823000 1181 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libSM.so.6: cannot open shared object file: No such file or directory
0:00:02.313986000 1181 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: /opt/gstreamer/lib/python2.3/site-packages/libxml2mod.so: undefined symbol: _Py_NoneStruct
0:00:02.399890000 1181 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libSM.so.6: cannot open shared object file: No such file or directory
0:00:02.408516000 1181 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libasound.so.2: cannot open shared object file: No such file or directory
0:00:02.416942000 1181 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libASFParser.so: cannot open shared object file: No such file or directory
0:00:02.430733000 1181 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libSM.so.6: cannot open shared object file: No such file or directory
0:00:02.442789000 1181 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libSM.so.6: cannot open shared object file: No such file or directory
0:00:02.479030000 1181 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libSM.so.6: cannot open shared object file: No such file or directory
0:00:02.486002000 1181 0x16cc8 INFO GST_INIT gst.c:662:scan_and_update_registry: Registry cache has not changed
0:00:03.853960000 1180 0x16cc8 INFO GST_REGISTRY gstregistryxml.c:576:gst_registry_xml_read_cache: loaded /root/.gstreamer-0.10/registry.arm.xml in 1.363485 seconds
0:00:03.856290000 1180 0x16cc8 INFO GST_INIT gst.c:387:gst_init_check: initialized GStreamer successfully
vesion 20070907
0:00:05.865851000 1180 0x16cc8 INFO GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "pipeline" named "pipe"
0:00:05.956446000 1180 0x16cc8 INFO GST_PLUGIN_LOADING gstplugin.c:467:gst_plugin_load_file: plugin "/opt/gstreamer/lib/gstreamer-0.10/bak/libgstcoreelements.so" loaded
0:00:05.958507000 1180 0x16cc8 INFO GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "filesrc" named "source"
0:00:05.963975000 1180 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<GstBaseSrc@0xa3058> adding pad 'src'
0:00:06.123621000 1180 0x16cc8 INFO GST_PLUGIN_LOADING gstplugin.c:467:gst_plugin_load_file: plugin "/opt/gstreamer/lib/gstreamer-0.10/libgstavi.so" loaded
0:00:06.125831000 1180 0x16cc8 INFO GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "avidemux" named "avi_demux"
0:00:06.129284000 1180 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<GstAviDemux@0xb2350> adding pad 'sink'
0:00:06.132502000 1180 0x16cc8 INFO GST_ELEMENT_PADS gstutils.c:1363:gst_element_link_pads: trying to link element source:(any) to element avi_demux:(any)
0:00:06.134362000 1180 0x16cc8 INFO GST_PADS gstutils.c:2036:gst_pad_can_link: trying to link source:src and avi_demux:sink
0:00:06.136103000 1180 0x16cc8 INFO GST_PADS gstutils.c:1267:prepare_link_maybe_ghosting: source and avi_demux in same bin, no need for ghost pads
0:00:06.137822000 1180 0x16cc8 INFO GST_PADS gstpad.c:1727:gst_pad_link_prepare: trying to link source:src and avi_demux:sink
0:00:06.139725000 1180 0x16cc8 INFO GST_PADS gstpad.c:1864:gst_pad_link: linked source:src and avi_demux:sink, successful
0:00:06.141870000 1180 0x16cc8 INFO GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "queue" named "a_queue"
0:00:06.145546000 1180 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<GstQueue@0xa1820> adding pad 'sink'
0:00:06.148781000 1180 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<GstQueue@0xa1820> adding pad 'src'
0:00:06.151718000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<a_queue> completed state change
0:00:06.154296000 1180 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:849:gst_element_get_static_pad: found pad a_queue:src
0:00:06.156377000 1180 0x16cc8 INFO GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "fakesink" named "fakesink"
0:00:06.159471000 1180 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<GstBaseSink@0xb4100> adding pad 'sink'
0:00:06.162149000 1180 0x16cc8 INFO GST_ELEMENT_PADS gstutils.c:1363:gst_element_link_pads: trying to link element a_queue:(any) to element fakesink:(any)
0:00:06.164170000 1180 0x16cc8 INFO GST_PADS gstutils.c:2036:gst_pad_can_link: trying to link a_queue:src and fakesink:sink
0:00:06.165927000 1180 0x16cc8 INFO GST_PADS gstutils.c:1267:prepare_link_maybe_ghosting: a_queue and fakesink in same bin, no need for ghost pads
0:00:06.167536000 1180 0x16cc8 INFO GST_PADS gstpad.c:1727:gst_pad_link_prepare: trying to link a_queue:src and fakesink:sink
0:00:06.169439000 1180 0x16cc8 INFO GST_PADS gstpad.c:1864:gst_pad_link: linked a_queue:src and fakesink:sink, successful
0:00:06.171167000 1180 0x16cc8 INFO GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "queue" named "v_queue"
0:00:06.174593000 1180 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<GstQueue@0xa1918> adding pad 'sink'
0:00:06.177892000 1180 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<GstQueue@0xa1918> adding pad 'src'
0:00:06.180353000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<v_queue> completed state change
0:00:06.182559000 1180 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:849:gst_element_get_static_pad: found pad v_queue:src
0:00:06.245473000 1180 0x16cc8 INFO GST_PLUGIN_LOADING gstplugin.c:467:gst_plugin_load_file: plugin "/opt/gstreamer/lib/gstreamer-0.10/libgstgdecoder.so" loaded
0:00:06.247413000 1180 0x16cc8 INFO GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "gdecoder" named "v_dec"
0:00:06.250331000 1180 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<GstGDecoder@0xb6008> adding pad 'sink'
0:00:06.253048000 1180 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<GstGDecoder@0xb6008> adding pad 'src'
0:00:06.255819000 1180 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:849:gst_element_get_static_pad: found pad v_dec:sink
0:00:06.257410000 1180 0x16cc8 INFO GST_PADS gstpad.c:1727:gst_pad_link_prepare: trying to link v_queue:src and v_dec:sink
sink getcaps invoked
0:00:06.261448000 1180 0x16cc8 INFO GST_PADS gstpad.c:1864:gst_pad_link: linked v_queue:src and v_dec:sink, successful
0:00:06.263470000 1180 0x16cc8 INFO GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "queue" named "vsinkqueue"
0:00:06.266910000 1180 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<GstQueue@0xa1a10> adding pad 'sink'
0:00:06.270102000 1180 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<GstQueue@0xa1a10> adding pad 'src'
0:00:06.311766000 1180 0x16cc8 INFO GST_PLUGIN_LOADING gstplugin.c:467:gst_plugin_load_file: plugin "/opt/gstreamer/lib/gstreamer-0.10/bak/libgstfbvideosink.so" loaded
0:00:06.313889000 1180 0x16cc8 INFO GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "fbvideosink" named "videosink"
0:00:06.317025000 1180 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<GstBaseSink@0xba128> adding pad 'sink'
0:00:06.320456000 1180 0x16cc8 INFO GST_ELEMENT_PADS gstutils.c:1363:gst_element_link_pads: trying to link element v_dec:(any) to element vsinkqueue:(any)
0:00:06.322201000 1180 0x16cc8 INFO GST_PADS gstutils.c:2036:gst_pad_can_link: trying to link v_dec:src and vsinkqueue:src
0:00:06.324114000 1180 0x16cc8 INFO GST_PADS gstutils.c:2056:gst_pad_can_link: Sink pad vsinkqueue:src is not sink pad, failed
0:00:06.325860000 1180 0x16cc8 INFO GST_PADS gstutils.c:2036:gst_pad_can_link: trying to link v_dec:src and vsinkqueue:sink
0:00:06.327491000 1180 0x16cc8 INFO GST_PADS gstutils.c:1267:prepare_link_maybe_ghosting: v_dec and vsinkqueue in same bin, no need for ghost pads
0:00:06.329098000 1180 0x16cc8 INFO GST_PADS gstpad.c:1727:gst_pad_link_prepare: trying to link v_dec:src and vsinkqueue:sink
0:00:06.331229000 1180 0x16cc8 INFO GST_PADS gstpad.c:1864:gst_pad_link: linked v_dec:src and vsinkqueue:sink, successful
0:00:06.332898000 1180 0x16cc8 INFO GST_ELEMENT_PADS gstutils.c:1363:gst_element_link_pads: trying to link element vsinkqueue:(any) to element videosink:(any)
0:00:06.334704000 1180 0x16cc8 INFO GST_PADS gstutils.c:2036:gst_pad_can_link: trying to link vsinkqueue:src and videosink:sink
0:00:06.336351000 1180 0x16cc8 INFO GST_PADS gstutils.c:1267:prepare_link_maybe_ghosting: vsinkqueue and videosink in same bin, no need for ghost pads
0:00:06.338313000 1180 0x16cc8 INFO GST_PADS gstpad.c:1727:gst_pad_link_prepare: trying to link vsinkqueue:src and videosink:sink
0:00:06.341925000 1180 0x16cc8 INFO GST_PADS gstpad.c:1864:gst_pad_link: linked vsinkqueue:src and videosink:sink, successful
Codec set to enum value 1
0:00:06.345925000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<videosink> completed state change
0:00:06.347517000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'videosink' changed state to 1(NULL) successfully
0:00:06.349364000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<fakesink> completed state change
0:00:06.350909000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'fakesink' changed state to 1(NULL) successfully
0:00:06.352744000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<vsinkqueue> completed state change
0:00:06.354297000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'vsinkqueue' changed state to 1(NULL) successfully
0:00:06.356819000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<a_queue> completed state change
0:00:06.358708000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'a_queue' changed state to 1(NULL) successfully
0:00:06.360527000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<v_dec> completed state change
0:00:06.362078000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'v_dec' changed state to 1(NULL) successfully
0:00:06.364065000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<v_queue> completed state change
0:00:06.366016000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'v_queue' changed state to 1(NULL) successfully
0:00:06.367907000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<avi_demux> completed state change
0:00:06.369451000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'avi_demux' changed state to 1(NULL) successfully
0:00:06.371492000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<source> completed state change
0:00:06.373058000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'source' changed state to 1(NULL) successfully
0:00:06.375002000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<pipe> completed state change
PLAY pipeline
0:00:06.380122000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<videosink> completed state change
0:00:06.381982000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'videosink' changed state to 2(READY) successfully
0:00:06.383828000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<fakesink> completed state change
0:00:06.385747000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'fakesink' changed state to 2(READY) successfully
0:00:06.387579000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<vsinkqueue> completed state change
0:00:06.389763000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'vsinkqueue' changed state to 2(READY) successfully
0:00:06.391522000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<a_queue> completed state change
0:00:06.393384000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'a_queue' changed state to 2(READY) successfully
0:00:06.395249000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<v_dec> completed state change
0:00:06.397109000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'v_dec' changed state to 2(READY) successfully
0:00:06.398844000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<v_queue> completed state change
0:00:06.400703000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'v_queue' changed state to 2(READY) successfully
0:00:06.402513000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<avi_demux> completed state change
0:00:06.404773000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'avi_demux' changed state to 2(READY) successfully
0:00:06.406576000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<source> completed state change
0:00:06.408417000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'source' changed state to 2(READY) successfully
0:00:06.410164000 1180 0x16cc8 INFO GST_STATES gstelement.c:1969:gst_element_continue_state:<pipe> committing state from NULL to READY, pending PLAYING
0:00:06.412083000 1180 0x16cc8 INFO GST_STATES gstelement.c:1978:gst_element_continue_state:<pipe> continue state change READY to PAUSED, final PLAYING
0:00:06.414713000 1180 0x16cc8 INFO GST_STATES gstbin.c:1936:gst_bin_change_state_func:<pipe> child 'videosink' is changing state asynchronously
0:00:06.416733000 1180 0x16cc8 INFO GST_STATES gstbin.c:1936:gst_bin_change_state_func:<pipe> child 'fakesink' is changing state asynchronously
0:00:06.421207000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<vsinkqueue> completed state change
0:00:06.423392000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'vsinkqueue' changed state to 3(PAUSED) successfully
0:00:06.426508000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<a_queue> completed state change
0:00:06.428360000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'a_queue' changed state to 3(PAUSED) successfully
Activate invoked
streaming mode...
0:00:06.431570000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<v_dec> completed state change
0:00:06.433415000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'v_dec' changed state to 3(PAUSED) successfully
0:00:06.436510000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<v_queue> completed state change
0:00:06.438368000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'v_queue' changed state to 3(PAUSED) successfully
0:00:06.440395000 1180 0x16cc8 INFO filesrc gstfilesrc.c:906:gst_file_src_start:<source> opening file /data/kiss.avi
0:00:06.466776000 1180 0x16cc8 INFO filesrc gstfilesrc.c:906:gst_file_src_start:<source> opening file /data/kiss.avi
volter: gst_avi_demux_sink_activate_pull-->loop schedule...
0:00:06.471379000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<avi_demux> completed state change
0:00:06.473252000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'avi_demux' changed state to 3(PAUSED) successfully
0:00:06.475242000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<source> completed state change
0:00:06.477242000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'source' changed state to 3(PAUSED) successfully
Running
0:00:06.485510000 1180 0xbe368 INFO avidemux gstavidemux.c:779:gst_avi_demux_parse_avih:<avi_demux> avih tag found:
0:00:06.487226000 1180 0xbe368 INFO avidemux gstavidemux.c:780:gst_avi_demux_parse_avih:<avi_demux> us_frame 41708
0:00:06.488790000 1180 0xbe368 INFO avidemux gstavidemux.c:781:gst_avi_demux_parse_avih:<avi_demux> max_bps 0
0:00:06.490570000 1180 0xbe368 INFO avidemux gstavidemux.c:782:gst_avi_demux_parse_avih:<avi_demux> pad_gran 0
0:00:06.492065000 1180 0xbe368 INFO avidemux gstavidemux.c:783:gst_avi_demux_parse_avih:<avi_demux> flags 0x00000910
0:00:06.493562000 1180 0xbe368 INFO avidemux gstavidemux.c:784:gst_avi_demux_parse_avih:<avi_demux> tot_frames 11508
0:00:06.495111000 1180 0xbe368 INFO avidemux gstavidemux.c:785:gst_avi_demux_parse_avih:<avi_demux> init_frames 0
0:00:06.496586000 1180 0xbe368 INFO avidemux gstavidemux.c:786:gst_avi_demux_parse_avih:<avi_demux> streams 2
0:00:06.498059000 1180 0xbe368 INFO avidemux gstavidemux.c:787:gst_avi_demux_parse_avih:<avi_demux> bufsize 0
0:00:06.499531000 1180 0xbe368 INFO avidemux gstavidemux.c:788:gst_avi_demux_parse_avih:<avi_demux> width 320
0:00:06.501007000 1180 0xbe368 INFO avidemux gstavidemux.c:789:gst_avi_demux_parse_avih:<avi_demux> height 240
0:00:06.502466000 1180 0xbe368 INFO avidemux gstavidemux.c:790:gst_avi_demux_parse_avih:<avi_demux> scale 0
0:00:06.504248000 1180 0xbe368 INFO avidemux gstavidemux.c:791:gst_avi_demux_parse_avih:<avi_demux> rate 0
0:00:06.505850000 1180 0xbe368 INFO avidemux gstavidemux.c:792:gst_avi_demux_parse_avih:<avi_demux> start 0
0:00:06.507322000 1180 0xbe368 INFO avidemux gstavidemux.c:793:gst_avi_demux_parse_avih:<avi_demux> length 0
0:00:06.509106000 1180 0xbe368 INFO riff riff-read.c:297:gst_riff_parse_strh:<avi_demux> strh tag found:
0:00:06.510540000 1180 0xbe368 INFO riff riff-read.c:299:gst_riff_parse_strh:<avi_demux> type vids
0:00:06.512033000 1180 0xbe368 INFO riff riff-read.c:301:gst_riff_parse_strh:<avi_demux> fcc_handler DIVX
0:00:06.513522000 1180 0xbe368 INFO riff riff-read.c:302:gst_riff_parse_strh:<avi_demux> flags 0x00000000
0:00:06.515068000 1180 0xbe368 INFO riff riff-read.c:303:gst_riff_parse_strh:<avi_demux> priority 0
0:00:06.516527000 1180 0xbe368 INFO riff riff-read.c:304:gst_riff_parse_strh:<avi_demux> init_frames 0
0:00:06.517974000 1180 0xbe368 INFO riff riff-read.c:305:gst_riff_parse_strh:<avi_demux> scale 1001
0:00:06.519742000 1180 0xbe368 INFO riff riff-read.c:306:gst_riff_parse_strh:<avi_demux> rate 24000
0:00:06.521223000 1180 0xbe368 INFO riff riff-read.c:307:gst_riff_parse_strh:<avi_demux> start 0
0:00:06.522696000 1180 0xbe368 INFO riff riff-read.c:308:gst_riff_parse_strh:<avi_demux> length 11508
0:00:06.524163000 1180 0xbe368 INFO riff riff-read.c:309:gst_riff_parse_strh:<avi_demux> bufsize 18383
0:00:06.525759000 1180 0xbe368 INFO riff riff-read.c:310:gst_riff_parse_strh:<avi_demux> quality 0
0:00:06.527223000 1180 0xbe368 INFO riff riff-read.c:311:gst_riff_parse_strh:<avi_demux> samplesize 0
0:00:06.528833000 1180 0xbe368 INFO riff riff-read.c:388:gst_riff_parse_strf_vids:<avi_demux> strf tag found in context vids:
0:00:06.530313000 1180 0xbe368 INFO riff riff-read.c:389:gst_riff_parse_strf_vids:<avi_demux> size 40
0:00:06.531784000 1180 0xbe368 INFO riff riff-read.c:390:gst_riff_parse_strf_vids:<avi_demux> width 320
0:00:06.533475000 1180 0xbe368 INFO riff riff-read.c:391:gst_riff_parse_strf_vids:<avi_demux> height 240
0:00:06.535320000 1180 0xbe368 INFO riff riff-read.c:392:gst_riff_parse_strf_vids:<avi_demux> planes 1
0:00:06.536804000 1180 0xbe368 INFO riff riff-read.c:393:gst_riff_parse_strf_vids:<avi_demux> bit_cnt 24
0:00:06.538274000 1180 0xbe368 INFO riff riff-read.c:395:gst_riff_parse_strf_vids:<avi_demux> compression DIVX
0:00:06.539772000 1180 0xbe368 INFO riff riff-read.c:396:gst_riff_parse_strf_vids:<avi_demux> image_size 230400
0:00:06.541265000 1180 0xbe368 INFO riff riff-read.c:397:gst_riff_parse_strf_vids:<avi_demux> xpels_meter 0
0:00:06.542736000 1180 0xbe368 INFO riff riff-read.c:398:gst_riff_parse_strf_vids:<avi_demux> ypels_meter 0
0:00:06.544207000 1180 0xbe368 INFO riff riff-read.c:399:gst_riff_parse_strf_vids:<avi_demux> num_colors 0
0:00:06.545916000 1180 0xbe368 INFO riff riff-read.c:400:gst_riff_parse_strf_vids:<avi_demux> imp_colors 0
0:00:06.549350000 1180 0xbe368 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<avi_demux> adding pad 'video_00'
Dynamic pad created, linking demuxer and a_queue
0:00:06.552215000 1180 0xbe368 INFO GST_ELEMENT_PADS gstelement.c:849:gst_element_get_static_pad: found pad a_queue:sink
0:00:06.553770000 1180 0xbe368 INFO GST_PADS gstpad.c:1727:gst_pad_link_prepare: trying to link avi_demux:video_00 and a_queue:sink
0:00:06.556879000 1180 0xbe368 INFO GST_PADS gstpad.c:1864:gst_pad_link: linked avi_demux:video_00 and a_queue:sink, successful
Dynamic pad created, linking demuxer and v_queue
0:00:06.559361000 1180 0xbe368 INFO GST_ELEMENT_PADS gstelement.c:849:gst_element_get_static_pad: found pad v_queue:sink
0:00:06.560925000 1180 0xbe368 INFO GST_PADS gstpad.c:1727:gst_pad_link_prepare: trying to link avi_demux:video_00 and v_queue:sink
0:00:06.562547000 1180 0xbe368 INFO GST_PADS gstpad.c:1768:gst_pad_link_prepare: src avi_demux:video_00 was already linked to a_queue:sink
0:00:06.564791000 1180 0xbe368 INFO riff riff-read.c:297:gst_riff_parse_strh:<avi_demux> strh tag found:
0:00:06.566263000 1180 0xbe368 INFO riff riff-read.c:299:gst_riff_parse_strh:<avi_demux> type auds
0:00:06.568076000 1180 0xbe368 INFO riff riff-read.c:301:gst_riff_parse_strh:<avi_demux> fcc_handler U
0:00:06.569591000 1180 0xbe368 INFO riff riff-read.c:302:gst_riff_parse_strh:<avi_demux> flags 0x00000000
0:00:06.571078000 1180 0xbe368 INFO riff riff-read.c:303:gst_riff_parse_strh:<avi_demux> priority 0
0:00:06.572551000 1180 0xbe368 INFO riff riff-read.c:304:gst_riff_parse_strh:<avi_demux> init_frames 20
0:00:06.574016000 1180 0xbe368 INFO riff riff-read.c:305:gst_riff_parse_strh:<avi_demux> scale 1
0:00:06.575556000 1180 0xbe368 INFO riff riff-read.c:306:gst_riff_parse_strh:<avi_demux> rate 16000
0:00:06.577024000 1180 0xbe368 INFO riff riff-read.c:307:gst_riff_parse_strh:<avi_demux> start 0
0:00:06.578489000 1180 0xbe368 INFO riff riff-read.c:308:gst_riff_parse_strh:<avi_demux> length 7687941
0:00:06.579961000 1180 0xbe368 INFO riff riff-read.c:309:gst_riff_parse_strh:<avi_demux> bufsize 418
0:00:06.581438000 1180 0xbe368 INFO riff riff-read.c:310:gst_riff_parse_strh:<avi_demux> quality 0
0:00:06.583210000 1180 0xbe368 INFO riff riff-read.c:311:gst_riff_parse_strh:<avi_demux> samplesize 1
0:00:06.584850000 1180 0xbe368 INFO riff riff-read.c:482:gst_riff_parse_strf_auds:<avi_demux> strf tag found in context auds:
0:00:06.586342000 1180 0xbe368 INFO riff riff-read.c:483:gst_riff_parse_strf_auds:<avi_demux> format 85
0:00:06.587829000 1180 0xbe368 INFO riff riff-read.c:484:gst_riff_parse_strf_auds:<avi_demux> channels 2
0:00:06.589316000 1180 0xbe368 INFO riff riff-read.c:485:gst_riff_parse_strf_auds:<avi_demux> rate 44100
0:00:06.590810000 1180 0xbe368 INFO riff riff-read.c:486:gst_riff_parse_strf_auds:<avi_demux> av_bps 16000
0:00:06.592396000 1180 0xbe368 INFO riff riff-read.c:487:gst_riff_parse_strf_auds:<avi_demux> blockalign 1
0:00:06.593885000 1180 0xbe368 INFO riff riff-read.c:488:gst_riff_parse_strf_auds:<avi_demux> size 0
0:00:06.595431000 1180 0xbe368 INFO riff riff-read.c:490:gst_riff_parse_strf_auds:<avi_demux> 12 bytes extradata
0:00:06.599048000 1180 0xbe368 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<avi_demux> adding pad 'audio_00'
Dynamic pad created, linking demuxer and a_queue
0:00:06.601550000 1180 0xbe368 INFO GST_ELEMENT_PADS gstelement.c:849:gst_element_get_static_pad: found pad a_queue:sink
0:00:06.603102000 1180 0xbe368 INFO GST_PADS gstpad.c:1727:gst_pad_link_prepare: trying to link avi_demux:audio_00 and a_queue:sink
0:00:06.604774000 1180 0xbe368 INFO GST_PADS gstpad.c:1785:gst_pad_link_prepare: sink a_queue:sink was already linked to avi_demux:video_00
Dynamic pad created, linking demuxer and v_queue
0:00:06.607260000 1180 0xbe368 INFO GST_ELEMENT_PADS gstelement.c:849:gst_element_get_static_pad: found pad v_queue:sink
0:00:06.608799000 1180 0xbe368 INFO GST_PADS gstpad.c:1727:gst_pad_link_prepare: trying to link avi_demux:audio_00 and v_queue:sink
sink getcaps invoked
0:00:06.612815000 1180 0xbe368 INFO GST_PADS gstpad.c:1801:gst_pad_link_prepare: caps are incompatible
0:00:06.616689000 1180 0xbe368 WARN avidemux gstavidemux.c:1617:gst_avi_demux_stream_index:<avi_demux> skip LIST chunk
0:00:07.973973000 1180 0xbe368 INFO avidemux gstavidemux.c:2290:gst_avi_demux_calculate_durations_from_index: Stream 0 duration according to header: 0:07:59.979500000
0:00:07.975887000 1180 0xbe368 INFO avidemux gstavidemux.c:2310:gst_avi_demux_calculate_durations_from_index: Stream 0 duration according to index: 0:07:59.979500000
0:00:07.977508000 1180 0xbe368 INFO avidemux gstavidemux.c:2290:gst_avi_demux_calculate_durations_from_index: Stream 1 duration according to header: 0:08:00.496312500
0:00:07.979103000 1180 0xbe368 INFO avidemux gstavidemux.c:2310:gst_avi_demux_calculate_durations_from_index: Stream 1 duration according to index: 0:08:00.496312500
0:00:07.980766000 1180 0xbe368 INFO avidemux gstavidemux.c:2340:gst_avi_demux_calculate_durations_from_index: Setting total duration to: 0:08:00.496312500
0:00:07.982490000 1180 0xbe368 INFO GST_EVENT gstevent.c:527:gst_event_new_new_segment_full: creating newsegment update 0, rate 1.000000, format GST_FORMAT_TIME, start 0:00:00.000000000, stop 0:08:00.496312500, position 0:00:00.000000000
0:00:07.998544000 1180 0x14a2d0 INFO GST_STATES gstbin.c:1321:gst_bin_recalc_state:<pipe> recalc state
0:00:08.000431000 1180 0x14a2d0 INFO GST_STATES gstbin.c:1331:gst_bin_recalc_state:<pipe> checking element states
0:00:08.002536000 1180 0x14a2d0 INFO GST_STATES gstbin.c:1421:gst_bin_recalc_state:<pipe> bin RETURN is now ASYNC
/
root@193.167.244.115:/opt/mpeg2test#
root@193.167.244.115:/opt/mpeg2test#
root@193.167.244.115:/opt/mpeg2test# GST_DEBUG=3 ./avividdectest /data/kiss.avi 1
0:00:00.003987000 1187 0x16cc8 INFO GST_INIT gst.c:542:init_pre: Initializing GStreamer Core Library version 0.10.11
0:00:00.007564000 1187 0x16cc8 INFO GST_INIT gst.c:543:init_pre: Using library installed in /opt/gstreamer/lib
0:00:00.010256000 1187 0x16cc8 INFO GST_INIT gstquery.c:101:_gst_query_initialize: init queries
0:00:00.020426000 1187 0x16cc8 INFO GST_INIT gstmessage.c:72:_gst_message_initialize: init messages
0:00:00.041983000 1187 0x16cc8 INFO GST_PLUGIN_LOADING gstplugin.c:182:_gst_plugin_register_static: loaded static plugin "staticelements"
0:00:01.432495000 1188 0x16cc8 INFO GST_REGISTRY gstregistryxml.c:576:gst_registry_xml_read_cache: loaded /root/.gstreamer-0.10/registry.arm.xml in 1.372115 seconds
0:00:01.525105000 1188 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libSM.so.6: cannot open shared object file: No such file or directory
0:00:01.546097000 1188 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libasound.so.2: cannot open shared object file: No such file or directory
0:00:01.568072000 1188 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libASFParser.so: cannot open shared object file: No such file or directory
0:00:01.594830000 1188 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libSM.so.6: cannot open shared object file: No such file or directory
0:00:01.659984000 1188 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libSM.so.6: cannot open shared object file: No such file or directory
0:00:01.762440000 1188 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libSM.so.6: cannot open shared object file: No such file or directory
0:00:02.201260000 1188 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libSM.so.6: cannot open shared object file: No such file or directory
0:00:02.224108000 1188 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libasound.so.2: cannot open shared object file: No such file or directory
0:00:02.245199000 1188 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libASFParser.so: cannot open shared object file: No such file or directory
0:00:02.259636000 1188 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libSM.so.6: cannot open shared object file: No such file or directory
0:00:02.271846000 1188 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libSM.so.6: cannot open shared object file: No such file or directory
0:00:02.298408000 1188 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libSM.so.6: cannot open shared object file: No such file or directory
0:00:02.417129000 1188 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: /opt/gstreamer/lib/python2.3/site-packages/libxml2mod.so: undefined symbol: _Py_NoneStruct
0:00:02.502494000 1188 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libSM.so.6: cannot open shared object file: No such file or directory
0:00:02.511313000 1188 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libasound.so.2: cannot open shared object file: No such file or directory
0:00:02.550550000 1188 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libASFParser.so: cannot open shared object file: No such file or directory
0:00:02.580742000 1188 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libSM.so.6: cannot open shared object file: No such file or directory
0:00:02.597519000 1188 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libSM.so.6: cannot open shared object file: No such file or directory
0:00:02.632900000 1188 0x16cc8 WARN GST_PLUGIN_LOADING gstplugin.c:414:gst_plugin_load_file: module_open failed: libSM.so.6: cannot open shared object file: No such file or directory
0:00:02.639978000 1188 0x16cc8 INFO GST_INIT gst.c:662:scan_and_update_registry: Registry cache has not changed
0:00:04.009636000 1187 0x16cc8 INFO GST_REGISTRY gstregistryxml.c:576:gst_registry_xml_read_cache: loaded /root/.gstreamer-0.10/registry.arm.xml in 1.365183 seconds
0:00:04.011879000 1187 0x16cc8 INFO GST_INIT gst.c:387:gst_init_check: initialized GStreamer successfully
vesion 20070907
0:00:06.017781000 1187 0x16cc8 INFO GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "pipeline" named "pipe"
0:00:06.105775000 1187 0x16cc8 INFO GST_PLUGIN_LOADING gstplugin.c:467:gst_plugin_load_file: plugin "/opt/gstreamer/lib/gstreamer-0.10/bak/libgstcoreelements.so" loaded
0:00:06.107931000 1187 0x16cc8 INFO GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "filesrc" named "source"
0:00:06.113477000 1187 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<GstBaseSrc@0xa3058> adding pad 'src'
0:00:06.267193000 1187 0x16cc8 INFO GST_PLUGIN_LOADING gstplugin.c:467:gst_plugin_load_file: plugin "/opt/gstreamer/lib/gstreamer-0.10/libgstavi.so" loaded
0:00:06.269322000 1187 0x16cc8 INFO GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "avidemux" named "avi_demux"
0:00:06.272791000 1187 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<GstAviDemux@0xb2350> adding pad 'sink'
0:00:06.276011000 1187 0x16cc8 INFO GST_ELEMENT_PADS gstutils.c:1363:gst_element_link_pads: trying to link element source:(any) to element avi_demux:(any)
0:00:06.277988000 1187 0x16cc8 INFO GST_PADS gstutils.c:2036:gst_pad_can_link: trying to link source:src and avi_demux:sink
0:00:06.279632000 1187 0x16cc8 INFO GST_PADS gstutils.c:1267:prepare_link_maybe_ghosting: source and avi_demux in same bin, no need for ghost pads
0:00:06.281352000 1187 0x16cc8 INFO GST_PADS gstpad.c:1727:gst_pad_link_prepare: trying to link source:src and avi_demux:sink
0:00:06.283246000 1187 0x16cc8 INFO GST_PADS gstpad.c:1864:gst_pad_link: linked source:src and avi_demux:sink, successful
0:00:06.285391000 1187 0x16cc8 INFO GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "queue" named "a_queue"
0:00:06.288913000 1187 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<GstQueue@0xa1820> adding pad 'sink'
0:00:06.292153000 1187 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<GstQueue@0xa1820> adding pad 'src'
0:00:06.295120000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<a_queue> completed state change
0:00:06.297782000 1187 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:849:gst_element_get_static_pad: found pad a_queue:src
0:00:06.299781000 1187 0x16cc8 INFO GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "fakesink" named "fakesink"
0:00:06.302898000 1187 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<GstBaseSink@0xb4100> adding pad 'sink'
0:00:06.305580000 1187 0x16cc8 INFO GST_ELEMENT_PADS gstutils.c:1363:gst_element_link_pads: trying to link element a_queue:(any) to element fakesink:(any)
0:00:06.307674000 1187 0x16cc8 INFO GST_PADS gstutils.c:2036:gst_pad_can_link: trying to link a_queue:src and fakesink:sink
0:00:06.309314000 1187 0x16cc8 INFO GST_PADS gstutils.c:1267:prepare_link_maybe_ghosting: a_queue and fakesink in same bin, no need for ghost pads
0:00:06.310945000 1187 0x16cc8 INFO GST_PADS gstpad.c:1727:gst_pad_link_prepare: trying to link a_queue:src and fakesink:sink
0:00:06.312866000 1187 0x16cc8 INFO GST_PADS gstpad.c:1864:gst_pad_link: linked a_queue:src and fakesink:sink, successful
0:00:06.314594000 1187 0x16cc8 INFO GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "queue" named "v_queue"
0:00:06.318054000 1187 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<GstQueue@0xa1918> adding pad 'sink'
0:00:06.321364000 1187 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<GstQueue@0xa1918> adding pad 'src'
0:00:06.323827000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<v_queue> completed state change
0:00:06.326026000 1187 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:849:gst_element_get_static_pad: found pad v_queue:src
0:00:06.404365000 1187 0x16cc8 INFO GST_PLUGIN_LOADING gstplugin.c:467:gst_plugin_load_file: plugin "/opt/gstreamer/lib/gstreamer-0.10/libgstgdecoder.so" loaded
0:00:06.406315000 1187 0x16cc8 INFO GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "gdecoder" named "v_dec"
0:00:06.409344000 1187 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<GstGDecoder@0xb6008> adding pad 'sink'
0:00:06.412106000 1187 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<GstGDecoder@0xb6008> adding pad 'src'
0:00:06.414794000 1187 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:849:gst_element_get_static_pad: found pad v_dec:sink
0:00:06.416391000 1187 0x16cc8 INFO GST_PADS gstpad.c:1727:gst_pad_link_prepare: trying to link v_queue:src and v_dec:sink
sink getcaps invoked
0:00:06.420545000 1187 0x16cc8 INFO GST_PADS gstpad.c:1864:gst_pad_link: linked v_queue:src and v_dec:sink, successful
0:00:06.422600000 1187 0x16cc8 INFO GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "queue" named "vsinkqueue"
0:00:06.425949000 1187 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<GstQueue@0xa1a10> adding pad 'sink'
0:00:06.429351000 1187 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<GstQueue@0xa1a10> adding pad 'src'
0:00:06.453184000 1187 0x16cc8 INFO GST_PLUGIN_LOADING gstplugin.c:467:gst_plugin_load_file: plugin "/opt/gstreamer/lib/gstreamer-0.10/bak/libgstfbvideosink.so" loaded
0:00:06.455297000 1187 0x16cc8 INFO GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "fbvideosink" named "videosink"
0:00:06.458431000 1187 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<GstBaseSink@0xba128> adding pad 'sink'
0:00:06.461908000 1187 0x16cc8 INFO GST_ELEMENT_PADS gstutils.c:1363:gst_element_link_pads: trying to link element v_dec:(any) to element vsinkqueue:(any)
0:00:06.463659000 1187 0x16cc8 INFO GST_PADS gstutils.c:2036:gst_pad_can_link: trying to link v_dec:src and vsinkqueue:src
0:00:06.465588000 1187 0x16cc8 INFO GST_PADS gstutils.c:2056:gst_pad_can_link: Sink pad vsinkqueue:src is not sink pad, failed
0:00:06.467313000 1187 0x16cc8 INFO GST_PADS gstutils.c:2036:gst_pad_can_link: trying to link v_dec:src and vsinkqueue:sink
0:00:06.468934000 1187 0x16cc8 INFO GST_PADS gstutils.c:1267:prepare_link_maybe_ghosting: v_dec and vsinkqueue in same bin, no need for ghost pads
0:00:06.470544000 1187 0x16cc8 INFO GST_PADS gstpad.c:1727:gst_pad_link_prepare: trying to link v_dec:src and vsinkqueue:sink
0:00:06.472677000 1187 0x16cc8 INFO GST_PADS gstpad.c:1864:gst_pad_link: linked v_dec:src and vsinkqueue:sink, successful
0:00:06.474345000 1187 0x16cc8 INFO GST_ELEMENT_PADS gstutils.c:1363:gst_element_link_pads: trying to link element vsinkqueue:(any) to element videosink:(any)
0:00:06.476091000 1187 0x16cc8 INFO GST_PADS gstutils.c:2036:gst_pad_can_link: trying to link vsinkqueue:src and videosink:sink
0:00:06.477823000 1187 0x16cc8 INFO GST_PADS gstutils.c:1267:prepare_link_maybe_ghosting: vsinkqueue and videosink in same bin, no need for ghost pads
0:00:06.479801000 1187 0x16cc8 INFO GST_PADS gstpad.c:1727:gst_pad_link_prepare: trying to link vsinkqueue:src and videosink:sink
0:00:06.483433000 1187 0x16cc8 INFO GST_PADS gstpad.c:1864:gst_pad_link: linked vsinkqueue:src and videosink:sink, successful
Codec set to enum value 1
0:00:06.487364000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<videosink> completed state change
0:00:06.488968000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'videosink' changed state to 1(NULL) successfully
0:00:06.490814000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<fakesink> completed state change
0:00:06.492383000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'fakesink' changed state to 1(NULL) successfully
0:00:06.494228000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<vsinkqueue> completed state change
0:00:06.495780000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'vsinkqueue' changed state to 1(NULL) successfully
0:00:06.498302000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<a_queue> completed state change
0:00:06.500186000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'a_queue' changed state to 1(NULL) successfully
0:00:06.502013000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<v_dec> completed state change
0:00:06.503563000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'v_dec' changed state to 1(NULL) successfully
0:00:06.505549000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<v_queue> completed state change
0:00:06.507504000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'v_queue' changed state to 1(NULL) successfully
0:00:06.509404000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<avi_demux> completed state change
0:00:06.510951000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'avi_demux' changed state to 1(NULL) successfully
0:00:06.513013000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<source> completed state change
0:00:06.514572000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'source' changed state to 1(NULL) successfully
0:00:06.516540000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<pipe> completed state change
PLAY pipeline
0:00:06.521686000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<videosink> completed state change
0:00:06.523560000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'videosink' changed state to 2(READY) successfully
0:00:06.525389000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<fakesink> completed state change
0:00:06.527318000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'fakesink' changed state to 2(READY) successfully
0:00:06.529138000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<vsinkqueue> completed state change
0:00:06.531340000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'vsinkqueue' changed state to 2(READY) successfully
0:00:06.533093000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<a_queue> completed state change
0:00:06.534938000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'a_queue' changed state to 2(READY) successfully
0:00:06.536829000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<v_dec> completed state change
0:00:06.538684000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'v_dec' changed state to 2(READY) successfully
0:00:06.540424000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<v_queue> completed state change
0:00:06.542281000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'v_queue' changed state to 2(READY) successfully
0:00:06.544108000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<avi_demux> completed state change
0:00:06.546582000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'avi_demux' changed state to 2(READY) successfully
0:00:06.548397000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<source> completed state change
0:00:06.550227000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'source' changed state to 2(READY) successfully
0:00:06.551986000 1187 0x16cc8 INFO GST_STATES gstelement.c:1969:gst_element_continue_state:<pipe> committing state from NULL to READY, pending PLAYING
0:00:06.553903000 1187 0x16cc8 INFO GST_STATES gstelement.c:1978:gst_element_continue_state:<pipe> continue state change READY to PAUSED, final PLAYING
0:00:06.556536000 1187 0x16cc8 INFO GST_STATES gstbin.c:1936:gst_bin_change_state_func:<pipe> child 'videosink' is changing state asynchronously
0:00:06.558563000 1187 0x16cc8 INFO GST_STATES gstbin.c:1936:gst_bin_change_state_func:<pipe> child 'fakesink' is changing state asynchronously
0:00:06.563033000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<vsinkqueue> completed state change
0:00:06.565209000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'vsinkqueue' changed state to 3(PAUSED) successfully
0:00:06.568308000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<a_queue> completed state change
0:00:06.570155000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'a_queue' changed state to 3(PAUSED) successfully
Activate invoked
streaming mode...
0:00:06.573367000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<v_dec> completed state change
0:00:06.575215000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'v_dec' changed state to 3(PAUSED) successfully
0:00:06.578294000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<v_queue> completed state change
0:00:06.580149000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'v_queue' changed state to 3(PAUSED) successfully
0:00:06.582170000 1187 0x16cc8 INFO filesrc gstfilesrc.c:906:gst_file_src_start:<source> opening file /data/kiss.avi
0:00:06.627238000 1187 0x16cc8 INFO filesrc gstfilesrc.c:906:gst_file_src_start:<source> opening file /data/kiss.avi
volter: gst_avi_demux_sink_activate_pull-->loop schedule...
0:00:06.674284000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<avi_demux> completed state change
0:00:06.676169000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'avi_demux' changed state to 3(PAUSED) successfully
0:00:06.678180000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state:<source> completed state change
0:00:06.680187000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func:<pipe> child 'source' changed state to 3(PAUSED) successfully
Running
0:00:06.688470000 1187 0xbe368 INFO avidemux gstavidemux.c:779:gst_avi_demux_parse_avih:<avi_demux> avih tag found:
0:00:06.690178000 1187 0xbe368 INFO avidemux gstavidemux.c:780:gst_avi_demux_parse_avih:<avi_demux> us_frame 41708
0:00:06.691742000 1187 0xbe368 INFO avidemux gstavidemux.c:781:gst_avi_demux_parse_avih:<avi_demux> max_bps 0
0:00:06.693526000 1187 0xbe368 INFO avidemux gstavidemux.c:782:gst_avi_demux_parse_avih:<avi_demux> pad_gran 0
0:00:06.695016000 1187 0xbe368 INFO avidemux gstavidemux.c:783:gst_avi_demux_parse_avih:<avi_demux> flags 0x00000910
0:00:06.696612000 1187 0xbe368 INFO avidemux gstavidemux.c:784:gst_avi_demux_parse_avih:<avi_demux> tot_frames 11508
0:00:06.698099000 1187 0xbe368 INFO avidemux gstavidemux.c:785:gst_avi_demux_parse_avih:<avi_demux> init_frames 0
0:00:06.699572000 1187 0xbe368 INFO avidemux gstavidemux.c:786:gst_avi_demux_parse_avih:<avi_demux> streams 2
0:00:06.701044000 1187 0xbe368 INFO avidemux gstavidemux.c:787:gst_avi_demux_parse_avih:<avi_demux> bufsize 0
0:00:06.702504000 1187 0xbe368 INFO avidemux gstavidemux.c:788:gst_avi_demux_parse_avih:<avi_demux> width 320
0:00:06.703972000 1187 0xbe368 INFO avidemux gstavidemux.c:789:gst_avi_demux_parse_avih:<avi_demux> height 240
0:00:06.705449000 1187 0xbe368 INFO avidemux gstavidemux.c:790:gst_avi_demux_parse_avih:<avi_demux> scale 0
0:00:06.707297000 1187 0xbe368 INFO avidemux gstavidemux.c:791:gst_avi_demux_parse_avih:<avi_demux> rate 0
0:00:06.708777000 1187 0xbe368 INFO avidemux gstavidemux.c:792:gst_avi_demux_parse_avih:<avi_demux> start 0
0:00:06.710242000 1187 0xbe368 INFO avidemux gstavidemux.c:793:gst_avi_demux_parse_avih:<avi_demux> length 0
0:00:06.711999000 1187 0xbe368 INFO riff riff-read.c:297:gst_riff_parse_strh:<avi_demux> strh tag found:
0:00:06.713439000 1187 0xbe368 INFO riff riff-read.c:299:gst_riff_parse_strh:<avi_demux> type vids
0:00:06.714931000 1187 0xbe368 INFO riff riff-read.c:301:gst_riff_parse_strh:<avi_demux> fcc_handler DIVX
0:00:06.716501000 1187 0xbe368 INFO riff riff-read.c:302:gst_riff_parse_strh:<avi_demux> flags 0x00000000
0:00:06.717980000 1187 0xbe368 INFO riff riff-read.c:303:gst_riff_parse_strh:<avi_demux> priority 0
0:00:06.719435000 1187 0xbe368 INFO riff riff-read.c:304:gst_riff_parse_strh:<avi_demux> init_frames 0
0:00:06.720886000 1187 0xbe368 INFO riff riff-read.c:305:gst_riff_parse_strh:<avi_demux> scale 1001
0:00:06.722657000 1187 0xbe368 INFO riff riff-read.c:306:gst_riff_parse_strh:<avi_demux> rate 24000
0:00:06.724134000 1187 0xbe368 INFO riff riff-read.c:307:gst_riff_parse_strh:<avi_demux> start 0
0:00:06.725595000 1187 0xbe368 INFO riff riff-read.c:308:gst_riff_parse_strh:<avi_demux> length 11508
0:00:06.727140000 1187 0xbe368 INFO riff riff-read.c:309:gst_riff_parse_strh:<avi_demux> bufsize 18383
0:00:06.728606000 1187 0xbe368 INFO riff riff-read.c:310:gst_riff_parse_strh:<avi_demux> quality 0
0:00:06.730061000 1187 0xbe368 INFO riff riff-read.c:311:gst_riff_parse_strh:<avi_demux> samplesize 0
0:00:06.731660000 1187 0xbe368 INFO riff riff-read.c:388:gst_riff_parse_strf_vids:<avi_demux> strf tag found in context vids:
0:00:06.733126000 1187 0xbe368 INFO riff riff-read.c:389:gst_riff_parse_strf_vids:<avi_demux> size 40
0:00:06.734588000 1187 0xbe368 INFO riff riff-read.c:390:gst_riff_parse_strf_vids:<avi_demux> width 320
0:00:06.736278000 1187 0xbe368 INFO riff riff-read.c:391:gst_riff_parse_strf_vids:<avi_demux> height 240
0:00:06.737928000 1187 0xbe368 INFO riff riff-read.c:392:gst_riff_parse_strf_vids:<avi_demux> planes 1
0:00:06.739398000 1187 0xbe368 INFO riff riff-read.c:393:gst_riff_parse_strf_vids:<avi_demux> bit_cnt 24
0:00:06.740865000 1187 0xbe368 INFO riff riff-read.c:395:gst_riff_parse_strf_vids:<avi_demux> compression DIVX
0:00:06.742360000 1187 0xbe368 INFO riff riff-read.c:396:gst_riff_parse_strf_vids:<avi_demux> image_size 230400
0:00:06.743840000 1187 0xbe368 INFO riff riff-read.c:397:gst_riff_parse_strf_vids:<avi_demux> xpels_meter 0
0:00:06.745311000 1187 0xbe368 INFO riff riff-read.c:398:gst_riff_parse_strf_vids:<avi_demux> ypels_meter 0
0:00:06.746864000 1187 0xbe368 INFO riff riff-read.c:399:gst_riff_parse_strf_vids:<avi_demux> num_colors 0
0:00:06.748328000 1187 0xbe368 INFO riff riff-read.c:400:gst_riff_parse_strf_vids:<avi_demux> imp_colors 0
0:00:06.751775000 1187 0xbe368 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<avi_demux> adding pad 'video_00'
Dynamic pad created, linking demuxer and v_queue
0:00:06.754840000 1187 0xbe368 INFO GST_ELEMENT_PADS gstelement.c:849:gst_element_get_static_pad: found pad v_queue:sink
0:00:06.756384000 1187 0xbe368 INFO GST_PADS gstpad.c:1727:gst_pad_link_prepare: trying to link avi_demux:video_00 and v_queue:sink
sink getcaps invoked
0:00:06.761169000 1187 0xbe368 INFO GST_PADS gstpad.c:1801:gst_pad_link_prepare: caps are incompatible
0:00:06.763206000 1187 0xbe368 INFO riff riff-read.c:297:gst_riff_parse_strh:<avi_demux> strh tag found:
0:00:06.764658000 1187 0xbe368 INFO riff riff-read.c:299:gst_riff_parse_strh:<avi_demux> type auds
0:00:06.766167000 1187 0xbe368 INFO riff riff-read.c:301:gst_riff_parse_strh:<avi_demux> fcc_handler U
0:00:06.767769000 1187 0xbe368 INFO riff riff-read.c:302:gst_riff_parse_strh:<avi_demux> flags 0x00000000
0:00:06.769248000 1187 0xbe368 INFO riff riff-read.c:303:gst_riff_parse_strh:<avi_demux> priority 0
0:00:06.770720000 1187 0xbe368 INFO riff riff-read.c:304:gst_riff_parse_strh:<avi_demux> init_frames 20
0:00:06.772510000 1187 0xbe368 INFO riff riff-read.c:305:gst_riff_parse_strh:<avi_demux> scale 1
0:00:06.774007000 1187 0xbe368 INFO riff riff-read.c:306:gst_riff_parse_strh:<avi_demux> rate 16000
0:00:06.775478000 1187 0xbe368 INFO riff riff-read.c:307:gst_riff_parse_strh:<avi_demux> start 0
0:00:06.777023000 1187 0xbe368 INFO riff riff-read.c:308:gst_riff_parse_strh:<avi_demux> length 7687941
0:00:06.778499000 1187 0xbe368 INFO riff riff-read.c:309:gst_riff_parse_strh:<avi_demux> bufsize 418
0:00:06.779976000 1187 0xbe368 INFO riff riff-read.c:310:gst_riff_parse_strh:<avi_demux> quality 0
0:00:06.781439000 1187 0xbe368 INFO riff riff-read.c:311:gst_riff_parse_strh:<avi_demux> samplesize 1
0:00:06.783014000 1187 0xbe368 INFO riff riff-read.c:482:gst_riff_parse_strf_auds:<avi_demux> strf tag found in context auds:
0:00:06.784496000 1187 0xbe368 INFO riff riff-read.c:483:gst_riff_parse_strf_auds:<avi_demux> format 85
0:00:06.785968000 1187 0xbe368 INFO riff riff-read.c:484:gst_riff_parse_strf_auds:<avi_demux> channels 2
0:00:06.787834000 1187 0xbe368 INFO riff riff-read.c:485:gst_riff_parse_strf_auds:<avi_demux> rate 44100
0:00:06.789344000 1187 0xbe368 INFO riff riff-read.c:486:gst_riff_parse_strf_auds:<avi_demux> av_bps 16000
0:00:06.790921000 1187 0xbe368 INFO riff riff-read.c:487:gst_riff_parse_strf_auds:<avi_demux> blockalign 1
0:00:06.792402000 1187 0xbe368 INFO riff riff-read.c:488:gst_riff_parse_strf_auds:<avi_demux> size 0
0:00:06.793888000 1187 0xbe368 INFO riff riff-read.c:490:gst_riff_parse_strf_auds:<avi_demux> 12 bytes extradata
0:00:06.797310000 1187 0xbe368 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad:<avi_demux> adding pad 'audio_00'
Dynamic pad created, linking demuxer and a_queue
0:00:06.799841000 1187 0xbe368 INFO GST_ELEMENT_PADS gstelement.c:849:gst_element_get_static_pad: found pad a_queue:sink
0:00:06.801381000 1187 0xbe368 INFO GST_PADS gstpad.c:1727:gst_pad_link_prepare: trying to link avi_demux:audio_00 and a_queue:sink
0:00:06.803799000 1187 0xbe368 INFO GST_PADS gstpad.c:1864:gst_pad_link: linked avi_demux:audio_00 and a_queue:sink, successful
0:00:06.808142000 1187 0xbe368 WARN avidemux gstavidemux.c:1617:gst_avi_demux_stream_index:<avi_demux> skip LIST chunk
0:00:08.190797000 1187 0xbe368 INFO avidemux gstavidemux.c:2290:gst_avi_demux_calculate_durations_from_index: Stream 0 duration according to header: 0:07:59.979500000
0:00:08.192601000 1187 0xbe368 INFO avidemux gstavidemux.c:2310:gst_avi_demux_calculate_durations_from_index: Stream 0 duration according to index: 0:07:59.979500000
0:00:08.194234000 1187 0xbe368 INFO avidemux gstavidemux.c:2290:gst_avi_demux_calculate_durations_from_index: Stream 1 duration according to header: 0:08:00.496312500
0:00:08.195823000 1187 0xbe368 INFO avidemux gstavidemux.c:2310:gst_avi_demux_calculate_durations_from_index: Stream 1 duration according to index: 0:08:00.496312500
0:00:08.197553000 1187 0xbe368 INFO avidemux gstavidemux.c:2340:gst_avi_demux_calculate_durations_from_index: Setting total duration to: 0:08:00.496312500
0:00:08.199288000 1187 0xbe368 INFO GST_EVENT gstevent.c:527:gst_event_new_new_segment_full: creating newsegment update 0, rate 1.000000, format GST_FORMAT_TIME, start 0:00:00.000000000, stop 0:08:00.496312500, position 0:00:00.000000000
0:00:08.215736000 1187 0xb9ea8 INFO GST_STATES gstbin.c:1321:gst_bin_recalc_state:<pipe> recalc state
0:00:08.217723000 1187 0xb9ea8 INFO GST_STATES gstbin.c:1331:gst_bin_recalc_state:<pipe> checking element states
0:00:08.219844000 1187 0xb9ea8 INFO GST_STATES gstbin.c:1421:gst_bin_recalc_state:<pipe> bin RETURN is now ASYNC