0:00:00.000865200 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m            GST_INIT gst.c:605:init_pre:[00m Initializing GStreamer Core Library version 0.10.32
0:00:00.001424850 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m            GST_INIT gst.c:606:init_pre:[00m Using library installed in /usr/lib
0:00:00.001552550 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m            GST_INIT gst.c:616:init_pre:[00m Linux omap3evm 2.6.37-rc3 #1 Wed Jul 27 09:55:12 IST 2011 armv7l
0:00:00.017289100 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m            GST_INIT gstquery.c:108:_gst_query_initialize:[00m init queries
0:00:00.033269800 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m            GST_INIT gstmessage.c:73:_gst_message_initialize:[00m init messages
0:00:00.038181800 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;36m  GST_PLUGIN_LOADING gstplugin.c:350:_gst_plugin_initialize:[00m registering 0 static plugins
0:00:00.039455500 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;36m  GST_PLUGIN_LOADING gstplugin.c:255:gst_plugin_register_static:[00m registered static plugin "staticelements"
0:00:00.039572350 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;36m  GST_PLUGIN_LOADING gstplugin.c:257:gst_plugin_register_static:[00m added static plugin "staticelements", result: 1
0:00:00.041833050 [331m  582[00m    0x16050 [32;01mINFO   [00m [00m        GST_REGISTRY gstregistry.c:1599:ensure_current_registry:[00m reading registry cache: /home/root/.gstreamer-0.10/registry.arm.bin
0:00:00.073906050 [331m  582[00m    0x16050 [32;01mINFO   [00m [00m        GST_REGISTRY gstregistrybinary.c:614:gst_registry_binary_read_cache:[00m loaded /home/root/.gstreamer-0.10/registry.arm.bin in 0.031875 seconds
0:00:00.074251600 [331m  582[00m    0x16050 [32;01mINFO   [00m [00m        GST_REGISTRY gstregistry.c:1469:scan_and_update_registry:[00m Validating plugins from registry cache: /home/root/.gstreamer-0.10/registry.arm.bin
0:00:00.092912000 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;36m  GST_PLUGIN_LOADING gstpluginloader.c:409:gst_plugin_loader_spawn:[00m No gst-plugin-scanner available, or not working

(gst-launch-0.10:582): GStreamer-WARNING **: External plugin loader failed. This most likely means that the plugin loader helper binary was not found or could not be run. 
0:00:00.198351750 [331m  582[00m    0x16050 [33;01mWARN   [00m [00;01;36m  GST_PLUGIN_LOADING gstplugin.c:769:gst_plugin_load_file:[00m module_open failed: libogg.so.0: cannot open shared object file: No such file or directory

(gst-launch-0.10:582): GStreamer-WARNING **: Failed to load plugin '/usr/lib/gstreamer-0.10/libgstogg.so': libogg.so.0: cannot open shared object file: No such file or directory
0:00:00.211812850 [331m  582[00m    0x16050 [33;01mWARN   [00m [00;01;36m  GST_PLUGIN_LOADING gstplugin.c:769:gst_plugin_load_file:[00m module_open failed: libvorbisenc.so.2: cannot open shared object file: No such file or directory

(gst-launch-0.10:582): GStreamer-WARNING **: Failed to load plugin '/usr/lib/gstreamer-0.10/libgstvorbis.so': libvorbisenc.so.2: cannot open shared object file: No such file or directory
0:00:00.212562750 [331m  582[00m    0x16050 [32;01mINFO   [00m [00m        GST_REGISTRY gstregistry.c:1561:scan_and_update_registry:[00m Registry cache has not changed
0:00:00.212669150 [331m  582[00m    0x16050 [32;01mINFO   [00m [00m        GST_REGISTRY gstregistry.c:1628:ensure_current_registry:[00m registry reading and updating done, result = 1
0:00:00.212746200 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m            GST_INIT gst.c:792:init_post:[00m GLib runtime version: 2.28.0
0:00:00.212827050 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m            GST_INIT gst.c:794:init_post:[00m GLib headers version: 2.28.0
0:00:00.213099600 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m        GST_PIPELINE gstparse.c:299:gst_parse_launch_full:[00m parsing pipeline description 'filesrc location=/media/Toss_the_feathers.mp3 ! mad ! audioconvert ! pulsesink '
0:00:00.261311400 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;36m  GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file:[00m plugin "/usr/lib/gstreamer-0.10/libgstcoreelements.so" loaded
0:00:00.261493000 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:371:gst_element_factory_create:[00m creating element "filesrc"
0:00:00.263046150 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:716:gst_element_add_pad:<GstBaseSrc@0x54000>[00m adding pad 'src'
0:00:00.340543600 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;36m  GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file:[00m plugin "/usr/lib/gstreamer-0.10/libgstmad.so" loaded
0:00:00.340752300 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:371:gst_element_factory_create:[00m creating element "mad"
0:00:00.342343750 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:716:gst_element_add_pad:<GstMad@0x58d98>[00m adding pad 'sink'
0:00:00.342818500 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:716:gst_element_add_pad:<GstMad@0x58d98>[00m adding pad 'src'
0:00:00.356395500 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;36m  GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file:[00m plugin "/usr/lib/gstreamer-0.10/libgstaudioconvert.so" loaded
0:00:00.356539050 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:371:gst_element_factory_create:[00m creating element "audioconvert"
0:00:00.359116450 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:716:gst_element_add_pad:<GstBaseTransform@0x66018>[00m adding pad 'sink'
0:00:00.359445600 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:716:gst_element_add_pad:<GstBaseTransform@0x66018>[00m adding pad 'src'
0:00:00.386387300 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;36m  GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file:[00m plugin "/usr/lib/gstreamer-0.10/libgstpulse.so" loaded
0:00:00.386572700 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:371:gst_element_factory_create:[00m creating element "pulsesink"
0:00:00.388847300 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:716:gst_element_add_pad:<GstBaseSink@0x6c008>[00m adding pad 'sink'
0:00:00.390075250 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:371:gst_element_factory_create:[00m creating element "pipeline"
0:00:00.391321300 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m        GST_PIPELINE ./grammar.y:568:gst_parse_perform_link:[00m linking filesrc0:(any) to mad0:(any) (0/0) with caps "(NULL)"
0:00:00.391527450 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstutils.c:1568:gst_element_link_pads_full:[00m trying to link element filesrc0:(any) to element mad0:(any)
0:00:00.391648050 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstutils.c:1028:gst_pad_check_link:[00m trying to link filesrc0:src and mad0:sink
0:00:00.391817350 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstutils.c:1468:prepare_link_maybe_ghosting:[00m filesrc0 and mad0 in same bin, no need for ghost pads
0:00:00.392012000 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1941:gst_pad_link_prepare:[00m trying to link filesrc0:src and mad0:sink
0:00:00.392134650 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2124:gst_pad_link_full:[00m linked filesrc0:src and mad0:sink, successful
0:00:00.392406200 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m        GST_PIPELINE ./grammar.y:568:gst_parse_perform_link:[00m linking mad0:(any) to audioconvert0:(any) (0/0) with caps "(NULL)"
0:00:00.392520950 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstutils.c:1568:gst_element_link_pads_full:[00m trying to link element mad0:(any) to element audioconvert0:(any)
0:00:00.392623650 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstutils.c:1028:gst_pad_check_link:[00m trying to link mad0:src and audioconvert0:sink
0:00:00.393437700 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstutils.c:1468:prepare_link_maybe_ghosting:[00m mad0 and audioconvert0 in same bin, no need for ghost pads
0:00:00.393608300 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1941:gst_pad_link_prepare:[00m trying to link mad0:src and audioconvert0:sink
0:00:00.393962150 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2124:gst_pad_link_full:[00m linked mad0:src and audioconvert0:sink, successful
0:00:00.394137300 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m        GST_PIPELINE ./grammar.y:568:gst_parse_perform_link:[00m linking audioconvert0:(any) to pulsesink0:(any) (0/0) with caps "(NULL)"
0:00:00.394248200 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstutils.c:1568:gst_element_link_pads_full:[00m trying to link element audioconvert0:(any) to element pulsesink0:(any)
0:00:00.394348500 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstutils.c:1028:gst_pad_check_link:[00m trying to link audioconvert0:src and pulsesink0:sink
0:00:00.397678850 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstutils.c:1468:prepare_link_maybe_ghosting:[00m audioconvert0 and pulsesink0 in same bin, no need for ghost pads
0:00:00.397853300 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:1941:gst_pad_link_prepare:[00m trying to link audioconvert0:src and pulsesink0:sink
0:00:00.400575200 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;41m            GST_PADS gstpad.c:2124:gst_pad_link_full:[00m linked audioconvert0:src and pulsesink0:sink, successful
Setting pipeline to PAUSED ...
0:00:00.414241450 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<pulsesink0>[00m completed state change to READY
0:00:00.414412650 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<pulsesink0>[00m posting state-changed NULL to READY
0:00:00.414701200 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2492:gst_bin_change_state_func:<pipeline0>[00m child 'pulsesink0' changed state to 2(READY) successfully
0:00:00.414864550 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<audioconvert0>[00m completed state change to READY
0:00:00.414961450 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<audioconvert0>[00m posting state-changed NULL to READY
0:00:00.415092600 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2492:gst_bin_change_state_func:<pipeline0>[00m child 'audioconvert0' changed state to 2(READY) successfully
0:00:00.415222200 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<mad0>[00m completed state change to READY
0:00:00.415315550 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<mad0>[00m posting state-changed NULL to READY
0:00:00.415532700 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2492:gst_bin_change_state_func:<pipeline0>[00m child 'mad0' changed state to 2(READY) successfully
0:00:00.415668000 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<filesrc0>[00m completed state change to READY
0:00:00.415762200 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<filesrc0>[00m posting state-changed NULL to READY
0:00:00.415920250 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2492:gst_bin_change_state_func:<pipeline0>[00m child 'filesrc0' changed state to 2(READY) successfully
0:00:00.416066150 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2314:gst_element_continue_state:<pipeline0>[00m committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:00.416351900 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2323:gst_element_continue_state:<pipeline0>[00m continue state change READY to PAUSED, final PAUSED
0:00:00.419115950 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2498:gst_bin_change_state_func:<pipeline0>[00m child 'pulsesink0' is changing state asynchronously to PAUSED
0:00:00.419317300 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<audioconvert0>[00m completed state change to PAUSED
0:00:00.419412900 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<audioconvert0>[00m posting state-changed READY to PAUSED
0:00:00.419551500 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2492:gst_bin_change_state_func:<pipeline0>[00m child 'audioconvert0' changed state to 3(PAUSED) successfully
0:00:00.419787900 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<mad0>[00m completed state change to PAUSED
0:00:00.419884450 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<mad0>[00m posting state-changed READY to PAUSED
0:00:00.420011200 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2492:gst_bin_change_state_func:<pipeline0>[00m child 'mad0' changed state to 3(PAUSED) successfully
0:00:00.420171750 [331m  582[00m    0x16050 [32;01mINFO   [00m [00m             filesrc gstfilesrc.c:963:gst_file_src_start:<filesrc0>[00m opening file /media/Toss_the_feathers.mp3
0:00:00.420438750 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;34m           GST_EVENT gstevent.c:606:gst_event_new_new_segment_full:[00m creating newsegment update 0, rate 1.000000, format bytes, start 0, stop 4823040, position 0
0:00:00.421347000 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<filesrc0>[00m completed state change to PAUSED
0:00:00.421474650 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<filesrc0>[00m posting state-changed READY to PAUSED
0:00:00.421613350 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2492:gst_bin_change_state_func:<pipeline0>[00m child 'filesrc0' changed state to 3(PAUSED) successfully
Pipeline is PREROLLING ...
0:00:00.427878900 [331m  582[00m    0x74160 [33;01mWARN   [00m [00m                 mad gstmad.c:1365:gst_mad_chain:[00m mad_header_decode had an error: lost synchronization
0:00:00.428118150 [331m  582[00m    0x74160 [33;01mWARN   [00m [00m                 mad gstmad.c:1392:gst_mad_chain:[00m mad_frame_decode had an error: reserved sample frequency value
0:00:00.428307100 [331m  582[00m    0x74160 [33;01mWARN   [00m [00m                 mad gstmad.c:1365:gst_mad_chain:[00m mad_header_decode had an error: lost synchronization
0:00:00.428400800 [331m  582[00m    0x74160 [33;01mWARN   [00m [00m                 mad gstmad.c:1392:gst_mad_chain:[00m mad_frame_decode had an error: reserved sample frequency value
0:00:00.428498050 [331m  582[00m    0x74160 [33;01mWARN   [00m [00m                 mad gstmad.c:1365:gst_mad_chain:[00m mad_header_decode had an error: lost synchronization
0:00:00.428579400 [331m  582[00m    0x74160 [33;01mWARN   [00m [00m                 mad gstmad.c:1392:gst_mad_chain:[00m mad_frame_decode had an error: reserved sample frequency value
0:00:00.428673700 [331m  582[00m    0x74160 [33;01mWARN   [00m [00m                 mad gstmad.c:1365:gst_mad_chain:[00m mad_header_decode had an error: lost synchronization
0:00:00.428750800 [331m  582[00m    0x74160 [33;01mWARN   [00m [00m                 mad gstmad.c:1392:gst_mad_chain:[00m mad_frame_decode had an error: reserved sample frequency value
0:00:00.428845800 [331m  582[00m    0x74160 [33;01mWARN   [00m [00m                 mad gstmad.c:1365:gst_mad_chain:[00m mad_header_decode had an error: lost synchronization
0:00:00.428921300 [331m  582[00m    0x74160 [33;01mWARN   [00m [00m                 mad gstmad.c:1392:gst_mad_chain:[00m mad_frame_decode had an error: reserved sample frequency value
0:00:00.429014750 [331m  582[00m    0x74160 [33;01mWARN   [00m [00m                 mad gstmad.c:1365:gst_mad_chain:[00m mad_header_decode had an error: lost synchronization
0:00:00.429088400 [331m  582[00m    0x74160 [33;01mWARN   [00m [00m                 mad gstmad.c:1392:gst_mad_chain:[00m mad_frame_decode had an error: reserved sample frequency value
0:00:00.429220300 [331m  582[00m    0x74160 [33;01mWARN   [00m [00m                 mad gstmad.c:1365:gst_mad_chain:[00m mad_header_decode had an error: lost synchronization
0:00:00.431303750 [331m  582[00m    0x74160 [32;01mINFO   [00m [00;01;34m           GST_EVENT gstevent.c:600:gst_event_new_new_segment_full:[00m creating newsegment update 0, rate 1.000000, format GST_FORMAT_TIME, start 0:00:00.000000000, stop 99:99:99.999999999, position 0:00:00.000000000
0:00:00.437272850 [331m  582[00m    0x74160 [32;01mINFO   [00m [00m       basetransform gstbasetransform.c:1148:gst_base_transform_setcaps:<audioconvert0>[00m reuse caps
0:00:00.437633000 [331m  582[00m    0x74160 [32;01mINFO   [00m [00m        audioconvert audioconvert.c:655:audio_convert_prepare_context:[00m use int mixing
0:00:00.437715150 [331m  582[00m    0x74160 [32;01mINFO   [00m [00m        audioconvert audioconvert.c:661:audio_convert_prepare_context:[00m unitsizes: 4 -> 4
0:00:00.437786900 [331m  582[00m    0x74160 [32;01mINFO   [00m [00m        audioconvert audioconvert.c:671:audio_convert_prepare_context:[00m in default 0, mix passthrough 1, out default 0
0:00:00.443335150 [331m  582[00m    0x74160 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2905:bin_handle_async_done:<pipeline0>[00m committing state from READY to PAUSED, old pending PAUSED
0:00:00.443498100 [331m  582[00m    0x74160 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2925:bin_handle_async_done:<pipeline0>[00m completed state change, pending VOID
Pipeline is PREROLLED ...
Setting pipeline to PLAYING ...
0:00:00.444869700 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;34m           GST_EVENT gstevent.c:1068:gst_event_new_latency:[00m creating latency event 0:00:00.000000000
0:00:00.445097250 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01m                 bin gstbin.c:2368:gst_bin_do_latency_func:<pipeline0>[00m configured latency of 0:00:00.000000000
0:00:00.445614450 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<pulsesink0>[00m completed state change to PLAYING
0:00:00.445726800 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<pulsesink0>[00m posting state-changed PAUSED to PLAYING
0:00:00.445938850 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2492:gst_bin_change_state_func:<pipeline0>[00m child 'pulsesink0' changed state to 4(PLAYING) successfully
0:00:00.446067600 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<audioconvert0>[00m completed state change to PLAYING
0:00:00.446157300 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<audioconvert0>[00m posting state-changed PAUSED to PLAYING
0:00:00.446283150 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2492:gst_bin_change_state_func:<pipeline0>[00m child 'audioconvert0' changed state to 4(PLAYING) successfully
0:00:00.446403600 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<mad0>[00m completed state change to PLAYING
0:00:00.446492600 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<mad0>[00m posting state-changed PAUSED to PLAYING
0:00:00.446618550 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2492:gst_bin_change_state_func:<pipeline0>[00m child 'mad0' changed state to 4(PLAYING) successfully
0:00:00.446728900 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<filesrc0>[00m completed state change to PLAYING
0:00:00.446817250 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<filesrc0>[00m posting state-changed PAUSED to PLAYING
0:00:00.446941150 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2492:gst_bin_change_state_func:<pipeline0>[00m child 'filesrc0' changed state to 4(PLAYING) successfully
0:00:00.447053150 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<pipeline0>[00m completed state change to PLAYING
0:00:00.447140350 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<pipeline0>[00m posting state-changed PAUSED to PLAYING
New clock: GstAudioSinkClock
Caught interrupt -- handling interrupt.
Interrupt: Stopping pipeline ...
Execution ended after 14521090701 ns.
Setting pipeline to PAUSED ...
0:00:14.971523601 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<pulsesink0>[00m completed state change to PAUSED
0:00:14.971688101 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<pulsesink0>[00m posting state-changed PLAYING to PAUSED
0:00:14.971846101 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2492:gst_bin_change_state_func:<pipeline0>[00m child 'pulsesink0' changed state to 3(PAUSED) successfully
0:00:14.972001501 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<audioconvert0>[00m completed state change to PAUSED
0:00:14.972095101 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<audioconvert0>[00m posting state-changed PLAYING to PAUSED
0:00:14.972224801 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2492:gst_bin_change_state_func:<pipeline0>[00m child 'audioconvert0' changed state to 3(PAUSED) successfully
0:00:14.972346601 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<mad0>[00m completed state change to PAUSED
0:00:14.972562201 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<mad0>[00m posting state-changed PLAYING to PAUSED
0:00:14.972698601 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2492:gst_bin_change_state_func:<pipeline0>[00m child 'mad0' changed state to 3(PAUSED) successfully
0:00:14.972815651 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<filesrc0>[00m completed state change to PAUSED
0:00:14.972906451 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<filesrc0>[00m posting state-changed PLAYING to PAUSED
0:00:14.973033601 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2492:gst_bin_change_state_func:<pipeline0>[00m child 'filesrc0' changed state to 3(PAUSED) successfully
0:00:14.973355351 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<pipeline0>[00m completed state change to PAUSED
0:00:14.973459901 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<pipeline0>[00m posting state-changed PLAYING to PAUSED
Setting pipeline to READY ...
0:00:14.977165451 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<pulsesink0>[00m completed state change to READY
0:00:14.977311851 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<pulsesink0>[00m posting state-changed PAUSED to READY
0:00:14.977460751 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2492:gst_bin_change_state_func:<pipeline0>[00m child 'pulsesink0' changed state to 2(READY) successfully
0:00:14.980043001 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<audioconvert0>[00m completed state change to READY
0:00:14.980169101 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<audioconvert0>[00m posting state-changed PAUSED to READY
0:00:14.980310901 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2492:gst_bin_change_state_func:<pipeline0>[00m child 'audioconvert0' changed state to 2(READY) successfully
0:00:14.980584401 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<mad0>[00m completed state change to READY
0:00:14.980681801 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<mad0>[00m posting state-changed PAUSED to READY
0:00:14.980809301 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2492:gst_bin_change_state_func:<pipeline0>[00m child 'mad0' changed state to 2(READY) successfully
0:00:14.980964251 [331m  582[00m    0x74160 [32;01mINFO   [00m [00m             basesrc gstbasesrc.c:2511:gst_base_src_loop:<filesrc0>[00m pausing after gst_pad_push() = wrong-state
0:00:14.981314551 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;34m           GST_EVENT gstpad.c:5406:gst_pad_send_event:<mad0:sink>[00m Received event on flushing pad. Discarding
0:00:14.981433851 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<filesrc0>[00m completed state change to READY
0:00:14.981522651 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<filesrc0>[00m posting state-changed PAUSED to READY
0:00:14.981727751 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2492:gst_bin_change_state_func:<pipeline0>[00m child 'filesrc0' changed state to 2(READY) successfully
0:00:14.981873751 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<pipeline0>[00m completed state change to READY
0:00:14.981963501 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<pipeline0>[00m posting state-changed PAUSED to READY
0:00:14.982183851 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:1759:gst_bin_get_state_func:<pipeline0>[00m getting state
Setting pipeline to NULL ...
0:00:14.984781251 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<pulsesink0>[00m completed state change to NULL
0:00:14.984902801 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<pulsesink0>[00m posting state-changed READY to NULL
0:00:14.985039301 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2492:gst_bin_change_state_func:<pipeline0>[00m child 'pulsesink0' changed state to 1(NULL) successfully
0:00:14.985206301 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<audioconvert0>[00m completed state change to NULL
0:00:14.985296851 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<audioconvert0>[00m posting state-changed READY to NULL
0:00:14.985423801 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2492:gst_bin_change_state_func:<pipeline0>[00m child 'audioconvert0' changed state to 1(NULL) successfully
0:00:14.985579751 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<mad0>[00m completed state change to NULL
0:00:14.985672551 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<mad0>[00m posting state-changed READY to NULL
0:00:14.985797201 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2492:gst_bin_change_state_func:<pipeline0>[00m child 'mad0' changed state to 1(NULL) successfully
0:00:14.985934001 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<filesrc0>[00m completed state change to NULL
0:00:14.986023351 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<filesrc0>[00m posting state-changed READY to NULL
0:00:14.986148301 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:2492:gst_bin_change_state_func:<pipeline0>[00m child 'filesrc0' changed state to 1(NULL) successfully
0:00:14.986580101 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2341:gst_element_continue_state:<pipeline0>[00m completed state change to NULL
0:00:14.986680001 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstelement.c:2354:gst_element_continue_state:<pipeline0>[00m posting state-changed READY to NULL
0:00:14.986810751 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m          GST_STATES gstbin.c:1759:gst_bin_get_state_func:<pipeline0>[00m getting state
Freeing pipeline ...
0:00:14.987021351 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1713:gst_pad_unlink:[00m unlinking audioconvert0:src(0x53328) and pulsesink0:sink(0x533f0)
0:00:14.987181951 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1758:gst_pad_unlink:[00m unlinked audioconvert0:src and pulsesink0:sink
0:00:14.987429051 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1440:gst_bin_remove_func:<pipeline0>[00m removed child "pulsesink0"
0:00:14.987746301 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:2955:gst_element_dispose:<pulsesink0>[00m dispose
0:00:14.987854851 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:835:gst_element_remove_pad:<pulsesink0>[00m removing pad 'sink'
0:00:14.988015301 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:2979:gst_element_dispose:<pulsesink0>[00m parent class dispose
0:00:14.988629851 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3010:gst_element_finalize:<pulsesink0>[00m finalize
0:00:14.988757901 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3021:gst_element_finalize:<pulsesink0>[00m finalize parent
0:00:14.988875701 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1713:gst_pad_unlink:[00m unlinking mad0:src(0x53198) and audioconvert0:sink(0x53260)
0:00:14.989033401 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1758:gst_pad_unlink:[00m unlinked mad0:src and audioconvert0:sink
0:00:14.989216451 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1440:gst_bin_remove_func:<pipeline0>[00m removed child "audioconvert0"
0:00:14.989358701 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:2955:gst_element_dispose:<audioconvert0>[00m dispose
0:00:14.989450101 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:835:gst_element_remove_pad:<audioconvert0>[00m removing pad 'src'
0:00:14.989577751 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:835:gst_element_remove_pad:<audioconvert0>[00m removing pad 'sink'
0:00:14.989703201 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:2979:gst_element_dispose:<audioconvert0>[00m parent class dispose
0:00:14.989804801 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3010:gst_element_finalize:<audioconvert0>[00m finalize
0:00:14.989897751 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3021:gst_element_finalize:<audioconvert0>[00m finalize parent
0:00:14.990003451 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1713:gst_pad_unlink:[00m unlinking filesrc0:src(0x53008) and mad0:sink(0x530d0)
0:00:14.990143301 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstpad.c:1758:gst_pad_unlink:[00m unlinked filesrc0:src and mad0:sink
0:00:14.990318251 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1440:gst_bin_remove_func:<pipeline0>[00m removed child "mad0"
0:00:14.990454001 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:2955:gst_element_dispose:<mad0>[00m dispose
0:00:14.990547051 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:835:gst_element_remove_pad:<mad0>[00m removing pad 'src'
0:00:14.990706601 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:835:gst_element_remove_pad:<mad0>[00m removing pad 'sink'
0:00:14.990861351 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:2979:gst_element_dispose:<mad0>[00m parent class dispose
0:00:14.990959551 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3010:gst_element_finalize:<mad0>[00m finalize
0:00:14.991268751 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3021:gst_element_finalize:<mad0>[00m finalize parent
0:00:14.991407351 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m       GST_PARENTAGE gstbin.c:1440:gst_bin_remove_func:<pipeline0>[00m removed child "filesrc0"
0:00:14.991558451 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:2955:gst_element_dispose:<filesrc0>[00m dispose
0:00:14.991651601 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;37;41m    GST_ELEMENT_PADS gstelement.c:835:gst_element_remove_pad:<filesrc0>[00m removing pad 'src'
0:00:14.991777151 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:2979:gst_element_dispose:<filesrc0>[00m parent class dispose
0:00:14.991884201 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3010:gst_element_finalize:<filesrc0>[00m finalize
0:00:14.991978501 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3021:gst_element_finalize:<filesrc0>[00m finalize parent
0:00:14.992074651 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:2955:gst_element_dispose:<pipeline0>[00m dispose
0:00:14.992202751 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:2979:gst_element_dispose:<pipeline0>[00m parent class dispose
0:00:14.992302201 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3010:gst_element_finalize:<pipeline0>[00m finalize
0:00:14.992395951 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31;44m     GST_REFCOUNTING gstelement.c:3021:gst_element_finalize:<pipeline0>[00m finalize parent
0:00:14.992496001 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m            GST_INIT gst.c:1010:gst_deinit:[00m deinitializing GStreamer
0:00:14.997395401 [331m  582[00m    0x16050 [32;01mINFO   [00m [00;01;31m            GST_INIT gst.c:1113:gst_deinit:[00m deinitialized GStreamer
