[gst-devel] help me to detect a pipeline problem.

Chenpeng championonline at gmail.com
Tue May 25 04:46:26 CEST 2010


Hi all,

I have built a pipeline:
                               --->queue--->
xviddec--->ffmpegcolorspace--->videoscale--->autovideosink
filesrc---> avidemux
                               --->mad--->audioconvert--->autoaudiosink

when playback the media, the pipeline is stopping after linking the audio
and video pad.

it look like pad linking is OK,  I cannot find out the reason why it
stopped.

I have opened the DEBUG level to 3, following is the detail log, please help
me to analyse the the problems, thanks for your help.

0:00:00.001334382  2782  0x81d0008 INFO                GST_INIT
gst.c:628:init_pre: Initializing GStreamer Core Library version 0.10.25
0:00:00.001437557  2782  0x81d0008 INFO                GST_INIT
gst.c:629:init_pre: Using library installed in /usr/lib
0:00:00.001458708  2782  0x81d0008 INFO                GST_INIT
gst.c:639:init_pre: Linux ubuntu 2.6.31-21-generic #59-Ubuntu SMP Wed Mar 24
07:28:56 UTC 2010 i686
0:00:00.001602959  2782  0x81d0008 INFO                GST_INIT
gstquery.c:105:_gst_query_initialize: init queries
0:00:00.002777173  2782  0x81d0008 INFO                GST_INIT
gstmessage.c:73:_gst_message_initialize: init messages
0:00:00.004285303  2782  0x81d0008 INFO      GST_PLUGIN_LOADING
gstplugin.c:334:_gst_plugin_initialize: registering 0 static plugins
0:00:00.004619178  2782  0x81d0008 INFO      GST_PLUGIN_LOADING
gstplugin.c:250:gst_plugin_register_static: registered static plugin
"staticelements"
0:00:00.004644406  2782  0x81d0008 INFO      GST_PLUGIN_LOADING
gstplugin.c:252:gst_plugin_register_static: added static plugin
"staticelements", result: 1
0:00:00.005441376  2782  0x81d0008 INFO                GST_INIT
gst.c:937:ensure_current_registry: reading registry cache:
/home/champion/.gstreamer-0.10/registry.i486.bin
0:00:00.050551655  2782  0x81d0008 INFO            GST_REGISTRY
gstregistrybinary.c:1310:gst_registry_binary_read_cache: loaded
/home/champion/.gstreamer-0.10/registry.i486.bin in 0.045034 seconds
0:00:00.051227844  2783  0x81d0008 INFO                GST_INIT
gst.c:691:scan_and_update_registry: Validating registry cache:
/home/champion/.gstreamer-0.10/registry.i486.bin
0:00:00.063611857  2783  0x81d0008 INFO                  ladspa
gstladspa.c:818:ladspa_plugin_path_search: 3 dirs in search paths
"/usr/lib/ladspa:/usr/local/lib/ladspa:/usr/lib/ladspa"
0:00:00.063701524  2783  0x81d0008 INFO                  ladspa
gstladspa.c:763:ladspa_plugin_directory_search: scanning directory for
plugins "/usr/lib/ladspa"
0:00:00.063752024  2783  0x81d0008 INFO                  ladspa
gstladspa.c:763:ladspa_plugin_directory_search: scanning directory for
plugins "/usr/local/lib/ladspa"
0:00:00.063778485  2783  0x81d0008 WARN      GST_PLUGIN_LOADING
gstplugin.c:422:gst_plugin_register_func: plugin
"/usr/lib/gstreamer-0.10/libgstladspa.so" failed to initialise
0:00:00.066879721  2783  0x81d0008 WARN                pyplugin
gstpythonplugin.c:343:plugin_init: Couldn't g_module_open libpython. Reason:
/usr/lib/libpython2.6.so: cannot open shared object file: 没有该文件或目录
0:00:00.066934368  2783  0x81d0008 WARN      GST_PLUGIN_LOADING
gstplugin.c:422:gst_plugin_register_func: plugin
"/usr/lib/gstreamer-0.10/libgstpython.so" failed to initialise
0:00:00.067796444  2783  0x81d0008 INFO                GST_INIT
gst.c:786:scan_and_update_registry: Registry cache has not changed
0:00:00.068704649  2782  0x81d0008 INFO                GST_INIT
gst.c:980:ensure_current_registry: registry reading and updating done,
result = 1
0:00:00.068789517  2782  0x81d0008 INFO                GST_INIT
gst.c:463:gst_init_check: initialized GStreamer successfully
Check arguments...
Setup elements...
0:00:00.069218032  2782  0x81d0008 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:400:gst_element_factory_create: creating element
"pipeline" named "audio-player"
0:00:00.070990566  2782  0x81d0008 INFO      GST_PLUGIN_LOADING
gstplugin.c:658:gst_plugin_load_file: plugin
"/usr/lib/gstreamer-0.10/libgstcoreelements.so" loaded
0:00:00.071048017  2782  0x81d0008 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:400:gst_element_factory_create: creating element
"filesrc" named "file-source"
0:00:00.071421328  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:727:gst_element_add_pad:<GstBaseSrc at 0x82f30a0> adding pad 'src'
0:00:00.073322844  2782  0x81d0008 INFO      GST_PLUGIN_LOADING
gstplugin.c:658:gst_plugin_load_file: plugin
"/usr/lib/gstreamer-0.10/libgstavi.so" loaded
0:00:00.073381455  2782  0x81d0008 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:400:gst_element_factory_create: creating element
"avidemux" named "avi-demuxer"
0:00:00.074756710  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:727:gst_element_add_pad:<GstAviDemux at 0x82fb800> adding pad
'sink'
0:00:00.083683175  2782  0x81d0008 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:400:gst_element_factory_create: creating element "queue"
named "vedio-queue"
0:00:00.084087794  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:727:gst_element_add_pad:<GstQueue at 0x83050a0> adding pad 'sink'
0:00:00.084165839  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:727:gst_element_add_pad:<GstQueue at 0x83050a0> adding pad 'src'
0:00:00.085396849  2782  0x81d0008 INFO      GST_PLUGIN_LOADING
gstplugin.c:658:gst_plugin_load_file: plugin
"/usr/lib/gstreamer-0.10/libgstxvid.so" loaded
0:00:00.085460793  2782  0x81d0008 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:400:gst_element_factory_create: creating element
"xviddec" named "vedio-decoder"
0:00:00.085943372  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:727:gst_element_add_pad:<GstXvidDec at 0x82f4320> adding pad
'sink'
0:00:00.086065636  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:727:gst_element_add_pad:<GstXvidDec at 0x82f4320> adding pad 'src'
0:00:00.087605209  2782  0x81d0008 INFO      GST_PLUGIN_LOADING
gstplugin.c:658:gst_plugin_load_file: plugin
"/usr/lib/gstreamer-0.10/libgstffmpegcolorspace.so" loaded
0:00:00.087657470  2782  0x81d0008 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:402:gst_element_factory_create: creating element
"ffmpegcolorspace"
0:00:00.087790197  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:727:gst_element_add_pad:<GstBaseTransform at 0x830e120> adding pad
'sink'
0:00:00.087852062  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:727:gst_element_add_pad:<GstBaseTransform at 0x830e120> adding pad
'src'
0:00:00.166706813  2782  0x81d0008 INFO      GST_PLUGIN_LOADING
gstplugin.c:658:gst_plugin_load_file: plugin
"/usr/lib/gstreamer-0.10/libgstvideoscale.so" loaded
0:00:00.166773903  2782  0x81d0008 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:402:gst_element_factory_create: creating element
"videoscale"
0:00:00.167737064  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:727:gst_element_add_pad:<GstBaseTransform at 0x82f3288> adding pad
'sink'
0:00:00.167803384  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:727:gst_element_add_pad:<GstBaseTransform at 0x82f3288> adding pad
'src'
0:00:00.168612781  2782  0x81d0008 INFO      GST_PLUGIN_LOADING
gstplugin.c:658:gst_plugin_load_file: plugin
"/usr/lib/gstreamer-0.10/libgstautodetect.so" loaded
0:00:00.168663351  2782  0x81d0008 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:400:gst_element_factory_create: creating element
"autovideosink" named "vedio-sink"
0:00:00.168945091  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:727:gst_element_add_pad:<GstAutoVideoSink at 0x8318030> adding pad
'sink'
0:00:00.169272507  2782  0x81d0008 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:400:gst_element_factory_create: creating element
"fakesink" named "tempsink"
0:00:00.169634237  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:727:gst_element_add_pad:<GstBaseSink at 0x831a1a0> adding pad
'sink'
0:00:00.169725229  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:973:gst_element_get_static_pad: found pad tempsink:sink
0:00:00.169750450  2782  0x81d0008 INFO                GST_PADS
gstpad.c:1874:gst_pad_link_prepare: trying to link sink:proxypad0 and
tempsink:sink
0:00:00.169786506  2782  0x81d0008 INFO                GST_PADS
gstpad.c:2047:gst_pad_link: linked sink:proxypad0 and tempsink:sink,
successful
0:00:00.170904016  2782  0x81d0008 INFO      GST_PLUGIN_LOADING
gstplugin.c:658:gst_plugin_load_file: plugin
"/usr/lib/gstreamer-0.10/libgstmad.so" loaded
0:00:00.170962036  2782  0x81d0008 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:400:gst_element_factory_create: creating element "mad"
named "audio-decoder"
0:00:00.181760190  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:727:gst_element_add_pad:<GstMad at 0x831ec70> adding pad 'sink'
0:00:00.181884609  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:727:gst_element_add_pad:<GstMad at 0x831ec70> adding pad 'src'
0:00:00.182528693  2782  0x81d0008 INFO      GST_PLUGIN_LOADING
gstplugin.c:658:gst_plugin_load_file: plugin
"/usr/lib/gstreamer-0.10/libgstaudioconvert.so" loaded
0:00:00.182580409  2782  0x81d0008 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:400:gst_element_factory_create: creating element
"audioconvert" named "audio-converter"
0:00:00.183081795  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:727:gst_element_add_pad:<GstBaseTransform at 0x832c050> adding pad
'sink'
0:00:00.183173888  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:727:gst_element_add_pad:<GstBaseTransform at 0x832c050> adding pad
'src'
0:00:00.183450354  2782  0x81d0008 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:400:gst_element_factory_create: creating element
"autoaudiosink" named "audio-output"
0:00:00.183650617  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:727:gst_element_add_pad:<GstAutoAudioSink at 0x83181e0> adding pad
'sink'
0:00:00.183864100  2782  0x81d0008 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:400:gst_element_factory_create: creating element
"fakesink" named "tempsink"
0:00:00.183952569  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:727:gst_element_add_pad:<GstBaseSink at 0x831a4f0> adding pad
'sink'
0:00:00.184011312  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:973:gst_element_get_static_pad: found pad tempsink:sink
0:00:00.184033768  2782  0x81d0008 INFO                GST_PADS
gstpad.c:1874:gst_pad_link_prepare: trying to link sink:proxypad1 and
tempsink:sink
0:00:00.184064263  2782  0x81d0008 INFO                GST_PADS
gstpad.c:2047:gst_pad_link: linked sink:proxypad1 and tempsink:sink,
successful
0:00:00.184300154  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstutils.c:1587:gst_element_link_pads: trying to link element
file-source:(any) to element avi-demuxer:(any)
0:00:00.184330445  2782  0x81d0008 INFO                GST_PADS
gstutils.c:1045:gst_pad_check_link: trying to link file-source:src and
avi-demuxer:sink
0:00:00.184358880  2782  0x81d0008 INFO                GST_PADS
gstutils.c:1492:prepare_link_maybe_ghosting: file-source and avi-demuxer in
same bin, no need for ghost pads
0:00:00.184392031  2782  0x81d0008 INFO                GST_PADS
gstpad.c:1874:gst_pad_link_prepare: trying to link file-source:src and
avi-demuxer:sink
0:00:00.184412772  2782  0x81d0008 INFO                GST_PADS
gstpad.c:2047:gst_pad_link: linked file-source:src and avi-demuxer:sink,
successful
0:00:00.184450555  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstutils.c:1587:gst_element_link_pads: trying to link element
vedio-queue:(any) to element vedio-decoder:(any)
0:00:00.184469942  2782  0x81d0008 INFO                GST_PADS
gstutils.c:1045:gst_pad_check_link: trying to link vedio-queue:src and
vedio-decoder:src
0:00:00.184486369  2782  0x81d0008 INFO                GST_PADS
gstutils.c:1065:gst_pad_check_link: Sink pad vedio-decoder:src is not sink
pad, failed
0:00:00.184503360  2782  0x81d0008 INFO                GST_PADS
gstutils.c:1045:gst_pad_check_link: trying to link vedio-queue:src and
vedio-decoder:sink
0:00:00.184536955  2782  0x81d0008 INFO                GST_PADS
gstutils.c:1492:prepare_link_maybe_ghosting: vedio-queue and vedio-decoder
in same bin, no need for ghost pads
0:00:00.184562393  2782  0x81d0008 INFO                GST_PADS
gstpad.c:1874:gst_pad_link_prepare: trying to link vedio-queue:src and
vedio-decoder:sink
0:00:00.184585008  2782  0x81d0008 INFO                GST_PADS
gstpad.c:2047:gst_pad_link: linked vedio-queue:src and vedio-decoder:sink,
successful
0:00:00.184612812  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstutils.c:1587:gst_element_link_pads: trying to link element
vedio-decoder:(any) to element ffmpegcsp0:(any)
0:00:00.184631505  2782  0x81d0008 INFO                GST_PADS
gstutils.c:1045:gst_pad_check_link: trying to link vedio-decoder:src and
ffmpegcsp0:src
0:00:00.184647341  2782  0x81d0008 INFO                GST_PADS
gstutils.c:1065:gst_pad_check_link: Sink pad ffmpegcsp0:src is not sink pad,
failed
0:00:00.184663536  2782  0x81d0008 INFO                GST_PADS
gstutils.c:1045:gst_pad_check_link: trying to link vedio-decoder:src and
ffmpegcsp0:sink
0:00:00.196348524  2782  0x81d0008 INFO                GST_PADS
gstutils.c:1492:prepare_link_maybe_ghosting: vedio-decoder and ffmpegcsp0 in
same bin, no need for ghost pads
0:00:00.196439159  2782  0x81d0008 INFO                GST_PADS
gstpad.c:1874:gst_pad_link_prepare: trying to link vedio-decoder:src and
ffmpegcsp0:sink
0:00:00.199772335  2782  0x81d0008 INFO                GST_PADS
gstpad.c:2047:gst_pad_link: linked vedio-decoder:src and ffmpegcsp0:sink,
successful
0:00:00.199811820  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstutils.c:1587:gst_element_link_pads: trying to link element
ffmpegcsp0:(any) to element videoscale0:(any)
0:00:00.199837467  2782  0x81d0008 INFO                GST_PADS
gstutils.c:1045:gst_pad_check_link: trying to link ffmpegcsp0:src and
videoscale0:src
0:00:00.199854706  2782  0x81d0008 INFO                GST_PADS
gstutils.c:1065:gst_pad_check_link: Sink pad videoscale0:src is not sink
pad, failed
0:00:00.199871982  2782  0x81d0008 INFO                GST_PADS
gstutils.c:1045:gst_pad_check_link: trying to link ffmpegcsp0:src and
videoscale0:sink
0:00:00.228183991  2782  0x81d0008 INFO                GST_PADS
gstutils.c:1492:prepare_link_maybe_ghosting: ffmpegcsp0 and videoscale0 in
same bin, no need for ghost pads
0:00:00.228282794  2782  0x81d0008 INFO                GST_PADS
gstpad.c:1874:gst_pad_link_prepare: trying to link ffmpegcsp0:src and
videoscale0:sink
0:00:00.277685622  2782  0x81d0008 INFO                GST_PADS
gstpad.c:2047:gst_pad_link: linked ffmpegcsp0:src and videoscale0:sink,
successful
0:00:00.277791193  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstutils.c:1587:gst_element_link_pads: trying to link element
videoscale0:(any) to element vedio-sink:(any)
0:00:00.277820679  2782  0x81d0008 INFO                GST_PADS
gstutils.c:1045:gst_pad_check_link: trying to link videoscale0:src and
vedio-sink:sink
0:00:00.312809559  2782  0x81d0008 INFO                GST_PADS
gstutils.c:1492:prepare_link_maybe_ghosting: videoscale0 and vedio-sink in
same bin, no need for ghost pads
0:00:00.312902624  2782  0x81d0008 INFO                GST_PADS
gstpad.c:1874:gst_pad_link_prepare: trying to link videoscale0:src and
vedio-sink:sink
0:00:00.365002054  2782  0x81d0008 INFO                GST_PADS
gstpad.c:2047:gst_pad_link: linked videoscale0:src and vedio-sink:sink,
successful
0:00:00.365103920  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstutils.c:1587:gst_element_link_pads: trying to link element
audio-decoder:(any) to element audio-converter:(any)
0:00:00.365132676  2782  0x81d0008 INFO                GST_PADS
gstutils.c:1045:gst_pad_check_link: trying to link audio-decoder:src and
audio-converter:src
0:00:00.365150785  2782  0x81d0008 INFO                GST_PADS
gstutils.c:1065:gst_pad_check_link: Sink pad audio-converter:src is not sink
pad, failed
0:00:00.365168341  2782  0x81d0008 INFO                GST_PADS
gstutils.c:1045:gst_pad_check_link: trying to link audio-decoder:src and
audio-converter:sink
0:00:00.365475705  2782  0x81d0008 INFO                GST_PADS
gstutils.c:1492:prepare_link_maybe_ghosting: audio-decoder and
audio-converter in same bin, no need for ghost pads
0:00:00.365505559  2782  0x81d0008 INFO                GST_PADS
gstpad.c:1874:gst_pad_link_prepare: trying to link audio-decoder:src and
audio-converter:sink
0:00:00.365791913  2782  0x81d0008 INFO                GST_PADS
gstpad.c:2047:gst_pad_link: linked audio-decoder:src and
audio-converter:sink, successful
0:00:00.365822532  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstutils.c:1587:gst_element_link_pads: trying to link element
audio-converter:(any) to element audio-output:(any)
0:00:00.365842493  2782  0x81d0008 INFO                GST_PADS
gstutils.c:1045:gst_pad_check_link: trying to link audio-converter:src and
audio-output:sink
0:00:00.367796159  2782  0x81d0008 INFO                GST_PADS
gstutils.c:1492:prepare_link_maybe_ghosting: audio-converter and
audio-output in same bin, no need for ghost pads
0:00:00.367891548  2782  0x81d0008 INFO                GST_PADS
gstpad.c:1874:gst_pad_link_prepare: trying to link audio-converter:src and
audio-output:sink
0:00:00.369153902  2782  0x81d0008 INFO                GST_PADS
gstpad.c:2047:gst_pad_link: linked audio-converter:src and
audio-output:sink, successful
Now playing: ./aviplayer
0:00:00.369301412  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2238:gst_element_continue_state:<tempsink> completed state
change to NULL
0:00:00.369334583  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstpad.c:1668:gst_pad_unlink: unlinking sink:proxypad1(0x83182b8) and
tempsink:sink(0x83104c0)
0:00:00.369356352  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstpad.c:1711:gst_pad_unlink: unlinked sink:proxypad1 and tempsink:sink
0:00:00.369375181  2782  0x81d0008 INFO           GST_PARENTAGE
gstbin.c:1371:gst_bin_remove_func:<audio-output> removed child "tempsink"
0:00:00.369397619  2782  0x81d0008 INFO         GST_REFCOUNTING
gstelement.c:2852:gst_element_dispose:<tempsink> dispose
0:00:00.369414959  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:845:gst_element_remove_pad:<tempsink> removing pad 'sink'
0:00:00.369467431  2782  0x81d0008 INFO         GST_REFCOUNTING
gstelement.c:2876:gst_element_dispose:<tempsink> parent class dispose
0:00:00.369489924  2782  0x81d0008 INFO         GST_REFCOUNTING
gstelement.c:2907:gst_element_finalize:<tempsink> finalize
0:00:00.369507472  2782  0x81d0008 INFO         GST_REFCOUNTING
gstelement.c:2918:gst_element_finalize:<tempsink> finalize parent
0:00:00.379024069  2782  0x81d0008 INFO      GST_PLUGIN_LOADING
gstplugin.c:658:gst_plugin_load_file: plugin
"/usr/lib/gstreamer-0.10/libgstpulse.so" loaded
0:00:00.379081961  2782  0x81d0008 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:400:gst_element_factory_create: creating element
"pulsesink" named "audio-output-actual-sink-pulse"
0:00:00.379613048  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:727:gst_element_add_pad:<GstBaseSink at 0x8338288> adding pad
'sink'
0:00:00.380067951  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:973:gst_element_get_static_pad: found pad
audio-output-actual-sink-pulse:sink
0:00:00.385181856  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2238:gst_element_continue_state:<audio-output-actual-sink-pulse>
completed state change to READY
0:00:00.385246548  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2251:gst_element_continue_state:<audio-output-actual-sink-pulse>
posting state-changed NULL to READY
0:00:00.385367089  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:973:gst_element_get_static_pad: found pad
audio-output-actual-sink-pulse:sink
0:00:00.385393231  2782  0x81d0008 INFO                GST_PADS
gstpad.c:1874:gst_pad_link_prepare: trying to link sink:proxypad1 and
audio-output-actual-sink-pulse:sink
0:00:00.386892168  2782  0x81d0008 INFO                GST_PADS
gstpad.c:2047:gst_pad_link: linked sink:proxypad1 and
audio-output-actual-sink-pulse:sink, successful
0:00:00.386935661  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2146:gst_bin_element_set_state:<audio-output-actual-sink-pulse>
skipping transition from READY to  READY, since bin pending is READY : last
change state return follows
0:00:00.386960751  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2425:gst_bin_change_state_func:<audio-output> child
'audio-output-actual-sink-pulse' changed state to 2(READY) successfully
0:00:00.386987882  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2238:gst_element_continue_state:<audio-output> completed state
change to READY
0:00:00.387005229  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2251:gst_element_continue_state:<audio-output> posting
state-changed NULL to READY
0:00:00.387032891  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2425:gst_bin_change_state_func:<audio-player> child 'audio-output'
changed state to 2(READY) successfully
0:00:00.387064096  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2238:gst_element_continue_state:<tempsink> completed state
change to NULL
0:00:00.387087212  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstpad.c:1668:gst_pad_unlink: unlinking sink:proxypad0(0x8318108) and
tempsink:sink(0x83100d8)
0:00:00.387170832  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstpad.c:1711:gst_pad_unlink: unlinked sink:proxypad0 and tempsink:sink
0:00:00.387191060  2782  0x81d0008 INFO           GST_PARENTAGE
gstbin.c:1371:gst_bin_remove_func:<vedio-sink> removed child "tempsink"
0:00:00.387213187  2782  0x81d0008 INFO         GST_REFCOUNTING
gstelement.c:2852:gst_element_dispose:<tempsink> dispose
0:00:00.387230467  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:845:gst_element_remove_pad:<tempsink> removing pad 'sink'
0:00:00.387260486  2782  0x81d0008 INFO         GST_REFCOUNTING
gstelement.c:2876:gst_element_dispose:<tempsink> parent class dispose
0:00:00.387281066  2782  0x81d0008 INFO         GST_REFCOUNTING
gstelement.c:2907:gst_element_finalize:<tempsink> finalize
0:00:00.387298234  2782  0x81d0008 INFO         GST_REFCOUNTING
gstelement.c:2918:gst_element_finalize:<tempsink> finalize parent
0:00:00.388807609  2782  0x81d0008 INFO      GST_PLUGIN_LOADING
gstplugin.c:658:gst_plugin_load_file: plugin
"/usr/lib/gstreamer-0.10/libgstxvimagesink.so" loaded
0:00:00.388869538  2782  0x81d0008 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:400:gst_element_factory_create: creating element
"xvimagesink" named "vedio-sink-actual-sink-xvimage"
0:00:00.389234814  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:727:gst_element_add_pad:<GstBaseSink at 0x8342048> adding pad
'sink'
0:00:00.389301272  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:973:gst_element_get_static_pad: found pad
vedio-sink-actual-sink-xvimage:sink
0:00:00.400225569  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2238:gst_element_continue_state:<vedio-sink-actual-sink-xvimage>
completed state change to READY
0:00:00.400295616  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2251:gst_element_continue_state:<vedio-sink-actual-sink-xvimage>
posting state-changed NULL to READY
0:00:00.400390031  2782  0x81d0008 INFO        GST_ELEMENT_PADS
gstelement.c:973:gst_element_get_static_pad: found pad
vedio-sink-actual-sink-xvimage:sink
0:00:00.400416223  2782  0x81d0008 INFO                GST_PADS
gstpad.c:1874:gst_pad_link_prepare: trying to link sink:proxypad0 and
vedio-sink-actual-sink-xvimage:sink
0:00:00.447442261  2782  0x81d0008 INFO                GST_PADS
gstpad.c:2047:gst_pad_link: linked sink:proxypad0 and
vedio-sink-actual-sink-xvimage:sink, successful
0:00:00.447536132  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2146:gst_bin_element_set_state:<vedio-sink-actual-sink-xvimage>
skipping transition from READY to  READY, since bin pending is READY : last
change state return follows
0:00:00.447565194  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2425:gst_bin_change_state_func:<vedio-sink> child
'vedio-sink-actual-sink-xvimage' changed state to 2(READY) successfully
0:00:00.447589930  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2238:gst_element_continue_state:<vedio-sink> completed state
change to READY
0:00:00.447607718  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2251:gst_element_continue_state:<vedio-sink> posting
state-changed NULL to READY
0:00:00.447649467  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2425:gst_bin_change_state_func:<audio-player> child 'vedio-sink'
changed state to 2(READY) successfully
0:00:00.447675497  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2238:gst_element_continue_state:<audio-converter> completed
state change to READY
0:00:00.447691719  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2251:gst_element_continue_state:<audio-converter> posting
state-changed NULL to READY
0:00:00.447714052  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2425:gst_bin_change_state_func:<audio-player> child
'audio-converter' changed state to 2(READY) successfully
0:00:00.447737093  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2238:gst_element_continue_state:<videoscale0> completed state
change to READY
0:00:00.447753169  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2251:gst_element_continue_state:<videoscale0> posting
state-changed NULL to READY
0:00:00.447775146  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2425:gst_bin_change_state_func:<audio-player> child 'videoscale0'
changed state to 2(READY) successfully
0:00:00.447796837  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2238:gst_element_continue_state:<audio-decoder> completed state
change to READY
0:00:00.447812983  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2251:gst_element_continue_state:<audio-decoder> posting
state-changed NULL to READY
0:00:00.447834818  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2425:gst_bin_change_state_func:<audio-player> child 'audio-decoder'
changed state to 2(READY) successfully
0:00:00.447857873  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2238:gst_element_continue_state:<ffmpegcsp0> completed state
change to READY
0:00:00.447874510  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2251:gst_element_continue_state:<ffmpegcsp0> posting
state-changed NULL to READY
0:00:00.447896916  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2425:gst_bin_change_state_func:<audio-player> child 'ffmpegcsp0'
changed state to 2(READY) successfully
0:00:00.449081361  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2238:gst_element_continue_state:<vedio-decoder> completed state
change to READY
0:00:00.449158279  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2251:gst_element_continue_state:<vedio-decoder> posting
state-changed NULL to READY
0:00:00.449214494  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2425:gst_bin_change_state_func:<audio-player> child 'vedio-decoder'
changed state to 2(READY) successfully
0:00:00.449245753  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2238:gst_element_continue_state:<vedio-queue> completed state
change to READY
0:00:00.449263781  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2251:gst_element_continue_state:<vedio-queue> posting
state-changed NULL to READY
0:00:00.449287535  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2425:gst_bin_change_state_func:<audio-player> child 'vedio-queue'
changed state to 2(READY) successfully
0:00:00.449320006  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2238:gst_element_continue_state:<avi-demuxer> completed state
change to READY
0:00:00.449336861  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2251:gst_element_continue_state:<avi-demuxer> posting
state-changed NULL to READY
0:00:00.449359621  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2425:gst_bin_change_state_func:<audio-player> child 'avi-demuxer'
changed state to 2(READY) successfully
0:00:00.449387442  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2238:gst_element_continue_state:<file-source> completed state
change to READY
0:00:00.449404542  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2251:gst_element_continue_state:<file-source> posting
state-changed NULL to READY
0:00:00.449427002  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2425:gst_bin_change_state_func:<audio-player> child 'file-source'
changed state to 2(READY) successfully
0:00:00.493466464  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2211:gst_element_continue_state:<audio-player> committing state
from NULL to READY, pending PLAYING, next PAUSED
0:00:00.493546805  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2220:gst_element_continue_state:<audio-player> continue state
change READY to PAUSED, final PLAYING
0:00:00.493688622  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2431:gst_bin_change_state_func:<audio-output> child
'audio-output-actual-sink-pulse' is changing state asynchronously to PAUSED
0:00:00.493720359  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2431:gst_bin_change_state_func:<audio-player> child 'audio-output'
is changing state asynchronously to PAUSED
0:00:00.499529210  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2431:gst_bin_change_state_func:<vedio-sink> child
'vedio-sink-actual-sink-xvimage' is changing state asynchronously to PAUSED
0:00:00.499589403  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2431:gst_bin_change_state_func:<audio-player> child 'vedio-sink' is
changing state asynchronously to PAUSED
0:00:00.499622595  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2238:gst_element_continue_state:<audio-converter> completed
state change to PAUSED
0:00:00.499640796  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2251:gst_element_continue_state:<audio-converter> posting
state-changed READY to PAUSED
0:00:00.499668792  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2425:gst_bin_change_state_func:<audio-player> child
'audio-converter' changed state to 3(PAUSED) successfully
0:00:00.499697722  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2238:gst_element_continue_state:<videoscale0> completed state
change to PAUSED
0:00:00.499715130  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2251:gst_element_continue_state:<videoscale0> posting
state-changed READY to PAUSED
0:00:00.499737767  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2425:gst_bin_change_state_func:<audio-player> child 'videoscale0'
changed state to 3(PAUSED) successfully
0:00:00.499790742  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2238:gst_element_continue_state:<audio-decoder> completed state
change to PAUSED
0:00:00.499808004  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2251:gst_element_continue_state:<audio-decoder> posting
state-changed READY to PAUSED
0:00:00.499830045  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2425:gst_bin_change_state_func:<audio-player> child 'audio-decoder'
changed state to 3(PAUSED) successfully
0:00:00.499858905  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2238:gst_element_continue_state:<ffmpegcsp0> completed state
change to PAUSED
0:00:00.499876161  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2251:gst_element_continue_state:<ffmpegcsp0> posting
state-changed READY to PAUSED
0:00:00.499898242  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2425:gst_bin_change_state_func:<audio-player> child 'ffmpegcsp0'
changed state to 3(PAUSED) successfully
0:00:00.499926695  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2238:gst_element_continue_state:<vedio-decoder> completed state
change to PAUSED
0:00:00.499943162  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2251:gst_element_continue_state:<vedio-decoder> posting
state-changed READY to PAUSED
0:00:00.499965170  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2425:gst_bin_change_state_func:<audio-player> child 'vedio-decoder'
changed state to 3(PAUSED) successfully
0:00:00.500210782  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2238:gst_element_continue_state:<vedio-queue> completed state
change to PAUSED
0:00:00.500241358  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2251:gst_element_continue_state:<vedio-queue> posting
state-changed READY to PAUSED
0:00:00.500269339  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2425:gst_bin_change_state_func:<audio-player> child 'vedio-queue'
changed state to 3(PAUSED) successfully
0:00:00.500314823  2782  0x81d0008 INFO                 filesrc
gstfilesrc.c:981:gst_file_src_start:<file-source> opening file
/home/champion/program/gstreamer/demo/aviplayer/lixiaoli.avi
0:00:00.500398059  2782  0x81d0008 INFO                 filesrc
gstfilesrc.c:981:gst_file_src_start:<file-source> opening file
/home/champion/program/gstreamer/demo/aviplayer/lixiaoli.avi
0:00:00.511736726  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2238:gst_element_continue_state:<avi-demuxer> completed state
change to PAUSED
0:00:00.511806423  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2251:gst_element_continue_state:<avi-demuxer> posting
state-changed READY to PAUSED
0:00:00.511859614  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2425:gst_bin_change_state_func:<audio-player> child 'avi-demuxer'
changed state to 3(PAUSED) successfully
0:00:00.511902635  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2238:gst_element_continue_state:<file-source> completed state
change to PAUSED
0:00:00.511920128  2782  0x81d0008 INFO              GST_STATES
gstelement.c:2251:gst_element_continue_state:<file-source> posting
state-changed READY to PAUSED
0:00:00.511943579  2782  0x81d0008 INFO              GST_STATES
gstbin.c:2425:gst_bin_change_state_func:<audio-player> child 'file-source'
changed state to 3(PAUSED) successfully
Running...
0:00:00.512343603  2782  0x8351550 INFO                avidemux
gstavidemux.c:977:gst_avi_demux_parse_avih:<avi-demuxer> avih tag found:
0:00:00.512386941  2782  0x8351550 INFO                avidemux
gstavidemux.c:978:gst_avi_demux_parse_avih:<avi-demuxer>  us_frame    55555
0:00:00.512402443  2782  0x8351550 INFO                avidemux
gstavidemux.c:979:gst_avi_demux_parse_avih:<avi-demuxer>  max_bps     0
0:00:00.512416886  2782  0x8351550 INFO                avidemux
gstavidemux.c:980:gst_avi_demux_parse_avih:<avi-demuxer>  pad_gran    0
0:00:00.512430854  2782  0x8351550 INFO                avidemux
gstavidemux.c:981:gst_avi_demux_parse_avih:<avi-demuxer>  flags      
0x00000910
0:00:00.512445285  2782  0x8351550 INFO                avidemux
gstavidemux.c:982:gst_avi_demux_parse_avih:<avi-demuxer>  tot_frames  5342
0:00:00.512459532  2782  0x8351550 INFO                avidemux
gstavidemux.c:983:gst_avi_demux_parse_avih:<avi-demuxer>  init_frames 0
0:00:00.512473642  2782  0x8351550 INFO                avidemux
gstavidemux.c:984:gst_avi_demux_parse_avih:<avi-demuxer>  streams     2
0:00:00.512487637  2782  0x8351550 INFO                avidemux
gstavidemux.c:985:gst_avi_demux_parse_avih:<avi-demuxer>  bufsize     0
0:00:00.512501686  2782  0x8351550 INFO                avidemux
gstavidemux.c:986:gst_avi_demux_parse_avih:<avi-demuxer>  width       320
0:00:00.512515743  2782  0x8351550 INFO                avidemux
gstavidemux.c:987:gst_avi_demux_parse_avih:<avi-demuxer>  height      240
0:00:00.563434230  2782  0x8351550 INFO                avidemux
gstavidemux.c:988:gst_avi_demux_parse_avih:<avi-demuxer>  scale       0
0:00:00.563489707  2782  0x8351550 INFO                avidemux
gstavidemux.c:989:gst_avi_demux_parse_avih:<avi-demuxer>  rate        0
0:00:00.563504981  2782  0x8351550 INFO                avidemux
gstavidemux.c:990:gst_avi_demux_parse_avih:<avi-demuxer>  start       0
0:00:00.563530515  2782  0x8351550 INFO                avidemux
gstavidemux.c:991:gst_avi_demux_parse_avih:<avi-demuxer>  length      0
0:00:00.563568937  2782  0x8351550 INFO                    riff
riff-read.c:307:gst_riff_parse_strh:<avi-demuxer> strh tag found:
0:00:00.563588655  2782  0x8351550 INFO                    riff
riff-read.c:309:gst_riff_parse_strh:<avi-demuxer>  type        vids
0:00:00.563603152  2782  0x8351550 INFO                    riff
riff-read.c:311:gst_riff_parse_strh:<avi-demuxer>  fcc_handler XVID
0:00:00.563617632  2782  0x8351550 INFO                    riff
riff-read.c:312:gst_riff_parse_strh:<avi-demuxer>  flags       0x00000000
0:00:00.563632084  2782  0x8351550 INFO                    riff
riff-read.c:313:gst_riff_parse_strh:<avi-demuxer>  priority    0
0:00:00.563645876  2782  0x8351550 INFO                    riff
riff-read.c:314:gst_riff_parse_strh:<avi-demuxer>  init_frames 0
0:00:00.563659548  2782  0x8351550 INFO                    riff
riff-read.c:315:gst_riff_parse_strh:<avi-demuxer>  scale       1
0:00:00.563673395  2782  0x8351550 INFO                    riff
riff-read.c:316:gst_riff_parse_strh:<avi-demuxer>  rate        18
0:00:00.563687010  2782  0x8351550 INFO                    riff
riff-read.c:317:gst_riff_parse_strh:<avi-demuxer>  start       0
0:00:00.563700511  2782  0x8351550 INFO                    riff
riff-read.c:318:gst_riff_parse_strh:<avi-demuxer>  length      5342
0:00:00.563714487  2782  0x8351550 INFO                    riff
riff-read.c:319:gst_riff_parse_strh:<avi-demuxer>  bufsize     25183
0:00:00.563728405  2782  0x8351550 INFO                    riff
riff-read.c:320:gst_riff_parse_strh:<avi-demuxer>  quality     0
0:00:00.563742119  2782  0x8351550 INFO                    riff
riff-read.c:321:gst_riff_parse_strh:<avi-demuxer>  samplesize  0
0:00:00.563761415  2782  0x8351550 INFO                avidemux
gstavidemux.c:1563:gst_avi_demux_parse_stream: Stream duration according to
header: 0:04:56.777777777
0:00:00.563780381  2782  0x8351550 INFO                    riff
riff-read.c:398:gst_riff_parse_strf_vids:<avi-demuxer> strf tag found in
context vids:
0:00:00.563795970  2782  0x8351550 INFO                    riff
riff-read.c:399:gst_riff_parse_strf_vids:<avi-demuxer>  size        40
0:00:00.598678662  2782  0x8351550 INFO                    riff
riff-read.c:400:gst_riff_parse_strf_vids:<avi-demuxer>  width       320
0:00:00.598740975  2782  0x8351550 INFO                    riff
riff-read.c:401:gst_riff_parse_strf_vids:<avi-demuxer>  height      240
0:00:00.598756418  2782  0x8351550 INFO                    riff
riff-read.c:402:gst_riff_parse_strf_vids:<avi-demuxer>  planes      1
0:00:00.598770540  2782  0x8351550 INFO                    riff
riff-read.c:403:gst_riff_parse_strf_vids:<avi-demuxer>  bit_cnt     12
0:00:00.598784587  2782  0x8351550 INFO                    riff
riff-read.c:405:gst_riff_parse_strf_vids:<avi-demuxer>  compression XVID
0:00:00.598799535  2782  0x8351550 INFO                    riff
riff-read.c:406:gst_riff_parse_strf_vids:<avi-demuxer>  image_size  115200
0:00:00.598814061  2782  0x8351550 INFO                    riff
riff-read.c:407:gst_riff_parse_strf_vids:<avi-demuxer>  xpels_meter 0
0:00:00.598828337  2782  0x8351550 INFO                    riff
riff-read.c:408:gst_riff_parse_strf_vids:<avi-demuxer>  ypels_meter 0
0:00:00.598842311  2782  0x8351550 INFO                    riff
riff-read.c:409:gst_riff_parse_strf_vids:<avi-demuxer>  num_colors  0
0:00:00.598856301  2782  0x8351550 INFO                    riff
riff-read.c:410:gst_riff_parse_strf_vids:<avi-demuxer>  imp_colors  0
0:00:00.598892000  2782  0x8351550 INFO                avidemux
gstavidemux.c:1418:gst_avi_demux_riff_parse_vprp:<avi-demuxer> vprp tag
found in context vids:
0:00:00.598908191  2782  0x8351550 INFO                avidemux
gstavidemux.c:1419:gst_avi_demux_riff_parse_vprp:<avi-demuxer>  format_token 
0
0:00:00.598922838  2782  0x8351550 INFO                avidemux
gstavidemux.c:1420:gst_avi_demux_riff_parse_vprp:<avi-demuxer>  standard     
0
0:00:00.598937370  2782  0x8351550 INFO                avidemux
gstavidemux.c:1421:gst_avi_demux_riff_parse_vprp:<avi-demuxer>  vert_rate    
18
0:00:00.598951560  2782  0x8351550 INFO                avidemux
gstavidemux.c:1422:gst_avi_demux_riff_parse_vprp:<avi-demuxer>  hor_t_total  
320
0:00:00.598965894  2782  0x8351550 INFO                avidemux
gstavidemux.c:1423:gst_avi_demux_riff_parse_vprp:<avi-demuxer>  vert_lines   
240
0:00:00.598980220  2782  0x8351550 INFO                avidemux
gstavidemux.c:1425:gst_avi_demux_riff_parse_vprp:<avi-demuxer>  aspect       
16384:12249
0:00:00.598995153  2782  0x8351550 INFO                avidemux
gstavidemux.c:1426:gst_avi_demux_riff_parse_vprp:<avi-demuxer>  width        
320
0:00:00.599009326  2782  0x8351550 INFO                avidemux
gstavidemux.c:1427:gst_avi_demux_riff_parse_vprp:<avi-demuxer>  height       
240
0:00:00.599023650  2782  0x8351550 INFO                avidemux
gstavidemux.c:1428:gst_avi_demux_riff_parse_vprp:<avi-demuxer>  fields       
1
0:00:00.599038178  2782  0x8351550 INFO                avidemux
gstavidemux.c:1433:gst_avi_demux_riff_parse_vprp:<avi-demuxer>  field 0
description:
0:00:00.599052477  2782  0x8351550 INFO                avidemux
gstavidemux.c:1435:gst_avi_demux_riff_parse_vprp:<avi-demuxer>  
compressed_bm_height  240
0:00:00.599066766  2782  0x8351550 INFO                avidemux
gstavidemux.c:1437:gst_avi_demux_riff_parse_vprp:<avi-demuxer>  
compressed_bm_width  320
0:00:00.599081079  2782  0x8351550 INFO                avidemux
gstavidemux.c:1439:gst_avi_demux_riff_parse_vprp:<avi-demuxer>  
valid_bm_height       240
0:00:00.599107835  2782  0x8351550 INFO                avidemux
gstavidemux.c:1440:gst_avi_demux_riff_parse_vprp:<avi-demuxer>  
valid_bm_width        320
0:00:00.599122941  2782  0x8351550 INFO                avidemux
gstavidemux.c:1442:gst_avi_demux_riff_parse_vprp:<avi-demuxer>  
valid_bm_x_offset     0
0:00:00.599137232  2782  0x8351550 INFO                avidemux
gstavidemux.c:1444:gst_avi_demux_riff_parse_vprp:<avi-demuxer>  
valid_bm_y_offset     0
0:00:00.599151458  2782  0x8351550 INFO                avidemux
gstavidemux.c:1446:gst_avi_demux_riff_parse_vprp:<avi-demuxer>  
video_x_t_offset      0
0:00:00.599165656  2782  0x8351550 INFO                avidemux
gstavidemux.c:1447:gst_avi_demux_riff_parse_vprp:<avi-demuxer>  
video_y_start         0
0:00:00.599335736  2782  0x8351550 INFO        GST_ELEMENT_PADS
gstelement.c:727:gst_element_add_pad:<avi-demuxer> adding pad 'video_00'
Dynamic pad created, linking demuxer/decoder video/x-xvid,
framerate=(fraction)18/1, width=(int)320, height=(int)240,
pixel-aspect-ratio=(fraction)4096/4083
video codec: video/x-xvid, framerate=(fraction)18/1, width=(int)320,
height=(int)240, pixel-aspect-ratio=(fraction)4096/4083
0:00:00.599649535  2782  0x8351550 INFO        GST_ELEMENT_PADS
gstelement.c:973:gst_element_get_static_pad: found pad vedio-queue:sink
0:00:00.599701821  2782  0x8351550 INFO                GST_PADS
gstpad.c:1874:gst_pad_link_prepare: trying to link avi-demuxer:video_00 and
vedio-queue:sink
0:00:00.599909221  2782  0x8351550 INFO                GST_PADS
gstpad.c:2047:gst_pad_link: linked avi-demuxer:video_00 and
vedio-queue:sink, successful
link pad ok!
0:00:00.599971105  2782  0x8351550 INFO                    riff
riff-read.c:307:gst_riff_parse_strh:<avi-demuxer> strh tag found:
0:00:00.599987480  2782  0x8351550 INFO                    riff
riff-read.c:309:gst_riff_parse_strh:<avi-demuxer>  type        auds
0:00:00.600001691  2782  0x8351550 INFO                    riff
riff-read.c:311:gst_riff_parse_strh:<avi-demuxer>  fcc_handler U
0:00:00.600015810  2782  0x8351550 INFO                    riff
riff-read.c:312:gst_riff_parse_strh:<avi-demuxer>  flags       0x00000000
0:00:00.600030333  2782  0x8351550 INFO                    riff
riff-read.c:313:gst_riff_parse_strh:<avi-demuxer>  priority    0
0:00:00.600044341  2782  0x8351550 INFO                    riff
riff-read.c:314:gst_riff_parse_strh:<avi-demuxer>  init_frames 20
0:00:00.600058503  2782  0x8351550 INFO                    riff
riff-read.c:315:gst_riff_parse_strh:<avi-demuxer>  scale       1
0:00:00.600072501  2782  0x8351550 INFO                    riff
riff-read.c:316:gst_riff_parse_strh:<avi-demuxer>  rate        8000
0:00:00.600086283  2782  0x8351550 INFO                    riff
riff-read.c:317:gst_riff_parse_strh:<avi-demuxer>  start       444
0:00:00.600100191  2782  0x8351550 INFO                    riff
riff-read.c:318:gst_riff_parse_strh:<avi-demuxer>  length      2370873
0:00:00.600114411  2782  0x8351550 INFO                    riff
riff-read.c:319:gst_riff_parse_strh:<avi-demuxer>  bufsize     209
0:00:00.600128426  2782  0x8351550 INFO                    riff
riff-read.c:320:gst_riff_parse_strh:<avi-demuxer>  quality     0
0:00:00.600142399  2782  0x8351550 INFO                    riff
riff-read.c:321:gst_riff_parse_strh:<avi-demuxer>  samplesize  1
0:00:00.600158975  2782  0x8351550 INFO                avidemux
gstavidemux.c:1563:gst_avi_demux_parse_stream: Stream duration according to
header: 0:04:56.359125000
0:00:00.600177334  2782  0x8351550 INFO                    riff
riff-read.c:492:gst_riff_parse_strf_auds:<avi-demuxer> strf tag found in
context auds:
0:00:00.600192312  2782  0x8351550 INFO                    riff
riff-read.c:493:gst_riff_parse_strf_auds:<avi-demuxer>  format      85
0:00:00.600206346  2782  0x8351550 INFO                    riff
riff-read.c:494:gst_riff_parse_strf_auds:<avi-demuxer>  channels    2
0:00:00.600220396  2782  0x8351550 INFO                    riff
riff-read.c:495:gst_riff_parse_strf_auds:<avi-demuxer>  rate        22050
0:00:00.600234743  2782  0x8351550 INFO                    riff
riff-read.c:496:gst_riff_parse_strf_auds:<avi-demuxer>  av_bps      8000
0:00:00.600248751  2782  0x8351550 INFO                    riff
riff-read.c:497:gst_riff_parse_strf_auds:<avi-demuxer>  blockalign  1
0:00:00.668304626  2782  0x8351550 INFO                    riff
riff-read.c:498:gst_riff_parse_strf_auds:<avi-demuxer>  size        0
0:00:00.668364017  2782  0x8351550 INFO                    riff
riff-read.c:500:gst_riff_parse_strf_auds:<avi-demuxer>  12 bytes extradata
0:00:00.668508157  2782  0x8351550 INFO        GST_ELEMENT_PADS
gstelement.c:727:gst_element_add_pad:<avi-demuxer> adding pad 'audio_00'
Dynamic pad created, linking demuxer/decoder audio/mpeg, mpegversion=(int)1,
layer=(int)3, rate=(int)22050, channels=(int)2,
codec_data=(buffer)010002000000d10001000000
audio codec: audio/mpeg, mpegversion=(int)1, layer=(int)3, rate=(int)22050,
channels=(int)2, codec_data=(buffer)010002000000d10001000000
0:00:00.668633864  2782  0x8351550 INFO        GST_ELEMENT_PADS
gstelement.c:973:gst_element_get_static_pad: found pad audio-decoder:sink
0:00:00.668674937  2782  0x8351550 INFO                GST_PADS
gstpad.c:1874:gst_pad_link_prepare: trying to link avi-demuxer:audio_00 and
audio-decoder:sink
0:00:00.668720188  2782  0x8351550 INFO                GST_PADS
gstpad.c:2047:gst_pad_link: linked avi-demuxer:audio_00 and
audio-decoder:sink, successful
link pad ok!
0:00:00.669644537  2782  0x8351550 INFO                avidemux
gstavidemux.c:2188:gst_avi_demux_stream_index: will parse index chunk size
266992 for tag idx1
0:00:00.669738298  2782  0x8351550 INFO                avidemux
gstavidemux.c:1989:gst_avi_demux_parse_index: Parsing index, nr_entries = 
16687
0:00:00.678722741  2782  0x8351550 INFO                avidemux
gstavidemux.c:2102:gst_avi_demux_parse_index: Parsed index,  16687 entries,
11466 keyframes, entry size = 48, total size =     800976
0:00:00.715919582  2782  0x8351550 INFO                avidemux
gstavidemux.c:2882:gst_avi_demux_calculate_durations_from_index: Stream 0
duration according to index: 0:04:56.777777777
0:00:00.716002939  2782  0x8351550 INFO                avidemux
gstavidemux.c:2882:gst_avi_demux_calculate_durations_from_index: Stream 1
duration according to index: 0:04:56.359125000
0:00:00.716021877  2782  0x8351550 INFO                avidemux
gstavidemux.c:2912:gst_avi_demux_calculate_durations_from_index: Setting
total duration to: 0:04:56.777777777
0:00:00.716053114  2782  0x8351550 INFO               GST_EVENT
gstevent.c:591:gst_event_new_new_segment_full: creating newsegment update 0,
rate 1.000000, format GST_FORMAT_TIME, start 0:00:00.000000000, stop
0:04:56.777777777, position 0:00:00.000000000
0:00:00.716289497  2782  0x8351550 INFO                GST_PADS
gstpad.c:3337:gst_pad_event_default_dispatch:<audio-decoder:sink> Sending
event 0x8330140 (tag) to all internally linked pads
0:00:00.716358859  2782  0x8351550 INFO                GST_PADS
gstpad.c:3337:gst_pad_event_default_dispatch:<audio-decoder:sink> Sending
event 0x83360a0 (tag) to all internally linked pads
 Get Tag message !
 Get Tag message !
 Get Tag message !
 Get Tag message !
0:00:00.730741025  2782  0x8351550 INFO           basetransform
gstbasetransform.c:1101:gst_base_transform_setcaps:<audio-converter> reuse
caps
0:00:00.730844177  2782  0x8351550 INFO            audioconvert
audioconvert.c:459:audio_convert_prepare_context: use int mixing
0:00:00.730860213  2782  0x8351550 INFO            audioconvert
audioconvert.c:465:audio_convert_prepare_context: unitsizes: 8 -> 8
0:00:00.730875013  2782  0x8351550 INFO            audioconvert
audioconvert.c:475:audio_convert_prepare_context: in default 1, mix
passthrough 1, out default 1
0:00:00.731283100  2782  0x8351550 INFO                   pulse
pulsesink.c:675:gst_pulseringbuffer_acquire:<audio-output-actual-sink-pulse>
tlength:   35200
0:00:00.731311751  2782  0x8351550 INFO                   pulse
pulsesink.c:676:gst_pulseringbuffer_acquire:<audio-output-actual-sink-pulse>
maxlength: -1
0:00:00.731327891  2782  0x8351550 INFO                   pulse
pulsesink.c:677:gst_pulseringbuffer_acquire:<audio-output-actual-sink-pulse>
prebuf:    0
0:00:00.731342817  2782  0x8351550 INFO                   pulse
pulsesink.c:678:gst_pulseringbuffer_acquire:<audio-output-actual-sink-pulse>
minreq:    1760
0:00:00.733718270  2782  0x8351550 INFO                   pulse
pulsesink.c:743:gst_pulseringbuffer_acquire:<audio-output-actual-sink-pulse>
tlength:   63736 (wanted: 35200)
0:00:00.733790778  2782  0x8351550 INFO                   pulse
pulsesink.c:744:gst_pulseringbuffer_acquire:<audio-output-actual-sink-pulse>
maxlength: 4194304
0:00:00.733807178  2782  0x8351550 INFO                   pulse
pulsesink.c:745:gst_pulseringbuffer_acquire:<audio-output-actual-sink-pulse>
prebuf:    0
0:00:00.733822348  2782  0x8351550 INFO                   pulse
pulsesink.c:747:gst_pulseringbuffer_acquire:<audio-output-actual-sink-pulse>
minreq:    1760 (wanted 1760)
0:00:00.734066600  2782  0x8351550 INFO              GST_STATES
gstbin.c:2815:bin_handle_async_done:<audio-output> committing state from
READY to PAUSED, old pending PAUSED
0:00:00.734091585  2782  0x8351550 INFO              GST_STATES
gstbin.c:2835:bin_handle_async_done:<audio-output> completed state change,
pending VOID 
-- 
View this message in context: http://gstreamer-devel.966125.n4.nabble.com/help-me-to-detect-a-pipeline-problem-tp2229432p2229432.html
Sent from the GStreamer-devel mailing list archive at Nabble.com.




More information about the gstreamer-devel mailing list