0:00:00.001087000  8764 0x83656d8 INFO              GST_INIT gst.c:575:init_pre: Initializing GStreamer Core Library version 0.10.11.1
0:00:00.001310000  8764 0x83656d8 INFO              GST_INIT gst.c:576:init_pre: Using library installed in /usr/local/lib
0:00:00.001334000  8764 0x83656d8 INFO              GST_INIT gst.c:586:init_pre: Linux sescento 2.6.20-2-386 #3 Sat Dec 16 12:21:12 UTC 2006 i686
0:00:00.001432000  8764 0x83656d8 INFO              GST_INIT gstquery.c:101:_gst_query_initialize: init queries
0:00:00.001772000  8764 0x83656d8 INFO              GST_INIT gstmessage.c:72:_gst_message_initialize: init messages
0:00:00.002647000  8764 0x83656d8 INFO    GST_PLUGIN_LOADING gstplugin.c:182:_gst_plugin_register_static: loaded static plugin "staticelements"
0:00:00.050380000  8765 0x83656d8 INFO          GST_REGISTRY gstregistryxml.c:575:gst_registry_xml_read_cache: loaded /home/laszlo/Software/SVN/gst/head/gstreamer/registry.xml in 0.047038 seconds
0:00:00.186581000  8765 0x83656d8 INFO              GST_INIT gst.c:706:scan_and_update_registry: Registry cache has not changed
0:00:00.232817000  8764 0x83656d8 INFO          GST_REGISTRY gstregistryxml.c:575:gst_registry_xml_read_cache: loaded /home/laszlo/Software/SVN/gst/head/gstreamer/registry.xml in 0.045633 seconds
0:00:00.233018000  8764 0x83656d8 INFO              GST_INIT gst.c:417:gst_init_check: initialized GStreamer successfully
0:00:00.330160000  8764 0x83656d8 INFO    GST_PLUGIN_LOADING gstplugin.c:467:gst_plugin_load_file: plugin "/home/laszlo/Software/SVN/gst/head/gst-plugins-base/ext/alsa/.libs/libgstalsa.so" loaded
0:00:00.330353000  8764 0x83656d8 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "alsasink" named "mysink"
0:00:00.330604000  8764 0x83656d8 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<GstBaseSink@0x85c2058> adding pad 'sink'
0:00:00.332126000  8764 0x83656d8 DEBUG               python gnlcutoff.py:15:__init__: Adding an instrument gnlcomposition
0:00:00.333440000  8764 0x83656d8 INFO    GST_PLUGIN_LOADING gstplugin.c:467:gst_plugin_load_file: plugin "/home/laszlo/Software/SVN/gst/head/gnonlin/gnl/.libs/libgnl.so" loaded
0:00:00.333515000  8764 0x83656d8 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "gnlcomposition" named "mycomposition10"
0:00:00.333572000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:366:gnl_composition_reset:<GnlComposition@0x85c7000> resetting
0:00:00.333597000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:391:gnl_composition_reset:<GnlComposition@0x85c7000> locking flushing_lock from thread 0x83656d8
0:00:00.333612000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:391:gnl_composition_reset:<GnlComposition@0x85c7000> locked flushing_lock from thread 0x83656d8
0:00:00.333627000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:396:gnl_composition_reset:<GnlComposition@0x85c7000> unlocking flushing_lock from thread 0x83656d8
0:00:00.333641000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:398:gnl_composition_reset:<GnlComposition@0x85c7000> Composition now resetted
0:00:00.333969000  8764 0x83656d8 DEBUG               python gnlcutoff.py:19:__init__: Adding a gnlfilesource
0:00:00.334009000  8764 0x83656d8 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "gnlfilesource" named "audio10"
0:00:00.357643000  8764 0x83656d8 INFO    GST_PLUGIN_LOADING gstplugin.c:467:gst_plugin_load_file: plugin "/home/laszlo/Software/SVN/gst/head/gst-plugins-base/ext/gnomevfs/.libs/libgstgnomevfs.so" loaded
0:00:00.357825000  8764 0x83656d8 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "gnomevfssrc" named "internal-filesource"
0:00:00.357955000  8764 0x83656d8 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<GstBaseSrc@0x85d8120> adding pad 'src'
0:00:00.358978000  8764 0x83656d8 INFO    GST_PLUGIN_LOADING gstplugin.c:467:gst_plugin_load_file: plugin "/home/laszlo/Software/SVN/gst/head/gst-plugins-base/gst/playback/.libs/libgstdecodebin.so" loaded
0:00:00.359101000  8764 0x83656d8 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "decodebin" named "internal-decodebin"
0:00:00.362356000  8764 0x83656d8 INFO    GST_PLUGIN_LOADING gstplugin.c:467:gst_plugin_load_file: plugin "/home/laszlo/Software/SVN/gst/head/gstreamer/plugins/elements/.libs/libgstcoreelements.so" loaded
0:00:00.362452000  8764 0x83656d8 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "typefind" named "typefind"
0:00:00.362562000  8764 0x83656d8 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<GstTypeFindElement@0x85e2840> adding pad 'sink'
0:00:00.362673000  8764 0x83656d8 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<GstTypeFindElement@0x85e2840> adding pad 'src'
0:00:00.362735000  8764 0x83656d8 INFO      GST_ELEMENT_PADS gstelement.c:870:gst_element_get_static_pad: found pad typefind:sink
0:00:00.362904000  8764 0x83656d8 INFO              GST_PADS gstpad.c:896:gst_pad_activate_push:<sink:proxypad0> failed to activate in push mode
0:00:00.362923000  8764 0x83656d8 INFO              GST_PADS gstpad.c:896:gst_pad_activate_push:<'':sink> failed to activate in push mode
0:00:00.362936000  8764 0x83656d8 WARN              GST_PADS gstpad.c:669:gst_pad_set_active: Failed to activate pad '':sink
0:00:00.362953000  8764 0x83656d8 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<GstDecodeBin@0x85b4220> adding pad 'sink'
0:00:00.362980000  8764 0x83656d8 INFO              GST_PADS gstpad.c:1730:gst_pad_link_prepare: trying to link sink:proxypad0 and typefind:sink
0:00:00.363014000  8764 0x83656d8 INFO              GST_PADS gstpad.c:1867:gst_pad_link: linked sink:proxypad0 and typefind:sink, successful
0:00:00.363047000  8764 0x83656d8 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "fakesink" named "fakesink"
0:00:00.363127000  8764 0x83656d8 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<GstBaseSink@0x85e6088> adding pad 'sink'
0:00:00.363199000  8764 0x83656d8 DEBUG            gnlsource gnlsource.c:413:gnl_source_add_element:<GnlFileSource@0x85c90a8> Adding element internal-filesource
0:00:00.363226000  8764 0x83656d8 DEBUG            gnlsource gnlsource.c:413:gnl_source_add_element:<GnlFileSource@0x85c90a8> Adding element internal-decodebin
0:00:00.363282000  8764 0x83656d8 INFO      GST_ELEMENT_PADS gstutils.c:1373:gst_element_link_pads: trying to link element internal-filesource:(any) to element internal-decodebin:(any)
0:00:00.363309000  8764 0x83656d8 INFO              GST_PADS gstutils.c:2046:gst_pad_can_link: trying to link internal-filesource:src and internal-decodebin:sink
0:00:00.363326000  8764 0x83656d8 INFO              GST_PADS gstutils.c:1278:prepare_link_maybe_ghosting: internal-filesource and internal-decodebin in same bin, no need for ghost pads
0:00:00.363340000  8764 0x83656d8 INFO              GST_PADS gstpad.c:1730:gst_pad_link_prepare: trying to link internal-filesource:src and internal-decodebin:sink
0:00:00.363373000  8764 0x83656d8 INFO              GST_PADS gstpad.c:1867:gst_pad_link: linked internal-filesource:src and internal-decodebin:sink, successful
0:00:00.363395000  8764 0x83656d8 DEBUG            gnlsource gnlsource.c:377:gnl_source_control_element_func:<GnlFileSource@0x85c90a8> element:internal-decodebin, source->element:(nil)
0:00:00.363422000  8764 0x83656d8 DEBUG            gnlsource gnlsource.c:392:gnl_source_control_element_func:<GnlFileSource@0x85c90a8> No valid source pad yet, dynamicpads:1
0:00:00.363452000  8764 0x83656d8 DEBUG        gnlfilesource gnlfilesource.c:141:gnl_filesource_init:<GnlFileSource@0x85c90a8> done
0:00:00.363882000  8764 0x83656d8 LOG              gnlobject gnlobject.c:1001:update_values:<audio10> Updating stop value : 0:00:10.000000000 [start:0:00:00.000000000, duration:0:00:10.000000000]
0:00:00.363933000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:1789:gnl_composition_add_object:<mycomposition10> element audio10
0:00:00.363981000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:1797:gnl_composition_add_object:<audio10> 0:00:00.000000000--0:00:10.000000000
0:00:00.363999000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1799:gnl_composition_add_object:<mycomposition10> locking objects_lock from thread 0x83656d8
0:00:00.364014000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1799:gnl_composition_add_object:<mycomposition10> locked object_lock from thread 0x83656d8
0:00:00.364091000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1864:gnl_composition_add_object:<mycomposition10> Head of objects_start is now audio10 [0:00:00.000000000--0:00:10.000000000]
0:00:00.364112000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1877:gnl_composition_add_object:<mycomposition10> Head of objects_stop is now audio10 [0:00:00.000000000--0:00:10.000000000]
0:00:00.364130000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:1882:gnl_composition_add_object:<mycomposition10> segment_start:99:99:99.999999999 segment_stop:99:99:99.999999999
0:00:00.364145000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1884:gnl_composition_add_object:<mycomposition10> unlocking objects_lock from thread 0x83656d8
0:00:00.364163000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:1548:update_pipeline:<mycomposition10> currenttime:99:99:99.999999999 initial:1 , change_state:1 , modify:1
0:00:00.364181000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1550:update_pipeline:<mycomposition10> locking objects_lock from thread 0x83656d8
0:00:00.364195000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1550:update_pipeline:<mycomposition10> locked object_lock from thread 0x83656d8
0:00:00.364211000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1139:update_start_stop_duration:<audio10> setting stop from audio10 to 0:00:10.000000000
0:00:00.364257000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1160:update_start_stop_duration:<mycomposition10> start:0:00:00.000000000 stop:0:00:10.000000000 duration:0:00:10.000000000
0:00:00.364275000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1683:update_pipeline:<mycomposition10> unlocking objects_lock from thread 0x83656d8
0:00:00.364289000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:1686:update_pipeline:<mycomposition10> Returning 1
0:00:00.364335000  8764 0x83656d8 DEBUG               python gnlcutoff.py:27:__init__: Adding an instrument audioconvert
0:00:00.365914000  8764 0x83656d8 INFO    GST_PLUGIN_LOADING gstplugin.c:467:gst_plugin_load_file: plugin "/home/laszlo/Software/SVN/gst/head/gst-plugins-base/gst/audioconvert/.libs/libgstaudioconvert.so" loaded
0:00:00.366003000  8764 0x83656d8 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:373:gst_element_factory_create: creating element "audioconvert" named "convert10"
0:00:00.366112000  8764 0x83656d8 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<GstBaseTransform@0x85ec020> adding pad 'sink'
0:00:00.366162000  8764 0x83656d8 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<GstBaseTransform@0x85ec020> adding pad 'src'
0:00:00.366400000  8764 0x83656d8 INFO      GST_ELEMENT_PADS gstutils.c:1373:gst_element_link_pads: trying to link element convert10:(any) to element mysink:(any)
0:00:00.366428000  8764 0x83656d8 INFO              GST_PADS gstutils.c:2046:gst_pad_can_link: trying to link convert10:src and mysink:sink
0:00:00.366445000  8764 0x83656d8 INFO              GST_PADS gstutils.c:1278:prepare_link_maybe_ghosting: convert10 and mysink in same bin, no need for ghost pads
0:00:00.366460000  8764 0x83656d8 INFO              GST_PADS gstpad.c:1730:gst_pad_link_prepare: trying to link convert10:src and mysink:sink
0:00:00.366941000  8764 0x83656d8 INFO              GST_PADS gstpad.c:1867:gst_pad_link: linked convert10:src and mysink:sink, successful
0:00:00.374140000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<mysink> completed state change
0:00:00.374356000  8764 0x83656d8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<pipeline0> child 'mysink' changed state to 2(READY) successfully
0:00:00.374390000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<convert10> completed state change
0:00:00.374417000  8764 0x83656d8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<pipeline0> child 'convert10' changed state to 2(READY) successfully
0:00:00.374445000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:1137:gnl_object_change_state:<mycomposition10> Calling parent change_state
0:00:00.374473000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:1137:gnl_object_change_state:<audio10> Calling parent change_state
0:00:00.374514000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<fakesink> completed state change
0:00:00.374558000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<audio10> message:state-changed
0:00:00.374577000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:state-changed from fakesink
0:00:00.374593000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:state-changed
0:00:00.374612000  8764 0x83656d8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<internal-decodebin> child 'fakesink' changed state to 2(READY) successfully
0:00:00.374634000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<typefind> completed state change
0:00:00.374657000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<audio10> message:state-changed
0:00:00.374673000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:state-changed from typefind
0:00:00.374688000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:state-changed
0:00:00.374706000  8764 0x83656d8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<internal-decodebin> child 'typefind' changed state to 2(READY) successfully
0:00:00.374735000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<internal-decodebin> completed state change
0:00:00.374757000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<audio10> message:state-changed
0:00:00.374773000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:state-changed from internal-decodebin
0:00:00.374788000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:state-changed
0:00:00.374806000  8764 0x83656d8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<audio10> child 'internal-decodebin' changed state to 2(READY) successfully
0:00:00.374826000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<internal-filesource> completed state change
0:00:00.374847000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<audio10> message:state-changed
0:00:00.374864000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:state-changed from internal-filesource
0:00:00.374878000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:state-changed
0:00:00.374896000  8764 0x83656d8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<audio10> child 'internal-filesource' changed state to 2(READY) successfully
0:00:00.374915000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:1141:gnl_object_change_state:<audio10> Return from parent change_state was 1
0:00:00.374947000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<audio10> completed state change
0:00:00.374969000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:state-changed from audio10
0:00:00.374983000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:state-changed
0:00:00.375001000  8764 0x83656d8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<mycomposition10> child 'audio10' changed state to 2(READY) successfully
0:00:00.375019000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:1141:gnl_object_change_state:<mycomposition10> Return from parent change_state was 1
0:00:00.375034000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<mycomposition10> completed state change
0:00:00.375057000  8764 0x83656d8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<pipeline0> child 'mycomposition10' changed state to 2(READY) successfully
0:00:00.375076000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2009:gst_element_continue_state:<pipeline0> committing state from NULL to READY, pending PLAYING
0:00:00.375099000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2018:gst_element_continue_state:<pipeline0> continue state change READY to PAUSED, final PLAYING
0:00:00.375164000  8764 0x83656d8 INFO              GST_PADS gstpad.c:791:gst_pad_activate_pull:<convert10:sink> can't activate unlinked sink pad in pull mode
0:00:00.375181000  8764 0x83656d8 INFO              GST_PADS gstpad.c:798:gst_pad_activate_pull:<convert10:src> failed to activate in pull mode
0:00:00.375204000  8764 0x83656d8 INFO            GST_STATES gstbin.c:1943:gst_bin_change_state_func:<pipeline0> child 'mysink' is changing state asynchronously
0:00:00.375231000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<convert10> completed state change
0:00:00.375256000  8764 0x83656d8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<pipeline0> child 'convert10' changed state to 3(PAUSED) successfully
0:00:00.375276000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:1035:gnl_composition_change_state:<mycomposition10> Setting all childs to READY and locking their state
0:00:00.375294000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:342:lock_child_state:<audio10> locking state
0:00:00.375312000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:1548:update_pipeline:<mycomposition10> currenttime:0:00:00.000000000 initial:1 , change_state:0 , modify:1
0:00:00.375328000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1550:update_pipeline:<mycomposition10> locking objects_lock from thread 0x83656d8
0:00:00.375342000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1550:update_pipeline:<mycomposition10> locked object_lock from thread 0x83656d8
0:00:00.375358000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1160:update_start_stop_duration:<mycomposition10> start:0:00:00.000000000 stop:0:00:10.000000000 duration:0:00:10.000000000
0:00:00.375373000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:1566:update_pipeline:<mycomposition10> now really updating the pipeline, current-state:READY
0:00:00.375387000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:921:get_clean_toplevel_stack:<mycomposition10> timestamp:0:00:00.000000000
0:00:00.375404000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:859:get_stack_list:<mycomposition10> timestamp:0:00:00.000000000, priority:0, activeonly:1
0:00:00.375419000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:868:get_stack_list:<audio10> start: 0:00:00.000000000 , stop:0:00:10.000000000 , duration:0:00:10.000000000, priority:0
0:00:00.375435000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:875:get_stack_list:<mycomposition10> adding audio10: sorted to the stack
0:00:00.375449000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:790:convert_list_to_tree: object:audio10 , stop:99:99:99.999999999highprio:0
0:00:00.375485000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:828:convert_list_to_tree:<audio10> *stop:0:00:10.000000000 priority:0
0:00:00.375501000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:739:next_stop_in_region_above_priority:<mycomposition10> start: 0:00:00.000000000 stop: 0:00:10.000000000 priority:0
0:00:00.375517000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:967:get_clean_toplevel_stack:<mycomposition10> Setting stop_time to 0:00:10.000000000
0:00:00.375533000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:1274:compare_relink_single_node:<mycomposition10> newobj:audio10
0:00:00.375547000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:999:get_src_pad: audio10 doesn't have a src pad !
0:00:00.375562000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1319:compare_relink_single_node:<audio10> no existing pad, connecting to 'no-more-pads'
0:00:00.375589000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1327:compare_relink_single_node:<audio10> DONE
0:00:00.375603000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1582:update_pipeline:<mycomposition10> unlocking objects_lock from thread 0x83656d8
0:00:00.375617000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:1608:update_pipeline:<mycomposition10> activating objects in new stack to PAUSED
0:00:00.375630000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1516:unlock_activate_stack:<mycomposition10> object:audio10
0:00:00.375644000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:1617:update_pipeline:<mycomposition10> Finished activating objects in new stack
0:00:00.375657000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1624:update_pipeline:<mycomposition10> locking objects_lock from thread 0x83656d8
0:00:00.375670000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1624:update_pipeline:<mycomposition10> locked object_lock from thread 0x83656d8
0:00:00.375683000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:535:get_new_seek_event:<mycomposition10> initial:1
0:00:00.375697000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:550:get_new_seek_event:<mycomposition10> Created new seek event. Flags:11, start:0:00:00.000000000, stop:0:00:10.000000000
0:00:00.375712000  8764 0x83656d8 INFO             GST_EVENT gstevent.c:840:gst_event_new_seek: creating seek rate 1.000000, format TIME, flags 11, start_type 2, start 0:00:00.000000000, stop_type 2, stop 0:00:10.000000000
0:00:00.375763000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1668:update_pipeline:<mycomposition10> The timeline stack isn't entirely linked, delaying sending seek event (waitingpads:1)
0:00:00.375778000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1672:update_pipeline:<mycomposition10> unlocking objects_lock from thread 0x83656d8
0:00:00.375792000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:1686:update_pipeline:<mycomposition10> Returning 1
0:00:00.375805000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:356:gnl_object_prepare:<mycomposition10> preparing
0:00:00.375819000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:361:gnl_object_prepare:<mycomposition10> finished preparing, returning 1
0:00:00.375831000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:1137:gnl_object_change_state:<mycomposition10> Calling parent change_state
0:00:00.375854000  8764 0x83656d8 LOG              gnlsource gnlsource.c:513:gnl_source_change_state:<audio10> ghostpad:(nil), dynamicpads:1
0:00:00.375872000  8764 0x83656d8 LOG              gnlsource gnlsource.c:518:gnl_source_change_state:<audio10> no ghostpad and not dynamic pads
0:00:00.375889000  8764 0x83656d8 WARN             gnlsource gnlsource.c:523:gnl_source_change_state:<audio10> Couldn't find a valid source pad
0:00:00.375903000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:356:gnl_object_prepare:<audio10> preparing
0:00:00.375963000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:361:gnl_object_prepare:<audio10> finished preparing, returning 1
0:00:00.375978000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:1137:gnl_object_change_state:<audio10> Calling parent change_state
0:00:00.376023000  8764 0x83656d8 INFO            GST_STATES gstbin.c:1943:gst_bin_change_state_func:<internal-decodebin> child 'fakesink' is changing state asynchronously
0:00:00.377280000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-ms-asf
0:00:00.377682000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-musepack
0:00:00.377807000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-au
0:00:00.377882000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-msvideo
0:00:00.377933000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-cdxa
0:00:00.377982000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-vcd
0:00:00.378030000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-fli
0:00:00.378077000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-id3v2
0:00:00.378123000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-id3v1
0:00:00.378172000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-apetag
0:00:00.378219000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-ttafile
0:00:00.378265000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-mod
0:00:00.378330000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/mpeg
0:00:00.378379000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-ac3
0:00:00.378433000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/mpeg1
0:00:00.378478000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/mpeg2
0:00:00.378532000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/mpegts
0:00:00.378591000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/ogg
0:00:00.378644000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/mpeg
0:00:00.378690000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/mpeg-stream
0:00:00.378735000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/mpeg4
0:00:00.378783000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-nuv
0:00:00.378830000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-m4a
0:00:00.378877000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-3gp
0:00:00.378958000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/quicktime
0:00:00.379006000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/x-quicktime
0:00:00.379060000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for text/html
0:00:00.379107000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/vnd.rn-realmedia
0:00:00.379155000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-pn-realaudio
0:00:00.379203000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-shockwave-flash
0:00:00.379251000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-flv
0:00:00.379302000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for text/plain
0:00:00.379350000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for text/uri-list
0:00:00.379398000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/smil
0:00:00.379446000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/xml
0:00:00.379494000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-wav
0:00:00.379542000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-aiff
0:00:00.379590000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-svx
0:00:00.379648000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-paris
0:00:00.379697000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-nist
0:00:00.379744000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-voc
0:00:00.379792000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-sds
0:00:00.379840000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-ircam
0:00:00.379887000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-w64
0:00:00.379935000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-shorten
0:00:00.379995000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-ape
0:00:00.380049000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/jpeg
0:00:00.380097000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/gif
0:00:00.380146000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/png
0:00:00.380195000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/bmp
0:00:00.380286000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/tiff
0:00:00.380363000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-matroska
0:00:00.380413000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-mve
0:00:00.380465000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-dv
0:00:00.380515000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-amr-nb-sh
0:00:00.380563000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-amr-wb-sh
0:00:00.380611000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/iLBC-sh
0:00:00.380660000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-sid
0:00:00.380717000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/x-xcf
0:00:00.380772000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-mng
0:00:00.380821000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/x-jng
0:00:00.380870000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/x-xpixmap
0:00:00.380918000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/x-sun-raster
0:00:00.380966000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-bzip
0:00:00.381015000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-gzip
0:00:00.381064000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/zip
0:00:00.381112000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-compress
0:00:00.381167000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-flac
0:00:00.381216000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-vorbis
0:00:00.381264000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-theora
0:00:00.381318000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-ogm-video
0:00:00.381368000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-ogm-audio
0:00:00.381417000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-ogm-text
0:00:00.381476000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-speex
0:00:00.381530000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-ogg-skeleton
0:00:00.381580000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for text/x-cmml
0:00:00.381629000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-executable
0:00:00.381687000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for adts_mpeg_stream
0:00:00.381757000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-spc
0:00:00.381811000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-wavpack
0:00:00.381864000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-wavpack-correction
0:00:00.381916000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-rar
0:00:00.381966000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-tar
0:00:00.382015000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-ar
0:00:00.382065000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-ms-dos-executable
0:00:00.382135000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-dirac
0:00:00.382186000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for multipart/x-mixed-replace
0:00:00.382240000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-mmsh
0:00:00.382291000  8764 0x83656d8 INFO          GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/vivo
0:00:00.382343000  8764 0x83656d8 INFO    GST_PLUGIN_LOADING gstplugin.c:467:gst_plugin_load_file: plugin "/home/laszlo/Software/SVN/gst/head/gst-plugins-base/gst/typefind/.libs/libgsttypefindfunctions.so" loaded
0:00:00.382958000  8764 0x83656d8 INFO              typefind gsttypefindelement.c:160:gst_type_find_element_have_type:<typefind> found caps application/ogg
0:00:00.383043000  8764 0x83656d8 INFO      GST_ELEMENT_PADS gstelement.c:870:gst_element_get_static_pad: found pad typefind:src
0:00:00.391366000  8764 0x83656d8 INFO    GST_PLUGIN_LOADING gstplugin.c:467:gst_plugin_load_file: plugin "/home/laszlo/Software/SVN/gst/head/gst-plugins-base/ext/ogg/.libs/libgstogg.so" loaded
0:00:00.391555000  8764 0x83656d8 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:375:gst_element_factory_create: creating element "oggdemux"
0:00:00.391700000  8764 0x83656d8 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<GstOggDemux@0x8621008> adding pad 'sink'
0:00:00.391738000  8764 0x83656d8 INFO      GST_ELEMENT_PADS gstelement.c:870:gst_element_get_static_pad: found pad oggdemux0:sink
0:00:00.391781000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<oggdemux0> completed state change
0:00:00.391820000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<audio10> message:state-changed
0:00:00.391841000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:state-changed from oggdemux0
0:00:00.391857000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:state-changed
0:00:00.391879000  8764 0x83656d8 INFO              GST_PADS gstpad.c:1730:gst_pad_link_prepare: trying to link typefind:src and oggdemux0:sink
0:00:00.391906000  8764 0x83656d8 INFO              GST_PADS gstpad.c:1867:gst_pad_link: linked typefind:src and oggdemux0:sink, successful
0:00:00.391934000  8764 0x83656d8 INFO      GST_ELEMENT_PADS gstelement.c:867:gst_element_get_static_pad: no such pad 'src_%d' in element "oggdemux0"
0:00:00.392179000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<oggdemux0> completed state change
0:00:00.392218000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<audio10> message:state-changed
0:00:00.392280000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:state-changed from oggdemux0
0:00:00.392297000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:state-changed
0:00:00.392330000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<typefind> completed state change
0:00:00.392353000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<audio10> message:state-changed
0:00:00.392370000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:state-changed from typefind
0:00:00.392384000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:state-changed
0:00:00.392404000  8764 0x83656d8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<internal-decodebin> child 'typefind' changed state to 3(PAUSED) successfully
0:00:00.392441000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<oggdemux0> completed state change
0:00:00.392456000  8764 0x83656d8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<internal-decodebin> child 'oggdemux0' changed state to 3(PAUSED) successfully
0:00:00.392476000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<typefind> completed state change
0:00:00.392492000  8764 0x83656d8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<internal-decodebin> child 'typefind' changed state to 3(PAUSED) successfully
0:00:00.392510000  8764 0x83656d8 INFO            GST_STATES gstbin.c:1943:gst_bin_change_state_func:<internal-decodebin> child 'fakesink' is changing state asynchronously
0:00:00.392536000  8764 0x83656d8 INFO            GST_STATES gstbin.c:1943:gst_bin_change_state_func:<audio10> child 'internal-decodebin' is changing state asynchronously
0:00:00.392560000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<internal-filesource> completed state change
0:00:00.392583000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<audio10> message:state-changed
0:00:00.392600000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:state-changed from internal-filesource
0:00:00.392614000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:state-changed
0:00:00.392632000  8764 0x83656d8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<audio10> child 'internal-filesource' changed state to 3(PAUSED) successfully
0:00:00.392654000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:1141:gnl_object_change_state:<audio10> Return from parent change_state was 2
0:00:00.392669000  8764 0x83656d8 INFO            GST_STATES gstbin.c:1943:gst_bin_change_state_func:<mycomposition10> child 'audio10' is changing state asynchronously
0:00:00.392689000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:1141:gnl_object_change_state:<mycomposition10> Return from parent change_state was 2
0:00:00.392704000  8764 0x83656d8 INFO            GST_STATES gstbin.c:1943:gst_bin_change_state_func:<pipeline0> child 'mycomposition10' is changing state asynchronously
0:00:00.397847000  8764 0x8620a18 INFO    GST_PLUGIN_LOADING gstplugin.c:467:gst_plugin_load_file: plugin "/home/laszlo/Software/SVN/gst/head/gst-plugins-base/ext/vorbis/.libs/libgstvorbis.so" loaded
0:00:00.397946000  8764 0x8620a18 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:375:gst_element_factory_create: creating element "vorbisdec"
0:00:00.398075000  8764 0x8620a18 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<GstVorbisDec@0x8636000> adding pad 'sink'
0:00:00.398142000  8764 0x8620a18 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<GstVorbisDec@0x8636000> adding pad 'src'
0:00:00.398207000  8764 0x8620a18 INFO      GST_ELEMENT_PADS gstelement.c:870:gst_element_get_static_pad: found pad vorbisdec0:sink
0:00:00.398231000  8764 0x8620a18 INFO            GST_STATES gstelement.c:2009:gst_element_continue_state:<vorbisdec0> committing state from NULL to READY, pending PAUSED
0:00:00.398271000  8764 0x8620a18 INFO            GST_STATES gstelement.c:2018:gst_element_continue_state:<vorbisdec0> continue state change READY to PAUSED, final PAUSED
0:00:00.398310000  8764 0x8620a18 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<vorbisdec0> completed state change
0:00:00.398395000  8764 0x8620a18 INFO      GST_ELEMENT_PADS gstelement.c:870:gst_element_get_static_pad: found pad vorbisdec0:src
0:00:00.398413000  8764 0x8620a18 INFO              GST_PADS gstpad.c:1730:gst_pad_link_prepare: trying to link vorbisdec0:src and '':internal
0:00:00.398455000  8764 0x8620a18 INFO              GST_PADS gstpad.c:1867:gst_pad_link: linked vorbisdec0:src and '':internal, successful
0:00:00.402053000  8764 0x8620a18 INFO              GST_PADS gstpad.c:2882:gst_pad_event_default_dispatch:<'':internal> Sending event 0x85ab8a0 (tag) to all internally linked pads
0:00:00.406738000  8764 0x8620a18 WARN              oggdemux gstoggdemux.c:2291:gst_ogg_demux_read_chain:<oggdemux0> failed to read chain
0:00:00.406884000  8764 0x8620a18 INFO        oggdemux_setup gstoggdemux.c:3185:gst_ogg_print:<oggdemux0> 1 chains
0:00:00.406905000  8764 0x8620a18 INFO        oggdemux_setup gstoggdemux.c:3187:gst_ogg_print:<oggdemux0>  total time: 0:00:03.477188208
0:00:00.406921000  8764 0x8620a18 INFO        oggdemux_setup gstoggdemux.c:3192:gst_ogg_print:<oggdemux0>  chain 0 (1 streams):
0:00:00.406935000  8764 0x8620a18 INFO        oggdemux_setup gstoggdemux.c:3194:gst_ogg_print:<oggdemux0>   offset: 0 - 41817
0:00:00.406951000  8764 0x8620a18 INFO        oggdemux_setup gstoggdemux.c:3196:gst_ogg_print:<oggdemux0>   begin time: 0:00:00.000000000
0:00:00.406966000  8764 0x8620a18 INFO        oggdemux_setup gstoggdemux.c:3198:gst_ogg_print:<oggdemux0>   total time: 0:00:03.477188208
0:00:00.406980000  8764 0x8620a18 INFO        oggdemux_setup gstoggdemux.c:3200:gst_ogg_print:<oggdemux0>   segment start: 0:00:00.000000000
0:00:00.406994000  8764 0x8620a18 INFO        oggdemux_setup gstoggdemux.c:3202:gst_ogg_print:<oggdemux0>   segment stop:  0:00:03.477188208
0:00:00.407008000  8764 0x8620a18 INFO        oggdemux_setup gstoggdemux.c:3207:gst_ogg_print:<oggdemux0>   stream 087fac05:
0:00:00.407021000  8764 0x8620a18 INFO        oggdemux_setup gstoggdemux.c:3209:gst_ogg_print:<oggdemux0>    start time:       0:00:00.000000000
0:00:00.407035000  8764 0x8620a18 INFO        oggdemux_setup gstoggdemux.c:3211:gst_ogg_print:<oggdemux0>    first granulepos: 17344
0:00:00.407048000  8764 0x8620a18 INFO        oggdemux_setup gstoggdemux.c:3213:gst_ogg_print:<oggdemux0>    first time:       0:00:00.393287981
0:00:00.407091000  8764 0x8620a18 INFO              GST_PADS gstpad.c:2882:gst_pad_event_default_dispatch:<typefind:src> Sending event 0x85ab8c8 (flush-stop) to all internally linked pads
0:00:00.407188000  8764 0x8620a18 INFO             GST_EVENT gstevent.c:528:gst_event_new_new_segment_full: creating newsegment update 0, rate 1.000000, format GST_FORMAT_TIME, start 0:00:00.000000000, stop 0:00:03.477188208, position 0:00:00.000000000
0:00:00.407245000  8764 0x8620a18 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<oggdemux0> adding pad 'serial_087fac05'
0:00:00.407668000  8764 0x8620a18 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:375:gst_element_factory_create: creating element "queue"
0:00:00.407799000  8764 0x8620a18 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<GstQueue@0x8657838> adding pad 'sink'
0:00:00.407864000  8764 0x8620a18 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<GstQueue@0x8657838> adding pad 'src'
0:00:00.407949000  8764 0x8620a18 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<queue0> completed state change
0:00:00.408036000  8764 0x8620a18 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<audio10> message:state-changed
0:00:00.408058000  8764 0x8620a18 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:state-changed from queue0
0:00:00.408074000  8764 0x8620a18 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:state-changed
0:00:00.408198000  8764 0x8620a18 INFO      GST_ELEMENT_PADS gstelement.c:870:gst_element_get_static_pad: found pad queue0:sink
0:00:00.408217000  8764 0x8620a18 INFO      GST_ELEMENT_PADS gstelement.c:870:gst_element_get_static_pad: found pad queue0:src
0:00:00.408231000  8764 0x8620a18 INFO              GST_PADS gstpad.c:1730:gst_pad_link_prepare: trying to link oggdemux0:serial_087fac05 and queue0:sink
0:00:00.408306000  8764 0x8620a18 INFO              GST_PADS gstpad.c:1867:gst_pad_link: linked oggdemux0:serial_087fac05 and queue0:sink, successful
0:00:00.408335000  8764 0x8620a18 INFO   GST_ELEMENT_FACTORY gstelementfactory.c:375:gst_element_factory_create: creating element "vorbisdec"
0:00:00.408417000  8764 0x8620a18 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<GstVorbisDec@0x8636220> adding pad 'sink'
0:00:00.408480000  8764 0x8620a18 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<GstVorbisDec@0x8636220> adding pad 'src'
0:00:00.408509000  8764 0x8620a18 INFO      GST_ELEMENT_PADS gstelement.c:870:gst_element_get_static_pad: found pad vorbisdec1:sink
0:00:00.408542000  8764 0x8620a18 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<vorbisdec1> completed state change
0:00:00.408572000  8764 0x8620a18 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<audio10> message:state-changed
0:00:00.408590000  8764 0x8620a18 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:state-changed from vorbisdec1
0:00:00.408606000  8764 0x8620a18 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:state-changed
0:00:00.408669000  8764 0x8620a18 INFO              GST_PADS gstpad.c:1730:gst_pad_link_prepare: trying to link queue0:src and vorbisdec1:sink
0:00:00.408700000  8764 0x8620a18 INFO              GST_PADS gstpad.c:1867:gst_pad_link: linked queue0:src and vorbisdec1:sink, successful
0:00:00.408734000  8764 0x8620a18 INFO      GST_ELEMENT_PADS gstelement.c:870:gst_element_get_static_pad: found pad vorbisdec1:src
0:00:00.408873000  8764 0x8620a18 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<internal-decodebin> adding pad 'src0'
0:00:00.408896000  8764 0x8620a18 INFO              GST_PADS gstpad.c:1730:gst_pad_link_prepare: trying to link vorbisdec1:src and src0:proxypad1
0:00:00.408930000  8764 0x8620a18 INFO              GST_PADS gstpad.c:1867:gst_pad_link: linked vorbisdec1:src and src0:proxypad1, successful
0:00:00.408955000  8764 0x8620a18 DEBUG            gnlsource gnlsource.c:200:element_pad_added_cb:<audio10> pad internal-decodebin:src0
0:00:00.408989000  8764 0x8620a18 DEBUG            gnlsource gnlsource.c:213:element_pad_added_cb:<internal-decodebin:src0> valid pad, about to add event probe and pad block
0:00:00.409007000  8764 0x8620a18 DEBUG            gnlsource gnlsource.c:225:element_pad_added_cb:<audio10> Done handling pad internal-decodebin:src0
0:00:00.409051000  8764 0x8620a18 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<vorbisdec1> completed state change
0:00:00.409082000  8764 0x8620a18 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<audio10> message:state-changed
0:00:00.409101000  8764 0x8620a18 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:state-changed from vorbisdec1
0:00:00.409117000  8764 0x8620a18 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:state-changed
0:00:00.409280000  8764 0x8620a18 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<queue0> completed state change
0:00:00.409342000  8764 0x8620a18 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<audio10> message:state-changed
0:00:00.409360000  8764 0x8620a18 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:state-changed from queue0
0:00:00.409376000  8764 0x8620a18 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:state-changed
0:00:00.410099000  8764 0x8639968 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<audio10> message:tag
0:00:00.410155000  8764 0x8639968 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:tag from vorbisdec1
0:00:00.410172000  8764 0x8639968 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:tag
0:00:00.412215000  8764 0x8639968 DEBUG            gnlsource gnlsource.c:332:pad_blocked_cb:<audio10> blocked:1 pad:internal-decodebin:src0
0:00:00.412508000  8764 0x83656d8 DEBUG            gnlsource gnlsource.c:298:ghost_seek_pad:<audio10> ghosting internal-decodebin:src0
0:00:00.412530000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:783:gnl_object_ghost_pad_full:<audio10> name:src0, target:0x85e3e20, flush_hack:1
0:00:00.412684000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:833:gnl_object_ghost_pad_no_target: grabbing existing pad functions
0:00:00.412704000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:842:gnl_object_ghost_pad_no_target:<'':src0> Setting priv->eventfunc to 0xb794cf00
0:00:00.412725000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:879:gnl_object_ghost_pad_set_target:<audio10> setting target internal-decodebin:src0 on ghostpad
0:00:00.412748000  8764 0x83656d8 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<audio10> adding pad 'src0'
0:00:00.412772000  8764 0x83656d8 INFO              GST_PADS gstpad.c:1730:gst_pad_link_prepare: trying to link internal-decodebin:src0 and src0:proxypad2
0:00:00.412829000  8764 0x83656d8 INFO              GST_PADS gstpad.c:1867:gst_pad_link: linked internal-decodebin:src0 and src0:proxypad2, successful
0:00:00.412856000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:1776:object_pad_added:<mycomposition10> pad audio10:src0 was added, blocking it
0:00:00.412877000  8764 0x83656d8 LOG              gnlobject gnlobject.c:725:control_internal_pad:<audio10:src0> overriding ghostpad's internal pad function
0:00:00.412893000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:731:control_internal_pad:<src0:proxypad2> Creating a GnlPadPrivate to put in element_private
0:00:00.412912000  8764 0x83656d8 DEBUG            gnlsource gnlsource.c:302:ghost_seek_pad:<audio10> emitting no more pads
0:00:00.412927000  8764 0x83656d8 DEBUG            gnlsource gnlsource.c:315:ghost_seek_pad:<audio10> about to unblock internal-decodebin:src0
0:00:00.412949000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1170:no_more_pads_object_cb:<audio10> no more pads
0:00:00.412969000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1174:no_more_pads_object_cb:<mycomposition10> locking objects_lock from thread 0x83656d8
0:00:00.412984000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1174:no_more_pads_object_cb:<mycomposition10> locked object_lock from thread 0x83656d8
0:00:00.413003000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1184:no_more_pads_object_cb:<mycomposition10> Number of waiting pads is now 0
0:00:00.413020000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:682:gnl_composition_ghost_pad_set_target:<mycomposition10> audio10:src0 , hadghost:0
0:00:00.413099000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:833:gnl_object_ghost_pad_no_target: grabbing existing pad functions
0:00:00.413117000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:842:gnl_object_ghost_pad_no_target:<'':src> Setting priv->eventfunc to 0xb794cf00
0:00:00.413165000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:690:gnl_composition_ghost_pad_set_target:<'':src> About to replace event_pad_func
0:00:00.413183000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:696:gnl_composition_ghost_pad_set_target:<'':src> eventfunc is now gnl_composition_event_handler
0:00:00.413197000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:879:gnl_object_ghost_pad_set_target:<mycomposition10> setting target audio10:src0 on ghostpad
0:00:00.413214000  8764 0x83656d8 INFO      GST_ELEMENT_PADS gstelement.c:624:gst_element_add_pad:<mycomposition10> adding pad 'src'
0:00:00.413231000  8764 0x83656d8 INFO              GST_PADS gstpad.c:1730:gst_pad_link_prepare: trying to link audio10:src0 and src:proxypad3
0:00:00.413268000  8764 0x83656d8 INFO              GST_PADS gstpad.c:1867:gst_pad_link: linked audio10:src0 and src:proxypad3, successful
0:00:00.413354000  8764 0x83656d8 DEBUG               python gnlcutoff.py:53:OnPad: Pad added
0:00:00.413461000  8764 0x83656d8 INFO              GST_PADS gstutils.c:2046:gst_pad_can_link: trying to link mycomposition10:src and convert10:sink
0:00:00.413506000  8764 0x83656d8 INFO              GST_PADS gstpad.c:1730:gst_pad_link_prepare: trying to link mycomposition10:src and convert10:sink
0:00:00.414028000  8764 0x83656d8 INFO                  alsa gstalsasink.c:326:gst_alsasink_getcaps:<mysink> returning caps audio/x-raw-int, endianness=(int)1234, signed=(boolean){ true, false }, width=(int)32, depth=(int)32, rate=(int)[ 4000, 2147483647 ], channels=(int)[ 1, 2 ]; audio/x-raw-int, endianness=(int)1234, signed=(boolean){ true, false }, width=(int)32, depth=(int)32, rate=(int)[ 4000, 2147483647 ], channels=(int)3, channel-positions=(GstAudioChannelPosition)< GST_AUDIO_CHANNEL_POSITION_FRONT_LEFT, GST_AUDIO_CHANNEL_POSITION_FRONT_RIGHT, GST_AUDIO_CHANNEL_POSITION_LFE >; audio/x-raw-int, endianness=(int)1234, signed=(boolean){ true, false }, width=(int)32, depth=(int)32, rate=(int)[ 4000, 2147483647 ], channels=(int)4, channel-positions=(GstAudioChannelPosition)< GST_AUDIO_CHANNEL_POSITION_FRONT_LEFT, GST_AUDIO_CHANNEL_POSITION_FRONT_RIGHT, GST_AUDIO_CHANNEL_POSITION_REAR_LEFT, GST_AUDIO_CHANNEL_POSITION_REAR_RIGHT >; audio/x-raw-int, endianness=(int)1234, signed=(boolean){ true, false }, width=(int)32, depth=(int)32, rate=(int)[ 4000, 2147483647 ], channels=(int)6, channel-positions=(GstAudioChannelPosition)< GST_AUDIO_CHANNEL_POSITION_FRONT_LEFT, GST_AUDIO_CHANNEL_POSITION_FRONT_RIGHT, GST_AUDIO_CHANNEL_POSITION_REAR_LEFT, GST_AUDIO_CHANNEL_POSITION_REAR_RIGHT, GST_AUDIO_CHANNEL_POSITION_FRONT_CENTER, GST_AUDIO_CHANNEL_POSITION_LFE >; audio/x-raw-int, endianness=(int)1234, signed=(boolean){ true, false }, width=(int)32, depth=(int)32, rate=(int)[ 4000, 2147483647 ], channels=(int)8, channel-positions=(GstAudioChannelPosition)< GST_AUDIO_CHANNEL_POSITION_FRONT_LEFT, GST_AUDIO_CHANNEL_POSITION_FRONT_RIGHT, GST_AUDIO_CHANNEL_POSITION_REAR_LEFT, GST_AUDIO_CHANNEL_POSITION_REAR_RIGHT, GST_AUDIO_CHANNEL_POSITION_FRONT_CENTER, GST_AUDIO_CHANNEL_POSITION_LFE, GST_AUDIO_CHANNEL_POSITION_SIDE_LEFT, GST_AUDIO_CHANNEL_POSITION_SIDE_RIGHT >; audio/x-raw-int, endianness=(int)1234, signed=(boolean){ true, false }, width=(int)16, depth=(int)16, rate=(int)[ 4000, 2147483647 ], channels=(int)[ 1, 2 ]; audio/x-raw-int, endianness=(int)1234, signed=(boolean){ true, false }, width=(int)16, depth=(int)16, rate=(int)[ 4000, 2147483647 ], channels=(int)3, channel-positions=(GstAudioChannelPosition)< GST_AUDIO_CHANNEL_POSITION_FRONT_LEFT, GST_AUDIO_CHANNEL_POSITION_FRONT_RIGHT, GST_AUDIO_CHANNEL_POSITION_LFE >; audio/x-raw-int, endianness=(int)1234, signed=(boolean){ true, false }, width=(int)16, depth=(int)16, rate=(int)[ 4000, 2147483647 ], channels=(int)4, channel-positions=(GstAudioChannelPosition)< GST_AUDIO_CHANNEL_POSITION_FRONT_LEFT, GST_AUDIO_CHANNEL_POSITION_FRONT_RIGHT, GST_AUDIO_CHANNEL_POSITION_REAR_LEFT, GST_AUDIO_CHANNEL_POSITION_REAR_RIGHT >; audio/x-raw-int, endianness=(int)1234, signed=(boolean){ true, false }, width=(int)16, depth=(int)16, rate=(int)[ 4000, 2147483647 ], channels=(int)6, channel-positions=(GstAudioChannelPosition)< GST_AUDIO_CHANNEL_POSITION_FRONT_LEFT, GST_AUDIO_CHANNEL_POSITION_FRONT_RIGHT, GST_AUDIO_CHANNEL_POSITION_REAR_LEFT, GST_AUDIO_CHANNEL_POSITION_REAR_RIGHT, GST_AUDIO_CHANNEL_POSITION_FRONT_CENTER, GST_AUDIO_CHANNEL_POSITION_LFE >; audio/x-raw-int, endianness=(int)1234, signed=(boolean){ true, false }, width=(int)16, depth=(int)16, rate=(int)[ 4000, 2147483647 ], channels=(int)8, channel-positions=(GstAudioChannelPosition)< GST_AUDIO_CHANNEL_POSITION_FRONT_LEFT, GST_AUDIO_CHANNEL_POSITION_FRONT_RIGHT, GST_AUDIO_CHANNEL_POSITION_REAR_LEFT, GST_AUDIO_CHANNEL_POSITION_REAR_RIGHT, GST_AUDIO_CHANNEL_POSITION_FRONT_CENTER, GST_AUDIO_CHANNEL_POSITION_LFE, GST_AUDIO_CHANNEL_POSITION_SIDE_LEFT, GST_AUDIO_CHANNEL_POSITION_SIDE_RIGHT >; audio/x-raw-int, signed=(boolean){ true, false }, width=(int)8, depth=(int)8, rate=(int)[ 4000, 2147483647 ], channels=(int)[ 1, 2 ]; audio/x-raw-int, signed=(boolean){ true, false }, width=(int)8, depth=(int)8, rate=(int)[ 4000, 2147483647 ], channels=(int)3, channel-positions=(GstAudioChannelPosition)< GST_AUDIO_CHANNEL_POSITION_FRONT_LEFT, GST_AUDIO_CHANNEL_POSITION_FRONT_RIGHT, GST_AUDIO_CHANNEL_POSITION_LFE >; audio/x-raw-int, signed=(boolean){ true, false }, width=(int)8, depth=(int)8, rate=(int)[ 4000, 2147483647 ], channels=(int)4, channel-positions=(GstAudioChannelPosition)< GST_AUDIO_CHANNEL_POSITION_FRONT_LEFT, GST_AUDIO_CHANNEL_POSITION_FRONT_RIGHT, GST_AUDIO_CHANNEL_POSITION_REAR_LEFT, GST_AUDIO_CHANNEL_POSITION_REAR_RIGHT >; audio/x-raw-int, signed=(boolean){ true, false }, width=(int)8, depth=(int)8, rate=(int)[ 4000, 2147483647 ], channels=(int)6, channel-positions=(GstAudioChannelPosition)< GST_AUDIO_CHANNEL_POSITION_FRONT_LEFT, GST_AUDIO_CHANNEL_POSITION_FRONT_RIGHT, GST_AUDIO_CHANNEL_POSITION_REAR_LEFT, GST_AUDIO_CHANNEL_POSITION_REAR_RIGHT, GST_AUDIO_CHANNEL_POSITION_FRONT_CENTER, GST_AUDIO_CHANNEL_POSITION_LFE >; audio/x-raw-int, signed=(boolean){ true, false }, width=(int)8, depth=(int)8, rate=(int)[ 4000, 2147483647 ], channels=(int)8, channel-positions=(GstAudioChannelPosition)< GST_AUDIO_CHANNEL_POSITION_FRONT_LEFT, GST_AUDIO_CHANNEL_POSITION_FRONT_RIGHT, GST_AUDIO_CHANNEL_POSITION_REAR_LEFT, GST_AUDIO_CHANNEL_POSITION_REAR_RIGHT, GST_AUDIO_CHANNEL_POSITION_FRONT_CENTER, GST_AUDIO_CHANNEL_POSITION_LFE, GST_AUDIO_CHANNEL_POSITION_SIDE_LEFT, GST_AUDIO_CHANNEL_POSITION_SIDE_RIGHT >
0:00:00.420626000  8764 0x83656d8 INFO              GST_PADS gstpad.c:1867:gst_pad_link: linked mycomposition10:src and convert10:sink, successful
0:00:00.420791000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:726:gnl_composition_ghost_pad_set_target:<mycomposition10> END
0:00:00.420813000  8764 0x83656d8 INFO        gnlcomposition gnlcomposition.c:1211:no_more_pads_object_cb:<mycomposition10> Sending pending seek for audio10
0:00:00.420834000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:647:ghostpad_event_function:<audio10:src0> event:seek
0:00:00.420876000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:408:translate_incoming_seek:<audio10> GOT SEEK rate:1.000000, format:3, flags:11, curtype:2, stoptype:2, 0:00:00.000000000 -- 0:00:10.000000000
0:00:00.420901000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:415:translate_incoming_seek: nrate:1.000000 , rate:1.000000, object->rate:1.000000
0:00:00.420926000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:212:gnl_object_to_media_time:<audio10> ObjectTime : 0:00:00.000000000
0:00:00.420942000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:219:gnl_object_to_media_time:<audio10> Start/Stop:[0:00:00.000000000 -- 0:00:10.000000000] Media [99:99:99.999999999 -- 99:99:99.999999999]
0:00:00.420960000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:247:gnl_object_to_media_time:<audio10> Returning MediaTime : 0:00:00.000000000
0:00:00.420974000  8764 0x83656d8 LOG              gnlobject gnlobject.c:423:translate_incoming_seek:<audio10> Setting cur to 0:00:00.000000000
0:00:00.421021000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:212:gnl_object_to_media_time:<audio10> ObjectTime : 0:00:10.000000000
0:00:00.421036000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:219:gnl_object_to_media_time:<audio10> Start/Stop:[0:00:00.000000000 -- 0:00:10.000000000] Media [99:99:99.999999999 -- 99:99:99.999999999]
0:00:00.421052000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:223:gnl_object_to_media_time:<audio10> ObjectTime is outside object start/stop times
0:00:00.421066000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:437:translate_incoming_seek:<audio10> Limiting end of seek to media_stop
0:00:00.421079000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:212:gnl_object_to_media_time:<audio10> ObjectTime : 0:00:10.000000000
0:00:00.421093000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:219:gnl_object_to_media_time:<audio10> Start/Stop:[0:00:00.000000000 -- 0:00:10.000000000] Media [99:99:99.999999999 -- 99:99:99.999999999]
0:00:00.421109000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:223:gnl_object_to_media_time:<audio10> ObjectTime is outside object start/stop times
0:00:00.421122000  8764 0x83656d8 LOG              gnlobject gnlobject.c:442:translate_incoming_seek:<audio10> Setting stop to 0:00:10.000000000
0:00:00.421137000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:451:translate_incoming_seek:<audio10> event already has GST_SEEK_FLAG_SEGMENT : 11
0:00:00.421150000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:460:translate_incoming_seek:<audio10> event already has GST_SEEK_FLAG_ACCURATE : 11
0:00:00.421164000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:468:translate_incoming_seek:<audio10> SENDING SEEK rate:1.000000, format:TIME, flags:11, curtype:SET, stoptype:SET, 0:00:00.000000000 -- 0:00:10.000000000
0:00:00.421182000  8764 0x83656d8 INFO             GST_EVENT gstevent.c:840:gst_event_new_seek: creating seek rate 1.000000, format TIME, flags 11, start_type 2, start 0:00:00.000000000, stop_type 2, stop 0:00:10.000000000
0:00:00.421223000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:663:ghostpad_event_function:<audio10:src0> Calling priv->eventfunc
0:00:00.421269000  8764 0x83656d8 INFO             GST_EVENT gstevent.c:840:gst_event_new_seek: creating seek rate 1.000000, format TIME, flags 11, start_type 2, start 0:00:00.000000000, stop_type 2, stop 0:00:10.000000000
0:00:00.421318000  8764 0x83656d8 INFO              GST_PADS gstpad.c:2882:gst_pad_event_default_dispatch:<typefind:src> Sending event 0x85ab478 (flush-start) to all internally linked pads
0:00:00.421397000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2009:gst_element_continue_state:<fakesink> committing state from READY to READY, pending NULL
0:00:00.421435000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<audio10> message:state-changed
0:00:00.421455000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:state-changed from fakesink
0:00:00.421471000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:state-changed
0:00:00.421492000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2018:gst_element_continue_state:<fakesink> continue state change READY to NULL, final NULL
0:00:00.421515000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<fakesink> completed state change
0:00:00.421538000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<audio10> message:state-changed
0:00:00.421554000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:state-changed from fakesink
0:00:00.421569000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:state-changed
0:00:00.421599000  8764 0x83656d8 INFO         GST_PARENTAGE gstbin.c:968:gst_bin_remove_func:<internal-decodebin> removed child "fakesink"
0:00:00.421650000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<audio10> message:state-dirty
0:00:00.421669000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:state-dirty from internal-decodebin
0:00:00.421684000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:state-dirty
0:00:00.421772000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:544:internalpad_event_function:<src0:proxypad2> event:flush-start
0:00:00.421790000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:569:internalpad_event_function:<src0:proxypad2> Calling priv->eventfunc 0xb794cf00
0:00:00.421842000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1328:gst_bin_recalc_state:<pipeline0> recalc state
0:00:00.421867000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1338:gst_bin_recalc_state:<pipeline0> checking element states
0:00:00.421988000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1289:gst_bin_get_state_func:<mycomposition10> getting state
0:00:00.422003000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1328:gst_bin_recalc_state:<mycomposition10> recalc state
0:00:00.422016000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1338:gst_bin_recalc_state:<mycomposition10> checking element states
0:00:00.422029000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1289:gst_bin_get_state_func:<audio10> getting state
0:00:00.422042000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1328:gst_bin_recalc_state:<audio10> recalc state
0:00:00.422055000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1338:gst_bin_recalc_state:<audio10> checking element states
0:00:00.422068000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1289:gst_bin_get_state_func:<internal-decodebin> getting state
0:00:00.422081000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1328:gst_bin_recalc_state:<internal-decodebin> recalc state
0:00:00.422093000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1338:gst_bin_recalc_state:<internal-decodebin> checking element states
0:00:00.422110000  8764 0x867cdc8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<internal-decodebin> completed state change
0:00:00.422146000  8764 0x867cdc8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<audio10> message:state-changed
0:00:00.422163000  8764 0x867cdc8 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:state-changed from internal-decodebin
0:00:00.422178000  8764 0x867cdc8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:state-changed
0:00:00.422197000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1428:gst_bin_recalc_state:<internal-decodebin> bin RETURN is now SUCCESS
0:00:00.422214000  8764 0x867cdc8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<audio10> completed state change
0:00:00.422236000  8764 0x867cdc8 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:state-changed from audio10
0:00:00.422251000  8764 0x867cdc8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:state-changed
0:00:00.422273000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1428:gst_bin_recalc_state:<audio10> bin RETURN is now SUCCESS
0:00:00.422289000  8764 0x867cdc8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<mycomposition10> completed state change
0:00:00.422311000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1428:gst_bin_recalc_state:<mycomposition10> bin RETURN is now SUCCESS
0:00:00.422331000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1428:gst_bin_recalc_state:<pipeline0> bin RETURN is now ASYNC
0:00:00.422375000  8764 0x8639968 INFO             GST_EVENT gstpad.c:4197:gst_pad_send_event:<src0:proxypad1> Received event on flushing pad. Discarding
0:00:00.422500000  8764 0x83656d8 INFO              GST_PADS gstpad.c:2882:gst_pad_event_default_dispatch:<typefind:src> Sending event 0x85ab4c8 (flush-stop) to all internally linked pads
0:00:00.422650000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:544:internalpad_event_function:<src0:proxypad2> event:flush-stop
0:00:00.422668000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:569:internalpad_event_function:<src0:proxypad2> Calling priv->eventfunc 0xb794cf00
0:00:00.422718000  8764 0x83656d8 INFO             GST_EVENT gstevent.c:528:gst_event_new_new_segment_full: creating newsegment update 0, rate 1.000000, format GST_FORMAT_TIME, start 0:00:00.393287981, stop 0:00:03.477188208, position 0:00:00.000000000
0:00:00.422794000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:666:ghostpad_event_function:<audio10:src0> Returned from calling priv->eventfunc : 1
0:00:00.422811000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1219:no_more_pads_object_cb:<mycomposition10> About to unblock top-level pad : audio10:src0
0:00:00.422827000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1222:no_more_pads_object_cb:<mycomposition10> Unblocked top-level pad
0:00:00.422845000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1235:no_more_pads_object_cb:<mycomposition10> unlocking objects_lock from thread 0x83656d8
0:00:00.422859000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:1238:no_more_pads_object_cb:<mycomposition10> end
0:00:00.422956000  8764 0x83656d8 INFO       GST_REFCOUNTING gstelement.c:2562:gst_element_dispose:<fakesink> dispose
0:00:00.422986000  8764 0x83656d8 INFO      GST_ELEMENT_PADS gstelement.c:742:gst_element_remove_pad:<fakesink> removing pad 'sink'
0:00:00.423026000  8764 0x83656d8 INFO       GST_REFCOUNTING gstelement.c:2587:gst_element_dispose:<fakesink> parent class dispose
0:00:00.423053000  8764 0x83656d8 INFO       GST_REFCOUNTING gstelement.c:2610:gst_element_finalize:<fakesink> finalize
0:00:00.423069000  8764 0x83656d8 INFO       GST_REFCOUNTING gstelement.c:2621:gst_element_finalize:<fakesink> finalize parent
0:00:00.423562000  8764 0x8639968 DEBUG            gnlobject gnlobject.c:544:internalpad_event_function:<src0:proxypad2> event:newsegment
0:00:00.423603000  8764 0x8639968 DEBUG            gnlobject gnlobject.c:511:translate_outgoing_new_segment:<audio10> Got NEWSEGMENT 0:00:00.393287981 -- 0:00:03.477188208 // 0:00:00.000000000
0:00:00.423623000  8764 0x8639968 DEBUG            gnlobject gnlobject.c:271:gnl_media_to_object_time:<audio10> MediaTime : 0:00:00.000000000
0:00:00.423640000  8764 0x8639968 DEBUG            gnlobject gnlobject.c:278:gnl_media_to_object_time:<audio10> Start/Stop:[0:00:00.000000000 -- 0:00:10.000000000] Media [99:99:99.999999999 -- 99:99:99.999999999]
0:00:00.423658000  8764 0x8639968 DEBUG            gnlobject gnlobject.c:212:gnl_object_to_media_time:<audio10> ObjectTime : 0:00:00.000000000
0:00:00.423673000  8764 0x8639968 DEBUG            gnlobject gnlobject.c:219:gnl_object_to_media_time:<audio10> Start/Stop:[0:00:00.000000000 -- 0:00:10.000000000] Media [99:99:99.999999999 -- 99:99:99.999999999]
0:00:00.423690000  8764 0x8639968 DEBUG            gnlobject gnlobject.c:247:gnl_object_to_media_time:<audio10> Returning MediaTime : 0:00:00.000000000
0:00:00.423705000  8764 0x8639968 DEBUG            gnlobject gnlobject.c:527:translate_outgoing_new_segment:<audio10> Sending NEWSEGMENT 0:00:00.393287981 -- 0:00:03.477188208 // 0:00:00.000000000
0:00:00.423721000  8764 0x8639968 INFO             GST_EVENT gstevent.c:528:gst_event_new_new_segment_full: creating newsegment update 0, rate 1.000000, format GST_FORMAT_TIME, start 0:00:00.393287981, stop 0:00:03.477188208, position 0:00:00.000000000
0:00:00.423770000  8764 0x8639968 DEBUG            gnlobject gnlobject.c:569:internalpad_event_function:<src0:proxypad2> Calling priv->eventfunc 0xb794cf00
0:00:00.423839000  8764 0x8639968 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:segment-start from audio10
0:00:00.423889000  8764 0x8639968 LOG         gnlcomposition gnlcomposition.c:452:gnl_composition_handle_message:<mycomposition10> locking flushing_lock from thread 0x8639968
0:00:00.423905000  8764 0x8639968 LOG         gnlcomposition gnlcomposition.c:452:gnl_composition_handle_message:<mycomposition10> locked flushing_lock from thread 0x8639968
0:00:00.423919000  8764 0x8639968 LOG         gnlcomposition gnlcomposition.c:459:gnl_composition_handle_message:<mycomposition10> unlocking flushing_lock from thread 0x8639968
0:00:00.423947000  8764 0x8639968 WARN             vorbisdec vorbisdec.c:1118:vorbis_dec_decode_buffer:<vorbisdec1> Already initialized, so ignoring header packet
0:00:00.423984000  8764 0x8639968 WARN             vorbisdec vorbisdec.c:1118:vorbis_dec_decode_buffer:<vorbisdec1> Already initialized, so ignoring header packet
0:00:00.424012000  8764 0x8639968 WARN             vorbisdec vorbisdec.c:1118:vorbis_dec_decode_buffer:<vorbisdec1> Already initialized, so ignoring header packet
0:00:00.433468000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1328:gst_bin_recalc_state:<pipeline0> recalc state
0:00:00.433597000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1338:gst_bin_recalc_state:<pipeline0> checking element states
0:00:00.433625000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1289:gst_bin_get_state_func:<mycomposition10> getting state
0:00:00.433640000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1434:gst_bin_recalc_state:<mycomposition10> not dirty
0:00:00.433657000  8764 0x867cdc8 INFO            GST_STATES gstelement.c:2009:gst_element_continue_state:<pipeline0> committing state from READY to PAUSED, pending PLAYING
0:00:00.433721000  8764 0x867cdc8 INFO            GST_STATES gstelement.c:2018:gst_element_continue_state:<pipeline0> continue state change PAUSED to PLAYING, final PLAYING
0:00:00.433969000  8764 0x867cdc8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<mysink> completed state change
0:00:00.434036000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<pipeline0> child 'mysink' changed state to 4(PLAYING) successfully
0:00:00.434061000  8764 0x867cdc8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<convert10> completed state change
0:00:00.434116000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<pipeline0> child 'convert10' changed state to 4(PLAYING) successfully
0:00:00.434139000  8764 0x867cdc8 DEBUG            gnlobject gnlobject.c:1137:gnl_object_change_state:<mycomposition10> Calling parent change_state
0:00:00.434162000  8764 0x867cdc8 DEBUG            gnlobject gnlobject.c:1137:gnl_object_change_state:<audio10> Calling parent change_state
0:00:00.434204000  8764 0x867cdc8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<vorbisdec1> completed state change
0:00:00.434237000  8764 0x867cdc8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<audio10> message:state-changed
0:00:00.434257000  8764 0x867cdc8 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:state-changed from vorbisdec1
0:00:00.434273000  8764 0x867cdc8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:state-changed
0:00:00.434323000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<internal-decodebin> child 'vorbisdec1' changed state to 4(PLAYING) successfully
0:00:00.434348000  8764 0x867cdc8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<queue0> completed state change
0:00:00.434372000  8764 0x867cdc8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<audio10> message:state-changed
0:00:00.434388000  8764 0x867cdc8 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:state-changed from queue0
0:00:00.434403000  8764 0x867cdc8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:state-changed
0:00:00.434482000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<internal-decodebin> child 'queue0' changed state to 4(PLAYING) successfully
0:00:00.434507000  8764 0x867cdc8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<oggdemux0> completed state change
0:00:00.434530000  8764 0x867cdc8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<audio10> message:state-changed
0:00:00.434547000  8764 0x867cdc8 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:state-changed from oggdemux0
0:00:00.434561000  8764 0x867cdc8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:state-changed
0:00:00.434609000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<internal-decodebin> child 'oggdemux0' changed state to 4(PLAYING) successfully
0:00:00.434632000  8764 0x867cdc8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<typefind> completed state change
0:00:00.434655000  8764 0x867cdc8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<audio10> message:state-changed
0:00:00.434672000  8764 0x867cdc8 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:state-changed from typefind
0:00:00.434686000  8764 0x867cdc8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:state-changed
0:00:00.434733000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<internal-decodebin> child 'typefind' changed state to 4(PLAYING) successfully
0:00:00.434755000  8764 0x867cdc8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<internal-decodebin> completed state change
0:00:00.434777000  8764 0x867cdc8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<audio10> message:state-changed
0:00:00.434793000  8764 0x867cdc8 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:state-changed from internal-decodebin
0:00:00.434807000  8764 0x867cdc8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:state-changed
0:00:00.434855000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<audio10> child 'internal-decodebin' changed state to 4(PLAYING) successfully
0:00:00.434878000  8764 0x867cdc8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<internal-filesource> completed state change
0:00:00.434900000  8764 0x867cdc8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<audio10> message:state-changed
0:00:00.434916000  8764 0x867cdc8 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:state-changed from internal-filesource
0:00:00.434930000  8764 0x867cdc8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:state-changed
0:00:00.434977000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<audio10> child 'internal-filesource' changed state to 4(PLAYING) successfully
0:00:00.434998000  8764 0x867cdc8 DEBUG            gnlobject gnlobject.c:1141:gnl_object_change_state:<audio10> Return from parent change_state was 1
0:00:00.435012000  8764 0x867cdc8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<audio10> completed state change
0:00:00.435033000  8764 0x867cdc8 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:state-changed from audio10
0:00:00.435048000  8764 0x867cdc8 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<mycomposition10> message:state-changed
0:00:00.435094000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<mycomposition10> child 'audio10' changed state to 4(PLAYING) successfully
0:00:00.435132000  8764 0x867cdc8 DEBUG            gnlobject gnlobject.c:1141:gnl_object_change_state:<mycomposition10> Return from parent change_state was 1
0:00:00.435148000  8764 0x867cdc8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<mycomposition10> completed state change
0:00:00.435201000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<pipeline0> child 'mycomposition10' changed state to 4(PLAYING) successfully
0:00:00.435222000  8764 0x867cdc8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<pipeline0> completed state change
0:00:00.435273000  8764 0x867cdc8 INFO            GST_STATES gstbin.c:1428:gst_bin_recalc_state:<pipeline0> bin RETURN is now SUCCESS
0:00:02.426217000  8764 0x8620a18 DEBUG            gnlobject gnlobject.c:965:gnl_object_handle_message:<audio10> message:segment-done
0:00:02.426286000  8764 0x8620a18 LOG              gnlobject gnlobject.c:928:translate_message_segment_done:<audio10> format:3, position:0:00:03.477188208
0:00:02.426304000  8764 0x8620a18 DEBUG            gnlobject gnlobject.c:271:gnl_media_to_object_time:<audio10> MediaTime : 0:00:03.477188208
0:00:02.426322000  8764 0x8620a18 DEBUG            gnlobject gnlobject.c:278:gnl_media_to_object_time:<audio10> Start/Stop:[0:00:00.000000000 -- 0:00:10.000000000] Media [99:99:99.999999999 -- 99:99:99.999999999]
0:00:02.426340000  8764 0x8620a18 DEBUG            gnlobject gnlobject.c:212:gnl_object_to_media_time:<audio10> ObjectTime : 0:00:03.477188208
0:00:02.426356000  8764 0x8620a18 DEBUG            gnlobject gnlobject.c:219:gnl_object_to_media_time:<audio10> Start/Stop:[0:00:00.000000000 -- 0:00:10.000000000] Media [99:99:99.999999999 -- 99:99:99.999999999]
0:00:02.426373000  8764 0x8620a18 DEBUG            gnlobject gnlobject.c:247:gnl_object_to_media_time:<audio10> Returning MediaTime : 0:00:03.477188208
0:00:02.426407000  8764 0x8620a18 DEBUG       gnlcomposition gnlcomposition.c:448:gnl_composition_handle_message:<mycomposition10> message:segment-done from audio10
0:00:02.426424000  8764 0x8620a18 LOG         gnlcomposition gnlcomposition.c:464:gnl_composition_handle_message:<mycomposition10> locking flushing_lock from thread 0x8620a18
0:00:02.426439000  8764 0x8620a18 LOG         gnlcomposition gnlcomposition.c:464:gnl_composition_handle_message:<mycomposition10> locked flushing_lock from thread 0x8620a18
0:00:02.426453000  8764 0x8620a18 LOG         gnlcomposition gnlcomposition.c:471:gnl_composition_handle_message:<mycomposition10> unlocking flushing_lock from thread 0x8620a18
0:00:02.426466000  8764 0x8620a18 DEBUG       gnlcomposition gnlcomposition.c:474:gnl_composition_handle_message:<mycomposition10> Adding segment_done handling to main thread
0:00:02.426610000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:407:segment_done_main_thread:<mycomposition10> Setting segment->start to segment_stop:0:00:10.000000000
0:00:02.426631000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:566:seek_handling:<mycomposition10> initial:1, update:1
0:00:02.426646000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:568:seek_handling:<mycomposition10> locking flushing_lock from thread 0x83656d8
0:00:02.426659000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:568:seek_handling:<mycomposition10> locked flushing_lock from thread 0x83656d8
0:00:02.426672000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:570:seek_handling:<mycomposition10> Setting flushing to TRUE
0:00:02.426685000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:582:seek_handling:<mycomposition10> unlocking flushing_lock from thread 0x83656d8
0:00:02.426699000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:1548:update_pipeline:<mycomposition10> currenttime:0:00:10.000000000 initial:1 , change_state:1 , modify:0
0:00:02.426715000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1550:update_pipeline:<mycomposition10> locking objects_lock from thread 0x83656d8
0:00:02.426729000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1550:update_pipeline:<mycomposition10> locked object_lock from thread 0x83656d8
0:00:02.426779000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1160:update_start_stop_duration:<mycomposition10> start:0:00:00.000000000 stop:0:00:10.000000000 duration:0:00:10.000000000
0:00:02.426797000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:1566:update_pipeline:<mycomposition10> now really updating the pipeline, current-state:PLAYING
0:00:02.426810000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:921:get_clean_toplevel_stack:<mycomposition10> timestamp:0:00:10.000000000
0:00:02.426824000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:859:get_stack_list:<mycomposition10> timestamp:0:00:10.000000000, priority:0, activeonly:1
0:00:02.426840000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:868:get_stack_list:<audio10> start: 0:00:00.000000000 , stop:0:00:10.000000000 , duration:0:00:10.000000000, priority:0
0:00:02.426857000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:931:get_clean_toplevel_stack:<mycomposition10> Got empty stack, checking if it really was after the last object
0:00:02.426871000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:967:get_clean_toplevel_stack:<mycomposition10> Setting stop_time to 0:00:00.000000000
0:00:02.426885000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:1360:compare_deactivate_single_node:<mycomposition10> oldobj:audio10
0:00:02.426906000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:1394:compare_deactivate_single_node:<mycomposition10> Setting ghostpad target to NULL so oldobj srcpad is no longer linked
0:00:02.426921000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:684:gnl_composition_ghost_pad_set_target:<mycomposition10> Removing target, hadghost:1
0:00:02.426937000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:710:gnl_composition_ghost_pad_set_target:<mycomposition10> Previous target was audio10:src0, blocking that pad
0:00:02.426953000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:881:gnl_object_ghost_pad_set_target:<mycomposition10> removing target from ghostpad
0:00:02.426971000  8764 0x83656d8 INFO      GST_ELEMENT_PADS gstpad.c:1520:gst_pad_unlink: unlinking audio10:src0(0x85e38e0) and src:proxypad3(0x85e4198)
0:00:02.426993000  8764 0x83656d8 INFO      GST_ELEMENT_PADS gstpad.c:1555:gst_pad_unlink: unlinked audio10:src0 and src:proxypad3
0:00:02.427010000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:721:control_internal_pad:<mycomposition10:src> ghostpad doesn't have a target, no need to control the internal pad
0:00:02.427024000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:726:gnl_composition_ghost_pad_set_target:<mycomposition10> END
0:00:02.427038000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1447:compare_deactivate_single_node:<mycomposition10> audio10 not used anymore
0:00:02.427052000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1464:compare_deactivate_single_node:<mycomposition10> adding audio10 to deactivate list
0:00:02.427067000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1582:update_pipeline:<mycomposition10> unlocking objects_lock from thread 0x83656d8
0:00:02.427080000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:1587:update_pipeline:<mycomposition10> De-activating objects no longer used
0:00:02.427092000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:1591:update_pipeline: 0x85c90a8
0:00:02.427111000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:1137:gnl_object_change_state:<audio10> Calling parent change_state
0:00:02.427172000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<vorbisdec1> completed state change
0:00:02.427190000  8764 0x83656d8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<internal-decodebin> child 'vorbisdec1' changed state to 4(PLAYING) successfully
0:00:02.427213000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<queue0> completed state change
0:00:02.427245000  8764 0x83656d8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<internal-decodebin> child 'queue0' changed state to 4(PLAYING) successfully
0:00:02.427267000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<oggdemux0> completed state change
0:00:02.427282000  8764 0x83656d8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<internal-decodebin> child 'oggdemux0' changed state to 4(PLAYING) successfully
0:00:02.427302000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<typefind> completed state change
0:00:02.427316000  8764 0x83656d8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<internal-decodebin> child 'typefind' changed state to 4(PLAYING) successfully
0:00:02.427335000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<internal-decodebin> completed state change
0:00:02.427350000  8764 0x83656d8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<audio10> child 'internal-decodebin' changed state to 4(PLAYING) successfully
0:00:02.427370000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<internal-filesource> completed state change
0:00:02.427385000  8764 0x83656d8 INFO            GST_STATES gstbin.c:1938:gst_bin_change_state_func:<audio10> child 'internal-filesource' changed state to 4(PLAYING) successfully
0:00:02.427402000  8764 0x83656d8 DEBUG            gnlobject gnlobject.c:1141:gnl_object_change_state:<audio10> Return from parent change_state was 1
0:00:02.427417000  8764 0x83656d8 INFO            GST_STATES gstelement.c:2035:gst_element_continue_state:<audio10> completed state change
0:00:02.427432000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:1599:update_pipeline:<mycomposition10> Finished de-activating objects no longer used
0:00:02.427446000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:1608:update_pipeline:<mycomposition10> activating objects in new stack to PLAYING
0:00:02.427460000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:1617:update_pipeline:<mycomposition10> Finished activating objects in new stack
0:00:02.427473000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:1686:update_pipeline:<mycomposition10> Returning 1
0:00:02.427486000  8764 0x83656d8 DEBUG       gnlcomposition gnlcomposition.c:414:segment_done_main_thread:<mycomposition10> Nothing else to play
0:00:02.427499000  8764 0x83656d8 LOG         gnlcomposition gnlcomposition.c:418:segment_done_main_thread:<mycomposition10> Pushing out EOS
0:00:02.447320000  8764 0x8639968 DEBUG       gnlcomposition gnlcomposition.c:668:pad_blocked:<mycomposition10> Pad : audio10:src0 , blocked:1
0:00:02.844452000  8764 0x83656d8 DEBUG               python gnlcutoff.py:59:Stop: EOS
Playing /home/laszlo/jokosherbits/123Stop.ogg
pad added!
EOS
0:00:02.874831000  8764 0x83656d8 INFO              GST_INIT gst.c:1164:gst_deinit: deinitializing GStreamer
0:00:02.877232000  8764 0x83656d8 INFO              GST_INIT gst.c:1186:gst_deinit: deinitialized GStreamer
