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: 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: 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: adding pad 'sink' 0:00:06.148781000 1180 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad: adding pad 'src' 0:00:06.151718000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state: 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: 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: adding pad 'sink' 0:00:06.177892000 1180 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad: adding pad 'src' 0:00:06.180353000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state: 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: adding pad 'sink' 0:00:06.253048000 1180 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad: 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: adding pad 'sink' 0:00:06.270102000 1180 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad: 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: 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: completed state change 0:00:06.347517000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: child 'videosink' changed state to 1(NULL) successfully 0:00:06.349364000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state: completed state change 0:00:06.350909000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: child 'fakesink' changed state to 1(NULL) successfully 0:00:06.352744000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state: completed state change 0:00:06.354297000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: child 'vsinkqueue' changed state to 1(NULL) successfully 0:00:06.356819000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state: completed state change 0:00:06.358708000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: 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: completed state change 0:00:06.362078000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: 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: completed state change 0:00:06.366016000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: 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: completed state change 0:00:06.369451000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: 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: completed state change 0:00:06.373058000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: child 'source' changed state to 1(NULL) successfully 0:00:06.375002000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state: completed state change PLAY pipeline 0:00:06.380122000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state: completed state change 0:00:06.381982000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: child 'videosink' changed state to 2(READY) successfully 0:00:06.383828000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state: completed state change 0:00:06.385747000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: child 'fakesink' changed state to 2(READY) successfully 0:00:06.387579000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state: completed state change 0:00:06.389763000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: child 'vsinkqueue' changed state to 2(READY) successfully 0:00:06.391522000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state: completed state change 0:00:06.393384000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: 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: completed state change 0:00:06.397109000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: 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: completed state change 0:00:06.400703000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: 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: completed state change 0:00:06.404773000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: 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: completed state change 0:00:06.408417000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: child 'source' changed state to 2(READY) successfully 0:00:06.410164000 1180 0x16cc8 INFO GST_STATES gstelement.c:1969:gst_element_continue_state: committing state from NULL to READY, pending PLAYING 0:00:06.412083000 1180 0x16cc8 INFO GST_STATES gstelement.c:1978:gst_element_continue_state: 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: child 'videosink' is changing state asynchronously 0:00:06.416733000 1180 0x16cc8 INFO GST_STATES gstbin.c:1936:gst_bin_change_state_func: child 'fakesink' is changing state asynchronously 0:00:06.421207000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state: completed state change 0:00:06.423392000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: child 'vsinkqueue' changed state to 3(PAUSED) successfully 0:00:06.426508000 1180 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state: completed state change 0:00:06.428360000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: 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: completed state change 0:00:06.433415000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: 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: completed state change 0:00:06.438368000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: child 'v_queue' changed state to 3(PAUSED) successfully 0:00:06.440395000 1180 0x16cc8 INFO filesrc gstfilesrc.c:906:gst_file_src_start: opening file /data/kiss.avi 0:00:06.466776000 1180 0x16cc8 INFO filesrc gstfilesrc.c:906:gst_file_src_start: 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: completed state change 0:00:06.473252000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: 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: completed state change 0:00:06.477242000 1180 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: 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: avih tag found: 0:00:06.487226000 1180 0xbe368 INFO avidemux gstavidemux.c:780:gst_avi_demux_parse_avih: us_frame 41708 0:00:06.488790000 1180 0xbe368 INFO avidemux gstavidemux.c:781:gst_avi_demux_parse_avih: max_bps 0 0:00:06.490570000 1180 0xbe368 INFO avidemux gstavidemux.c:782:gst_avi_demux_parse_avih: pad_gran 0 0:00:06.492065000 1180 0xbe368 INFO avidemux gstavidemux.c:783:gst_avi_demux_parse_avih: flags 0x00000910 0:00:06.493562000 1180 0xbe368 INFO avidemux gstavidemux.c:784:gst_avi_demux_parse_avih: tot_frames 11508 0:00:06.495111000 1180 0xbe368 INFO avidemux gstavidemux.c:785:gst_avi_demux_parse_avih: init_frames 0 0:00:06.496586000 1180 0xbe368 INFO avidemux gstavidemux.c:786:gst_avi_demux_parse_avih: streams 2 0:00:06.498059000 1180 0xbe368 INFO avidemux gstavidemux.c:787:gst_avi_demux_parse_avih: bufsize 0 0:00:06.499531000 1180 0xbe368 INFO avidemux gstavidemux.c:788:gst_avi_demux_parse_avih: width 320 0:00:06.501007000 1180 0xbe368 INFO avidemux gstavidemux.c:789:gst_avi_demux_parse_avih: height 240 0:00:06.502466000 1180 0xbe368 INFO avidemux gstavidemux.c:790:gst_avi_demux_parse_avih: scale 0 0:00:06.504248000 1180 0xbe368 INFO avidemux gstavidemux.c:791:gst_avi_demux_parse_avih: rate 0 0:00:06.505850000 1180 0xbe368 INFO avidemux gstavidemux.c:792:gst_avi_demux_parse_avih: start 0 0:00:06.507322000 1180 0xbe368 INFO avidemux gstavidemux.c:793:gst_avi_demux_parse_avih: length 0 0:00:06.509106000 1180 0xbe368 INFO riff riff-read.c:297:gst_riff_parse_strh: strh tag found: 0:00:06.510540000 1180 0xbe368 INFO riff riff-read.c:299:gst_riff_parse_strh: type vids 0:00:06.512033000 1180 0xbe368 INFO riff riff-read.c:301:gst_riff_parse_strh: fcc_handler DIVX 0:00:06.513522000 1180 0xbe368 INFO riff riff-read.c:302:gst_riff_parse_strh: flags 0x00000000 0:00:06.515068000 1180 0xbe368 INFO riff riff-read.c:303:gst_riff_parse_strh: priority 0 0:00:06.516527000 1180 0xbe368 INFO riff riff-read.c:304:gst_riff_parse_strh: init_frames 0 0:00:06.517974000 1180 0xbe368 INFO riff riff-read.c:305:gst_riff_parse_strh: scale 1001 0:00:06.519742000 1180 0xbe368 INFO riff riff-read.c:306:gst_riff_parse_strh: rate 24000 0:00:06.521223000 1180 0xbe368 INFO riff riff-read.c:307:gst_riff_parse_strh: start 0 0:00:06.522696000 1180 0xbe368 INFO riff riff-read.c:308:gst_riff_parse_strh: length 11508 0:00:06.524163000 1180 0xbe368 INFO riff riff-read.c:309:gst_riff_parse_strh: bufsize 18383 0:00:06.525759000 1180 0xbe368 INFO riff riff-read.c:310:gst_riff_parse_strh: quality 0 0:00:06.527223000 1180 0xbe368 INFO riff riff-read.c:311:gst_riff_parse_strh: samplesize 0 0:00:06.528833000 1180 0xbe368 INFO riff riff-read.c:388:gst_riff_parse_strf_vids: strf tag found in context vids: 0:00:06.530313000 1180 0xbe368 INFO riff riff-read.c:389:gst_riff_parse_strf_vids: size 40 0:00:06.531784000 1180 0xbe368 INFO riff riff-read.c:390:gst_riff_parse_strf_vids: width 320 0:00:06.533475000 1180 0xbe368 INFO riff riff-read.c:391:gst_riff_parse_strf_vids: height 240 0:00:06.535320000 1180 0xbe368 INFO riff riff-read.c:392:gst_riff_parse_strf_vids: planes 1 0:00:06.536804000 1180 0xbe368 INFO riff riff-read.c:393:gst_riff_parse_strf_vids: bit_cnt 24 0:00:06.538274000 1180 0xbe368 INFO riff riff-read.c:395:gst_riff_parse_strf_vids: compression DIVX 0:00:06.539772000 1180 0xbe368 INFO riff riff-read.c:396:gst_riff_parse_strf_vids: image_size 230400 0:00:06.541265000 1180 0xbe368 INFO riff riff-read.c:397:gst_riff_parse_strf_vids: xpels_meter 0 0:00:06.542736000 1180 0xbe368 INFO riff riff-read.c:398:gst_riff_parse_strf_vids: ypels_meter 0 0:00:06.544207000 1180 0xbe368 INFO riff riff-read.c:399:gst_riff_parse_strf_vids: num_colors 0 0:00:06.545916000 1180 0xbe368 INFO riff riff-read.c:400:gst_riff_parse_strf_vids: imp_colors 0 0:00:06.549350000 1180 0xbe368 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad: 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: strh tag found: 0:00:06.566263000 1180 0xbe368 INFO riff riff-read.c:299:gst_riff_parse_strh: type auds 0:00:06.568076000 1180 0xbe368 INFO riff riff-read.c:301:gst_riff_parse_strh: fcc_handler U 0:00:06.569591000 1180 0xbe368 INFO riff riff-read.c:302:gst_riff_parse_strh: flags 0x00000000 0:00:06.571078000 1180 0xbe368 INFO riff riff-read.c:303:gst_riff_parse_strh: priority 0 0:00:06.572551000 1180 0xbe368 INFO riff riff-read.c:304:gst_riff_parse_strh: init_frames 20 0:00:06.574016000 1180 0xbe368 INFO riff riff-read.c:305:gst_riff_parse_strh: scale 1 0:00:06.575556000 1180 0xbe368 INFO riff riff-read.c:306:gst_riff_parse_strh: rate 16000 0:00:06.577024000 1180 0xbe368 INFO riff riff-read.c:307:gst_riff_parse_strh: start 0 0:00:06.578489000 1180 0xbe368 INFO riff riff-read.c:308:gst_riff_parse_strh: length 7687941 0:00:06.579961000 1180 0xbe368 INFO riff riff-read.c:309:gst_riff_parse_strh: bufsize 418 0:00:06.581438000 1180 0xbe368 INFO riff riff-read.c:310:gst_riff_parse_strh: quality 0 0:00:06.583210000 1180 0xbe368 INFO riff riff-read.c:311:gst_riff_parse_strh: samplesize 1 0:00:06.584850000 1180 0xbe368 INFO riff riff-read.c:482:gst_riff_parse_strf_auds: strf tag found in context auds: 0:00:06.586342000 1180 0xbe368 INFO riff riff-read.c:483:gst_riff_parse_strf_auds: format 85 0:00:06.587829000 1180 0xbe368 INFO riff riff-read.c:484:gst_riff_parse_strf_auds: channels 2 0:00:06.589316000 1180 0xbe368 INFO riff riff-read.c:485:gst_riff_parse_strf_auds: rate 44100 0:00:06.590810000 1180 0xbe368 INFO riff riff-read.c:486:gst_riff_parse_strf_auds: av_bps 16000 0:00:06.592396000 1180 0xbe368 INFO riff riff-read.c:487:gst_riff_parse_strf_auds: blockalign 1 0:00:06.593885000 1180 0xbe368 INFO riff riff-read.c:488:gst_riff_parse_strf_auds: size 0 0:00:06.595431000 1180 0xbe368 INFO riff riff-read.c:490:gst_riff_parse_strf_auds: 12 bytes extradata 0:00:06.599048000 1180 0xbe368 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad: 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: 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: recalc state 0:00:08.000431000 1180 0x14a2d0 INFO GST_STATES gstbin.c:1331:gst_bin_recalc_state: checking element states 0:00:08.002536000 1180 0x14a2d0 INFO GST_STATES gstbin.c:1421:gst_bin_recalc_state: 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: 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: 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: adding pad 'sink' 0:00:06.292153000 1187 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad: adding pad 'src' 0:00:06.295120000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state: 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: 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: adding pad 'sink' 0:00:06.321364000 1187 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad: adding pad 'src' 0:00:06.323827000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state: 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: adding pad 'sink' 0:00:06.412106000 1187 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad: 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: adding pad 'sink' 0:00:06.429351000 1187 0x16cc8 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad: 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: 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: completed state change 0:00:06.488968000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: child 'videosink' changed state to 1(NULL) successfully 0:00:06.490814000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state: completed state change 0:00:06.492383000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: child 'fakesink' changed state to 1(NULL) successfully 0:00:06.494228000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state: completed state change 0:00:06.495780000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: child 'vsinkqueue' changed state to 1(NULL) successfully 0:00:06.498302000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state: completed state change 0:00:06.500186000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: 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: completed state change 0:00:06.503563000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: 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: completed state change 0:00:06.507504000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: 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: completed state change 0:00:06.510951000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: 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: completed state change 0:00:06.514572000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: child 'source' changed state to 1(NULL) successfully 0:00:06.516540000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state: completed state change PLAY pipeline 0:00:06.521686000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state: completed state change 0:00:06.523560000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: child 'videosink' changed state to 2(READY) successfully 0:00:06.525389000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state: completed state change 0:00:06.527318000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: child 'fakesink' changed state to 2(READY) successfully 0:00:06.529138000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state: completed state change 0:00:06.531340000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: child 'vsinkqueue' changed state to 2(READY) successfully 0:00:06.533093000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state: completed state change 0:00:06.534938000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: 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: completed state change 0:00:06.538684000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: 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: completed state change 0:00:06.542281000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: 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: completed state change 0:00:06.546582000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: 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: completed state change 0:00:06.550227000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: child 'source' changed state to 2(READY) successfully 0:00:06.551986000 1187 0x16cc8 INFO GST_STATES gstelement.c:1969:gst_element_continue_state: committing state from NULL to READY, pending PLAYING 0:00:06.553903000 1187 0x16cc8 INFO GST_STATES gstelement.c:1978:gst_element_continue_state: 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: child 'videosink' is changing state asynchronously 0:00:06.558563000 1187 0x16cc8 INFO GST_STATES gstbin.c:1936:gst_bin_change_state_func: child 'fakesink' is changing state asynchronously 0:00:06.563033000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state: completed state change 0:00:06.565209000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: child 'vsinkqueue' changed state to 3(PAUSED) successfully 0:00:06.568308000 1187 0x16cc8 INFO GST_STATES gstelement.c:1995:gst_element_continue_state: completed state change 0:00:06.570155000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: 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: completed state change 0:00:06.575215000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: 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: completed state change 0:00:06.580149000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: child 'v_queue' changed state to 3(PAUSED) successfully 0:00:06.582170000 1187 0x16cc8 INFO filesrc gstfilesrc.c:906:gst_file_src_start: opening file /data/kiss.avi 0:00:06.627238000 1187 0x16cc8 INFO filesrc gstfilesrc.c:906:gst_file_src_start: 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: completed state change 0:00:06.676169000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: 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: completed state change 0:00:06.680187000 1187 0x16cc8 INFO GST_STATES gstbin.c:1931:gst_bin_change_state_func: 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: avih tag found: 0:00:06.690178000 1187 0xbe368 INFO avidemux gstavidemux.c:780:gst_avi_demux_parse_avih: us_frame 41708 0:00:06.691742000 1187 0xbe368 INFO avidemux gstavidemux.c:781:gst_avi_demux_parse_avih: max_bps 0 0:00:06.693526000 1187 0xbe368 INFO avidemux gstavidemux.c:782:gst_avi_demux_parse_avih: pad_gran 0 0:00:06.695016000 1187 0xbe368 INFO avidemux gstavidemux.c:783:gst_avi_demux_parse_avih: flags 0x00000910 0:00:06.696612000 1187 0xbe368 INFO avidemux gstavidemux.c:784:gst_avi_demux_parse_avih: tot_frames 11508 0:00:06.698099000 1187 0xbe368 INFO avidemux gstavidemux.c:785:gst_avi_demux_parse_avih: init_frames 0 0:00:06.699572000 1187 0xbe368 INFO avidemux gstavidemux.c:786:gst_avi_demux_parse_avih: streams 2 0:00:06.701044000 1187 0xbe368 INFO avidemux gstavidemux.c:787:gst_avi_demux_parse_avih: bufsize 0 0:00:06.702504000 1187 0xbe368 INFO avidemux gstavidemux.c:788:gst_avi_demux_parse_avih: width 320 0:00:06.703972000 1187 0xbe368 INFO avidemux gstavidemux.c:789:gst_avi_demux_parse_avih: height 240 0:00:06.705449000 1187 0xbe368 INFO avidemux gstavidemux.c:790:gst_avi_demux_parse_avih: scale 0 0:00:06.707297000 1187 0xbe368 INFO avidemux gstavidemux.c:791:gst_avi_demux_parse_avih: rate 0 0:00:06.708777000 1187 0xbe368 INFO avidemux gstavidemux.c:792:gst_avi_demux_parse_avih: start 0 0:00:06.710242000 1187 0xbe368 INFO avidemux gstavidemux.c:793:gst_avi_demux_parse_avih: length 0 0:00:06.711999000 1187 0xbe368 INFO riff riff-read.c:297:gst_riff_parse_strh: strh tag found: 0:00:06.713439000 1187 0xbe368 INFO riff riff-read.c:299:gst_riff_parse_strh: type vids 0:00:06.714931000 1187 0xbe368 INFO riff riff-read.c:301:gst_riff_parse_strh: fcc_handler DIVX 0:00:06.716501000 1187 0xbe368 INFO riff riff-read.c:302:gst_riff_parse_strh: flags 0x00000000 0:00:06.717980000 1187 0xbe368 INFO riff riff-read.c:303:gst_riff_parse_strh: priority 0 0:00:06.719435000 1187 0xbe368 INFO riff riff-read.c:304:gst_riff_parse_strh: init_frames 0 0:00:06.720886000 1187 0xbe368 INFO riff riff-read.c:305:gst_riff_parse_strh: scale 1001 0:00:06.722657000 1187 0xbe368 INFO riff riff-read.c:306:gst_riff_parse_strh: rate 24000 0:00:06.724134000 1187 0xbe368 INFO riff riff-read.c:307:gst_riff_parse_strh: start 0 0:00:06.725595000 1187 0xbe368 INFO riff riff-read.c:308:gst_riff_parse_strh: length 11508 0:00:06.727140000 1187 0xbe368 INFO riff riff-read.c:309:gst_riff_parse_strh: bufsize 18383 0:00:06.728606000 1187 0xbe368 INFO riff riff-read.c:310:gst_riff_parse_strh: quality 0 0:00:06.730061000 1187 0xbe368 INFO riff riff-read.c:311:gst_riff_parse_strh: samplesize 0 0:00:06.731660000 1187 0xbe368 INFO riff riff-read.c:388:gst_riff_parse_strf_vids: strf tag found in context vids: 0:00:06.733126000 1187 0xbe368 INFO riff riff-read.c:389:gst_riff_parse_strf_vids: size 40 0:00:06.734588000 1187 0xbe368 INFO riff riff-read.c:390:gst_riff_parse_strf_vids: width 320 0:00:06.736278000 1187 0xbe368 INFO riff riff-read.c:391:gst_riff_parse_strf_vids: height 240 0:00:06.737928000 1187 0xbe368 INFO riff riff-read.c:392:gst_riff_parse_strf_vids: planes 1 0:00:06.739398000 1187 0xbe368 INFO riff riff-read.c:393:gst_riff_parse_strf_vids: bit_cnt 24 0:00:06.740865000 1187 0xbe368 INFO riff riff-read.c:395:gst_riff_parse_strf_vids: compression DIVX 0:00:06.742360000 1187 0xbe368 INFO riff riff-read.c:396:gst_riff_parse_strf_vids: image_size 230400 0:00:06.743840000 1187 0xbe368 INFO riff riff-read.c:397:gst_riff_parse_strf_vids: xpels_meter 0 0:00:06.745311000 1187 0xbe368 INFO riff riff-read.c:398:gst_riff_parse_strf_vids: ypels_meter 0 0:00:06.746864000 1187 0xbe368 INFO riff riff-read.c:399:gst_riff_parse_strf_vids: num_colors 0 0:00:06.748328000 1187 0xbe368 INFO riff riff-read.c:400:gst_riff_parse_strf_vids: imp_colors 0 0:00:06.751775000 1187 0xbe368 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad: 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: strh tag found: 0:00:06.764658000 1187 0xbe368 INFO riff riff-read.c:299:gst_riff_parse_strh: type auds 0:00:06.766167000 1187 0xbe368 INFO riff riff-read.c:301:gst_riff_parse_strh: fcc_handler U 0:00:06.767769000 1187 0xbe368 INFO riff riff-read.c:302:gst_riff_parse_strh: flags 0x00000000 0:00:06.769248000 1187 0xbe368 INFO riff riff-read.c:303:gst_riff_parse_strh: priority 0 0:00:06.770720000 1187 0xbe368 INFO riff riff-read.c:304:gst_riff_parse_strh: init_frames 20 0:00:06.772510000 1187 0xbe368 INFO riff riff-read.c:305:gst_riff_parse_strh: scale 1 0:00:06.774007000 1187 0xbe368 INFO riff riff-read.c:306:gst_riff_parse_strh: rate 16000 0:00:06.775478000 1187 0xbe368 INFO riff riff-read.c:307:gst_riff_parse_strh: start 0 0:00:06.777023000 1187 0xbe368 INFO riff riff-read.c:308:gst_riff_parse_strh: length 7687941 0:00:06.778499000 1187 0xbe368 INFO riff riff-read.c:309:gst_riff_parse_strh: bufsize 418 0:00:06.779976000 1187 0xbe368 INFO riff riff-read.c:310:gst_riff_parse_strh: quality 0 0:00:06.781439000 1187 0xbe368 INFO riff riff-read.c:311:gst_riff_parse_strh: samplesize 1 0:00:06.783014000 1187 0xbe368 INFO riff riff-read.c:482:gst_riff_parse_strf_auds: strf tag found in context auds: 0:00:06.784496000 1187 0xbe368 INFO riff riff-read.c:483:gst_riff_parse_strf_auds: format 85 0:00:06.785968000 1187 0xbe368 INFO riff riff-read.c:484:gst_riff_parse_strf_auds: channels 2 0:00:06.787834000 1187 0xbe368 INFO riff riff-read.c:485:gst_riff_parse_strf_auds: rate 44100 0:00:06.789344000 1187 0xbe368 INFO riff riff-read.c:486:gst_riff_parse_strf_auds: av_bps 16000 0:00:06.790921000 1187 0xbe368 INFO riff riff-read.c:487:gst_riff_parse_strf_auds: blockalign 1 0:00:06.792402000 1187 0xbe368 INFO riff riff-read.c:488:gst_riff_parse_strf_auds: size 0 0:00:06.793888000 1187 0xbe368 INFO riff riff-read.c:490:gst_riff_parse_strf_auds: 12 bytes extradata 0:00:06.797310000 1187 0xbe368 INFO GST_ELEMENT_PADS gstelement.c:616:gst_element_add_pad: 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: 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: recalc state 0:00:08.217723000 1187 0xb9ea8 INFO GST_STATES gstbin.c:1331:gst_bin_recalc_state: checking element states 0:00:08.219844000 1187 0xb9ea8 INFO GST_STATES gstbin.c:1421:gst_bin_recalc_state: bin RETURN is now ASYNC