0:00:00.003946622  7129 0x804e408 INFO              GST_INIT gstquery.c:102:_gst_query_initialize: init queries
0:00:00.004737231  7129 0x804e408 INFO              GST_INIT gstmessage.c:72:_gst_message_initialize: init messages
0:00:00.005433694  7129 0x804e408 INFO    GST_PLUGIN_LOADING gstplugin.c:262:_gst_plugin_initialize: registering 0 static plugins
0:00:00.005688197  7129 0x804e408 INFO    GST_PLUGIN_LOADING gstplugin.c:247:gst_plugin_register_static: registered static plugin "staticelements"
0:00:00.005713061  7129 0x804e408 INFO    GST_PLUGIN_LOADING gstplugin.c:249:gst_plugin_register_static: added static plugin "staticelements", result: 1
0:00:00.005766420  7129 0x804e408 INFO              GST_INIT gst.c:796:ensure_current_registry_forking: reading registry cache: /home/rikai/.gstreamer-0.10/registry.i486.xml
0:00:00.056538349  7129 0x804e408 INFO          GST_REGISTRY gstregistryxml.c:592:gst_registry_xml_read_cache: loaded /home/rikai/.gstreamer-0.10/registry.i486.xml in 0.050726 seconds
0:00:00.088138419  7130 0x804e408 INFO              GST_INIT gst.c:733:scan_and_update_registry: Registry cache has not changed
/home/rikai/.gtkrc-2.0:3: error: scanner: unterminated string constant - e.g. `style'
Gtk-Message: (for origin information, set GTK_DEBUG): failed to retrieve property `GtkOptionMenu::indicator-size' of type `GtkRequisition' from rc file value "0" of type `glong'
Gtk-Message: (for origin information, set GTK_DEBUG): failed to retrieve property `GtkOptionMenu::indicator-spacing' of type `GtkBorder' from rc file value "0" of type `glong'
0:00:00.289958366  7129 0x804e408 INFO          GST_PIPELINE gstparse.c:160:gst_parse_launch: parsing pipeline description autoaudiosink
0:00:00.290901510  7129 0x804e408 INFO    GST_PLUGIN_LOADING gstplugin.c:620:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstautodetect.so" loaded
0:00:00.290959059  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "autoaudiosink"
0:00:00.291151544  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstAutoAudioSink@0x841c810> adding pad 'sink'
0:00:00.293987401  7129 0x804e408 INFO    GST_PLUGIN_LOADING gstplugin.c:620:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstcoreelements.so" loaded
0:00:00.294059198  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "fakesink" named "tempsink"
0:00:00.294141891  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseSink@0x8428040> adding pad 'sink'
0:00:00.294213409  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:887:gst_element_get_static_pad: found pad tempsink:sink
0:00:00.294240507  7129 0x804e408 INFO              GST_PADS gstpad.c:1769:gst_pad_link_prepare: trying to link sink:proxypad0 and tempsink:sink
0:00:00.294278222  7129 0x804e408 INFO              GST_PADS gstpad.c:1906:gst_pad_link: linked sink:proxypad0 and tempsink:sink, successful
0:00:00.294364547  7129 0x804e408 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<tempsink> completed state change to NULL
0:00:00.294393042  7129 0x804e408 INFO      GST_ELEMENT_PADS gstpad.c:1548:gst_pad_unlink: unlinking sink:proxypad0(0x841c8d8) and tempsink:sink(0x81879b8)
0:00:00.294440255  7129 0x804e408 INFO      GST_ELEMENT_PADS gstpad.c:1583:gst_pad_unlink: unlinked sink:proxypad0 and tempsink:sink
0:00:00.294465957  7129 0x804e408 INFO         GST_PARENTAGE gstbin.c:1224:gst_bin_remove_func:<autoaudiosink0> removed child "tempsink"
0:00:00.294489703  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<tempsink> dispose
0:00:00.294510656  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<tempsink> removing pad 'sink'
0:00:00.294533564  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<tempsink> parent class dispose
0:00:00.294555913  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<tempsink> finalize
0:00:00.294584967  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<tempsink> finalize parent
0:00:00.294616536  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<autoaudiosink0> dispose
0:00:00.294636371  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<autoaudiosink0> removing pad 'sink'
0:00:00.294678276  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<autoaudiosink0> parent class dispose
0:00:00.294699787  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<autoaudiosink0> finalize
0:00:00.294719343  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<autoaudiosink0> finalize parent
0:00:00.294991167  7129 0x804e408 INFO          GST_PIPELINE gstparse.c:160:gst_parse_launch: parsing pipeline description alsasink
0:00:00.298598637  7129 0x804e408 INFO    GST_PLUGIN_LOADING gstplugin.c:620:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstalsa.so" loaded
0:00:00.298666523  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "alsasink"
0:00:00.298749495  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseSink@0x8430150> adding pad 'sink'
0:00:00.298859287  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<alsasink0> dispose
0:00:00.298882195  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<alsasink0> removing pad 'sink'
0:00:00.298909293  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<alsasink0> parent class dispose
0:00:00.298941141  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<alsasink0> finalize
0:00:00.298962373  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<alsasink0> finalize parent
gstreamer-properties-Message: Skipping unavailable plugin 'artsdsink'
gstreamer-properties-Message: Skipping unavailable plugin 'esdsink'
0:00:00.299176089  7129 0x804e408 INFO          GST_PIPELINE gstparse.c:160:gst_parse_launch: parsing pipeline description osssink
0:00:00.300652266  7129 0x804e408 INFO    GST_PLUGIN_LOADING gstplugin.c:620:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstossaudio.so" loaded
0:00:00.300877156  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "osssink"
0:00:00.300959011  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseSink@0x8434150> adding pad 'sink'
0:00:00.301041704  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<osssink0> dispose
0:00:00.301064333  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<osssink0> removing pad 'sink'
0:00:00.301090593  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<osssink0> parent class dispose
0:00:00.301113501  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<osssink0> finalize
0:00:00.301134174  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<osssink0> finalize parent
0:00:00.301309896  7129 0x804e408 INFO          GST_PIPELINE gstparse.c:160:gst_parse_launch: parsing pipeline description pulsesink
0:00:00.304031772  7129 0x804e408 INFO    GST_PLUGIN_LOADING gstplugin.c:620:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstpulse.so" loaded
0:00:00.304101334  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "pulsesink"
0:00:00.304188497  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseSink@0x843a0f8> adding pad 'sink'
0:00:00.304401654  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<pulsesink0> dispose
0:00:00.304426518  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<pulsesink0> removing pad 'sink'
0:00:00.304453896  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<pulsesink0> parent class dispose
0:00:00.304557820  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<pulsesink0> finalize
0:00:00.304581008  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<pulsesink0> finalize parent
0:00:00.307269359  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "pulsesink" named "test"
0:00:00.307362388  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseSink@0x843a3c0> adding pad 'sink'
0:00:00.307516320  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<test> dispose
0:00:00.307540066  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<test> removing pad 'sink'
0:00:00.307568003  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<test> parent class dispose
0:00:00.307645108  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<test> finalize
0:00:00.307668016  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<test> finalize parent
0:00:00.309494519  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "pulsesink" named "test"
0:00:00.309584196  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseSink@0x843a688> adding pad 'sink'
0:00:00.309709353  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<test> dispose
0:00:00.309733658  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<test> removing pad 'sink'
0:00:00.309761874  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<test> parent class dispose
0:00:00.309838979  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<test> finalize
0:00:00.309861608  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<test> finalize parent
0:00:00.310957007  7129 0x804e408 INFO          GST_PIPELINE gstparse.c:160:gst_parse_launch: parsing pipeline description autovideosink
0:00:00.311015395  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "autovideosink"
0:00:00.311117923  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstAutoVideoSink@0x841c9a0> adding pad 'sink'
0:00:00.311146697  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "fakesink" named "tempsink"
0:00:00.311201453  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseSink@0x8428298> adding pad 'sink'
0:00:00.311248666  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:887:gst_element_get_static_pad: found pad tempsink:sink
0:00:00.311271295  7129 0x804e408 INFO              GST_PADS gstpad.c:1769:gst_pad_link_prepare: trying to link sink:proxypad1 and tempsink:sink
0:00:00.311301467  7129 0x804e408 INFO              GST_PADS gstpad.c:1906:gst_pad_link: linked sink:proxypad1 and tempsink:sink, successful
0:00:00.311349518  7129 0x804e408 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<tempsink> completed state change to NULL
0:00:00.311375220  7129 0x804e408 INFO      GST_ELEMENT_PADS gstpad.c:1548:gst_pad_unlink: unlinking sink:proxypad1(0x841ca68) and tempsink:sink(0x8187d78)
0:00:00.311398128  7129 0x804e408 INFO      GST_ELEMENT_PADS gstpad.c:1583:gst_pad_unlink: unlinked sink:proxypad1 and tempsink:sink
0:00:00.311421315  7129 0x804e408 INFO         GST_PARENTAGE gstbin.c:1224:gst_bin_remove_func:<autovideosink0> removed child "tempsink"
0:00:00.311445341  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<tempsink> dispose
0:00:00.311467132  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<tempsink> removing pad 'sink'
0:00:00.311489760  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<tempsink> parent class dispose
0:00:00.311513507  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<tempsink> finalize
0:00:00.311559323  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<tempsink> finalize parent
0:00:00.311587818  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<autovideosink0> dispose
0:00:00.311608771  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<autovideosink0> removing pad 'sink'
0:00:00.311643971  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<autovideosink0> parent class dispose
0:00:00.311666041  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<autovideosink0> finalize
0:00:00.311952672  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<autovideosink0> finalize parent
gstreamer-properties-Message: Skipping unavailable plugin 'sdlvideosink'
0:00:00.312250757  7129 0x804e408 INFO          GST_PIPELINE gstparse.c:160:gst_parse_launch: parsing pipeline description ximagesink
0:00:00.313613231  7129 0x804e408 INFO    GST_PLUGIN_LOADING gstplugin.c:620:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstximagesink.so" loaded
0:00:00.313677486  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "ximagesink"
0:00:00.313756826  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseSink@0x8444018> adding pad 'sink'
0:00:00.313815772  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<ximagesink0> dispose
0:00:00.313838680  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<ximagesink0> removing pad 'sink'
0:00:00.313865779  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<ximagesink0> parent class dispose
0:00:00.313897068  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<ximagesink0> finalize
0:00:00.313918579  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<ximagesink0> finalize parent
0:00:00.314078936  7129 0x804e408 INFO          GST_PIPELINE gstparse.c:160:gst_parse_launch: parsing pipeline description xvimagesink
0:00:00.315498960  7129 0x804e408 INFO    GST_PLUGIN_LOADING gstplugin.c:620:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstxvimagesink.so" loaded
0:00:00.315560700  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "xvimagesink"
0:00:00.315644790  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseSink@0x8448038> adding pad 'sink'
0:00:00.315733070  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<xvimagesink0> dispose
0:00:00.315756537  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<xvimagesink0> removing pad 'sink'
0:00:00.315785032  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<xvimagesink0> parent class dispose
0:00:00.315817439  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<xvimagesink0> finalize
0:00:00.315862138  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<xvimagesink0> finalize parent
0:00:00.317441401  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "autovideosink" named "test"
0:00:00.317565440  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstAutoVideoSink@0x841cb30> adding pad 'sink'
0:00:00.317594773  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "fakesink" named "tempsink"
0:00:00.317647853  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseSink@0x84284f0> adding pad 'sink'
0:00:00.317694508  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:887:gst_element_get_static_pad: found pad tempsink:sink
0:00:00.317717695  7129 0x804e408 INFO              GST_PADS gstpad.c:1769:gst_pad_link_prepare: trying to link sink:proxypad2 and tempsink:sink
0:00:00.317747308  7129 0x804e408 INFO              GST_PADS gstpad.c:1906:gst_pad_link: linked sink:proxypad2 and tempsink:sink, successful
0:00:00.317790331  7129 0x804e408 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<tempsink> completed state change to NULL
0:00:00.317816312  7129 0x804e408 INFO      GST_ELEMENT_PADS gstpad.c:1548:gst_pad_unlink: unlinking sink:proxypad2(0x841cbf8) and tempsink:sink(0x8187b38)
0:00:00.317837823  7129 0x804e408 INFO      GST_ELEMENT_PADS gstpad.c:1583:gst_pad_unlink: unlinked sink:proxypad2 and tempsink:sink
0:00:00.317860731  7129 0x804e408 INFO         GST_PARENTAGE gstbin.c:1224:gst_bin_remove_func:<test> removed child "tempsink"
0:00:00.317884757  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<tempsink> dispose
0:00:00.317905709  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<tempsink> removing pad 'sink'
0:00:00.317928059  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<tempsink> parent class dispose
0:00:00.317951525  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<tempsink> finalize
0:00:00.317973037  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<tempsink> finalize parent
0:00:00.318000694  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<test> dispose
0:00:00.318021088  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<test> removing pad 'sink'
0:00:00.318056568  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<test> parent class dispose
0:00:00.318077520  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<test> finalize
0:00:00.318097355  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<test> finalize parent
gstreamer-properties-Message: Skipping unavailable plugin 'v4lmjpegsrc'
gstreamer-properties-Message: Skipping unavailable plugin 'qcamsrc'
0:00:00.319754562  7129 0x804e408 INFO          GST_PIPELINE gstparse.c:160:gst_parse_launch: parsing pipeline description videotestsrc is-live=true
0:00:00.361817216  7129 0x804e408 INFO    GST_PLUGIN_LOADING gstplugin.c:620:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstvideotestsrc.so" loaded
0:00:00.362021713  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "videotestsrc"
0:00:00.362102729  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseSrc@0x8454018> adding pad 'src'
0:00:00.362178717  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<videotestsrc0> dispose
0:00:00.362201625  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<videotestsrc0> removing pad 'src'
0:00:00.362229283  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<videotestsrc0> parent class dispose
0:00:00.362252750  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<videotestsrc0> finalize
0:00:00.362273981  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<videotestsrc0> finalize parent
0:00:00.362454453  7129 0x804e408 INFO          GST_PIPELINE gstparse.c:160:gst_parse_launch: parsing pipeline description v4lsrc
0:00:00.363735631  7129 0x804e408 INFO    GST_PLUGIN_LOADING gstplugin.c:620:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstvideo4linux.so" loaded
0:00:00.363799327  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "v4lsrc"
0:00:00.363879226  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseSrc@0x8456008> adding pad 'src'
0:00:00.363929233  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<v4lsrc0> dispose
0:00:00.363951582  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<v4lsrc0> removing pad 'src'
0:00:00.363979239  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<v4lsrc0> parent class dispose
0:00:00.364002986  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<v4lsrc0> finalize
0:00:00.364023379  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<v4lsrc0> finalize parent
0:00:00.364186530  7129 0x804e408 INFO          GST_PIPELINE gstparse.c:160:gst_parse_launch: parsing pipeline description v4l2src
0:00:00.365662148  7129 0x804e408 INFO    GST_PLUGIN_LOADING gstplugin.c:620:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstvideo4linux2.so" loaded
0:00:00.365724447  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "v4l2src"
0:00:00.365783114  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseSrc@0x8454210> adding pad 'src'
0:00:00.365844016  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<v4l2src0> dispose
0:00:00.365890391  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<v4l2src0> removing pad 'src'
0:00:00.365918886  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<v4l2src0> parent class dispose
0:00:00.365942912  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<v4l2src0> finalize
0:00:00.365963864  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<v4l2src0> finalize parent
0:00:00.367741478  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "v4l2src" named "test"
0:00:00.367830597  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseSrc@0x8454408> adding pad 'src'
0:00:00.368396315  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<test> dispose
0:00:00.368419503  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<test> removing pad 'src'
0:00:00.368447439  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<test> parent class dispose
0:00:00.368471186  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<test> finalize
0:00:00.368492138  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<test> finalize parent
0:00:00.369959375  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "v4l2src" named "test"
0:00:00.370045700  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseSrc@0x8454600> adding pad 'src'
0:00:00.370094589  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<test> dispose
0:00:00.370116659  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<test> removing pad 'src'
0:00:00.370144596  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<test> parent class dispose
0:00:00.370168901  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<test> finalize
0:00:00.370190133  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<test> finalize parent
0:00:00.371146407  7129 0x804e408 INFO          GST_PIPELINE gstparse.c:160:gst_parse_launch: parsing pipeline description alsasrc
0:00:00.371196972  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "alsasrc"
0:00:00.371295309  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseSrc@0x845c110> adding pad 'src'
0:00:00.371371856  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<alsasrc0> dispose
0:00:00.371394205  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<alsasrc0> removing pad 'src'
0:00:00.371421304  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<alsasrc0> parent class dispose
0:00:00.371469635  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<alsasrc0> finalize
0:00:00.371491146  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<alsasrc0> finalize parent
gstreamer-properties-Message: Skipping unavailable plugin 'esdmon'
0:00:00.371647592  7129 0x804e408 INFO          GST_PIPELINE gstparse.c:160:gst_parse_launch: parsing pipeline description osssrc
0:00:00.371674411  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "osssrc"
0:00:00.372144586  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseSrc@0x845e018> adding pad 'src'
0:00:00.372208840  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<osssrc0> dispose
0:00:00.372230352  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<osssrc0> removing pad 'src'
0:00:00.372257730  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<osssrc0> parent class dispose
0:00:00.372280917  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<osssrc0> finalize
0:00:00.372301870  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<osssrc0> finalize parent
0:00:00.372449376  7129 0x804e408 INFO          GST_PIPELINE gstparse.c:160:gst_parse_launch: parsing pipeline description pulsesrc
0:00:00.372479268  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "pulsesrc"
0:00:00.372544640  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseSrc@0x8460020> adding pad 'src'
0:00:00.372659460  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<pulsesrc0> dispose
0:00:00.372681530  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<pulsesrc0> removing pad 'src'
0:00:00.372706673  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<pulsesrc0> parent class dispose
0:00:00.372781823  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<pulsesrc0> finalize
0:00:00.372803893  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<pulsesrc0> finalize parent
0:00:00.372928211  7129 0x804e408 INFO          GST_PIPELINE gstparse.c:160:gst_parse_launch: parsing pipeline description audiotestsrc wave=triangle is-live=true
0:00:00.374092055  7129 0x804e408 INFO    GST_PLUGIN_LOADING gstplugin.c:620:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstaudiotestsrc.so" loaded
0:00:00.374151281  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "audiotestsrc"
0:00:00.374219726  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseSrc@0x84630f8> adding pad 'src'
0:00:00.374303257  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<audiotestsrc0> dispose
0:00:00.374349911  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<audiotestsrc0> removing pad 'src'
0:00:00.374377289  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<audiotestsrc0> parent class dispose
0:00:00.374400197  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<audiotestsrc0> finalize
0:00:00.374420591  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<audiotestsrc0> finalize parent
0:00:00.374579551  7129 0x804e408 INFO          GST_PIPELINE gstparse.c:160:gst_parse_launch: parsing pipeline description audiotestsrc wave=silence is-live=true
0:00:00.374608047  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "audiotestsrc"
0:00:00.374659730  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseSrc@0x84630f8> adding pad 'src'
0:00:00.374711412  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<audiotestsrc1> dispose
0:00:00.374733482  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<audiotestsrc1> removing pad 'src'
0:00:00.374758905  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<audiotestsrc1> parent class dispose
0:00:00.374780975  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<audiotestsrc1> finalize
0:00:00.374801089  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<audiotestsrc1> finalize parent
0:00:00.376876788  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "pulsesrc" named "test"
0:00:00.376967862  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseSrc@0x8460250> adding pad 'src'
0:00:00.377087431  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<test> dispose
0:00:00.377109781  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<test> removing pad 'src'
0:00:00.377137438  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<test> parent class dispose
0:00:00.377214823  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<test> finalize
0:00:00.377237172  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<test> finalize parent
0:00:00.378820905  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:397:gst_element_factory_create: creating element "pulsesrc" named "test"
0:00:00.378907788  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseSrc@0x8460480> adding pad 'src'
0:00:00.379026519  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<test> dispose
0:00:00.379049427  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<test> removing pad 'src'
0:00:00.379103904  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<test> parent class dispose
0:00:00.379179892  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<test> finalize
0:00:00.379202241  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<test> finalize parent
0:00:03.650312809  7129 0x804e408 INFO          GST_PIPELINE gstparse.c:160:gst_parse_launch: parsing pipeline description pulsesrc ! audioconvert ! audioresample ! pulsesink
0:00:03.650448023  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "pulsesrc"
0:00:03.650530157  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseSrc@0x84606b0> adding pad 'src'
0:00:03.651864974  7129 0x804e408 INFO    GST_PLUGIN_LOADING gstplugin.c:620:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstaudioconvert.so" loaded
0:00:03.651924479  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "audioconvert"
0:00:03.651982308  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseTransform@0x845e230> adding pad 'sink'
0:00:03.652034829  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseTransform@0x845e230> adding pad 'src'
0:00:03.652989986  7129 0x804e408 INFO    GST_PLUGIN_LOADING gstplugin.c:620:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstaudioresample.so" loaded
0:00:03.653041110  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "audioresample"
0:00:03.653097821  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseTransform@0x848a110> adding pad 'sink'
0:00:03.653143638  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseTransform@0x848a110> adding pad 'src'
0:00:03.653192806  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "pulsesink"
0:00:03.653243372  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:641:gst_element_add_pad:<GstBaseSink@0x843a950> adding pad 'sink'
0:00:03.653495920  7129 0x804e408 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:399:gst_element_factory_create: creating element "pipeline"
0:00:03.653621635  7129 0x804e408 INFO          GST_PIPELINE ./grammar.y:466:gst_parse_perform_link: linking pulsesrc3:(any) to audioconvert0:(any) (0/0) with caps "(NULL)"
0:00:03.653672480  7129 0x804e408 INFO      GST_ELEMENT_PADS gstutils.c:1358:gst_element_link_pads: trying to link element pulsesrc3:(any) to element audioconvert0:(any)
0:00:03.653696226  7129 0x804e408 INFO              GST_PADS gstutils.c:2039:gst_pad_can_link: trying to link pulsesrc3:src and audioconvert0:src
0:00:03.653716899  7129 0x804e408 INFO              GST_PADS gstutils.c:2059:gst_pad_can_link: Sink pad audioconvert0:src is not sink pad, failed
0:00:03.653738131  7129 0x804e408 INFO              GST_PADS gstutils.c:2039:gst_pad_can_link: trying to link pulsesrc3:src and audioconvert0:sink
0:00:03.653791211  7129 0x804e408 INFO              GST_PADS gstutils.c:1263:prepare_link_maybe_ghosting: pulsesrc3 and audioconvert0 in same bin, no need for ghost pads
0:00:03.653812722  7129 0x804e408 INFO              GST_PADS gstpad.c:1769:gst_pad_link_prepare: trying to link pulsesrc3:src and audioconvert0:sink
0:00:03.654392130  7129 0x804e408 INFO              GST_PADS gstpad.c:1906:gst_pad_link: linked pulsesrc3:src and audioconvert0:sink, successful
0:00:03.654418670  7129 0x804e408 INFO          GST_PIPELINE ./grammar.y:466:gst_parse_perform_link: linking audioconvert0:(any) to audioresample0:(any) (0/0) with caps "(NULL)"
0:00:03.654441299  7129 0x804e408 INFO      GST_ELEMENT_PADS gstutils.c:1358:gst_element_link_pads: trying to link element audioconvert0:(any) to element audioresample0:(any)
0:00:03.654462810  7129 0x804e408 INFO              GST_PADS gstutils.c:2039:gst_pad_can_link: trying to link audioconvert0:src and audioresample0:src
0:00:03.654482086  7129 0x804e408 INFO              GST_PADS gstutils.c:2059:gst_pad_can_link: Sink pad audioresample0:src is not sink pad, failed
0:00:03.654501642  7129 0x804e408 INFO              GST_PADS gstutils.c:2039:gst_pad_can_link: trying to link audioconvert0:src and audioresample0:sink
0:00:03.654521756  7129 0x804e408 INFO              GST_PADS gstutils.c:1263:prepare_link_maybe_ghosting: audioconvert0 and audioresample0 in same bin, no need for ghost pads
0:00:03.654540474  7129 0x804e408 INFO              GST_PADS gstpad.c:1769:gst_pad_link_prepare: trying to link audioconvert0:src and audioresample0:sink
0:00:03.656461962  7129 0x804e408 INFO              GST_PADS gstpad.c:1906:gst_pad_link: linked audioconvert0:src and audioresample0:sink, successful
0:00:03.656486546  7129 0x804e408 INFO          GST_PIPELINE ./grammar.y:466:gst_parse_perform_link: linking audioresample0:(any) to pulsesink3:(any) (0/0) with caps "(NULL)"
0:00:03.656510293  7129 0x804e408 INFO      GST_ELEMENT_PADS gstutils.c:1358:gst_element_link_pads: trying to link element audioresample0:(any) to element pulsesink3:(any)
0:00:03.656532363  7129 0x804e408 INFO              GST_PADS gstutils.c:2039:gst_pad_can_link: trying to link audioresample0:src and pulsesink3:sink
0:00:03.656552198  7129 0x804e408 INFO              GST_PADS gstutils.c:1263:prepare_link_maybe_ghosting: audioresample0 and pulsesink3 in same bin, no need for ghost pads
0:00:03.656570915  7129 0x804e408 INFO              GST_PADS gstpad.c:1769:gst_pad_link_prepare: trying to link audioresample0:src and pulsesink3:sink
0:00:03.658489330  7129 0x804e408 INFO              GST_PADS gstpad.c:1906:gst_pad_link: linked audioresample0:src and pulsesink3:sink, successful
0:00:03.660794390  7129 0x804e408 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<pulsesink3> completed state change to READY
0:00:03.660963128  7129 0x804e408 INFO            GST_STATES gstelement.c:2161:gst_element_continue_state:<pulsesink3> posting state-changed NULL to READY
0:00:03.661037998  7129 0x804e408 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline0> child 'pulsesink3' changed state to 2(READY) successfully
0:00:03.661105605  7129 0x804e408 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<audioresample0> completed state change to READY
0:00:03.661125440  7129 0x804e408 INFO            GST_STATES gstelement.c:2161:gst_element_continue_state:<audioresample0> posting state-changed NULL to READY
0:00:03.661153097  7129 0x804e408 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline0> child 'audioresample0' changed state to 2(READY) successfully
0:00:03.661178240  7129 0x804e408 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<audioconvert0> completed state change to READY
0:00:03.661197517  7129 0x804e408 INFO            GST_STATES gstelement.c:2161:gst_element_continue_state:<audioconvert0> posting state-changed NULL to READY
0:00:03.661223777  7129 0x804e408 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline0> child 'audioconvert0' changed state to 2(READY) successfully
0:00:03.665251974  7129 0x804e408 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<pulsesrc3> completed state change to READY
0:00:03.665297232  7129 0x804e408 INFO            GST_STATES gstelement.c:2161:gst_element_continue_state:<pulsesrc3> posting state-changed NULL to READY
0:00:03.671846439  7129 0x804e408 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline0> child 'pulsesrc3' changed state to 2(READY) successfully
0:00:03.671890020  7129 0x804e408 INFO            GST_STATES gstelement.c:2121:gst_element_continue_state:<pipeline0> committing state from NULL to READY, pending PLAYING
0:00:03.671917677  7129 0x804e408 INFO            GST_STATES gstelement.c:2130:gst_element_continue_state:<pipeline0> continue state change READY to PAUSED, final PLAYING
0:00:03.672014338  7129 0x804e408 INFO            GST_STATES gstbin.c:2197:gst_bin_change_state_func:<pipeline0> child 'pulsesink3' is changing state asynchronously to PAUSED
0:00:03.672053170  7129 0x804e408 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<audioresample0> completed state change to PAUSED
0:00:03.672074402  7129 0x804e408 INFO            GST_STATES gstelement.c:2161:gst_element_continue_state:<audioresample0> posting state-changed READY to PAUSED
0:00:03.672101780  7129 0x804e408 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline0> child 'audioresample0' changed state to 3(PAUSED) successfully
0:00:03.672131393  7129 0x804e408 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<audioconvert0> completed state change to PAUSED
0:00:03.672151228  7129 0x804e408 INFO            GST_STATES gstelement.c:2161:gst_element_continue_state:<audioconvert0> posting state-changed READY to PAUSED
0:00:03.672177489  7129 0x804e408 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline0> child 'audioconvert0' changed state to 3(PAUSED) successfully
0:00:03.688949582  7129 0x804e408 INFO             GST_EVENT gstevent.c:534:gst_event_new_new_segment_full: 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:03.689185648  7129 0x804e408 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<pulsesrc3> completed state change to PAUSED
0:00:03.689238448  7129 0x804e408 INFO            GST_STATES gstelement.c:2161:gst_element_continue_state:<pulsesrc3> posting state-changed READY to PAUSED
0:00:03.689273928  7129 0x804e408 INFO            GST_STATES gstbin.c:2212:gst_bin_change_state_func:<pipeline0> child 'pulsesrc3' changed state to 3(PAUSED) successfully without preroll
0:00:03.689304938  7129 0x804e408 INFO            GST_STATES gstelement.c:2121:gst_element_continue_state:<pipeline0> committing state from READY to PAUSED, pending PLAYING
0:00:03.689331198  7129 0x804e408 INFO            GST_STATES gstelement.c:2130:gst_element_continue_state:<pipeline0> continue state change PAUSED to PLAYING, final PLAYING
0:00:03.689416405  7129 0x804e408 WARN                   bin gstbin.c:2080:do_bin_latency:<pipeline0> failed to query latency
0:00:03.689450209  7129 0x804e408 INFO            GST_STATES gstbin.c:2197:gst_bin_change_state_func:<pipeline0> child 'pulsesink3' is changing state asynchronously to PLAYING
0:00:03.689475072  7129 0x804e408 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<audioresample0> completed state change to PLAYING
0:00:03.689494628  7129 0x804e408 INFO            GST_STATES gstelement.c:2161:gst_element_continue_state:<audioresample0> posting state-changed PAUSED to PLAYING
0:00:03.689521447  7129 0x804e408 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline0> child 'audioresample0' changed state to 4(PLAYING) successfully
0:00:03.689545473  7129 0x804e408 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<audioconvert0> completed state change to PLAYING
0:00:03.689564470  7129 0x804e408 INFO            GST_STATES gstelement.c:2161:gst_element_continue_state:<audioconvert0> posting state-changed PAUSED to PLAYING
0:00:03.689590172  7129 0x804e408 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline0> child 'audioconvert0' changed state to 4(PLAYING) successfully
0:00:03.689616711  7129 0x804e408 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<pulsesrc3> completed state change to PLAYING
0:00:03.689635988  7129 0x804e408 INFO            GST_STATES gstelement.c:2161:gst_element_continue_state:<pulsesrc3> posting state-changed PAUSED to PLAYING
0:00:03.689661690  7129 0x804e408 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline0> child 'pulsesrc3' changed state to 4(PLAYING) successfully
0:00:03.689689906  7129 0x804e408 INFO            GST_STATES gstbin.c:1545:gst_bin_get_state_func:<pipeline0> getting state
0:00:03.689717842  7129 0x804e408 INFO            GST_STATES gstelement.c:1896:gst_element_get_state_func:<pipeline0> waiting for element to commit state
0:00:03.721600353  7129 0x84930d8 INFO          audioconvert audioconvert.c:458:audio_convert_prepare_context: use float mixing
0:00:03.721835301  7129 0x84930d8 INFO          audioconvert audioconvert.c:461:audio_convert_prepare_context: unitsizes: 8 -> 8
0:00:03.721857371  7129 0x84930d8 INFO          audioconvert audioconvert.c:471:audio_convert_prepare_context: in default 0, mix passthrough 1, out default 0
0:00:03.731171197  7129 0x84930d8 INFO            GST_STATES gstbin.c:2594:bin_handle_async_done:<pipeline0> continue state change, pending PLAYING
0:00:03.731430170  7129 0x8492660 INFO            GST_STATES gstbin.c:2392:gst_bin_continue_func:<pipeline0> continue state change PAUSED to PLAYING, final PLAYING
0:00:03.731527110  7129 0x8492660 INFO             GST_EVENT gstevent.c:942:gst_event_new_latency: creating latency event 0:00:00.220000000
0:00:03.731568177  7129 0x8492660 INFO                   bin gstbin.c:2069:do_bin_latency:<pipeline0> configured latency of 0:00:00.220000000
0:00:03.731734122  7129 0x8492660 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<pulsesink3> completed state change to PLAYING
0:00:03.731788878  7129 0x8492660 INFO            GST_STATES gstelement.c:2161:gst_element_continue_state:<pulsesink3> posting state-changed PAUSED to PLAYING
0:00:03.731940295  7129 0x8492660 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline0> child 'pulsesink3' changed state to 4(PLAYING) successfully
0:00:03.732107915  7129 0x8492660 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<audioresample0> completed state change to PLAYING
0:00:03.732129985  7129 0x8492660 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline0> child 'audioresample0' changed state to 4(PLAYING) successfully
0:00:03.732154290  7129 0x8492660 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<audioconvert0> completed state change to PLAYING
0:00:03.732173846  7129 0x8492660 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline0> child 'audioconvert0' changed state to 4(PLAYING) successfully
0:00:03.732198151  7129 0x8492660 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<pulsesrc3> completed state change to PLAYING
0:00:03.732217706  7129 0x8492660 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline0> child 'pulsesrc3' changed state to 4(PLAYING) successfully
0:00:03.732241732  7129 0x8492660 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<pipeline0> completed state change to PLAYING
0:00:03.732260729  7129 0x8492660 INFO            GST_STATES gstelement.c:2161:gst_element_continue_state:<pipeline0> posting state-changed PAUSED to PLAYING
0:00:03.753868949  7129 0x84930d8 WARN         baseaudiosink gstbaseaudiosink.c:919:gst_base_audio_sink_skew_slaving:<pulsesink3> correct clock skew -30000000 < -5000000
0:00:03.783085738  7129 0x84930d8 WARN         baseaudiosink gstbaseaudiosink.c:919:gst_base_audio_sink_skew_slaving:<pulsesink3> correct clock skew -20625000 < -5000000
0:00:03.783140494  7129 0x84930d8 WARN         baseaudiosink gstbaseaudiosink.c:919:gst_base_audio_sink_skew_slaving:<pulsesink3> correct clock skew -11230468 < -5000000
0:00:03.789316466  7129 0x84930d8 WARN         baseaudiosink gstbaseaudiosink.c:919:gst_base_audio_sink_skew_slaving:<pulsesink3> correct clock skew -5727629 < -5000000
0:00:03.842156272  7129 0x84930d8 WARN         baseaudiosink gstbaseaudiosink.c:919:gst_base_audio_sink_skew_slaving:<pulsesink3> correct clock skew -5142640 < -5000000
0:00:03.887120434  7129 0x84930d8 WARN         baseaudiosink gstbaseaudiosink.c:919:gst_base_audio_sink_skew_slaving:<pulsesink3> correct clock skew -5868295 < -5000000
0:00:03.942176460  7129 0x84930d8 WARN         baseaudiosink gstbaseaudiosink.c:919:gst_base_audio_sink_skew_slaving:<pulsesink3> correct clock skew -6725691 < -5000000
0:00:04.017166738  7129 0x84930d8 WARN         baseaudiosink gstbaseaudiosink.c:919:gst_base_audio_sink_skew_slaving:<pulsesink3> correct clock skew -5597288 < -5000000
0:00:04.067155880  7129 0x84930d8 WARN         baseaudiosink gstbaseaudiosink.c:919:gst_base_audio_sink_skew_slaving:<pulsesink3> correct clock skew -6251757 < -5000000
0:00:04.142173536  7129 0x84930d8 WARN         baseaudiosink gstbaseaudiosink.c:919:gst_base_audio_sink_skew_slaving:<pulsesink3> correct clock skew -5219266 < -5000000
0:00:04.242124162  7129 0x84930d8 WARN         baseaudiosink gstbaseaudiosink.c:919:gst_base_audio_sink_skew_slaving:<pulsesink3> correct clock skew -5200838 < -5000000
0:00:04.467084380  7129 0x84930d8 WARN         baseaudiosink gstbaseaudiosink.c:919:gst_base_audio_sink_skew_slaving:<pulsesink3> correct clock skew -5141585 < -5000000
0:00:05.354754897  7129 0x804e408 INFO            GST_STATES gstbin.c:2197:gst_bin_change_state_func:<pipeline0> child 'pulsesink3' is changing state asynchronously to PAUSED
0:00:05.354949616  7129 0x804e408 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<audioresample0> completed state change to PAUSED
0:00:05.354973642  7129 0x804e408 INFO            GST_STATES gstelement.c:2161:gst_element_continue_state:<audioresample0> posting state-changed PLAYING to PAUSED
0:00:05.355005489  7129 0x804e408 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline0> child 'audioresample0' changed state to 3(PAUSED) successfully
0:00:05.355030074  7129 0x804e408 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<audioconvert0> completed state change to PAUSED
0:00:05.355050747  7129 0x804e408 INFO            GST_STATES gstelement.c:2161:gst_element_continue_state:<audioconvert0> posting state-changed PLAYING to PAUSED
0:00:05.355077007  7129 0x804e408 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline0> child 'audioconvert0' changed state to 3(PAUSED) successfully
0:00:05.355116678  7129 0x804e408 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<pulsesrc3> completed state change to PAUSED
0:00:05.355137071  7129 0x804e408 INFO            GST_STATES gstelement.c:2161:gst_element_continue_state:<pulsesrc3> posting state-changed PLAYING to PAUSED
0:00:05.355163891  7129 0x804e408 INFO            GST_STATES gstbin.c:2212:gst_bin_change_state_func:<pipeline0> child 'pulsesrc3' changed state to 3(PAUSED) successfully without preroll
0:00:05.355200208  7129 0x804e408 INFO            GST_STATES gstelement.c:2121:gst_element_continue_state:<pipeline0> committing state from PLAYING to PAUSED, pending NULL
0:00:05.355229542  7129 0x804e408 INFO            GST_STATES gstelement.c:2130:gst_element_continue_state:<pipeline0> continue state change PAUSED to READY, final NULL
0:00:05.355480693  7129 0x804e408 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<pulsesink3> completed state change to READY
0:00:05.355505836  7129 0x804e408 INFO            GST_STATES gstelement.c:2161:gst_element_continue_state:<pulsesink3> posting state-changed PLAYING to READY
0:00:05.355604732  7129 0x804e408 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline0> child 'pulsesink3' changed state to 2(READY) successfully
0:00:05.355654739  7129 0x804e408 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<audioresample0> completed state change to READY
0:00:05.355675692  7129 0x804e408 INFO            GST_STATES gstelement.c:2161:gst_element_continue_state:<audioresample0> posting state-changed PAUSED to READY
0:00:05.355839959  7129 0x804e408 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline0> child 'audioresample0' changed state to 2(READY) successfully
0:00:05.355884379  7129 0x804e408 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<audioconvert0> completed state change to READY
0:00:05.355904773  7129 0x804e408 INFO            GST_STATES gstelement.c:2161:gst_element_continue_state:<audioconvert0> posting state-changed PAUSED to READY
0:00:05.355931313  7129 0x804e408 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline0> child 'audioconvert0' changed state to 2(READY) successfully
0:00:05.356018755  7129 0x84930d8 INFO               basesrc gstbasesrc.c:2021:gst_base_src_loop:<pulsesrc3> pausing after gst_base_src_get_range() = wrong-state
0:00:05.356083847  7129 0x804e408 INFO             GST_EVENT gstpad.c:4317:gst_pad_send_event:<audioconvert0:sink> Received event on flushing pad. Discarding
0:00:05.368060602  7129 0x804e408 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<pulsesrc3> completed state change to READY
0:00:05.368248057  7129 0x804e408 INFO            GST_STATES gstelement.c:2161:gst_element_continue_state:<pulsesrc3> posting state-changed PAUSED to READY
0:00:05.368297784  7129 0x804e408 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline0> child 'pulsesrc3' changed state to 2(READY) successfully
0:00:05.368336616  7129 0x804e408 INFO            GST_STATES gstelement.c:2121:gst_element_continue_state:<pipeline0> committing state from PAUSED to READY, pending NULL
0:00:05.368363436  7129 0x804e408 INFO            GST_STATES gstelement.c:2130:gst_element_continue_state:<pipeline0> continue state change READY to NULL, final NULL
0:00:05.368580225  7129 0x804e408 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<pulsesink3> completed state change to NULL
0:00:05.368602853  7129 0x804e408 INFO            GST_STATES gstelement.c:2161:gst_element_continue_state:<pulsesink3> posting state-changed READY to NULL
0:00:05.368631349  7129 0x804e408 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline0> child 'pulsesink3' changed state to 1(NULL) successfully
0:00:05.368661521  7129 0x804e408 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<audioresample0> completed state change to NULL
0:00:05.368697000  7129 0x804e408 INFO            GST_STATES gstelement.c:2161:gst_element_continue_state:<audioresample0> posting state-changed READY to NULL
0:00:05.368722981  7129 0x804e408 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline0> child 'audioresample0' changed state to 1(NULL) successfully
0:00:05.368752315  7129 0x804e408 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<audioconvert0> completed state change to NULL
0:00:05.368796455  7129 0x804e408 INFO            GST_STATES gstelement.c:2161:gst_element_continue_state:<audioconvert0> posting state-changed READY to NULL
0:00:05.368823274  7129 0x804e408 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline0> child 'audioconvert0' changed state to 1(NULL) successfully
0:00:05.369013523  7129 0x804e408 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<pulsesrc3> completed state change to NULL
0:00:05.369035593  7129 0x804e408 INFO            GST_STATES gstelement.c:2161:gst_element_continue_state:<pulsesrc3> posting state-changed READY to NULL
0:00:05.369063251  7129 0x804e408 INFO            GST_STATES gstbin.c:2191:gst_bin_change_state_func:<pipeline0> child 'pulsesrc3' changed state to 1(NULL) successfully
0:00:05.369119124  7129 0x804e408 INFO            GST_STATES gstelement.c:2148:gst_element_continue_state:<pipeline0> completed state change to NULL
0:00:05.369138680  7129 0x804e408 INFO            GST_STATES gstelement.c:2161:gst_element_continue_state:<pipeline0> posting state-changed READY to NULL
0:00:05.369171366  7129 0x804e408 INFO      GST_ELEMENT_PADS gstpad.c:1548:gst_pad_unlink: unlinking audioresample0:src(0x8187cb8) and pulsesink3:sink(0x8187d78)
0:00:05.369194553  7129 0x804e408 INFO      GST_ELEMENT_PADS gstpad.c:1583:gst_pad_unlink: unlinked audioresample0:src and pulsesink3:sink
0:00:05.369217741  7129 0x804e408 INFO         GST_PARENTAGE gstbin.c:1224:gst_bin_remove_func:<pipeline0> removed child "pulsesink3"
0:00:05.373148997  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<pulsesink3> dispose
0:00:05.373186991  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<pulsesink3> removing pad 'sink'
0:00:05.373218839  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<pulsesink3> parent class dispose
0:00:05.373359361  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<pulsesink3> finalize
0:00:05.373382548  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<pulsesink3> finalize parent
0:00:05.373411882  7129 0x804e408 INFO      GST_ELEMENT_PADS gstpad.c:1548:gst_pad_unlink: unlinking audioconvert0:src(0x8187b38) and audioresample0:sink(0x8187bf8)
0:00:05.373434231  7129 0x804e408 INFO      GST_ELEMENT_PADS gstpad.c:1583:gst_pad_unlink: unlinked audioconvert0:src and audioresample0:sink
0:00:05.373457419  7129 0x804e408 INFO         GST_PARENTAGE gstbin.c:1224:gst_bin_remove_func:<pipeline0> removed child "audioresample0"
0:00:05.373482003  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<audioresample0> dispose
0:00:05.373501559  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<audioresample0> removing pad 'src'
0:00:05.373526981  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<audioresample0> removing pad 'sink'
0:00:05.373551845  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<audioresample0> parent class dispose
0:00:05.373596543  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<audioresample0> finalize
0:00:05.373616658  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<audioresample0> finalize parent
0:00:05.373640404  7129 0x804e408 INFO      GST_ELEMENT_PADS gstpad.c:1548:gst_pad_unlink: unlinking pulsesrc3:src(0x8187a78) and audioconvert0:sink(0x81879b8)
0:00:05.373661915  7129 0x804e408 INFO      GST_ELEMENT_PADS gstpad.c:1583:gst_pad_unlink: unlinked pulsesrc3:src and audioconvert0:sink
0:00:05.373699909  7129 0x804e408 INFO         GST_PARENTAGE gstbin.c:1224:gst_bin_remove_func:<pipeline0> removed child "audioconvert0"
0:00:05.373737345  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<audioconvert0> dispose
0:00:05.373757180  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<audioconvert0> removing pad 'src'
0:00:05.373782043  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<audioconvert0> removing pad 'sink'
0:00:05.373806628  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<audioconvert0> parent class dispose
0:00:05.373827580  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<audioconvert0> finalize
0:00:05.373847415  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<audioconvert0> finalize parent
0:00:05.373871720  7129 0x804e408 INFO         GST_PARENTAGE gstbin.c:1224:gst_bin_remove_func:<pipeline0> removed child "pulsesrc3"
0:00:05.373899657  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<pulsesrc3> dispose
0:00:05.373919213  7129 0x804e408 INFO      GST_ELEMENT_PADS gstelement.c:759:gst_element_remove_pad:<pulsesrc3> removing pad 'src'
0:00:05.373943797  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<pulsesrc3> parent class dispose
0:00:05.374008890  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<pulsesrc3> finalize
0:00:05.374030680  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<pulsesrc3> finalize parent
0:00:05.374051912  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2719:gst_element_dispose:<pipeline0> dispose
0:00:05.374080966  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2743:gst_element_dispose:<pipeline0> parent class dispose
0:00:05.374101360  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2766:gst_element_finalize:<pipeline0> finalize
0:00:05.374120636  7129 0x804e408 INFO       GST_REFCOUNTING gstelement.c:2777:gst_element_finalize:<pipeline0> finalize parent