0:23:07.679829927  9972      0x24db080 LOG                  default gsttypefindhelper.c:305:gst_type_find_helper_get_range:<sink:proxypad0> Returning audio/x-wav (probability = 100)
0:23:07.679847797  9972      0x24db080 LOG          GST_REFCOUNTING gstobject.c:324:gst_object_unref:<sink:proxypad0> 0x27aa050 unref 2->1
0:23:07.679859896  9972      0x24db080 DEBUG               GST_PADS gstpad.c:765:gst_pad_activate_pull:<typefind:sink> deactivating pad from pull
0:23:07.679871437  9972      0x24db080 LOG          GST_REFCOUNTING gstobject.c:297:gst_object_ref:<sink:proxypad0> 0x27aa050 ref 1->2
0:23:07.679882807  9972      0x24db080 DEBUG               GST_PADS gstpad.c:772:gst_pad_activate_pull:<typefind:sink> calling peer
0:23:07.679893109  9972      0x24db080 DEBUG               GST_PADS gstpad.c:765:gst_pad_activate_pull:<sink:proxypad0> deactivating pad from pull
0:23:07.679903724  9972      0x24db080 DEBUG               GST_PADS gstpad.c:600:pre_activate:<sink:proxypad0> setting ACTIVATE_MODE NONE, set flushing
0:23:07.679915477  9972      0x24db080 LOG                 GST_PADS gstghostpad.c:559:gst_ghost_pad_internal_do_activate_pull:<sink:proxypad0> deactivate pull on sink:proxypad0
0:23:07.679926897  9972      0x24db080 LOG                 GST_PADS gstghostpad.c:567:gst_ghost_pad_internal_do_activate_pull:<sink:proxypad0> pad is src, activate internal
0:23:07.679937367  9972      0x24db080 DEBUG               GST_PADS gstpad.c:765:gst_pad_activate_pull:<decodebin0:sink> deactivating pad from pull
0:23:07.679947911  9972      0x24db080 LOG          GST_REFCOUNTING gstobject.c:297:gst_object_ref:<gnomevfssrc0:src> 0x278e080 ref 2->3
0:23:07.679959327  9972      0x24db080 DEBUG               GST_PADS gstpad.c:772:gst_pad_activate_pull:<decodebin0:sink> calling peer
0:23:07.679969545  9972      0x24db080 DEBUG               GST_PADS gstpad.c:765:gst_pad_activate_pull:<gnomevfssrc0:src> deactivating pad from pull
0:23:07.679980411  9972      0x24db080 DEBUG               GST_PADS gstpad.c:600:pre_activate:<gnomevfssrc0:src> setting ACTIVATE_MODE NONE, set flushing
0:23:07.679991576  9972      0x24db080 DEBUG                basesrc gstbasesrc.c:2819:gst_base_src_activate_pull:<gnomevfssrc0> Deactivating in pull mode
0:23:07.680002733  9972      0x24db080 DEBUG                basesrc gstbasesrc.c:2608:gst_base_src_stop:<gnomevfssrc0> stopping source
0:23:07.680014670  9972      0x24db080 DEBUG            gnomevfssrc gstgnomevfssrc.c:585:gst_gnome_vfs_src_pop_callbacks:<gnomevfssrc0> popping callbacks
0:23:08.268930409  9972      0x24db080 WARN             gnomevfssrc gstgnomevfssrc.c:889:gst_gnome_vfs_src_stop:<gnomevfssrc0> error: Could not close vfs handle: Operation cancelled
0:23:08.268977497  9972      0x24db080 DEBUG            GST_MESSAGE gstelement.c:1732:gst_element_message_full:<gnomevfssrc0> start
0:23:08.268998582  9972      0x24db080 LOG          GST_REFCOUNTING gstobject.c:297:gst_object_ref:<gnomevfssrc0> 0x278c070 ref 4->5
0:23:08.269010823  9972      0x24db080 LOG          GST_REFCOUNTING gstobject.c:297:gst_object_ref:<pipeline0> 0x27962a0 ref 3->4
0:23:08.269023573  9972      0x24db080 LOG          GST_REFCOUNTING gstobject.c:324:gst_object_unref:<pipeline0> 0x27962a0 unref 4->3
0:23:08.269034622  9972      0x24db080 LOG          GST_REFCOUNTING gstobject.c:324:gst_object_unref:<gnomevfssrc0> 0x278c070 unref 5->4
0:23:08.269046225  9972      0x24db080 INFO        GST_ERROR_SYSTEM gstelement.c:1763:gst_element_message_full:<gnomevfssrc0> posting message: Could not close resource.
0:23:08.269063390  9972      0x24db080 LOG              GST_MESSAGE gstmessage.c:183:gst_message_init: new message 0x27ab9f0
0:23:08.269073763  9972      0x24db080 LOG              GST_MESSAGE gstmessage.c:269:gst_message_new_custom: source gnomevfssrc0: creating new message 0x27ab9f0 error
0:23:08.269083361  9972      0x24db080 LOG          GST_REFCOUNTING gstobject.c:297:gst_object_ref:<gnomevfssrc0> 0x278c070 ref 4->5
0:23:08.269093734  9972      0x24db080 LOG          GST_REFCOUNTING gstobject.c:297:gst_object_ref:<bus1> 0x250d280 ref 4->5
0:23:08.269104020  9972      0x24db080 DEBUG                GST_BUS gstbus.c:308:gst_bus_post:<bus1> [msg 0x27ab9f0] posting on bus, type error, GstMessageError, gerror=(GstGError)NULL, debug=(string)"gstgnomevfssrc.c\(889\):\ gst_gnome_vfs_src_stop\ \(\):\ /GstPipeline:pipeline0/GstGnomeVFSSrc:gnomevfssrc0:\012Could\ not\ close\ vfs\ handle:\ Operation\ cancelled"; from source <gnomevfssrc0>
0:23:08.269135565  9972      0x24db080 DEBUG                    bin gstbin.c:2976:gst_bin_handle_message_func:<pipeline0> [msg 0x27ab9f0] handling child gnomevfssrc0 message of type error
0:23:08.269145530  9972      0x24db080 DEBUG                    bin gstbin.c:3250:gst_bin_handle_message_func:<pipeline0> posting message upward
0:23:08.269154432  9972      0x24db080 LOG          GST_REFCOUNTING gstobject.c:297:gst_object_ref:<bus2> 0x250d340 ref 1->2
0:23:08.269164300  9972      0x24db080 DEBUG                GST_BUS gstbus.c:308:gst_bus_post:<bus2> [msg 0x27ab9f0] posting on bus, type error, GstMessageError, gerror=(GstGError)NULL, debug=(string)"gstgnomevfssrc.c\(889\):\ gst_gnome_vfs_src_stop\ \(\):\ /GstPipeline:pipeline0/GstGnomeVFSSrc:gnomevfssrc0:\012Could\ not\ close\ vfs\ handle:\ Operation\ cancelled"; from source <gnomevfssrc0>
0:23:08.269183521  9972      0x24db080 DEBUG                GST_BUS gstbus.c:338:gst_bus_post:<bus2> [msg 0x27ab9f0] pushing on async queue
0:23:08.269193707  9972      0x24db080 DEBUG                GST_BUS gstbus.c:343:gst_bus_post:<bus2> [msg 0x27ab9f0] pushed on async queue
0:23:08.269203647  9972      0x24db080 LOG          GST_REFCOUNTING gstobject.c:324:gst_object_unref:<bus2> 0x250d340 unref 2->1
0:23:08.269213578  9972      0x24db080 DEBUG                GST_BUS gstbus.c:334:gst_bus_post:<bus1> [msg 0x27ab9f0] dropped
0:23:08.269232727  9972      0x24db080 LOG          GST_REFCOUNTING gstobject.c:324:gst_object_unref:<bus1> 0x250d280 unref 5->4
0:23:08.269242596  9972      0x24db080 INFO        GST_ERROR_SYSTEM gstelement.c:1786:gst_element_message_full:<gnomevfssrc0> posted error message: Could not close resource.
0:23:08.269254083  9972      0x24db080 DEBUG               GST_PADS gstpad.c:621:post_activate:<gnomevfssrc0:src> stopped streaming
0:23:08.269263902  9972      0x24db080 DEBUG               GST_PADS gstpad.c:804:gst_pad_activate_pull:<gnomevfssrc0:src> deactivated in pull mode
0:23:08.269273566  9972      0x24db080 LOG          GST_REFCOUNTING gstobject.c:324:gst_object_unref:<gnomevfssrc0:src> 0x278e080 unref 3->2
0:23:08.269284060  9972      0x24db080 DEBUG               GST_PADS gstpad.c:600:pre_activate:<decodebin0:sink> setting ACTIVATE_MODE NONE, set flushing
0:23:08.269294000  9972      0x24db080 LOG                 GST_PADS gstghostpad.c:608:gst_ghost_pad_do_activate_pull:<decodebin0:sink> deactivate pull on decodebin0:sink
0:23:08.269304061  9972      0x24db080 LOG          GST_REFCOUNTING gstobject.c:297:gst_object_ref:<gnomevfssrc0:src> 0x278e080 ref 2->3
0:23:08.269313354  9972      0x24db080 LOG                 GST_PADS gstghostpad.c:620:gst_ghost_pad_do_activate_pull:<decodebin0:sink> activating peer
0:23:08.269322781  9972      0x24db080 DEBUG               GST_PADS gstpad.c:754:gst_pad_activate_pull:<gnomevfssrc0:src> deactivating pad from none, was ok
0:23:08.269332387  9972      0x24db080 DEBUG               GST_PADS gstpad.c:811:gst_pad_activate_pull:<gnomevfssrc0:src> already deactivated in pull mode
0:23:08.269341602  9972      0x24db080 LOG          GST_REFCOUNTING gstobject.c:324:gst_object_unref:<gnomevfssrc0:src> 0x278e080 unref 3->2
0:23:08.269351116  9972      0x24db080 DEBUG               GST_PADS gstpad.c:621:post_activate:<decodebin0:sink> stopped streaming
0:23:08.269360489  9972      0x24db080 DEBUG               GST_PADS gstpad.c:804:gst_pad_activate_pull:<decodebin0:sink> deactivated in pull mode
0:23:08.269370220  9972      0x24db080 DEBUG               GST_PADS gstpad.c:621:post_activate:<sink:proxypad0> stopped streaming
0:23:08.269379868  9972      0x24db080 DEBUG               GST_PADS gstpad.c:804:gst_pad_activate_pull:<sink:proxypad0> deactivated in pull mode
0:23:08.269389261  9972      0x24db080 LOG          GST_REFCOUNTING gstobject.c:324:gst_object_unref:<sink:proxypad0> 0x27aa050 unref 2->1
0:23:08.269399080  9972      0x24db080 DEBUG               GST_PADS gstpad.c:600:pre_activate:<typefind:sink> setting ACTIVATE_MODE NONE, set flushing
0:23:08.269408940  9972      0x24db080 DEBUG               GST_PADS gstpad.c:621:post_activate:<typefind:sink> stopped streaming
0:23:08.269418263  9972      0x24db080 DEBUG               GST_PADS gstpad.c:804:gst_pad_activate_pull:<typefind:sink> deactivated in pull mode
0:23:08.269428561  9972      0x24db080 DEBUG               GST_PADS gstpad.c:869:gst_pad_activate_push:<typefind:src> trying to set deactivated in push mode
0:23:08.269438188  9972      0x24db080 DEBUG               GST_PADS gstpad.c:906:gst_pad_activate_push:<typefind:src> deactivating pad from push
0:23:08.269457363  9972      0x24db080 DEBUG               GST_PADS gstpad.c:600:pre_activate:<typefind:src> setting ACTIVATE_MODE NONE, set flushing
0:23:08.269467536  9972      0x24db080 DEBUG               GST_PADS gstpad.c:621:post_activate:<typefind:src> stopped streaming
0:23:08.269476671  9972      0x24db080 DEBUG               GST_PADS gstpad.c:925:gst_pad_activate_push:<typefind:src> deactivated in push mode
0:23:08.269494316  9972      0x24db080 INFO                typefind gsttypefindelement.c:166:gst_type_find_element_have_type:<typefind> found caps audio/x-wav, probability=100
0:23:08.269508484  9972      0x24db080 LOG                 GST_CAPS gstcaps.c:153:gst_caps_new_empty: created caps 0x2595500
0:23:08.269519411  9972      0x24db080 LOG          GST_REFCOUNTING gstcaps.c:1923:gst_caps_replace: 0x278e420, (nil) -> 0x27ce500
0:23:08.269530618  9972      0x24db080 LOG          GST_REFCOUNTING gstcaps.c:380:gst_caps_ref: 0x27ce500 1->2
0:23:08.269539691  9972      0x24db080 DEBUG               GST_CAPS gstpad.c:2537:gst_pad_set_caps:<typefind:src> caps 0x27ce500 audio/x-wav
0:23:08.269557073  9972      0x24db080 LOG          GST_REFCOUNTING gstobject.c:297:gst_object_ref:<typefind> 0x26a3730 ref 5->6
0:23:08.269567534  9972      0x24db080 LOG           GST_PROPERTIES gstobject.c:520:gst_object_dispatch_properties_changed:<typefind> deep notification from src (caps)
0:23:08.269578086  9972      0x24db080 LOG          GST_REFCOUNTING gstobject.c:297:gst_object_ref:<decodebin0> 0x27960d0 ref 5->6
0:23:08.269588160  9972      0x24db080 LOG          GST_REFCOUNTING gstobject.c:324:gst_object_unref:<typefind> 0x26a3730 unref 6->5
0:23:08.269597766  9972      0x24db080 LOG           GST_PROPERTIES gstobject.c:520:gst_object_dispatch_properties_changed:<decodebin0> deep notification from src (caps)
0:23:08.269607856  9972      0x24db080 LOG          GST_REFCOUNTING gstobject.c:297:gst_object_ref:<pipeline0> 0x27962a0 ref 3->4
0:23:08.269617708  9972      0x24db080 LOG          GST_REFCOUNTING gstobject.c:324:gst_object_unref:<decodebin0> 0x27960d0 unref 6->5
0:23:08.269627297  9972      0x24db080 LOG           GST_PROPERTIES gstobject.c:520:gst_object_dispatch_properties_changed:<pipeline0> deep notification from src (caps)