INFO  (0x8719848 - 309451:18:38.646490000)        GST_INIT(22850) gstcpu.c(55):_gst_cpu_initialize: CPU features: (00000000) MMX SSE 
INFO  (0x8719848 - 309451:18:38.649821000) GST_PLUGIN_LOADING(22850) gstplugin.c(134):_gst_plugin_register_static: loaded static plugin "gstcoreelements"
INFO  (0x8719848 - 309451:18:38.684843000)         default(22850) gstxmlregistry.c(697):gst_xml_registry_load: loaded user_registry in 0.032709 seconds (/root/.gstreamer-0.8/registry.xml)
INFO  (0x8719848 - 309451:18:39.004442000)         default(22850) gstxmlregistry.c(697):gst_xml_registry_load: loaded global_registry in 0.319241 seconds (/usr/local/var/cache/gstreamer-0.8/registry.xml)
INFO  (0x8719848 - 309451:18:39.005399000) GST_ELEMENT_FACTORY(22850) gstelementfactory.c(296):gst_element_factory_create: creating "pipeline" named "mix-pipeline"
INFO  (0x8719848 - 309451:18:39.007055000) GST_PLUGIN_LOADING(22850) gstplugin.c(356):gst_plugin_check_file: looks like a gst plugin "/usr/local/lib/gstreamer-0.8/libgstoptscheduler.so"
INFO  (0x8719848 - 309451:18:39.008442000) GST_PLUGIN_LOADING(22850) gstplugin.c(447):gst_plugin_load_file: plugin "/usr/local/lib/gstreamer-0.8/libgstoptscheduler.so" loaded
INFO  (0x8719848 - 309451:18:39.010354000) GST_PLUGIN_LOADING(22850) gstplugin.c(356):gst_plugin_check_file: looks like a gst plugin "/usr/lib/gstreamer-0.8//libgstladspa.so"
INFO  (0x8719848 - 309451:18:39.044855000) GST_PLUGIN_LOADING(22850) gstplugin.c(447):gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.8//libgstladspa.so" loaded
INFO  (0x8719848 - 309451:18:39.045041000) GST_ELEMENT_FACTORY(22850) gstelementfactory.c(296):gst_element_factory_create: creating "ladspa-vlevel-stereo" named "avc"
INFO  (0x8719848 - 309451:18:39.045991000) GST_ELEMENT_PADS(22850) gstelement.c(1175):gst_element_add_pad:<ladspa-vlevel-stereo@0x872fe88> adding pad 'Input-1'
INFO  (0x8719848 - 309451:18:39.046161000) GST_ELEMENT_PADS(22850) gstelement.c(1175):gst_element_add_pad:<ladspa-vlevel-stereo@0x872fe88> adding pad 'Output-1'
INFO  (0x8719848 - 309451:18:39.046315000) GST_ELEMENT_PADS(22850) gstelement.c(1175):gst_element_add_pad:<ladspa-vlevel-stereo@0x872fe88> adding pad 'Input-2'
INFO  (0x8719848 - 309451:18:39.046410000) GST_ELEMENT_PADS(22850) gstelement.c(1175):gst_element_add_pad:<ladspa-vlevel-stereo@0x872fe88> adding pad 'Output-2'
INFO  (0x8719848 - 309451:18:39.048428000) GST_PLUGIN_LOADING(22850) gstplugin.c(356):gst_plugin_check_file: looks like a gst plugin "/usr/local/lib/gstreamer-0.8/libgstaudioconvert.so"
INFO  (0x8719848 - 309451:18:39.050345000) GST_PLUGIN_LOADING(22850) gstplugin.c(356):gst_plugin_check_file: looks like a gst plugin "/usr/local/lib/gstreamer-0.8/libgstaudio.so"
INFO  (0x8719848 - 309451:18:39.051440000) GST_PLUGIN_LOADING(22850) gstplugin.c(447):gst_plugin_load_file: plugin "/usr/local/lib/gstreamer-0.8/libgstaudio.so" loaded
INFO  (0x8719848 - 309451:18:39.054655000) GST_PLUGIN_LOADING(22850) gstplugin.c(447):gst_plugin_load_file: plugin "/usr/local/lib/gstreamer-0.8/libgstaudioconvert.so" loaded
INFO  (0x8719848 - 309451:18:39.054821000) GST_ELEMENT_FACTORY(22850) gstelementfactory.c(296):gst_element_factory_create: creating "audioconvert" named "converter1"
INFO  (0x8719848 - 309451:18:39.055350000) GST_ELEMENT_PADS(22850) gstelement.c(1175):gst_element_add_pad:<GstAudioConvert@0x87a0dd8> adding pad 'sink'
INFO  (0x8719848 - 309451:18:39.055718000) GST_ELEMENT_PADS(22850) gstelement.c(1175):gst_element_add_pad:<GstAudioConvert@0x87a0dd8> adding pad 'src'
INFO  (0x8719848 - 309451:18:39.056070000) GST_ELEMENT_FACTORY(22850) gstelementfactory.c(296):gst_element_factory_create: creating "audioconvert" named "converter2"
INFO  (0x8719848 - 309451:18:39.056474000) GST_ELEMENT_PADS(22850) gstelement.c(1175):gst_element_add_pad:<GstAudioConvert@0x87a21c8> adding pad 'sink'
INFO  (0x8719848 - 309451:18:39.056770000) GST_ELEMENT_PADS(22850) gstelement.c(1175):gst_element_add_pad:<GstAudioConvert@0x87a21c8> adding pad 'src'
INFO  (0x8719848 - 309451:18:39.057014000) GST_ELEMENT_FACTORY(22850) gstelementfactory.c(296):gst_element_factory_create: creating "buffer-frames-convert" named "frame-converter1"
INFO  (0x8719848 - 309451:18:39.057349000) GST_ELEMENT_PADS(22850) gstelement.c(1175):gst_element_add_pad:<GstBufferFramesConvert@0x87a3ac0> adding pad 'sink'
INFO  (0x8719848 - 309451:18:39.057634000) GST_ELEMENT_PADS(22850) gstelement.c(1175):gst_element_add_pad:<GstBufferFramesConvert@0x87a3ac0> adding pad 'src'
INFO  (0x8719848 - 309451:18:39.057918000) GST_ELEMENT_FACTORY(22850) gstelementfactory.c(296):gst_element_factory_create: creating "buffer-frames-convert" named "frame-converter2"
INFO  (0x8719848 - 309451:18:39.058089000) GST_ELEMENT_PADS(22850) gstelement.c(1175):gst_element_add_pad:<GstBufferFramesConvert@0x87a4140> adding pad 'sink'
INFO  (0x8719848 - 309451:18:39.058281000) GST_ELEMENT_PADS(22850) gstelement.c(1175):gst_element_add_pad:<GstBufferFramesConvert@0x87a4140> adding pad 'src'
INFO  (0x8719848 - 309451:18:39.063245000) GST_PLUGIN_LOADING(22850) gstplugin.c(356):gst_plugin_check_file: looks like a gst plugin "/usr/local/lib/gstreamer-0.8/libgstalsa.so"
INFO  (0x8719848 - 309451:18:39.068273000) GST_PLUGIN_LOADING(22850) gstplugin.c(356):gst_plugin_check_file: looks like a gst plugin "/usr/local/lib/gstreamer-0.8/libgstaudio.so"
INFO  (0x8719848 - 309451:18:39.095359000) GST_PLUGIN_LOADING(22850) gstplugin.c(447):gst_plugin_load_file: plugin "/usr/local/lib/gstreamer-0.8/libgstalsa.so" loaded
INFO  (0x8719848 - 309451:18:39.095538000) GST_ELEMENT_FACTORY(22850) gstelementfactory.c(296):gst_element_factory_create: creating "alsasink" named "alsa-output"
INFO  (0x8719848 - 309451:18:39.095917000) GST_ELEMENT_PADS(22850) gstelement.c(1175):gst_element_add_pad:<GstAlsaSink@0x87a8408> adding pad 'sink'
INFO  (0x8719848 - 309451:18:39.097030000) GST_ELEMENT_FACTORY(22850) gstelementfactory.c(296):gst_element_factory_create: creating "bin" named "bin-1"
INFO  (0x8719848 - 309451:18:39.099340000) GST_PLUGIN_LOADING(22850) gstplugin.c(356):gst_plugin_check_file: looks like a gst plugin "/usr/local/lib/gstreamer-0.8/libgstelements.so"
INFO  (0x8719848 - 309451:18:39.111587000) GST_PLUGIN_LOADING(22850) gstplugin.c(447):gst_plugin_load_file: plugin "/usr/local/lib/gstreamer-0.8/libgstelements.so" loaded
INFO  (0x8719848 - 309451:18:39.111771000) GST_ELEMENT_FACTORY(22850) gstelementfactory.c(296):gst_element_factory_create: creating "filesrc" named "file-source-1"
INFO  (0x8719848 - 309451:18:39.112214000) GST_ELEMENT_PADS(22850) gstelement.c(1175):gst_element_add_pad:<GstFileSrc@0x87af4d0> adding pad 'src'
INFO  (0x8719848 - 309451:18:39.116837000) GST_PLUGIN_LOADING(22850) gstplugin.c(356):gst_plugin_check_file: looks like a gst plugin "/usr/local/lib/gstreamer-0.8/libgstmmp.so"
INFO  (0x8719848 - 309451:18:39.122336000) GST_PLUGIN_LOADING(22850) gstplugin.c(447):gst_plugin_load_file: plugin "/usr/local/lib/gstreamer-0.8/libgstmmp.so" loaded
INFO  (0x8719848 - 309451:18:39.122532000) GST_ELEMENT_FACTORY(22850) gstelementfactory.c(296):gst_element_factory_create: creating "mmp" named "mmp-1"
INFO  (0x8719848 - 309451:18:39.122927000) GST_ELEMENT_PADS(22850) gstelement.c(1175):gst_element_add_pad:<mmp_plugin@0x87b00a0> adding pad 'sink'
INFO  (0x8719848 - 309451:18:39.123014000) GST_ELEMENT_PADS(22850) gstelement.c(1175):gst_element_add_pad:<mmp_plugin@0x87b00a0> adding pad 'src'
INFO  (0x8719848 - 309451:18:39.125696000) GST_PLUGIN_LOADING(22850) gstplugin.c(356):gst_plugin_check_file: looks like a gst plugin "/usr/local/lib/gstreamer-0.8/libgstmad.so"
INFO  (0x8719848 - 309451:18:39.129193000) GST_PLUGIN_LOADING(22850) gstplugin.c(356):gst_plugin_check_file: looks like a gst plugin "/usr/local/lib/gstreamer-0.8/libgsttagedit.so"
INFO  (0x8719848 - 309451:18:39.131393000) GST_PLUGIN_LOADING(22850) gstplugin.c(447):gst_plugin_load_file: plugin "/usr/local/lib/gstreamer-0.8/libgsttagedit.so" loaded
INFO  (0x8719848 - 309451:18:39.134886000) GST_PLUGIN_LOADING(22850) gstplugin.c(447):gst_plugin_load_file: plugin "/usr/local/lib/gstreamer-0.8/libgstmad.so" loaded
INFO  (0x8719848 - 309451:18:39.135050000) GST_ELEMENT_FACTORY(22850) gstelementfactory.c(296):gst_element_factory_create: creating "mad" named "mp3-1"
INFO  (0x8719848 - 309451:18:39.135615000) GST_ELEMENT_PADS(22850) gstelement.c(1175):gst_element_add_pad:<GstMad@0x87b3798> adding pad 'sink'
INFO  (0x8719848 - 309451:18:39.135888000) GST_ELEMENT_PADS(22850) gstelement.c(1175):gst_element_add_pad:<GstMad@0x87b3798> adding pad 'src'
INFO  (0x8719848 - 309451:18:39.136862000) GST_ELEMENT_PADS(22850) gstelement.c(1893):gst_element_link_pads_filtered: trying to link element file-source-1:(any) to element mmp-1:(any)
INFO  (0x8719848 - 309451:18:39.137028000)        GST_PADS(22850) gstpad.c(1693):gst_pad_can_link_filtered: trying to link file-source-1:src and mmp-1:sink
INFO  (0x8719848 - 309451:18:39.137368000)        GST_PADS(22850) gstpad.c(1814):gst_pad_link_filtered: trying to link file-source-1:src and mmp-1:sink
INFO  (0x8719848 - 309451:18:39.137585000)        GST_PADS(22850) gstpad.c(1904):gst_pad_link_filtered: not telling link to scheduler file-source-1:src and mmp-1:sink, (nil) (nil)
INFO  (0x8719848 - 309451:18:39.137665000)        GST_PADS(22850) gstpad.c(1908):gst_pad_link_filtered: linked file-source-1:src and mmp-1:sink, successful
INFO  (0x8719848 - 309451:18:39.137720000) GST_ELEMENT_PADS(22850) gstelement.c(1893):gst_element_link_pads_filtered: trying to link element mmp-1:(any) to element mp3-1:(any)
INFO  (0x8719848 - 309451:18:39.137780000)        GST_PADS(22850) gstpad.c(1693):gst_pad_can_link_filtered: trying to link mmp-1:src and mp3-1:sink
INFO  (0x8719848 - 309451:18:39.138107000)        GST_PADS(22850) gstpad.c(1814):gst_pad_link_filtered: trying to link mmp-1:src and mp3-1:sink
INFO  (0x8719848 - 309451:18:39.138592000)        GST_PADS(22850) gstpad.c(1904):gst_pad_link_filtered: not telling link to scheduler mmp-1:src and mp3-1:sink, (nil) (nil)
INFO  (0x8719848 - 309451:18:39.138671000)        GST_PADS(22850) gstpad.c(1908):gst_pad_link_filtered: linked mmp-1:src and mp3-1:sink, successful
INFO  (0x8719848 - 309451:18:39.138757000)   GST_PARENTAGE(22850) gstbin.c(526):gst_bin_add:<bin-1> adding element "file-source-1"
INFO  (0x8719848 - 309451:18:39.138839000)   GST_PARENTAGE(22850) gstbin.c(526):gst_bin_add:<bin-1> adding element "mmp-1"
INFO  (0x8719848 - 309451:18:39.138905000)   GST_PARENTAGE(22850) gstbin.c(526):gst_bin_add:<bin-1> adding element "mp3-1"
INFO  (0x8719848 - 309451:18:39.138990000)      GST_STATES(22850) gstelement.c(2825):gst_element_set_state_func:<file-source-1> setting state from NULL to READY
INFO  (0x8719848 - 309451:18:39.139114000)      GST_STATES(22850) gstelement.c(2825):gst_element_set_state_func:<mmp-1> setting state from NULL to READY
INFO  (0x8719848 - 309451:18:39.139312000)      GST_STATES(22850) gstelement.c(2825):gst_element_set_state_func:<mp3-1> setting state from NULL to READY
INFO  (0x8719848 - 309451:18:39.139405000) GST_ELEMENT_PADS(22850) gstelement.c(1374):gst_element_get_static_pad: found pad mp3-1:src
INFO  (0x8719848 - 309451:18:39.139782000) GST_ELEMENT_PADS(22850) gstelement.c(1175):gst_element_add_pad:<bin-1> adding pad 'stream-1'
INFO  (0x8719848 - 309451:18:39.139963000)   GST_PARENTAGE(22850) gstbin.c(526):gst_bin_add:<mix-pipeline> adding element "bin-1"
INFO  (0x8719848 - 309451:18:39.140038000)      GST_STATES(22850) gstelement.c(2825):gst_element_set_state_func:<mix-pipeline> setting state from NULL to READY
INFO  (0x8719848 - 309451:18:39.140416000)       scheduler(22850) gstoptimalscheduler.c(2123):gst_opt_scheduler_pad_link: scheduling link between file-source-1:src and mmp-1:sink
INFO  (0x8719848 - 309451:18:39.140528000)       scheduler(22850) gstoptimalscheduler.c(2123):gst_opt_scheduler_pad_link: scheduling link between mmp-1:src and mp3-1:sink
INFO  (0x8719848 - 309451:18:39.140610000)   GST_PARENTAGE(22850) gstbin.c(526):gst_bin_add:<mix-pipeline> adding element "converter1"
INFO  (0x8719848 - 309451:18:39.140692000)   GST_PARENTAGE(22850) gstbin.c(526):gst_bin_add:<mix-pipeline> adding element "frame-converter1"
INFO  (0x8719848 - 309451:18:39.140772000)   GST_PARENTAGE(22850) gstbin.c(526):gst_bin_add:<mix-pipeline> adding element "avc"
INFO  (0x8719848 - 309451:18:39.140959000)   GST_PARENTAGE(22850) gstbin.c(526):gst_bin_add:<mix-pipeline> adding element "alsa-output"
INFO  (0x8719848 - 309451:18:39.141113000)   GST_PARENTAGE(22850) gstbin.c(526):gst_bin_add:<mix-pipeline> adding element "frame-converter2"
INFO  (0x8719848 - 309451:18:39.141221000) GST_ELEMENT_PADS(22850) gstelement.c(1374):gst_element_get_static_pad: found pad bin-1:stream-1
INFO  (0x8719848 - 309451:18:39.141275000) GST_ELEMENT_PADS(22850) gstelement.c(1374):gst_element_get_static_pad: found pad converter1:sink
INFO  (0x8719848 - 309451:18:39.141331000)        GST_PADS(22850) gstpad.c(1814):gst_pad_link_filtered: trying to link bin-1:stream-1 and converter1:sink
INFO  (0x8719848 - 309451:18:39.141384000)        GST_PADS(22850) gstpad.c(1822):gst_pad_link_filtered: *actually* linking mp3-1:src and converter1:sink
INFO  (0x8719848 - 309451:18:39.196451000)       scheduler(22850) gstoptimalscheduler.c(2123):gst_opt_scheduler_pad_link: scheduling link between mp3-1:src and converter1:sink
INFO  (0x8719848 - 309451:18:39.196694000)        GST_PADS(22850) gstpad.c(1908):gst_pad_link_filtered: linked mp3-1:src and converter1:sink, successful
*** Linking Converter to Frame-converter ***
INFO  (0x8719848 - 309451:18:39.196871000) GST_ELEMENT_PADS(22850) gstelement.c(1893):gst_element_link_pads_filtered: trying to link element converter1:(any) to element frame-converter1:(any)
INFO  (0x8719848 - 309451:18:39.196942000)        GST_PADS(22850) gstpad.c(1693):gst_pad_can_link_filtered: trying to link converter1:src and frame-converter1:sink
INFO  (0x8719848 - 309451:18:39.268891000)        GST_PADS(22850) gstpad.c(1814):gst_pad_link_filtered: trying to link converter1:src and frame-converter1:sink
INFO  (0x8719848 - 309451:18:39.387318000)       scheduler(22850) gstoptimalscheduler.c(2123):gst_opt_scheduler_pad_link: scheduling link between converter1:src and frame-converter1:sink
INFO  (0x8719848 - 309451:18:39.387572000)        GST_PADS(22850) gstpad.c(1908):gst_pad_link_filtered: linked converter1:src and frame-converter1:sink, successful
*** Linking Frame-converter to AVC ***
INFO  (0x8719848 - 309451:18:39.387661000) GST_ELEMENT_PADS(22850) gstelement.c(1374):gst_element_get_static_pad: found pad frame-converter1:src
INFO  (0x8719848 - 309451:18:39.387716000) GST_ELEMENT_PADS(22850) gstelement.c(1374):gst_element_get_static_pad: found pad avc:Input-1
INFO  (0x8719848 - 309451:18:39.387768000)        GST_PADS(22850) gstpad.c(1814):gst_pad_link_filtered: trying to link frame-converter1:src and avc:Input-1
INFO  (0x8719848 - 309451:18:39.467537000)       scheduler(22850) gstoptimalscheduler.c(2123):gst_opt_scheduler_pad_link: scheduling link between frame-converter1:src and avc:Input-1
INFO  (0x8719848 - 309451:18:39.467919000)        GST_PADS(22850) gstpad.c(1908):gst_pad_link_filtered: linked frame-converter1:src and avc:Input-1, successful
*** Linking AVC to CONVERTER ***
INFO  (0x8719848 - 309451:18:39.468008000) GST_ELEMENT_PADS(22850) gstelement.c(1374):gst_element_get_static_pad: found pad avc:Output-1
INFO  (0x8719848 - 309451:18:39.468061000) GST_ELEMENT_PADS(22850) gstelement.c(1374):gst_element_get_static_pad: found pad frame-converter2:sink
INFO  (0x8719848 - 309451:18:39.468113000)        GST_PADS(22850) gstpad.c(1814):gst_pad_link_filtered: trying to link avc:Output-1 and frame-converter2:sink
INFO  (0x8719848 - 309451:18:39.468688000)       scheduler(22850) gstoptimalscheduler.c(2123):gst_opt_scheduler_pad_link: scheduling link between avc:Output-1 and frame-converter2:sink
INFO  (0x8719848 - 309451:18:39.468782000)        GST_PADS(22850) gstpad.c(1908):gst_pad_link_filtered: linked avc:Output-1 and frame-converter2:sink, successful
INFO  (0x8719848 - 309451:18:39.468838000) GST_ELEMENT_PADS(22850) gstelement.c(1893):gst_element_link_pads_filtered: trying to link element frame-converter2:(any) to element alsa-output:(any)
INFO  (0x8719848 - 309451:18:39.468906000)        GST_PADS(22850) gstpad.c(1693):gst_pad_can_link_filtered: trying to link frame-converter2:src and alsa-output:sink
INFO  (0x8719848 - 309451:18:39.472380000)        GST_PADS(22850) gstpad.c(1814):gst_pad_link_filtered: trying to link frame-converter2:src and alsa-output:sink
INFO  (0x8719848 - 309451:18:39.476549000)       scheduler(22850) gstoptimalscheduler.c(2123):gst_opt_scheduler_pad_link: scheduling link between frame-converter2:src and alsa-output:sink
INFO  (0x8719848 - 309451:18:39.476696000)        GST_PADS(22850) gstpad.c(1908):gst_pad_link_filtered: linked frame-converter2:src and alsa-output:sink, successful
INFO  (0x8719848 - 309451:18:39.476778000)      GST_STATES(22850) gstelement.c(2825):gst_element_set_state_func:<mix-pipeline> setting state from READY to PLAYING
INFO  (0x8719848 - 309451:18:39.476889000)      GST_STATES(22850) gstelement.c(2825):gst_element_set_state_func:<bin-1> setting state from READY to PAUSED
INFO  (0x8719848 - 309451:18:39.476959000)      GST_STATES(22850) gstelement.c(2825):gst_element_set_state_func:<file-source-1> setting state from READY to PAUSED
INFO  (0x8719848 - 309451:18:39.477027000)         filesrc(22850) gstfilesrc.c(771):gst_filesrc_open_file:<file-source-1> opening file /store/otherfile.mmp
INFO  (0x8719848 - 309451:18:39.477945000)        GST_CAPS(22850) gstpad.c(1533):gst_pad_try_set_caps:<mmp-1:src> caps audio/mpeg, mpegversion=(int)1, layer=(int)3, rate=(int)8000, channels=(int)1
INFO  (0x8719848 - 309451:18:39.478812000)      GST_STATES(22850) gstelement.c(2825):gst_element_set_state_func:<mmp-1> setting state from READY to PAUSED
going to set keys
setting keys
INFO  (0x8719848 - 309451:18:39.479602000)      GST_STATES(22850) gstelement.c(2825):gst_element_set_state_func:<mp3-1> setting state from READY to PAUSED
INFO  (0x8719848 - 309451:18:39.480172000)      GST_STATES(22850) gstelement.c(2825):gst_element_set_state_func:<converter1> setting state from NULL to PAUSED
INFO  (0x8719848 - 309451:18:39.522661000)        GST_CAPS(22850) gstpad.c(1334):gst_pad_link_call_link_functions:<mp3-1:src> pad doesn't accept caps audio/x-raw-int, endianness=(int)1234, signed=(boolean)true, width=(int)16, depth=(int)16, rate=(int)8000, channels=(int)2
INFO  (0x8719848 - 309451:18:39.524366000)      GST_STATES(22850) gstelement.c(2825):gst_element_set_state_func:<frame-converter1> setting state from NULL to PAUSED
INFO  (0x8719848 - 309451:18:39.582460000)        GST_CAPS(22850) gstpad.c(1334):gst_pad_link_call_link_functions:<mp3-1:src> pad doesn't accept caps audio/x-raw-int, endianness=(int)1234, signed=(boolean)true, width=(int)16, depth=(int)16, rate=(int)8000, channels=(int)1
INFO  (0x8719848 - 309451:18:39.584054000)        GST_CAPS(22850) gstpad.c(1334):gst_pad_link_call_link_functions:<converter1:src> pad doesn't accept caps audio/x-raw-float, rate=(int)8000, buffer-frames=(int)0, channels=(int)1, endianness=(int)1234, width=(int)32
INFO  (0x8719848 - 309451:18:39.585816000)      GST_STATES(22850) gstelement.c(2825):gst_element_set_state_func:<avc> setting state from NULL to PAUSED
INFO  (0x8719848 - 309451:18:39.658184000)        GST_CAPS(22850) gstpad.c(1533):gst_pad_try_set_caps:<frame-converter1:sink> caps audio/x-raw-float, rate=(int)8000, channels=(int)1, endianness=(int)1234, width=(int)32, buffer-frames=(int)1
INFO  (0x8719848 - 309451:18:39.762999000)        GST_CAPS(22850) gstpad.c(1334):gst_pad_link_call_link_functions:<mp3-1:src> pad doesn't accept caps audio/x-raw-int, endianness=(int)1234, signed=(boolean)true, width=(int)16, depth=(int)16, rate=(int)8000, channels=(int)1
INFO  (0x8719848 - 309451:18:39.763460000)        GST_CAPS(22850) gstpad.c(1334):gst_pad_link_call_link_functions:<converter1:src> pad doesn't accept caps audio/x-raw-float, rate=(int)8000, buffer-frames=(int)0, channels=(int)1, endianness=(int)1234, width=(int)32
INFO  (0x8719848 - 309451:18:39.765019000)        GST_CAPS(22850) gstpad.c(1334):gst_pad_link_call_link_functions:<frame-converter1:src> pad doesn't accept caps audio/x-raw-float, rate=(int)8000, channels=(int)1, endianness=(int)1234, width=(int)32, buffer-frames=(int)1
INFO  (0x8719848 - 309451:18:39.765753000)      GST_STATES(22850) gstelement.c(2825):gst_element_set_state_func:<alsa-output> setting state from NULL to PAUSED
INFO  (0x8719848 - 309451:18:39.812108000)            alsa(22850) gstalsa.c(1581):gst_alsa_open_audio: Opening alsa device "hw:1,0"...
INFO  (0x8719848 - 309451:18:39.814205000)      GST_STATES(22850) gstelement.c(2825):gst_element_set_state_func:<frame-converter2> setting state from NULL to PAUSED
INFO  (0x8719848 - 309451:18:39.816095000)      GST_STATES(22850) gstelement.c(3015):gst_element_change_state:<frame-converter2> failed state change, could not negotiate pads
INFO  (0x8719848 - 309451:18:39.816250000)      GST_STATES(22850) gstelement.c(2858):gst_element_set_state_func:<frame-converter2> have failed change_state return
INFO  (0x8719848 - 309451:18:39.816313000)      GST_STATES(22850) gstbin.c(811):set_kid_state_func:<mix-pipeline> child 'frame-converter2' failed to go to state 4(PAUSED)
INFO  (0x8719848 - 309451:18:39.816378000)      GST_STATES(22850) gstelement.c(2825):gst_element_set_state_func:<frame-converter2> setting state from READY to NULL
INFO  (0x8719848 - 309451:18:39.816488000)      GST_STATES(22850) gstelement.c(2858):gst_element_set_state_func:<mix-pipeline> have failed change_state return
Bin: mix-pipeline ... [GST_STATE_READY](expanding)
    Bin: bin-1 ... [GST_STATE_PAUSED](expanding)
        Element: file-source-1, [GST_STATE_PAUSED]
        Element: mmp-1, [GST_STATE_PAUSED]
        Element: mp3-1, [GST_STATE_PAUSED]
    Element: converter1, [GST_STATE_PAUSED]
    Element: frame-converter1, [GST_STATE_PAUSED]
    Element: avc, [GST_STATE_PAUSED]
    Element: alsa-output, [GST_STATE_PAUSED]
    Element: frame-converter2, [GST_STATE_NULL]
pipeline1: failed to set to playing state