0:00:00.000939519   766   0x46b050 INFO                GST_INIT gst.c:613:init_pre: Initializing GStreamer Core Library version 0.10.36.1
0:00:00.001333260   766   0x46b050 INFO                GST_INIT gst.c:614:init_pre: Using library installed in /usr/lib
0:00:00.001476519   766   0x46b050 INFO                GST_INIT gst.c:624:init_pre: Linux dm7020hd 3.2-dm7020hd #1 SMP Mon Jul 23 09:07:32 CEST 2012 mips
0:00:00.002571185   766   0x46b050 INFO                GST_INIT gstquery.c:112:_gst_query_initialize: init queries
0:00:00.013904444   766   0x46b050 INFO                GST_INIT gstmessage.c:73:_gst_message_initialize: init messages
0:00:00.017140333   766   0x46b050 INFO      GST_PLUGIN_LOADING gstplugin.c:350:_gst_plugin_initialize: registering 0 static plugins
0:00:00.019528778   766   0x46b050 INFO      GST_PLUGIN_LOADING gstplugin.c:255:gst_plugin_register_static: registered static plugin "staticelements"
0:00:00.019721370   766   0x46b050 INFO      GST_PLUGIN_LOADING gstplugin.c:257:gst_plugin_register_static: added static plugin "staticelements", result: 1
0:00:00.030431000   766   0x46b050 INFO            GST_REGISTRY gstregistry.c:1648:ensure_current_registry: reading registry cache: /home/root/.gstreamer-0.10/registry.mipsel.bin
0:00:00.131734185   766   0x46b050 INFO            GST_REGISTRY gstregistrybinary.c:616:gst_registry_binary_read_cache: loaded /home/root/.gstreamer-0.10/registry.mipsel.bin in 0.101014 seconds
0:00:00.132360593   766   0x46b050 INFO            GST_REGISTRY gstregistry.c:1518:scan_and_update_registry: Validating plugins from registry cache: /home/root/.gstreamer-0.10/registry.mipsel.bin
0:00:00.207624704   766   0x46b050 INFO            GST_REGISTRY gstregistry.c:1610:scan_and_update_registry: Registry cache has not changed
0:00:00.207785963   766   0x46b050 INFO            GST_REGISTRY gstregistry.c:1683:ensure_current_registry: registry reading and updating done, result = 1
0:00:00.207886481   766   0x46b050 INFO                GST_INIT gst.c:805:init_post: GLib runtime version: 2.30.3
0:00:00.207986926   766   0x46b050 INFO                GST_INIT gst.c:807:init_post: GLib headers version: 2.30.3
0:00:00.208412371   766   0x46b050 INFO            GST_PIPELINE gstparse.c:334:gst_parse_launch_full: parsing pipeline description 'playbin2 uri=file:///media/net/MP3s/70s\ Disco/Chic\ -\ Le\ Freak.mp3 '
0:00:00.248561519   766   0x46b050 INFO      GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstplaybin.so" loaded
0:00:00.248738852   766   0x46b050 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:376:gst_element_factory_create: creating element "playbin2"
0:00:00.258120778   766   0x46b050 LOG                 playbin2 gstplaybin2.c:1447:gst_playbin_uri_is_valid:<playbin20> checking uri 'file:///media/net/MP3s/70s Disco/Chic - Le Freak.mp3'
0:00:00.258332889   766   0x46b050 WARN                playbin2 gstplaybin2.c:1465:gst_playbin_uri_is_valid:<playbin20> uri 'file:///media/net/MP3s/70s Disco/Chic - Le Freak.mp3' not valid, character #26
0:00:00.258460074   766   0x46b050 WARN                playbin2 gstplaybin2.c:1485:gst_play_bin_set_uri:<playbin20> not entirely correct file URI 'file:///media/net/MP3s/70s Disco/Chic - Le Freak.mp3' - make sure to escape spaces and non-ASCII characters properly and specify an absolute path. Use gst_filename_to_uri() to convert filenames to URIs
0:00:00.258582259   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:1501:gst_play_bin_set_uri: set new uri to file:///media/net/MP3s/70s Disco/Chic - Le Freak.mp3
Setting pipeline to PAUSED ...
0:00:00.266107518   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<playsink> current NULL pending VOID_PENDING, desired next READY
0:00:00.266448556   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<streamsynchronizer0> current NULL pending VOID_PENDING, desired next READY
0:00:00.266686704   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<streamsynchronizer0> completed state change to READY
0:00:00.266837482   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<streamsynchronizer0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.267327074   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playsink> child 'streamsynchronizer0' changed state to 2(READY) successfully
0:00:00.267530741   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<playsink> completed state change to READY
0:00:00.267651556   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<playsink> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.267888037   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playbin20> child 'playsink' changed state to 2(READY) successfully
0:00:00.268061926   766   0x46b050 INFO              GST_STATES gstelement.c:2438:gst_element_continue_state:<playbin20> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:00.268188222   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<playbin20> notifying about state-changed NULL to READY (PAUSED pending)
0:00:00.269804296   766   0x46b050 INFO              GST_STATES gstelement.c:2445:gst_element_continue_state:<playbin20> continue state change READY to PAUSED, final PAUSED
0:00:00.269973333   766   0x46b050 LOG                 playbin2 gstplaybin2.c:4192:gst_play_bin_change_state:<playbin20> clearing shutdown flag
0:00:00.270105926   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:4087:setup_next_source:<playbin20> setup sources
0:00:00.270260186   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3761:activate_group:<playbin20> activating group 0x4f13dc
0:00:00.270389000   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3790:activate_group:<playbin20> making new uridecodebin
0:00:00.275991296   766   0x46b050 INFO      GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstdecodebin2.so" loaded
0:00:00.276169000   766   0x46b050 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:376:gst_element_factory_create: creating element "uridecodebin"
0:00:00.279555519   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<uridecodebin0> completed state change to READY
0:00:00.279719963   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<uridecodebin0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.279976963   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3740:group_set_locked_state_unlocked:<playbin20> locked_state 0 on group 0x4f13dc
0:00:00.280285111   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<playsink> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.280447445   766   0x46b050 INFO                playsink gstplaysink.c:1317:do_async_start:<playsink> Sending async_start message
0:00:00.280734000   766   0x46b050 LOG                 playsink gstplaysink.c:3008:gst_play_sink_reconfigure:<playsink> Triggering reconfiguration
0:00:00.280854074   766   0x46b050 LOG                 playsink gstplaysink.c:3010:gst_play_sink_reconfigure:<playsink> locking from thread 0x46b050
0:00:00.280984222   766   0x46b050 LOG                 playsink gstplaysink.c:3010:gst_play_sink_reconfigure:<playsink> locked from thread 0x46b050
0:00:00.281104592   766   0x46b050 LOG                 playsink gstplaysink.c:3037:gst_play_sink_reconfigure:<playsink> unlocking from thread 0x46b050
0:00:00.281341185   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<streamsynchronizer0> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.281545963   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<streamsynchronizer0> completed state change to PAUSED
0:00:00.281716852   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<streamsynchronizer0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.281978481   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playsink> child 'streamsynchronizer0' changed state to 3(PAUSED) successfully
0:00:00.282186926   766   0x46b050 INFO              GST_STATES gstbin.c:2593:gst_bin_change_state_func:<playbin20> child 'playsink' is changing state asynchronously to PAUSED
0:00:00.282380371   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<uridecodebin0> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.291920704   766   0x46b050 INFO      GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstcoreelements.so" loaded
0:00:00.292116111   766   0x46b050 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:374:gst_element_factory_create: creating element "filesrc" named "source"
0:00:00.293894778   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<GstBaseSrc@0x4fa020> adding pad 'src'
0:00:00.294384371   766   0x46b050 INFO                 filesrc gstfilesrc.c:374:gst_file_src_set_location: filename : /media/net/MP3s/70s Disco/Chic - Le Freak.mp3
0:00:00.294503148   766   0x46b050 INFO                 filesrc gstfilesrc.c:375:gst_file_src_set_location: uri      : file:///media/net/MP3s/70s%20Disco/Chic%20-%20Le%20Freak.mp3
0:00:00.295317148   766   0x46b050 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:376:gst_element_factory_create: creating element "decodebin2"
0:00:00.297626963   766   0x46b050 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:374:gst_element_factory_create: creating element "typefind" named "typefind"
0:00:00.299459037   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<GstTypeFindElement@0x4ff038> adding pad 'sink'
0:00:00.300202074   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<GstTypeFindElement@0x4ff038> adding pad 'src'
0:00:00.300659111   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad typefind:sink
0:00:00.302961148   766   0x46b050 INFO                GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link sink:proxypad0 and typefind:sink
0:00:00.303133444   766   0x46b050 INFO                GST_PADS gstpad.c:2161:gst_pad_link_full: linked sink:proxypad0 and typefind:sink, successful
0:00:00.303366148   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<GstDecodeBin2@0x4fe018> adding pad 'sink'
0:00:00.304893963   766   0x46b050 INFO        GST_ELEMENT_PADS gstutils.c:1698:gst_element_link_pads_full: trying to link element source:(any) to element decodebin20:sink
0:00:00.305052296   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad decodebin20:sink
0:00:00.305171407   766   0x46b050 INFO                GST_PADS gstutils.c:1596:prepare_link_maybe_ghosting: source and decodebin20 in same bin, no need for ghost pads
0:00:00.305765407   766   0x46b050 INFO                GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link source:src and decodebin20:sink
0:00:00.306041852   766   0x46b050 INFO                GST_PADS gstpad.c:2161:gst_pad_link_full: linked source:src and decodebin20:sink, successful
0:00:00.306587741   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<decodebin20> current NULL pending VOID_PENDING, desired next PAUSED
0:00:00.306864482   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<typefind> current NULL pending VOID_PENDING, desired next READY
0:00:00.307020667   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<typefind> completed state change to READY
0:00:00.307138148   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<typefind> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.307515815   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<decodebin20> child 'typefind' changed state to 2(READY) successfully
0:00:00.307687148   766   0x46b050 INFO              GST_STATES gstelement.c:2438:gst_element_continue_state:<decodebin20> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:00.307813815   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<decodebin20> notifying about state-changed NULL to READY (PAUSED pending)
0:00:00.308045667   766   0x46b050 INFO              GST_STATES gstelement.c:2445:gst_element_continue_state:<decodebin20> continue state change READY to PAUSED, final PAUSED
0:00:00.308380630   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:2507:gst_play_bin_handle_message:<playbin20> Ignoring async state change of uridecodebin: uridecodebin0
0:00:00.308643074   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<typefind> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.308907074   766   0x46b050 INFO                 filesrc gstfilesrc.c:953:gst_file_src_start:<source> opening file /media/net/MP3s/70s Disco/Chic - Le Freak.mp3
0:00:00.316444852   766   0x46b050 INFO                 filesrc gstfilesrc.c:953:gst_file_src_start:<source> opening file /media/net/MP3s/70s Disco/Chic - Le Freak.mp3
0:00:00.340920481   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-ms-asf
0:00:00.341572926   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-musepack
0:00:00.341942667   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-au
0:00:00.342324556   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-msvideo
0:00:00.342665519   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/qcelp
0:00:00.343045074   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-cdxa
0:00:00.343423334   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-vcd
0:00:00.343772333   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-imelody
0:00:00.344119852   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/midi
0:00:00.344490037   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/riff-midi
0:00:00.344835111   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/mobile-xmf
0:00:00.345264482   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-fli
0:00:00.345621259   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-id3v2
0:00:00.345994444   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-id3v1
0:00:00.346757222   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-apetag
0:00:00.347162074   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-ttafile
0:00:00.347556334   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-mod
0:00:00.348035296   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/mpeg
0:00:00.348410037   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-ac3
0:00:00.348751111   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-dts
0:00:00.349137555   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-gsm
0:00:00.349588778   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/mpeg-sys
0:00:00.349994926   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/mpegts
0:00:00.350383815   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/ogg
0:00:00.350749185   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/mpeg-elementary
0:00:00.351645519   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/mpeg4
0:00:00.351995111   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-h263
0:00:00.352628408   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-h264
0:00:00.353051704   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-nuv
0:00:00.353451000   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-m4a
0:00:00.353865185   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-3gp
0:00:00.354250186   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/quicktime
0:00:00.354602000   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/x-quicktime
0:00:00.354945815   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/jp2
0:00:00.355318704   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/mj2
0:00:00.355658630   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for text/html
0:00:00.355992259   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/vnd.rn-realmedia
0:00:00.356359778   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-pn-realaudio
0:00:00.356709963   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-shockwave-flash
0:00:00.357048074   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-flv
0:00:00.357419852   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for text/plain
0:00:00.357774815   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for text/utf-16
0:00:00.358157222   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for text/utf-32
0:00:00.358631667   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for text/uri-list
0:00:00.359014259   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-hls
0:00:00.359389185   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/sdp
0:00:00.359730630   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/smil
0:00:00.360108778   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/xml
0:00:00.360477222   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-wav
0:00:00.360823852   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-aiff
0:00:00.361205481   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-svx
0:00:00.361594704   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-paris
0:00:00.361930815   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-nist
0:00:00.362290186   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-voc
0:00:00.362640296   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-sds
0:00:00.362982926   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-ircam
0:00:00.363395371   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-w64
0:00:00.363769519   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-shorten
0:00:00.364112296   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-ape
0:00:00.364489148   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/jpeg
0:00:00.364836370   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/gif
0:00:00.365169852   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/png
0:00:00.365539000   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/bmp
0:00:00.365989185   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/tiff
0:00:00.366433593   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/x-portable-pixmap
0:00:00.366802593   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-matroska
0:00:00.367153259   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/webm
0:00:00.367528259   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/mxf
0:00:00.367925518   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-mve
0:00:00.368335815   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-dv
0:00:00.368746000   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-amr-nb-sh
0:00:00.369111852   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-amr-wb-sh
0:00:00.369512889   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/iLBC-sh
0:00:00.369866704   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-sid
0:00:00.370233926   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/x-xcf
0:00:00.370575704   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-mng
0:00:00.371399667   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/x-jng
0:00:00.371803926   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/x-xpixmap
0:00:00.372192704   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/x-sun-raster
0:00:00.372575815   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-bzip
0:00:00.372957704   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-gzip
0:00:00.373335223   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/zip
0:00:00.373677704   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-compress
0:00:00.373996148   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for subtitle/x-kate
0:00:00.374386778   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-flac
0:00:00.374750741   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-vorbis
0:00:00.375093518   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-theora
0:00:00.375458741   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-ogm-video
0:00:00.375801704   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-ogm-audio
0:00:00.376142259   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-ogm-text
0:00:00.376512482   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-speex
0:00:00.376844815   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-celt
0:00:00.377249334   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-ogg-skeleton
0:00:00.377599445   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for text/x-cmml
0:00:00.377939667   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-executable
0:00:00.378395482   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/aac
0:00:00.378824111   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-spc
0:00:00.379291371   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-wavpack
0:00:00.379759889   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-wavpack-correction
0:00:00.380128852   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/postscript
0:00:00.380517482   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/svg+xml
0:00:00.380858444   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-rar
0:00:00.381205963   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-tar
0:00:00.381586296   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-ar
0:00:00.381938667   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-ms-dos-executable
0:00:00.382330963   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-dirac
0:00:00.382676000   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for multipart/x-mixed-replace
0:00:00.383018037   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-mmsh
0:00:00.383384963   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/vivo
0:00:00.383726111   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-nsf
0:00:00.384066259   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-gym
0:00:00.384427778   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-ay
0:00:00.384887074   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-gbs
0:00:00.385301223   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-vgm
0:00:00.385661037   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-sap
0:00:00.386002444   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for video/x-ivf
0:00:00.386375148   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for audio/x-kss
0:00:00.386722630   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/pdf
0:00:00.387097037   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/msword
0:00:00.387857667   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/octet-stream
0:00:00.388285371   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/vnd.adobe.photoshop
0:00:00.388603963   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/vnd.wap.wbmp
0:00:00.388928148   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/x-yuv4mpeg
0:00:00.389299667   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/x-icon
0:00:00.389611963   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for xdgmime-base
0:00:00.389910926   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for image/x-degas
0:00:00.390301519   766   0x46b050 INFO            GST_TYPEFIND gsttypefind.c:83:gst_type_find_register: registering typefind function for application/octet-stream
0:00:00.390808815   766   0x46b050 INFO      GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgsttypefindfunctions.so" loaded
0:00:00.393412297   766   0x46b050 INFO                typefind gsttypefindelement.c:178:gst_type_find_element_have_type:<typefind> found caps application/x-id3, probability=100
0:00:00.393834704   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad typefind:src
0:00:00.393952222   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad typefind:sink
0:00:00.395619185   766   0x46b050 INFO                GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link typefind:src and decodepad0:proxypad1
0:00:00.395781407   766   0x46b050 INFO                GST_PADS gstpad.c:2161:gst_pad_link_full: linked typefind:src and decodepad0:proxypad1, successful
0:00:00.398146296   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3498:autoplug_continue_cb:<playbin20> continue autoplugging group 0x4f13dc for '':decodepad0, application/x-id3: 1
0:00:00.398553630   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3335:autoplug_factories_cb:<playbin20> factories group 0x4f13dc for '':decodepad0, application/x-id3
0:00:00.420618074   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3345:autoplug_factories_cb:<playbin20> found factories 0x5026c0
0:00:00.421111222   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1750:gst_pad_unlink: unlinking typefind:src(0x4fc1a0) and decodepad0:proxypad1(0x5000f0)
0:00:00.421449000   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1795:gst_pad_unlink: unlinked typefind:src and decodepad0:proxypad1
0:00:00.421859630   766   0x46b050 INFO                GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link typefind:src and decodepad0:proxypad1
0:00:00.421988963   766   0x46b050 INFO                GST_PADS gstpad.c:2161:gst_pad_link_full: linked typefind:src and decodepad0:proxypad1, successful
0:00:00.422401408   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3552:autoplug_select_cb:<playbin20> select group 0x4f13dc for '':decodepad0, application/x-id3
0:00:00.422567185   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3555:autoplug_select_cb:<playbin20> checking factory id3demux
0:00:00.422731370   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1750:gst_pad_unlink: unlinking typefind:src(0x4fc1a0) and decodepad0:proxypad1(0x5000f0)
0:00:00.422980444   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1795:gst_pad_unlink: unlinked typefind:src and decodepad0:proxypad1
0:00:00.435039667   766   0x46b050 INFO      GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstid3demux.so" loaded
0:00:00.435301037   766   0x46b050 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:376:gst_element_factory_create: creating element "id3demux"
0:00:00.437182815   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<GstTagDemux@0x5210a0> adding pad 'sink'
0:00:00.437848074   766   0x46b050 INFO                GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link typefind:src and id3demux0:sink
0:00:00.438016370   766   0x46b050 INFO                GST_PADS gstpad.c:2161:gst_pad_link_full: linked typefind:src and id3demux0:sink, successful
0:00:00.438391926   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<id3demux0> completed state change to READY
0:00:00.438555074   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<id3demux0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.438863444   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:972:gst_element_get_static_pad: no such pad 'src' in element "id3demux0"
0:00:00.439283371   766   0x46b050 INFO                 filesrc gstfilesrc.c:953:gst_file_src_start:<source> opening file /media/net/MP3s/70s Disco/Chic - Le Freak.mp3
0:00:00.443258223   766   0x46b050 INFO                 filesrc gstfilesrc.c:953:gst_file_src_start:<source> opening file /media/net/MP3s/70s Disco/Chic - Le Freak.mp3
0:00:00.446837815   766   0x46b050 INFO                id3demux gstid3demux.c:155:gst_id3demux_identify_tag:<id3demux0> Found ID3v2 marker, tag_size = 1627
0:00:00.447270556   766   0x46b050 INFO                 default gstid3tag.c:133:gst_tag_from_id3_tag: Cannot map ID3v2 tag 'WXXX' to GStreamer tag
0:00:00.447891963   766   0x46b050 INFO                 default gstid3tag.c:133:gst_tag_from_id3_tag: Cannot map ID3v2 tag 'TOPE' to GStreamer tag
0:00:00.449341926   766   0x46b050 INFO                 default gstid3tag.c:133:gst_tag_from_id3_tag: Cannot map ID3v2 tag 'TMED' to GStreamer tag
0:00:00.450445445   766   0x46b050 INFO                id3demux gstid3demux.c:155:gst_id3demux_identify_tag:<id3demux0> Found ID3v1 marker, tag_size = 128
0:00:00.464147629   766   0x46b050 INFO       typefindfunctions gsttypefindfunctions.c:1267:mp3_type_find_at_offset: audio/mpeg calculated 100  =  100  *  5 / 5  *  (10000 - 0) / 10000
0:00:00.466733815   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<id3demux0> adding pad 'src'
0:00:00.468359185   766   0x46b050 INFO                GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link id3demux0:src and decodepad1:proxypad2
0:00:00.468511148   766   0x46b050 INFO                GST_PADS gstpad.c:2161:gst_pad_link_full: linked id3demux0:src and decodepad1:proxypad2, successful
0:00:00.468919000   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3498:autoplug_continue_cb:<playbin20> continue autoplugging group 0x4f13dc for '':decodepad1, audio/mpeg, mpegversion=(int)1, layer=(int)3: 1
0:00:00.469852815   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3335:autoplug_factories_cb:<playbin20> factories group 0x4f13dc for '':decodepad1, audio/mpeg, mpegversion=(int)1, layer=(int)3
0:00:00.471382074   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3345:autoplug_factories_cb:<playbin20> found factories 0x4c6c40
0:00:00.471980222   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1750:gst_pad_unlink: unlinking id3demux0:src(0x4fc330) and decodepad1:proxypad2(0x5001c0)
0:00:00.472332297   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1795:gst_pad_unlink: unlinked id3demux0:src and decodepad1:proxypad2
0:00:00.472751370   766   0x46b050 INFO                GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link id3demux0:src and decodepad1:proxypad2
0:00:00.472876111   766   0x46b050 INFO                GST_PADS gstpad.c:2161:gst_pad_link_full: linked id3demux0:src and decodepad1:proxypad2, successful
0:00:00.473292926   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3552:autoplug_select_cb:<playbin20> select group 0x4f13dc for '':decodepad1, audio/mpeg, mpegversion=(int)1, layer=(int)3
0:00:00.473547371   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3555:autoplug_select_cb:<playbin20> checking factory dvbaudiosink
0:00:00.473668667   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3628:autoplug_select_cb:<playbin20> we found a sink
0:00:00.473780667   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3634:autoplug_select_cb:<playbin20> we found an audio sink
0:00:00.473895037   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3677:autoplug_select_cb:<playbin20> we have no pending sink, try to create one
0:00:00.484201185   766   0x46b050 INFO      GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstdvbaudiosink.so" loaded
0:00:00.484444222   766   0x46b050 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:376:gst_element_factory_create: creating element "dvbaudiosink"
0:00:00.485320297   766   0x46b050 INFO            dvbaudiosink gstdvbaudiosink.c:255:gst_dvbaudiosink_base_init: model is DM7020HD set broadcom dts caps
0:00:00.489150259   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<GstBaseSink@0x522250> adding pad 'sink'
0:00:00.489723000   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<dvbaudiosink0> completed state change to READY
0:00:00.489861667   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<dvbaudiosink0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.490086407   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad dvbaudiosink0:sink
0:00:00.490264741   766   0x46b050 INFO            dvbaudiosink gstdvbaudiosink.c:515:gst_dvbaudiosink_acceptcaps:<dvbaudiosink0> gst_dvbaudiosink_acceptcaps!!!!!!!!!!!!!
0:00:00.491358185   766   0x46b050 WARN            dvbaudiosink gstdvbaudiosink.c:539:gst_dvbaudiosink_acceptcaps:<dvbaudiosink0> input stream is unframed && unparsed! can't handle!
0:00:00.491524556   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3700:autoplug_select_cb:<playbin20> remember sink
0:00:00.491645037   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3707:autoplug_select_cb:<playbin20> we found a working sink, expose pad
0:00:00.492156592   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<id3demux0> completed state change to PAUSED
0:00:00.492346371   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<id3demux0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.493015778   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<typefind> completed state change to PAUSED
0:00:00.493155592   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<typefind> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.493479185   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<decodebin20> child 'typefind' changed state to 3(PAUSED) successfully
0:00:00.493721259   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<id3demux0> current PAUSED pending VOID_PENDING, desired next PAUSED
0:00:00.493851889   766   0x46b050 INFO              GST_STATES gstbin.c:2292:gst_bin_element_set_state:<id3demux0> skipping transition from PAUSED to  PAUSED
0:00:00.493981000   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<decodebin20> child 'id3demux0' changed state to 3(PAUSED) successfully
0:00:00.494145629   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<typefind> current PAUSED pending VOID_PENDING, desired next PAUSED
0:00:00.494302593   766   0x46b050 INFO              GST_STATES gstbin.c:2292:gst_bin_element_set_state:<typefind> skipping transition from PAUSED to  PAUSED
0:00:00.494430926   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<decodebin20> child 'typefind' changed state to 3(PAUSED) successfully
0:00:00.494666037   766   0x46b050 INFO              GST_STATES gstbin.c:2593:gst_bin_change_state_func:<uridecodebin0> child 'decodebin20' is changing state asynchronously to PAUSED
0:00:00.494822963   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<source> current NULL pending VOID_PENDING, desired next PAUSED
0:00:00.494988333   766   0x46b050 INFO              GST_STATES gstelement.c:2438:gst_element_continue_state:<source> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:00.495152296   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<source> notifying about state-changed NULL to READY (PAUSED pending)
0:00:00.495441074   766   0x46b050 INFO              GST_STATES gstelement.c:2445:gst_element_continue_state:<source> continue state change READY to PAUSED, final PAUSED
0:00:00.495619889   766   0x46b050 INFO                 filesrc gstfilesrc.c:953:gst_file_src_start:<source> opening file /media/net/MP3s/70s Disco/Chic - Le Freak.mp3
0:00:00.498533519   766   0x46b050 INFO               GST_EVENT gstevent.c:606:gst_event_new_new_segment_full: creating newsegment update 0, rate 1.000000, format bytes, start 0, stop 7962624, position 0
0:00:00.499073741   766   0x46b050 INFO                    task gsttask.c:447:gst_task_set_lock: setting stream lock 0x4f8a60 on task 0x524860
0:00:00.499254741   766   0x46b050 INFO                GST_PADS gstpad.c:5611:gst_pad_start_task:<source:src> created task 0x524860
0:00:00.501693222   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<source> completed state change to PAUSED
0:00:00.501932593   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<source> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.502329482   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<uridecodebin0> child 'source' changed state to 3(PAUSED) successfully
0:00:00.502651259   766   0x46b050 INFO              GST_STATES gstbin.c:2593:gst_bin_change_state_func:<playbin20> child 'uridecodebin0' is changing state asynchronously to PAUSED
0:00:00.502710963   766   0x50f3c0 INFO               GST_EVENT gstevent.c:606:gst_event_new_new_segment_full: creating newsegment update 0, rate 1.000000, format bytes, start 0, stop 7960869, position 0
Pipeline is PREROLLING ...
0:00:00.503396297   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<decodebin20> adding pad 'src0'
0:00:00.505682482   766   0x50f3c0 INFO                GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link decodebin20:src0 and src0:proxypad3
0:00:00.506008593   766   0x50f3c0 INFO                GST_PADS gstpad.c:2161:gst_pad_link_full: linked decodebin20:src0 and src0:proxypad3, successful
0:00:00.506602148   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<uridecodebin0> adding pad 'src0'
0:00:00.507090407   766   0x50f3c0 DEBUG               playbin2 gstplaybin2.c:2801:pad_added_cb:<playbin20> pad uridecodebin0:src0 with caps audio/mpeg, mpegversion=(int)1, layer=(int)3 added in group 0x4f13dc
0:00:00.507545778   766   0x50f3c0 DEBUG               playbin2 gstplaybin2.c:2831:pad_added_cb:<playbin20> creating new input selector
0:00:00.507698296   766   0x50f3c0 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:376:gst_element_factory_create: creating element "input-selector"
0:00:00.509740296   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<GstInputSelector@0x529078> adding pad 'src'
0:00:00.510556556   766   0x50f3c0 DEBUG               playbin2 gstplaybin2.c:2853:pad_added_cb:<playbin20> adding new selector 0x529078
0:00:00.511035222   766   0x50f3c0 INFO              GST_STATES gstelement.c:2438:gst_element_continue_state:<inputselector0> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:00.511336815   766   0x50f3c0 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<inputselector0> notifying about state-changed NULL to READY (PAUSED pending)
0:00:00.511759407   766   0x50f3c0 INFO              GST_STATES gstelement.c:2445:gst_element_continue_state:<inputselector0> continue state change READY to PAUSED, final PAUSED
0:00:00.512052185   766   0x50f3c0 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<inputselector0> completed state change to PAUSED
0:00:00.512366037   766   0x50f3c0 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<inputselector0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.512674333   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad inputselector0:src
0:00:00.512850704   766   0x50f3c0 DEBUG               playbin2 gstplaybin2.c:2872:pad_added_cb:<playbin20> blocking <inputselector0:src>
0:00:00.513904444   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<inputselector0> adding pad 'sink0'
0:00:00.514108148   766   0x50f3c0 DEBUG               playbin2 gstplaybin2.c:2883:pad_added_cb:<playbin20> got pad inputselector0:sink0 from selector
0:00:00.514362037   766   0x50f3c0 DEBUG               playbin2 gstplaybin2.c:2904:pad_added_cb:<playbin20> pad 0x4fe558 added to array
0:00:00.514637815   766   0x50f3c0 INFO                GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link uridecodebin0:src0 and inputselector0:sink0
0:00:00.514980148   766   0x50f3c0 INFO                GST_PADS gstpad.c:2161:gst_pad_link_full: linked uridecodebin0:src0 and inputselector0:sink0, successful
0:00:00.515353260   766   0x50f3c0 DEBUG               playbin2 gstplaybin2.c:2916:pad_added_cb:<playbin20> linked pad uridecodebin0:src0 to selector 0x529078
0:00:00.515818852   766   0x50f3c0 DEBUG               playbin2 gstplaybin2.c:3098:no_more_pads_cb:<playbin20> no more pads in group 0x4f13dc
0:00:00.515950481   766   0x50f3c0 DEBUG               playbin2 gstplaybin2.c:3111:no_more_pads_cb:<playbin20> requesting new sink pad 0
0:00:00.516089667   766   0x50f3c0 DEBUG               playsink gstplaysink.c:4050:gst_play_sink_request_pad:<playsink> request pad type 0
0:00:00.516241000   766   0x50f3c0 LOG                 playsink gstplaysink.c:4052:gst_play_sink_request_pad:<playsink> locking from thread 0x50f3c0
0:00:00.516369630   766   0x50f3c0 LOG                 playsink gstplaysink.c:4052:gst_play_sink_request_pad:<playsink> locked from thread 0x50f3c0
0:00:00.516488297   766   0x50f3c0 LOG                 playsink gstplaysink.c:4058:gst_play_sink_request_pad:<playsink> creating tee
0:00:00.516610852   766   0x50f3c0 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:374:gst_element_factory_create: creating element "tee" named "audiotee"
0:00:00.518615148   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<GstTee@0x52b800> adding pad 'sink'
0:00:00.518976000   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad audiotee:sink
0:00:00.519305926   766   0x50f3c0 INFO              GST_STATES gstelement.c:2438:gst_element_continue_state:<audiotee> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:00.519448000   766   0x50f3c0 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<audiotee> notifying about state-changed NULL to READY (PAUSED pending)
0:00:00.519775926   766   0x50f3c0 INFO              GST_STATES gstelement.c:2445:gst_element_continue_state:<audiotee> continue state change READY to PAUSED, final PAUSED
0:00:00.520049222   766   0x50f3c0 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<audiotee> completed state change to PAUSED
0:00:00.520203074   766   0x50f3c0 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<audiotee> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.520543000   766   0x50f3c0 LOG                 playsink gstplaysink.c:4079:gst_play_sink_request_pad:<playsink> ghosting tee sinkpad
0:00:00.521547148   766   0x50f3c0 INFO                GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link audio_sink:proxypad4 and audiotee:sink
0:00:00.521699556   766   0x50f3c0 INFO                GST_PADS gstpad.c:2161:gst_pad_link_full: linked audio_sink:proxypad4 and audiotee:sink, successful
0:00:00.521881481   766   0x50f3c0 LOG                 playsink gstplaysink.c:4129:gst_play_sink_request_pad:<playsink> unlocking from thread 0x50f3c0
0:00:00.522076407   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<playsink> adding pad 'audio_sink'
0:00:00.522422371   766   0x50f3c0 INFO                GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link inputselector0:src and playsink:audio_sink
0:00:00.522721370   766   0x50f3c0 INFO                GST_PADS gstpad.c:2161:gst_pad_link_full: linked inputselector0:src and playsink:audio_sink, successful
0:00:00.523045518   766   0x50f3c0 DEBUG               playbin2 gstplaybin2.c:3127:no_more_pads_cb:<playbin20> linked type audio/, result: 0
0:00:00.523173481   766   0x50f3c0 DEBUG               playbin2 gstplaybin2.c:3136:no_more_pads_cb:<playbin20> pending 1 > 0
0:00:00.523321260   766   0x50f3c0 LOG                 playbin2 gstplaybin2.c:3147:no_more_pads_cb:<playbin20> last group complete
0:00:00.523437074   766   0x50f3c0 INFO                playbin2 gstplaybin2.c:3161:no_more_pads_cb:<playbin20> setting custom audio sink <dvbaudiosink0>
0:00:00.523567408   766   0x50f3c0 LOG                 playsink gstplaysink.c:854:gst_play_sink_set_sink: Setting sink <dvbaudiosink0> as sink type 0
0:00:00.523722519   766   0x50f3c0 LOG                 playsink gstplaysink.c:856:gst_play_sink_set_sink:<playsink> locking from thread 0x50f3c0
0:00:00.523848815   766   0x50f3c0 LOG                 playsink gstplaysink.c:856:gst_play_sink_set_sink:<playsink> locked from thread 0x50f3c0
0:00:00.523972704   766   0x50f3c0 LOG                 playsink gstplaysink.c:878:gst_play_sink_set_sink:<playsink> unlocking from thread 0x50f3c0
0:00:00.524145000   766   0x50f3c0 INFO               GST_EVENT gstevent.c:1277:gst_event_new_sink_message: creating sink-message event
0:00:00.524382519   766   0x50f3c0 DEBUG               playbin2 gstplaybin2.c:3231:no_more_pads_cb:<playbin20> unblocking <inputselector0:src>
0:00:00.524721963   766   0x50f3c0 INFO              GST_STATES gstbin.c:3003:bin_handle_async_done:<decodebin20> committing state from READY to PAUSED, old pending PAUSED
0:00:00.524852852   766   0x50f3c0 INFO              GST_STATES gstbin.c:3023:bin_handle_async_done:<decodebin20> completed state change, pending VOID
0:00:00.524969111   766   0x50f3c0 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<decodebin20> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.525416037   766   0x50f3c0 INFO              GST_STATES gstbin.c:3003:bin_handle_async_done:<uridecodebin0> committing state from READY to PAUSED, old pending PAUSED
0:00:00.525597593   766   0x50f3c0 INFO              GST_STATES gstbin.c:3023:bin_handle_async_done:<uridecodebin0> completed state change, pending VOID
0:00:00.525728148   766   0x50f3c0 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<uridecodebin0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.525995518   766   0x50f3c0 DEBUG               playbin2 gstplaybin2.c:2507:gst_play_bin_handle_message:<playbin20> Ignoring async state change of uridecodebin: uridecodebin0
0:00:00.526860963   766   0x50f3c0 LOG                 playsink gstplaysink.c:3871:sinkpad_blocked_cb:<playsink> locking from thread 0x50f3c0
0:00:00.527019555   766   0x50f3c0 LOG                 playsink gstplaysink.c:3871:sinkpad_blocked_cb:<playsink> locked from thread 0x50f3c0
0:00:00.527149259   766   0x50f3c0 DEBUG               playsink gstplaysink.c:3883:sinkpad_blocked_cb:<playsink:audio_sink> Audio pad blocked: 1
0:00:00.527312111   766   0x50f3c0 DEBUG               playsink gstplaysink.c:3912:sinkpad_blocked_cb:<playsink> All pads blocked -- reconfiguring
0:00:00.527613778   766   0x50f3c0 DEBUG               playsink gstplaysink.c:3923:sinkpad_blocked_cb:<playsink> Audio pad is raw: 0
0:00:00.527733778   766   0x50f3c0 DEBUG               playsink gstplaysink.c:3052:gst_play_sink_do_reconfigure:<playsink> reconfiguring
0:00:00.527847852   766   0x50f3c0 LOG                 playsink gstplaysink.c:3057:gst_play_sink_do_reconfigure:<playsink> locking from thread 0x50f3c0
0:00:00.528002926   766   0x50f3c0 LOG                 playsink gstplaysink.c:3057:gst_play_sink_do_reconfigure:<playsink> locked from thread 0x50f3c0
0:00:00.528124444   766   0x50f3c0 DEBUG               playsink gstplaysink.c:3115:gst_play_sink_do_reconfigure:<playsink> audio:1, video:0, vis:0, text:0
0:00:00.528278445   766   0x50f3c0 DEBUG               playsink gstplaysink.c:3241:gst_play_sink_do_reconfigure:<playsink> no video needed
0:00:00.528392926   766   0x50f3c0 DEBUG               playsink gstplaysink.c:3304:gst_play_sink_do_reconfigure:<playsink> adding audio
0:00:00.528504445   766   0x50f3c0 DEBUG               playsink gstplaysink.c:3352:gst_play_sink_do_reconfigure:<playsink> creating new audio chain
0:00:00.528635815   766   0x50f3c0 DEBUG               playsink gstplaysink.c:2558:gen_audio_chain:<playsink> making audio chain 0x4c5270
0:00:00.528755963   766   0x50f3c0 DEBUG               playsink gstplaysink.c:2562:gen_audio_chain:<playsink> trying configured audiosink <dvbaudiosink0>
0:00:00.528941518   766   0x50f3c0 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<dvbaudiosink0> completed state change to READY
0:00:00.529083259   766   0x50f3c0 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:374:gst_element_factory_create: creating element "bin" named "abin"
0:00:00.529819519   766   0x50f3c0 DEBUG               playsink gstplaysink.c:2592:gen_audio_chain:<playsink> adding audio queue
0:00:00.529958000   766   0x50f3c0 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:374:gst_element_factory_create: creating element "queue" named "aqueue"
0:00:00.532557185   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<GstQueue@0x52d028> adding pad 'sink'
0:00:00.533360482   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<GstQueue@0x52d028> adding pad 'src'
0:00:00.533868963   766   0x50f3c0 DEBUG               playsink gstplaysink.c:1218:element_has_property:<dvbaudiosink0> has ts-offset property of type gint64
0:00:00.534016963   766   0x50f3c0 DEBUG               playsink gstplaysink.c:1211:element_has_property:<dvbaudiosink0> no volume property
0:00:00.534136815   766   0x50f3c0 DEBUG               playsink gstplaysink.c:2653:gen_audio_chain:<playsink> the sink has no volume property
0:00:00.534286963   766   0x50f3c0 DEBUG               playsink gstplaysink.c:2665:gen_audio_chain:<playsink> creating audioconvert with use-converters 1, use-volume 1
0:00:00.537043704   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<GstPlaySinkConvertBin@0x52e038> adding pad 'sink'
0:00:00.538454519   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<GstPlaySinkConvertBin@0x52e038> adding pad 'src'
0:00:00.538642037   766   0x50f3c0 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:374:gst_element_factory_create: creating element "identity" named "identity"
0:00:00.540703593   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<GstBaseTransform@0x530108> adding pad 'sink'
0:00:00.541190111   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<GstBaseTransform@0x530108> adding pad 'src'
0:00:00.576554667   766   0x50f3c0 INFO      GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstvolume.so" loaded
0:00:00.576739407   766   0x50f3c0 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:374:gst_element_factory_create: creating element "volume" named "volume"
0:00:00.580501445   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<GstBaseTransform@0x594248> adding pad 'sink'
0:00:00.580995370   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<GstBaseTransform@0x594248> adding pad 'src'
0:00:00.581452185   766   0x50f3c0 DEBUG   playsinkaudioconvert gstplaysinkaudioconvert.c:55:gst_play_sink_audio_convert_add_conversion_elements:<GstPlaySinkAudioConvert@0x52e038> Building audio conversion with use-converters 0, use-volume 0
0:00:00.581623222   766   0x50f3c0 WARN      playsinkconvertbin gstplaysinkconvertbin.c:479:gst_play_sink_convert_bin_cache_converter_caps:<GstPlaySinkAudioConvert@0x52e038> No conversion elements
0:00:00.581797704   766   0x50f3c0 LOG     playsinkaudioconvert gstplaysinkaudioconvert.c:111:gst_play_sink_audio_convert_set_property:<aconv> locking from thread 0x50f3c0
0:00:00.581922074   766   0x50f3c0 LOG     playsinkaudioconvert gstplaysinkaudioconvert.c:111:gst_play_sink_audio_convert_set_property:<aconv> locked from thread 0x50f3c0
0:00:00.582041815   766   0x50f3c0 DEBUG   playsinkaudioconvert gstplaysinkaudioconvert.c:133:gst_play_sink_audio_convert_set_property:<aconv> Rebuilding converter bin
0:00:00.582157148   766   0x50f3c0 DEBUG   playsinkaudioconvert gstplaysinkaudioconvert.c:55:gst_play_sink_audio_convert_add_conversion_elements:<aconv> Building audio conversion with use-converters 1, use-volume 0
0:00:00.609697704   766   0x50f3c0 INFO      GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstaudioconvert.so" loaded
0:00:00.609879296   766   0x50f3c0 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:374:gst_element_factory_create: creating element "audioconvert" named "conv"
0:00:00.615533000   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<GstBaseTransform@0x6a4040> adding pad 'sink'
0:00:00.616034815   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<GstBaseTransform@0x6a4040> adding pad 'src'
0:00:00.644951185   766   0x50f3c0 INFO           audioresample gstaudioresample.c:1557:_benchmark_integer_resampling: Using integer resampler if appropriate: 622303.600000 < 1040163.857143
0:00:00.645378408   766   0x50f3c0 INFO      GST_PLUGIN_LOADING gstplugin.c:859:gst_plugin_load_file: plugin "/usr/lib/gstreamer-0.10/libgstaudioresample.so" loaded
0:00:00.645511445   766   0x50f3c0 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:374:gst_element_factory_create: creating element "audioresample" named "resample"
0:00:00.650099555   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<GstBaseTransform@0x6aa0d0> adding pad 'sink'
0:00:00.650682556   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<GstBaseTransform@0x6aa0d0> adding pad 'src'
0:00:00.651719556   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstutils.c:1698:gst_element_link_pads_full: trying to link element conv:src to element resample:sink
0:00:00.651888593   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad conv:src
0:00:00.652002444   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad resample:sink
0:00:00.652106444   766   0x50f3c0 INFO                GST_PADS gstutils.c:1596:prepare_link_maybe_ghosting: conv and resample in same bin, no need for ghost pads
0:00:00.652392741   766   0x50f3c0 INFO                GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link conv:src and resample:sink
0:00:00.652598185   766   0x50f3c0 INFO                GST_PADS gstpad.c:2161:gst_pad_link_full: linked conv:src and resample:sink, successful
0:00:00.652903481   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad conv:sink
0:00:00.682259037   766   0x50f3c0 INFO      playsinkconvertbin gstplaysinkconvertbin.c:492:gst_play_sink_convert_bin_cache_converter_caps:<aconv> Converter caps: audio/x-raw-float, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], endianness=(int)1234, width=(int)64; audio/x-raw-float, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], endianness=(int)1234, width=(int)32; audio/x-raw-int, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], endianness=(int)1234, width=(int)32, depth=(int)32, signed=(boolean)true; audio/x-raw-int, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 11 ], endianness=(int)1234, width=(int)24, depth=(int)24, signed=(boolean)true; audio/x-raw-int, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], endianness=(int)1234, width=(int)24, depth=(int)24, signed=(boolean)true; audio/x-raw-int, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 11 ], endianness=(int)1234, width=(int)16, depth=(int)16, signed=(boolean)true; audio/x-raw-int, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], endianness=(int)1234, width=(int)16, depth=(int)16, signed=(boolean)true; audio/x-raw-int, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 11 ], endianness=(int)1234, width=(int)8, depth=(int)8, signed=(boolean)true; audio/x-raw-int, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], endianness=(int)1234, width=(int)8, depth=(int)8, signed=(boolean)true; audio/x-raw-float, width=(int)64, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], endianness=(int){ 1234, 4321 }; audio/x-raw-float, width=(int)32, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], endianness=(int){ 1234, 4321 }; audio/x-raw-int, width=(int)32, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], endianness=(int){ 1234, 4321 }, signed=(boolean){ true, false }, depth=(int)[ 1, 32 ]; audio/x-raw-int, width=(int)24, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 11 ], endianness=(int){ 1234, 4321 }, depth=(int)[ 1, 24 ], signed=(boolean){ true, false }; audio/x-raw-int, width=(int)16, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 11 ], endianness=(int){ 1234, 4321 }, depth=(int)[ 1, 16 ], signed=(boolean){ true, false }; audio/x-raw-int, width=(int)8, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 11 ], endianness=(int){ 1234, 4321 }, depth=(int)[ 1, 8 ], signed=(boolean){ true, false }; audio/x-raw-int, width=(int)24, depth=(int)24, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], endianness=(int){ 1234, 4321 }, signed=(boolean){ true, false }; audio/x-raw-int, width=(int)16, depth=(int)16, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], endianness=(int){ 1234, 4321 }, signed=(boolean){ true, false }; audio/x-raw-int, width=(int)8, depth=(int)8, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], endianness=(int){ 1234, 4321 }, signed=(boolean){ true, false }
0:00:00.686132592   766   0x50f3c0 LOG     playsinkaudioconvert gstplaysinkaudioconvert.c:139:gst_play_sink_audio_convert_set_property:<aconv> unlocking from thread 0x50f3c0
0:00:00.686313556   766   0x50f3c0 LOG     playsinkaudioconvert gstplaysinkaudioconvert.c:111:gst_play_sink_audio_convert_set_property:<aconv> locking from thread 0x50f3c0
0:00:00.686434185   766   0x50f3c0 LOG     playsinkaudioconvert gstplaysinkaudioconvert.c:111:gst_play_sink_audio_convert_set_property:<aconv> locked from thread 0x50f3c0
0:00:00.686551111   766   0x50f3c0 DEBUG   playsinkaudioconvert gstplaysinkaudioconvert.c:133:gst_play_sink_audio_convert_set_property:<aconv> Rebuilding converter bin
0:00:00.686696778   766   0x50f3c0 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<conv> completed state change to NULL
0:00:00.686844259   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstpad.c:1750:gst_pad_unlink: unlinking conv:src(0x52a588) and resample:sink(0x52a650)
0:00:00.687114222   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstpad.c:1795:gst_pad_unlink: unlinked conv:src and resample:sink
0:00:00.687469667   766   0x50f3c0 INFO           GST_PARENTAGE gstbin.c:1465:gst_bin_remove_func:<aconv> removed child "conv"
0:00:00.687695556   766   0x50f3c0 INFO         GST_REFCOUNTING gstelement.c:3067:gst_element_dispose:<conv> dispose
0:00:00.687845407   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<conv> removing pad 'src'
0:00:00.688036555   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<conv> removing pad 'sink'
0:00:00.688252334   766   0x50f3c0 INFO         GST_REFCOUNTING gstelement.c:3091:gst_element_dispose:<conv> parent class dispose
0:00:00.688448593   766   0x50f3c0 INFO         GST_REFCOUNTING gstelement.c:3122:gst_element_finalize:<conv> finalize
0:00:00.688598148   766   0x50f3c0 INFO         GST_REFCOUNTING gstelement.c:3133:gst_element_finalize:<conv> finalize parent
0:00:00.688751370   766   0x50f3c0 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<resample> completed state change to NULL
0:00:00.688924111   766   0x50f3c0 INFO           GST_PARENTAGE gstbin.c:1465:gst_bin_remove_func:<aconv> removed child "resample"
0:00:00.689107000   766   0x50f3c0 INFO         GST_REFCOUNTING gstelement.c:3067:gst_element_dispose:<resample> dispose
0:00:00.689249667   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<resample> removing pad 'src'
0:00:00.689438889   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<resample> removing pad 'sink'
0:00:00.689619741   766   0x50f3c0 INFO         GST_REFCOUNTING gstelement.c:3091:gst_element_dispose:<resample> parent class dispose
0:00:00.689823148   766   0x50f3c0 INFO         GST_REFCOUNTING gstelement.c:3122:gst_element_finalize:<resample> finalize
0:00:00.689942000   766   0x50f3c0 INFO         GST_REFCOUNTING gstelement.c:3133:gst_element_finalize:<resample> finalize parent
0:00:00.690390593   766   0x50f3c0 DEBUG   playsinkaudioconvert gstplaysinkaudioconvert.c:55:gst_play_sink_audio_convert_add_conversion_elements:<aconv> Building audio conversion with use-converters 1, use-volume 1
0:00:00.690527148   766   0x50f3c0 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:374:gst_element_factory_create: creating element "audioconvert" named "conv"
0:00:00.691549556   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<GstBaseTransform@0x6a42c8> adding pad 'sink'
0:00:00.692052481   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<GstBaseTransform@0x6a42c8> adding pad 'src'
0:00:00.692489037   766   0x50f3c0 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:374:gst_element_factory_create: creating element "audioresample" named "resample"
0:00:00.693032778   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<GstBaseTransform@0x6aa330> adding pad 'sink'
0:00:00.693514185   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<GstBaseTransform@0x6aa330> adding pad 'src'
0:00:00.693929778   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstutils.c:1698:gst_element_link_pads_full: trying to link element conv:src to element resample:sink
0:00:00.694055444   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad conv:src
0:00:00.694165037   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad resample:sink
0:00:00.694298445   766   0x50f3c0 INFO                GST_PADS gstutils.c:1596:prepare_link_maybe_ghosting: conv and resample in same bin, no need for ghost pads
0:00:00.694535482   766   0x50f3c0 INFO                GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link conv:src and resample:sink
0:00:00.694732815   766   0x50f3c0 INFO                GST_PADS gstpad.c:2161:gst_pad_link_full: linked conv:src and resample:sink, successful
0:00:00.695151852   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstutils.c:1698:gst_element_link_pads_full: trying to link element resample:src to element volume:sink
0:00:00.695303519   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad resample:src
0:00:00.695416334   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad volume:sink
0:00:00.695519111   766   0x50f3c0 INFO                GST_PADS gstutils.c:1596:prepare_link_maybe_ghosting: resample and volume in same bin, no need for ghost pads
0:00:00.695735926   766   0x50f3c0 INFO                GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link resample:src and volume:sink
0:00:00.695899556   766   0x50f3c0 INFO                GST_PADS gstpad.c:2161:gst_pad_link_full: linked resample:src and volume:sink, successful
0:00:00.696191518   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad conv:sink
0:00:00.731908852   766   0x50f3c0 INFO      playsinkconvertbin gstplaysinkconvertbin.c:492:gst_play_sink_convert_bin_cache_converter_caps:<aconv> Converter caps: audio/x-raw-float, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], endianness=(int)1234, width=(int)64; audio/x-raw-float, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], endianness=(int)1234, width=(int)32; audio/x-raw-int, channels=(int)[ 1, 11 ], rate=(int)[ 1, 2147483647 ], endianness=(int)1234, width=(int)8, depth=(int)8, signed=(boolean)true; audio/x-raw-int, channels=(int)[ 1, 2147483647 ], rate=(int)[ 1, 2147483647 ], endianness=(int)1234, width=(int)8, depth=(int)8, signed=(boolean)true; audio/x-raw-int, channels=(int)[ 1, 11 ], rate=(int)[ 1, 2147483647 ], endianness=(int)1234, width=(int)16, depth=(int)16, signed=(boolean)true; audio/x-raw-int, channels=(int)[ 1, 2147483647 ], rate=(int)[ 1, 2147483647 ], endianness=(int)1234, width=(int)16, depth=(int)16, signed=(boolean)true; audio/x-raw-int, channels=(int)[ 1, 11 ], rate=(int)[ 1, 2147483647 ], endianness=(int)1234, width=(int)24, depth=(int)24, signed=(boolean)true; audio/x-raw-int, channels=(int)[ 1, 2147483647 ], rate=(int)[ 1, 2147483647 ], endianness=(int)1234, width=(int)24, depth=(int)24, signed=(boolean)true; audio/x-raw-int, channels=(int)[ 1, 2147483647 ], rate=(int)[ 1, 2147483647 ], endianness=(int)1234, width=(int)32, depth=(int)32, signed=(boolean)true; audio/x-raw-float, width=(int)64, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], endianness=(int){ 1234, 4321 }; audio/x-raw-float, width=(int)32, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], endianness=(int){ 1234, 4321 }; audio/x-raw-int, width=(int)32, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], endianness=(int){ 1234, 4321 }, signed=(boolean){ true, false }, depth=(int)[ 1, 32 ]; audio/x-raw-int, width=(int)24, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 11 ], endianness=(int){ 1234, 4321 }, depth=(int)[ 1, 24 ], signed=(boolean){ true, false }; audio/x-raw-int, width=(int)16, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 11 ], endianness=(int){ 1234, 4321 }, depth=(int)[ 1, 16 ], signed=(boolean){ true, false }; audio/x-raw-int, width=(int)8, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 11 ], endianness=(int){ 1234, 4321 }, depth=(int)[ 1, 8 ], signed=(boolean){ true, false }; audio/x-raw-int, width=(int)8, depth=(int)8, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], endianness=(int){ 1234, 4321 }, signed=(boolean){ true, false }; audio/x-raw-int, width=(int)16, depth=(int)16, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], endianness=(int){ 1234, 4321 }, signed=(boolean){ true, false }; audio/x-raw-int, width=(int)24, depth=(int)24, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], endianness=(int){ 1234, 4321 }, signed=(boolean){ true, false }
0:00:00.735826111   766   0x50f3c0 LOG     playsinkaudioconvert gstplaysinkaudioconvert.c:139:gst_play_sink_audio_convert_set_property:<aconv> unlocking from thread 0x50f3c0
0:00:00.736386000   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstutils.c:1698:gst_element_link_pads_full: trying to link element aqueue:src to element aconv:sink
0:00:00.736521778   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad aqueue:src
0:00:00.736633185   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad aconv:sink
0:00:00.736733259   766   0x50f3c0 INFO                GST_PADS gstutils.c:1596:prepare_link_maybe_ghosting: aqueue and aconv in same bin, no need for ghost pads
0:00:00.736965000   766   0x50f3c0 INFO                GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link aqueue:src and aconv:sink
0:00:00.737122296   766   0x50f3c0 INFO                GST_PADS gstpad.c:2161:gst_pad_link_full: linked aqueue:src and aconv:sink, successful
0:00:00.738280778   766   0x50f3c0 DEBUG               playsink gstplaysink.c:2706:gen_audio_chain:<playsink> linking to sink
0:00:00.738440482   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstutils.c:1698:gst_element_link_pads_full: trying to link element aconv:src to element dvbaudiosink0:(any)
0:00:00.738564445   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad aconv:src
0:00:00.738708222   766   0x50f3c0 INFO                GST_PADS gstutils.c:1032:gst_pad_check_link: trying to link aconv:src and dvbaudiosink0:sink
0:00:00.738859000   766   0x50f3c0 LOG       playsinkconvertbin gstplaysinkconvertbin.c:380:gst_play_sink_convert_bin_getcaps:<aconv> locking from thread 0x50f3c0
0:00:00.739041000   766   0x50f3c0 LOG       playsinkconvertbin gstplaysinkconvertbin.c:380:gst_play_sink_convert_bin_getcaps:<aconv> locked from thread 0x50f3c0
0:00:00.739253778   766   0x50f3c0 LOG       playsinkconvertbin gstplaysinkconvertbin.c:408:gst_play_sink_convert_bin_getcaps:<aconv> unlocking from thread 0x50f3c0
0:00:00.739382408   766   0x50f3c0 DEBUG     playsinkconvertbin gstplaysinkconvertbin.c:412:gst_play_sink_convert_bin_getcaps:<aconv:src> Returning caps ANY
0:00:00.739973185   766   0x50f3c0 INFO                GST_PADS gstutils.c:1596:prepare_link_maybe_ghosting: aconv and dvbaudiosink0 in same bin, no need for ghost pads
0:00:00.740246445   766   0x50f3c0 INFO                GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link aconv:src and dvbaudiosink0:sink
0:00:00.740393852   766   0x50f3c0 INFO                GST_PADS gstpad.c:2161:gst_pad_link_full: linked aconv:src and dvbaudiosink0:sink, successful
0:00:00.740705074   766   0x50f3c0 DEBUG               playsink gstplaysink.c:2719:gen_audio_chain:<playsink> ghosting sink pad
0:00:00.740815444   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad aqueue:sink
0:00:00.741732370   766   0x50f3c0 INFO                GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link sink:proxypad7 and aqueue:sink
0:00:00.741879704   766   0x50f3c0 INFO                GST_PADS gstpad.c:2161:gst_pad_link_full: linked sink:proxypad7 and aqueue:sink, successful
0:00:00.742023667   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<abin> adding pad 'sink'
0:00:00.743588259   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<streamsynchronizer0> adding pad 'src_0'
0:00:00.743771704   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<streamsynchronizer0> adding pad 'sink_0'
0:00:00.743957481   766   0x50f3c0 DEBUG               playsink gstplaysink.c:3372:gst_play_sink_do_reconfigure:<playsink> adding audio chain
0:00:00.744453037   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:728:gst_element_add_pad:<audiotee> adding pad 'src0'
0:00:00.745181815   766   0x50f3c0 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<dvbaudiosink0> current READY pending VOID_PENDING, desired next READY
0:00:00.745363556   766   0x50f3c0 INFO              GST_STATES gstbin.c:2292:gst_bin_element_set_state:<dvbaudiosink0> skipping transition from READY to  READY
0:00:00.745490593   766   0x50f3c0 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<abin> child 'dvbaudiosink0' changed state to 2(READY) successfully
0:00:00.745674741   766   0x50f3c0 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<aconv> current NULL pending VOID_PENDING, desired next READY
0:00:00.745984593   766   0x50f3c0 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<volume> current NULL pending VOID_PENDING, desired next READY
0:00:00.746149148   766   0x50f3c0 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<volume> completed state change to READY
0:00:00.746303741   766   0x50f3c0 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<volume> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.746984963   766   0x50f3c0 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<aconv> child 'volume' changed state to 2(READY) successfully
0:00:00.747265297   766   0x50f3c0 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<resample> current NULL pending VOID_PENDING, desired next READY
0:00:00.747420297   766   0x50f3c0 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<resample> completed state change to READY
0:00:00.747538334   766   0x50f3c0 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<resample> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.748191370   766   0x50f3c0 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<aconv> child 'resample' changed state to 2(READY) successfully
0:00:00.748405519   766   0x50f3c0 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<conv> current NULL pending VOID_PENDING, desired next READY
0:00:00.748556889   766   0x50f3c0 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<conv> completed state change to READY
0:00:00.748678148   766   0x50f3c0 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<conv> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.749376667   766   0x50f3c0 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<aconv> child 'conv' changed state to 2(READY) successfully
0:00:00.749569185   766   0x50f3c0 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<identity> current NULL pending VOID_PENDING, desired next READY
0:00:00.749726926   766   0x50f3c0 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<identity> completed state change to READY
0:00:00.749846741   766   0x50f3c0 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<identity> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.750151222   766   0x50f3c0 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<aconv> child 'identity' changed state to 2(READY) successfully
0:00:00.750464074   766   0x50f3c0 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<aconv> completed state change to READY
0:00:00.750599148   766   0x50f3c0 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<aconv> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.750916074   766   0x50f3c0 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<abin> child 'aconv' changed state to 2(READY) successfully
0:00:00.751165296   766   0x50f3c0 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<aqueue> current NULL pending VOID_PENDING, desired next READY
0:00:00.751455852   766   0x50f3c0 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<aqueue> completed state change to READY
0:00:00.751612408   766   0x50f3c0 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<aqueue> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.751965630   766   0x50f3c0 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<abin> child 'aqueue' changed state to 2(READY) successfully
0:00:00.752186148   766   0x50f3c0 INFO              GST_STATES gstelement.c:2438:gst_element_continue_state:<abin> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:00.752400963   766   0x50f3c0 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<abin> notifying about state-changed NULL to READY (PAUSED pending)
0:00:00.752675296   766   0x50f3c0 INFO              GST_STATES gstelement.c:2445:gst_element_continue_state:<abin> continue state change READY to PAUSED, final PAUSED
0:00:00.753080815   766   0x50f3c0 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<dvbaudiosink0> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.760385667   766   0x50f3c0 INFO              GST_STATES gstbin.c:2593:gst_bin_change_state_func:<abin> child 'dvbaudiosink0' is changing state asynchronously to PAUSED
0:00:00.760682074   766   0x50f3c0 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<aconv> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.760841074   766   0x50f3c0 LOG       playsinkconvertbin gstplaysinkconvertbin.c:513:gst_play_sink_convert_bin_change_state:<aconv> locking from thread 0x50f3c0
0:00:00.760970518   766   0x50f3c0 LOG       playsinkconvertbin gstplaysinkconvertbin.c:513:gst_play_sink_convert_bin_change_state:<aconv> locked from thread 0x50f3c0
0:00:00.761093074   766   0x50f3c0 DEBUG     playsinkconvertbin gstplaysinkconvertbin.c:162:gst_play_sink_convert_bin_set_targets:<aconv> Setting pad targets with passthrough 1
0:00:00.761243963   766   0x50f3c0 DEBUG     playsinkconvertbin gstplaysinkconvertbin.c:165:gst_play_sink_convert_bin_set_targets:<aconv> no conversion elements, using identity (0x530108) as head/tail
0:00:00.761361704   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad identity:sink
0:00:00.761490297   766   0x50f3c0 DEBUG     playsinkconvertbin gstplaysinkconvertbin.c:182:gst_play_sink_convert_bin_set_targets:<aconv> Ghosting bin sink pad to <identity:sink>
0:00:00.761638482   766   0x50f3c0 INFO                GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link sink:proxypad5 and identity:sink
0:00:00.761774926   766   0x50f3c0 INFO                GST_PADS gstpad.c:2161:gst_pad_link_full: linked sink:proxypad5 and identity:sink, successful
0:00:00.761896667   766   0x50f3c0 INFO        GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad identity:src
0:00:00.762018815   766   0x50f3c0 DEBUG     playsinkconvertbin gstplaysinkconvertbin.c:187:gst_play_sink_convert_bin_set_targets:<aconv> Ghosting bin src pad to <identity:src>
0:00:00.762312186   766   0x50f3c0 INFO                GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link identity:src and src:proxypad6
0:00:00.762447778   766   0x50f3c0 INFO                GST_PADS gstpad.c:2161:gst_pad_link_full: linked identity:src and src:proxypad6, successful
0:00:00.762751778   766   0x50f3c0 LOG       playsinkconvertbin gstplaysinkconvertbin.c:517:gst_play_sink_convert_bin_change_state:<aconv> unlocking from thread 0x50f3c0
0:00:00.763100148   766   0x50f3c0 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<volume> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.763367297   766   0x50f3c0 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<volume> completed state change to PAUSED
0:00:00.763488408   766   0x50f3c0 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<volume> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.764188889   766   0x50f3c0 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<aconv> child 'volume' changed state to 3(PAUSED) successfully
0:00:00.764422815   766   0x50f3c0 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<resample> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.764746667   766   0x50f3c0 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<resample> completed state change to PAUSED
0:00:00.764872000   766   0x50f3c0 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<resample> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.765388037   766   0x50f3c0 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<aconv> child 'resample' changed state to 3(PAUSED) successfully
0:00:00.765622704   766   0x50f3c0 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<conv> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.765949296   766   0x50f3c0 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<conv> completed state change to PAUSED
0:00:00.766077481   766   0x50f3c0 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<conv> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.766492445   766   0x50f3c0 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<aconv> child 'conv' changed state to 3(PAUSED) successfully
0:00:00.766727852   766   0x50f3c0 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<identity> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.767002111   766   0x50f3c0 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<identity> completed state change to PAUSED
0:00:00.767130741   766   0x50f3c0 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<identity> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.767482704   766   0x50f3c0 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<aconv> child 'identity' changed state to 3(PAUSED) successfully
0:00:00.767803519   766   0x50f3c0 LOG       playsinkconvertbin gstplaysinkconvertbin.c:536:gst_play_sink_convert_bin_change_state:<aconv> locking from thread 0x50f3c0
0:00:00.767943741   766   0x50f3c0 LOG       playsinkconvertbin gstplaysinkconvertbin.c:536:gst_play_sink_convert_bin_change_state:<aconv> locked from thread 0x50f3c0
0:00:00.768076592   766   0x50f3c0 LOG       playsinkconvertbin gstplaysinkconvertbin.c:540:gst_play_sink_convert_bin_change_state:<aconv> unlocking from thread 0x50f3c0
0:00:00.768202148   766   0x50f3c0 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<aconv> completed state change to PAUSED
0:00:00.768370519   766   0x50f3c0 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<aconv> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.768709815   766   0x50f3c0 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<abin> child 'aconv' changed state to 3(PAUSED) successfully
0:00:00.768942667   766   0x50f3c0 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<aqueue> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.769379260   766   0x50f3c0 INFO                    task gsttask.c:447:gst_task_set_lock: setting stream lock 0x502da0 on task 0x524f48
0:00:00.769524334   766   0x50f3c0 INFO                GST_PADS gstpad.c:5611:gst_pad_start_task:<aqueue:src> created task 0x524f48
0:00:00.770666111   766   0x50f3c0 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<aqueue> completed state change to PAUSED
0:00:00.770886148   766   0x50f3c0 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<aqueue> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.771407593   766   0x50f3c0 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<abin> child 'aqueue' changed state to 3(PAUSED) successfully
0:00:00.771995296   766   0x50f3c0 INFO                GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link audiotee:src0 and streamsynchronizer0:sink_0
0:00:00.772192259   766   0x50f3c0 INFO                GST_PADS gstpad.c:2161:gst_pad_link_full: linked audiotee:src0 and streamsynchronizer0:sink_0, successful
0:00:00.772811148   766   0x50f3c0 INFO                GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link streamsynchronizer0:src_0 and abin:sink
0:00:00.773007926   766   0x50f3c0 INFO                GST_PADS gstpad.c:2161:gst_pad_link_full: linked streamsynchronizer0:src_0 and abin:sink, successful
0:00:00.773419074   766   0x50f3c0 DEBUG               playsink gstplaysink.c:3448:gst_play_sink_do_reconfigure:<playsink> no vis needed
0:00:00.773548963   766   0x50f3c0 DEBUG               playsink gstplaysink.c:3520:gst_play_sink_do_reconfigure:<playsink> no text needed
0:00:00.773665259   766   0x50f3c0 LOG                 playsink gstplaysink.c:3709:update_av_offset:<playsink> no ts_offset elements
0:00:00.773782889   766   0x50f3c0 INFO                playsink gstplaysink.c:1329:do_async_done:<playsink> Sending async_done message
0:00:00.773995518   766   0x50f3c0 LOG                 playsink gstplaysink.c:3566:gst_play_sink_do_reconfigure:<playsink> unlocking from thread 0x50f3c0
0:00:00.774143555   766   0x50f3c0 LOG                 playsink gstplaysink.c:3955:sinkpad_blocked_cb:<playsink> unlocking from thread 0x50f3c0
0:00:00.774309741   766   0x50f3c0 LOG                 playsink gstplaysink.c:3871:sinkpad_blocked_cb:<playsink> locking from thread 0x50f3c0
0:00:00.774432371   766   0x50f3c0 LOG                 playsink gstplaysink.c:3871:sinkpad_blocked_cb:<playsink> locked from thread 0x50f3c0
0:00:00.774557815   766   0x50f3c0 DEBUG               playsink gstplaysink.c:3883:sinkpad_blocked_cb:<playsink:audio_sink> Audio pad blocked: 0
0:00:00.774679889   766   0x50f3c0 LOG                 playsink gstplaysink.c:3897:sinkpad_blocked_cb:<playsink> unlocking from thread 0x50f3c0
0:00:00.774823000   766   0x50f3c0 INFO                GST_PADS gstpad.c:3554:gst_pad_event_default_dispatch:<audiotee:sink> Sending event 0x4f0950 (newsegment) to all internally linked pads
0:00:00.775372630   766   0x50f3c0 INFO                GST_PADS gstpad.c:3554:gst_pad_event_default_dispatch:<audiotee:sink> Sending event 0x4c7678 (sink-message) to all internally linked pads
0:00:00.775359148   766   0x6ad3e8 LOG       playsinkconvertbin gstplaysinkconvertbin.c:216:pad_blocked_cb:<aconv> locking from thread 0x6ad3e8
0:00:00.775647741   766   0x6ad3e8 LOG       playsinkconvertbin gstplaysinkconvertbin.c:216:pad_blocked_cb:<aconv> locked from thread 0x6ad3e8
0:00:00.775789778   766   0x6ad3e8 DEBUG     playsinkconvertbin gstplaysinkconvertbin.c:218:pad_blocked_cb:<aconv> Pad blocked: 1
0:00:00.775822704   766   0x50f3c0 WARN                tagdemux gsttagdemux.c:680:gst_tag_demux_chain:<id3demux0> Downstream did not handle newsegment event as it should
0:00:00.777053407   766   0x6ad3e8 DEBUG     playsinkconvertbin gstplaysinkconvertbin.c:230:pad_blocked_cb:<aconv> Caps audio/mpeg, mpegversion=(int)1, layer=(int)3 are raw: 0
0:00:00.777171926   766   0x50f3c0 DEBUG               playbin2 gstplaybin2.c:2753:notify_tags_cb:<playbin20> Tags on pad <inputselector0:sink0> with stream id 0 and type 0 have changed
0:00:00.777502334   766   0x6ad3e8 LOG       playsinkconvertbin gstplaysinkconvertbin.c:260:pad_blocked_cb:<aconv> unlocking from thread 0x6ad3e8
0:00:00.777661074   766   0x6ad3e8 LOG       playsinkconvertbin gstplaysinkconvertbin.c:216:pad_blocked_cb:<aconv> locking from thread 0x6ad3e8
0:00:00.777700333   766   0x50f3c0 INFO                GST_PADS gstpad.c:3554:gst_pad_event_default_dispatch:<audiotee:sink> Sending event 0x5280c8 (tag) to all internally linked pads
0:00:00.777791407   766   0x6ad3e8 LOG       playsinkconvertbin gstplaysinkconvertbin.c:216:pad_blocked_cb:<aconv> locked from thread 0x6ad3e8
0:00:00.777929556   766   0x6ad3e8 DEBUG     playsinkconvertbin gstplaysinkconvertbin.c:218:pad_blocked_cb:<aconv> Pad blocked: 0
0:00:00.778065852   766   0x6ad3e8 LOG       playsinkconvertbin gstplaysinkconvertbin.c:260:pad_blocked_cb:<aconv> unlocking from thread 0x6ad3e8
0:00:00.778402667   766   0x6ad3e8 LOG       playsinkconvertbin gstplaysinkconvertbin.c:279:gst_play_sink_convert_bin_sink_event:<aconv> locking from thread 0x6ad3e8
0:00:00.778572371   766   0x6ad3e8 LOG       playsinkconvertbin gstplaysinkconvertbin.c:279:gst_play_sink_convert_bin_sink_event:<aconv> locked from thread 0x6ad3e8
0:00:00.778714370   766   0x6ad3e8 DEBUG     playsinkconvertbin gstplaysinkconvertbin.c:284:gst_play_sink_convert_bin_sink_event:<aconv> Segment before UNDEFINED segment
0:00:00.778880852   766   0x6ad3e8 DEBUG     playsinkconvertbin gstplaysinkconvertbin.c:288:gst_play_sink_convert_bin_sink_event:<aconv> Segment after bytes segment start=0, stop=7960869, last_stop=0, duration=-1, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, accum=0:00:00.000000000
0:00:00.779166481   766   0x6ad3e8 LOG       playsinkconvertbin gstplaysinkconvertbin.c:289:gst_play_sink_convert_bin_sink_event:<aconv> unlocking from thread 0x6ad3e8
0:00:00.779391074   766   0x50f3c0 DEBUG               playsink gstplaysink.c:3975:caps_notify_cb:<playsink:audio_sink> Audio caps changed: raw 0 reconfigure 0 caps audio/mpeg, mpegversion=(int)1, layer=(int)3
0:00:00.780040518   766   0x50f3c0 DEBUG               playsink gstplaysink.c:3975:caps_notify_cb:<playsink:audio_sink> Audio caps changed: raw 0 reconfigure 0 caps audio/mpeg, mpegversion=(int)1, layer=(int)3
0:00:00.781957926   766   0x6ad3e8 DEBUG     playsinkconvertbin gstplaysinkconvertbin.c:314:gst_play_sink_convert_bin_sink_setcaps:<aconv:sink> setcaps: audio/mpeg, mpegversion=(int)1, layer=(int)3
0:00:00.782360778   766   0x6ad3e8 LOG       playsinkconvertbin gstplaysinkconvertbin.c:315:gst_play_sink_convert_bin_sink_setcaps:<aconv> locking from thread 0x6ad3e8
0:00:00.782508667   766   0x6ad3e8 LOG       playsinkconvertbin gstplaysinkconvertbin.c:315:gst_play_sink_convert_bin_sink_setcaps:<aconv> locked from thread 0x6ad3e8
0:00:00.782661704   766   0x6ad3e8 DEBUG     playsinkconvertbin gstplaysinkconvertbin.c:326:gst_play_sink_convert_bin_sink_setcaps:<aconv> raw 0, self->raw 0, blocked 0
0:00:00.782809482   766   0x6ad3e8 LOG       playsinkconvertbin gstplaysinkconvertbin.c:361:gst_play_sink_convert_bin_sink_setcaps:<aconv> unlocking from thread 0x6ad3e8
0:00:00.783511926   766   0x6ad3e8 DEBUG     playsinkconvertbin gstplaysinkconvertbin.c:365:gst_play_sink_convert_bin_sink_setcaps:<aconv> Setting sink caps audio/mpeg, mpegversion=(int)1, layer=(int)3: 1
0:00:00.784161222   766   0x6ad3e8 INFO            dvbaudiosink gstdvbaudiosink.c:515:gst_dvbaudiosink_acceptcaps:<dvbaudiosink0> gst_dvbaudiosink_acceptcaps!!!!!!!!!!!!!
0:00:00.785036037   766   0x6ad3e8 WARN            dvbaudiosink gstdvbaudiosink.c:539:gst_dvbaudiosink_acceptcaps:<dvbaudiosink0> input stream is unframed && unparsed! can't handle!
0:00:00.785300556   766   0x6ad3e8 INFO           basetransform gstbasetransform.c:1264:gst_base_transform_setcaps:<identity> reuse caps
0:00:00.786921222   766   0x6ad3e8 INFO            dvbaudiosink gstdvbaudiosink.c:573:gst_dvbaudiosink_set_caps:<dvbaudiosink0> MIMETYPE audio/mpeg version 1 layer 3
0:00:00.787148518   766   0x6ad3e8 INFO            dvbaudiosink gstdvbaudiosink.c:774:gst_dvbaudiosink_set_caps:<dvbaudiosink0> setting dvb mode 0x0a

0:00:00.789114296   766   0x6ad3e8 INFO              GST_STATES gstbin.c:3003:bin_handle_async_done:<abin> committing state from READY to PAUSED, old pending PAUSED
0:00:00.789396111   766   0x6ad3e8 INFO              GST_STATES gstbin.c:3023:bin_handle_async_done:<abin> completed state change, pending VOID
0:00:00.789536704   766   0x6ad3e8 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<abin> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.790685185   766   0x6ad3e8 INFO              GST_STATES gstbin.c:3003:bin_handle_async_done:<playsink> committing state from READY to PAUSED, old pending PAUSED
0:00:00.791555482   766   0x6ad3e8 INFO              GST_STATES gstbin.c:3023:bin_handle_async_done:<playsink> completed state change, pending VOID
0:00:00.791718222   766   0x6ad3e8 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<playsink> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.792901926   766   0x6ad3e8 INFO              GST_STATES gstbin.c:3003:bin_handle_async_done:<playbin20> committing state from READY to PAUSED, old pending PAUSED
0:00:00.793109407   766   0x6ad3e8 INFO              GST_STATES gstbin.c:3023:bin_handle_async_done:<playbin20> completed state change, pending VOID
0:00:00.793301223   766   0x6ad3e8 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<playbin20> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
Pipeline is PREROLLED ...
Setting pipeline to PLAYING ...
0:00:00.798912370   766   0x46b050 INFO               GST_EVENT gstevent.c:1136:gst_event_new_latency: creating latency event 0:00:00.000000000
0:00:00.799718778   766   0x46b050 INFO                GST_PADS gstpad.c:3554:gst_pad_event_default_dispatch:<audiotee:src0> Sending event 0x50f6c8 (latency) to all internally linked pads
0:00:00.800588667   766   0x46b050 INFO                     bin gstbin.c:2452:gst_bin_do_latency_func:<playbin20> configured latency of 0:00:00.000000000
0:00:00.801081444   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<playsink> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.801599296   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<abin> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.802022407   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<dvbaudiosink0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.809016555   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<dvbaudiosink0> completed state change to PLAYING
0:00:00.809292741   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<dvbaudiosink0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.809694370   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<abin> child 'dvbaudiosink0' changed state to 4(PLAYING) successfully
0:00:00.809948111   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<aconv> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.810420334   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<volume> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.810639037   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<volume> completed state change to PLAYING
0:00:00.810789333   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<volume> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.811284445   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<aconv> child 'volume' changed state to 4(PLAYING) successfully
0:00:00.811588593   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<resample> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.811795667   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<resample> completed state change to PLAYING
0:00:00.811945889   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<resample> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.812409408   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<aconv> child 'resample' changed state to 4(PLAYING) successfully
0:00:00.812630778   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<conv> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.812814926   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<conv> completed state change to PLAYING
0:00:00.812963148   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<conv> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.813397889   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<aconv> child 'conv' changed state to 4(PLAYING) successfully
0:00:00.817379926   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<identity> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.817707704   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<identity> completed state change to PLAYING
0:00:00.817859037   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<identity> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.818362111   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<aconv> child 'identity' changed state to 4(PLAYING) successfully
0:00:00.818710852   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<aconv> completed state change to PLAYING
0:00:00.818905407   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<aconv> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.819361630   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<abin> child 'aconv' changed state to 4(PLAYING) successfully
0:00:00.819601074   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<aqueue> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.819791148   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<aqueue> completed state change to PLAYING
0:00:00.819938593   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<aqueue> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.820474852   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<abin> child 'aqueue' changed state to 4(PLAYING) successfully
0:00:00.820769407   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<abin> completed state change to PLAYING
0:00:00.820917630   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<abin> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.821351852   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playsink> child 'abin' changed state to 4(PLAYING) successfully
0:00:00.821679482   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<streamsynchronizer0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.821933704   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<streamsynchronizer0> completed state change to PLAYING
0:00:00.822129444   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<streamsynchronizer0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.828666408   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playsink> child 'streamsynchronizer0' changed state to 4(PLAYING) successfully
0:00:00.828939407   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<audiotee> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.829367815   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<audiotee> completed state change to PLAYING
0:00:00.829587000   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<audiotee> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.830012370   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playsink> child 'audiotee' changed state to 4(PLAYING) successfully
0:00:00.830331371   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<playsink> completed state change to PLAYING
0:00:00.830497222   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<playsink> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.831575926   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playbin20> child 'playsink' changed state to 4(PLAYING) successfully
0:00:00.831914333   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<inputselector0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.832116148   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<inputselector0> completed state change to PLAYING
0:00:00.832365074   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<inputselector0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.832752296   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playbin20> child 'inputselector0' changed state to 4(PLAYING) successfully
0:00:00.832989555   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<uridecodebin0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.833466630   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<decodebin20> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.833919481   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<id3demux0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.834352704   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<id3demux0> completed state change to PLAYING
0:00:00.834662630   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<id3demux0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.835145741   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<decodebin20> child 'id3demux0' changed state to 4(PLAYING) successfully
0:00:00.835533667   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<typefind> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.835726185   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<typefind> completed state change to PLAYING
0:00:00.835915852   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<typefind> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.836519334   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<decodebin20> child 'typefind' changed state to 4(PLAYING) successfully
0:00:00.836761074   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<decodebin20> completed state change to PLAYING
0:00:00.836901556   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<decodebin20> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.837454519   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<uridecodebin0> child 'decodebin20' changed state to 4(PLAYING) successfully
0:00:00.837694148   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<source> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.837928704   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<source> completed state change to PLAYING
0:00:00.838115778   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<source> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.839085185   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<uridecodebin0> child 'source' changed state to 4(PLAYING) successfully
0:00:00.839547815   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<uridecodebin0> completed state change to PLAYING
0:00:00.839753259   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<uridecodebin0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.840113037   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playbin20> child 'uridecodebin0' changed state to 4(PLAYING) successfully
0:00:00.840645852   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<playbin20> completed state change to PLAYING
0:00:00.840914815   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<playbin20> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
New clock: GstSystemClock
0:00:05.026481296   766   0x50f3c0 INFO                 basesrc gstbasesrc.c:2456:gst_base_src_loop:<source> pausing after gst_base_src_get_range() = unexpected
0:00:05.026807481   766   0x50f3c0 INFO                GST_PADS gstpad.c:3554:gst_pad_event_default_dispatch:<id3demux0:sink> Sending event 0x528058 (eos) to all internally linked pads
0:00:05.027102333   766   0x50f3c0 DEBUG               playbin2 gstplaybin2.c:3281:drained_cb:<playbin20> about to finish in group 0x4f13dc
0:00:05.027277037   766   0x50f3c0 DEBUG               playbin2 gstplaybin2.c:4087:setup_next_source:<playbin20> setup sources
0:00:05.027392667   766   0x50f3c0 DEBUG               playbin2 gstplaybin2.c:4119:setup_next_source:<playbin20> no next group
0:00:05.027719444   766   0x50f3c0 INFO                GST_PADS gstpad.c:3554:gst_pad_event_default_dispatch:<audiotee:sink> Sending event 0x528058 (eos) to all internally linked pads
Got EOS from element "playbin20".
Execution ended after 4890587186 ns.
Setting pipeline to PAUSED ...
0:00:05.732919333   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<playsink> current PLAYING pending VOID_PENDING, desired next PAUSED
0:00:05.733322260   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<abin> current PLAYING pending VOID_PENDING, desired next PAUSED
0:00:05.733643815   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<dvbaudiosink0> current PLAYING pending VOID_PENDING, desired next PAUSED
0:00:05.735047889   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<dvbaudiosink0> completed state change to PAUSED
0:00:05.735186555   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<dvbaudiosink0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:05.735521000   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<abin> child 'dvbaudiosink0' changed state to 3(PAUSED) successfully
0:00:05.735706630   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<aconv> current PLAYING pending VOID_PENDING, desired next PAUSED
0:00:05.736029111   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<volume> current PLAYING pending VOID_PENDING, desired next PAUSED
0:00:05.736188851   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<volume> completed state change to PAUSED
0:00:05.736340334   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<volume> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:05.736609222   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<aconv> child 'volume' changed state to 3(PAUSED) successfully
0:00:05.736782592   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<resample> current PLAYING pending VOID_PENDING, desired next PAUSED
0:00:05.736937555   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<resample> completed state change to PAUSED
0:00:05.737054814   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<resample> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:05.737351334   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<aconv> child 'resample' changed state to 3(PAUSED) successfully
0:00:05.737505778   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<conv> current PLAYING pending VOID_PENDING, desired next PAUSED
0:00:05.737659074   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<conv> completed state change to PAUSED
0:00:05.737811444   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<conv> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:05.738076926   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<aconv> child 'conv' changed state to 3(PAUSED) successfully
0:00:05.738267852   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<identity> current PLAYING pending VOID_PENDING, desired next PAUSED
0:00:05.738424593   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<identity> completed state change to PAUSED
0:00:05.738544296   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<identity> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:05.738809185   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<aconv> child 'identity' changed state to 3(PAUSED) successfully
0:00:05.738975703   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<aconv> completed state change to PAUSED
0:00:05.739098111   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<aconv> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:05.739385704   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<abin> child 'aconv' changed state to 3(PAUSED) successfully
0:00:05.739550222   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<aqueue> current PLAYING pending VOID_PENDING, desired next PAUSED
0:00:05.739698741   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<aqueue> completed state change to PAUSED
0:00:05.739822444   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<aqueue> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:05.740086518   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<abin> child 'aqueue' changed state to 3(PAUSED) successfully
0:00:05.740277260   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<abin> completed state change to PAUSED
0:00:05.740398000   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<abin> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:05.740649259   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playsink> child 'abin' changed state to 3(PAUSED) successfully
0:00:05.740825296   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<streamsynchronizer0> current PLAYING pending VOID_PENDING, desired next PAUSED
0:00:05.740979777   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<streamsynchronizer0> completed state change to PAUSED
0:00:05.741098777   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<streamsynchronizer0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:05.741369074   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playsink> child 'streamsynchronizer0' changed state to 3(PAUSED) successfully
0:00:05.741537259   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<audiotee> current PLAYING pending VOID_PENDING, desired next PAUSED
0:00:05.741685741   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<audiotee> completed state change to PAUSED
0:00:05.741802889   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<audiotee> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:05.742074444   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playsink> child 'audiotee' changed state to 3(PAUSED) successfully
0:00:05.742268815   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<playsink> completed state change to PAUSED
0:00:05.742391482   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<playsink> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:05.742623222   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playbin20> child 'playsink' changed state to 3(PAUSED) successfully
0:00:05.742805815   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<inputselector0> current PLAYING pending VOID_PENDING, desired next PAUSED
0:00:05.742964629   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<inputselector0> completed state change to PAUSED
0:00:05.743087444   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<inputselector0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:05.743347148   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playbin20> child 'inputselector0' changed state to 3(PAUSED) successfully
0:00:05.743493333   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<uridecodebin0> current PLAYING pending VOID_PENDING, desired next PAUSED
0:00:05.743765000   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<decodebin20> current PLAYING pending VOID_PENDING, desired next PAUSED
0:00:05.744039740   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<id3demux0> current PLAYING pending VOID_PENDING, desired next PAUSED
0:00:05.744193629   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<id3demux0> completed state change to PAUSED
0:00:05.744345148   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<id3demux0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:05.744619259   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<decodebin20> child 'id3demux0' changed state to 3(PAUSED) successfully
0:00:05.744778333   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<typefind> current PLAYING pending VOID_PENDING, desired next PAUSED
0:00:05.744930407   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<typefind> completed state change to PAUSED
0:00:05.745048074   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<typefind> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:05.745331148   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<decodebin20> child 'typefind' changed state to 3(PAUSED) successfully
0:00:05.745500667   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<decodebin20> completed state change to PAUSED
0:00:05.745619593   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<decodebin20> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:05.745864259   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<uridecodebin0> child 'decodebin20' changed state to 3(PAUSED) successfully
0:00:05.746009333   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<source> current PLAYING pending VOID_PENDING, desired next PAUSED
0:00:05.746167629   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<source> completed state change to PAUSED
0:00:05.746310667   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<source> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:05.746592815   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<uridecodebin0> child 'source' changed state to 3(PAUSED) successfully
0:00:05.746762667   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<uridecodebin0> completed state change to PAUSED
0:00:05.746882926   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<uridecodebin0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:05.747114963   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playbin20> child 'uridecodebin0' changed state to 3(PAUSED) successfully
0:00:05.747344852   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<playbin20> completed state change to PAUSED
0:00:05.747465889   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<playbin20> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:05.747888481   766   0x46b050 INFO              GST_STATES gstbin.c:1784:gst_bin_get_state_func:<playbin20> getting state
Setting pipeline to READY ...
0:00:05.748318074   766   0x46b050 LOG                 playbin2 gstplaybin2.c:4204:gst_play_bin_change_state:<playbin20> setting shutdown flag
0:00:05.748438259   766   0x46b050 LOG                 playbin2 gstplaybin2.c:4212:gst_play_bin_change_state:<playbin20> dynamic lock taken, we can continue shutdown
0:00:05.748753185   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<playsink> current PAUSED pending VOID_PENDING, desired next READY
0:00:05.748905778   766   0x46b050 LOG                 playsink gstplaysink.c:4437:gst_play_sink_change_state:<playsink> locking from thread 0x46b050
0:00:05.749030926   766   0x46b050 LOG                 playsink gstplaysink.c:4437:gst_play_sink_change_state:<playsink> locked from thread 0x46b050
0:00:05.749161074   766   0x46b050 LOG                 playsink gstplaysink.c:4470:gst_play_sink_change_state:<playsink> unlocking from thread 0x46b050
0:00:05.749500333   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<abin> current PAUSED pending VOID_PENDING, desired next READY
0:00:05.751499815   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<dvbaudiosink0> current PAUSED pending VOID_PENDING, desired next READY
0:00:05.753057000   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<dvbaudiosink0> completed state change to READY
0:00:05.753389259   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<dvbaudiosink0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:05.753923852   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<abin> child 'dvbaudiosink0' changed state to 2(READY) successfully
0:00:05.754504926   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<aconv> current PAUSED pending VOID_PENDING, desired next READY
0:00:05.754755481   766   0x46b050 LOG       playsinkconvertbin gstplaysinkconvertbin.c:506:gst_play_sink_convert_bin_change_state:<aconv> locking from thread 0x46b050
0:00:05.755091481   766   0x46b050 LOG       playsinkconvertbin gstplaysinkconvertbin.c:506:gst_play_sink_convert_bin_change_state:<aconv> locked from thread 0x46b050
0:00:05.755271778   766   0x46b050 LOG       playsinkconvertbin gstplaysinkconvertbin.c:510:gst_play_sink_convert_bin_change_state:<aconv> unlocking from thread 0x46b050
0:00:05.755833444   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<volume> current PAUSED pending VOID_PENDING, desired next READY
0:00:05.756156481   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<volume> completed state change to READY
0:00:05.756360815   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<volume> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:05.756670778   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<aconv> child 'volume' changed state to 2(READY) successfully
0:00:05.756849592   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<resample> current PAUSED pending VOID_PENDING, desired next READY
0:00:05.757146296   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<resample> completed state change to READY
0:00:05.757305260   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<resample> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:05.757581963   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<aconv> child 'resample' changed state to 2(READY) successfully
0:00:05.757736889   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<conv> current PAUSED pending VOID_PENDING, desired next READY
0:00:05.758028666   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<conv> completed state change to READY
0:00:05.758149925   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<conv> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:05.758474963   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<aconv> child 'conv' changed state to 2(READY) successfully
0:00:05.758641889   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<identity> current PAUSED pending VOID_PENDING, desired next READY
0:00:05.759205407   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<identity> completed state change to READY
0:00:05.759695370   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<identity> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:05.760020889   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<aconv> child 'identity' changed state to 2(READY) successfully
0:00:05.760573259   766   0x46b050 LOG       playsinkconvertbin gstplaysinkconvertbin.c:529:gst_play_sink_convert_bin_change_state:<aconv> locking from thread 0x46b050
0:00:05.760712667   766   0x46b050 LOG       playsinkconvertbin gstplaysinkconvertbin.c:529:gst_play_sink_convert_bin_change_state:<aconv> locked from thread 0x46b050
0:00:05.760834963   766   0x46b050 DEBUG     playsinkconvertbin gstplaysinkconvertbin.c:162:gst_play_sink_convert_bin_set_targets:<aconv> Setting pad targets with passthrough 1
0:00:05.760953407   766   0x46b050 DEBUG     playsinkconvertbin gstplaysinkconvertbin.c:165:gst_play_sink_convert_bin_set_targets:<aconv> no conversion elements, using identity (0x530108) as head/tail
0:00:05.761068777   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad identity:sink
0:00:05.761195703   766   0x46b050 DEBUG     playsinkconvertbin gstplaysinkconvertbin.c:182:gst_play_sink_convert_bin_set_targets:<aconv> Ghosting bin sink pad to <identity:sink>
0:00:05.761371445   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1750:gst_pad_unlink: unlinking sink:proxypad5(0x76601818) and identity:sink(0x52a1a0)
0:00:05.761518852   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1795:gst_pad_unlink: unlinked sink:proxypad5 and identity:sink
0:00:05.761644815   766   0x46b050 INFO                GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link sink:proxypad5 and identity:sink
0:00:05.761776629   766   0x46b050 INFO                GST_PADS gstpad.c:2161:gst_pad_link_full: linked sink:proxypad5 and identity:sink, successful
0:00:05.761933629   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:975:gst_element_get_static_pad: found pad identity:src
0:00:05.762061666   766   0x46b050 DEBUG     playsinkconvertbin gstplaysinkconvertbin.c:187:gst_play_sink_convert_bin_set_targets:<aconv> Ghosting bin src pad to <identity:src>
0:00:05.762206740   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1750:gst_pad_unlink: unlinking identity:src(0x52a268) and src:proxypad6(0x766018e8)
0:00:05.762505926   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1795:gst_pad_unlink: unlinked identity:src and src:proxypad6
0:00:05.762923592   766   0x46b050 INFO                GST_PADS gstpad.c:1978:gst_pad_link_prepare: trying to link identity:src and src:proxypad6
0:00:05.763057629   766   0x46b050 INFO                GST_PADS gstpad.c:2161:gst_pad_link_full: linked identity:src and src:proxypad6, successful
0:00:05.763395259   766   0x46b050 LOG       playsinkconvertbin gstplaysinkconvertbin.c:533:gst_play_sink_convert_bin_change_state:<aconv> unlocking from thread 0x46b050
0:00:05.763529296   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<aconv> completed state change to READY
0:00:05.763652852   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<aconv> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:05.763933259   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<abin> child 'aconv' changed state to 2(READY) successfully
0:00:05.764110666   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<aqueue> current PAUSED pending VOID_PENDING, desired next READY
0:00:05.765106814   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<aqueue> completed state change to READY
0:00:05.765302630   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<aqueue> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:05.765590667   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<abin> child 'aqueue' changed state to 2(READY) successfully
0:00:05.766004666   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<abin> completed state change to READY
0:00:05.766130740   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<abin> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:05.766417778   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playsink> child 'abin' changed state to 2(READY) successfully
0:00:05.766605630   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<streamsynchronizer0> current PAUSED pending VOID_PENDING, desired next READY
0:00:05.767102888   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<streamsynchronizer0> completed state change to READY
0:00:05.767272778   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<streamsynchronizer0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:05.767599482   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playsink> child 'streamsynchronizer0' changed state to 2(READY) successfully
0:00:05.767778889   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<audiotee> current PAUSED pending VOID_PENDING, desired next READY
0:00:05.768316667   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<audiotee> completed state change to READY
0:00:05.768447185   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<audiotee> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:05.768705296   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playsink> child 'audiotee' changed state to 2(READY) successfully
0:00:05.769282482   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<streamsynchronizer0> removing pad 'src_0'
0:00:05.769408704   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1750:gst_pad_unlink: unlinking streamsynchronizer0:src_0(0x52a718) and abin:sink(0x766011c0)
0:00:05.769679111   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1795:gst_pad_unlink: unlinked streamsynchronizer0:src_0 and abin:sink
0:00:05.770030370   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<streamsynchronizer0> removing pad 'sink_0'
0:00:05.770139222   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1750:gst_pad_unlink: unlinking audiotee:src0(0x52a4c0) and streamsynchronizer0:sink_0(0x52a650)
0:00:05.770417000   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1795:gst_pad_unlink: unlinked audiotee:src0 and streamsynchronizer0:sink_0
0:00:05.771618111   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<dvbaudiosink0> current READY pending VOID_PENDING, desired next NULL
0:00:05.776496148   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<dvbaudiosink0> completed state change to NULL
0:00:05.776678407   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<dvbaudiosink0> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:05.777000926   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<abin> child 'dvbaudiosink0' changed state to 1(NULL) successfully
0:00:05.777199777   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<aconv> current READY pending VOID_PENDING, desired next NULL
0:00:05.777609000   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<volume> current READY pending VOID_PENDING, desired next NULL
0:00:05.777859074   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<volume> completed state change to NULL
0:00:05.777978926   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<volume> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:05.778278778   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<aconv> child 'volume' changed state to 1(NULL) successfully
0:00:05.778452778   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<resample> current READY pending VOID_PENDING, desired next NULL
0:00:05.778692185   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<resample> completed state change to NULL
0:00:05.778813000   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<resample> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:05.779083296   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<aconv> child 'resample' changed state to 1(NULL) successfully
0:00:05.779260445   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<conv> current READY pending VOID_PENDING, desired next NULL
0:00:05.779499519   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<conv> completed state change to NULL
0:00:05.779621704   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<conv> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:05.779890703   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<aconv> child 'conv' changed state to 1(NULL) successfully
0:00:05.780056777   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<identity> current READY pending VOID_PENDING, desired next NULL
0:00:05.780324630   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<identity> completed state change to NULL
0:00:05.780477741   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<identity> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:05.780752926   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<aconv> child 'identity' changed state to 1(NULL) successfully
0:00:05.781015740   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<aconv> completed state change to NULL
0:00:05.781133666   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<aconv> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:05.781419148   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<abin> child 'aconv' changed state to 1(NULL) successfully
0:00:05.781581630   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<aqueue> current READY pending VOID_PENDING, desired next NULL
0:00:05.781816333   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<aqueue> completed state change to NULL
0:00:05.781933592   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<aqueue> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:05.782182629   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<abin> child 'aqueue' changed state to 1(NULL) successfully
0:00:05.782455445   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<abin> completed state change to NULL
0:00:05.782576444   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<abin> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:05.782881074   766   0x46b050 INFO           GST_PARENTAGE gstbin.c:1465:gst_bin_remove_func:<playsink> removed child "abin"
0:00:05.783170333   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<playsink> completed state change to READY
0:00:05.783323593   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<playsink> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:05.783567445   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playbin20> child 'playsink' changed state to 2(READY) successfully
0:00:05.783749889   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<inputselector0> current PAUSED pending VOID_PENDING, desired next READY
0:00:05.784455778   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<inputselector0> completed state change to READY
0:00:05.784601185   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<inputselector0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:05.784859629   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playbin20> child 'inputselector0' changed state to 2(READY) successfully
0:00:05.785008370   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<uridecodebin0> current PAUSED pending VOID_PENDING, desired next READY
0:00:05.785506296   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<decodebin20> current PAUSED pending VOID_PENDING, desired next READY
0:00:05.785998777   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<id3demux0> current PAUSED pending VOID_PENDING, desired next READY
0:00:05.786564556   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<id3demux0> removing pad 'src'
0:00:05.786683667   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1750:gst_pad_unlink: unlinking id3demux0:src(0x4fc330) and src0:proxypad2(0x5001c0)
0:00:05.786988889   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1795:gst_pad_unlink: unlinked id3demux0:src and src0:proxypad2
0:00:05.787708000   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<id3demux0> completed state change to READY
0:00:05.787845926   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<id3demux0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:05.788121851   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<decodebin20> child 'id3demux0' changed state to 2(READY) successfully
0:00:05.788406074   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<id3demux0> current READY pending VOID_PENDING, desired next READY
0:00:05.788534370   766   0x46b050 INFO              GST_STATES gstbin.c:2292:gst_bin_element_set_state:<id3demux0> skipping transition from READY to  READY
0:00:05.788657259   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<decodebin20> child 'id3demux0' changed state to 2(READY) successfully
0:00:05.788814111   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<typefind> current PAUSED pending VOID_PENDING, desired next READY
0:00:05.789269482   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<typefind> completed state change to READY
0:00:05.789403000   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<typefind> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:05.789670259   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<decodebin20> child 'typefind' changed state to 2(READY) successfully
0:00:05.790062074   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1750:gst_pad_unlink: unlinking typefind:src(0x4fc1a0) and id3demux0:sink(0x4fc268)
0:00:05.790353778   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1795:gst_pad_unlink: unlinked typefind:src and id3demux0:sink
0:00:05.790684704   766   0x46b050 INFO           GST_PARENTAGE gstbin.c:1465:gst_bin_remove_func:<decodebin20> removed child "id3demux0"
0:00:05.791468519   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<id3demux0> completed state change to NULL
0:00:05.791636630   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<id3demux0> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:05.791903333   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<decodebin20> removing pad 'src0'
0:00:05.792019889   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1750:gst_pad_unlink: unlinking decodebin20:src0(0x513110) and src0:proxypad3(0x5005d0)
0:00:05.792349926   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1795:gst_pad_unlink: unlinked decodebin20:src0 and src0:proxypad3
0:00:05.792737630   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<uridecodebin0> removing pad 'src0'
0:00:05.792853259   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1750:gst_pad_unlink: unlinking uridecodebin0:src0(0x4ffe20) and inputselector0:sink0(0x4fe558)
0:00:05.793122074   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1795:gst_pad_unlink: unlinked uridecodebin0:src0 and inputselector0:sink0
0:00:05.793518037   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3001:pad_removed_cb:<playbin20> pad uridecodebin0:src0 removed from group 0x4f13dc
0:00:05.793708444   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3029:pad_removed_cb:<playbin20> pad 0x4fe558 removed from array
0:00:05.793831889   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3032:pad_removed_cb:<playbin20> all selector sinkpads removed
0:00:05.793949148   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3033:pad_removed_cb:<playbin20> removing selector 0x529078
0:00:05.794268297   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<inputselector0> completed state change to NULL
0:00:05.794400519   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<inputselector0> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:05.794670222   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1750:gst_pad_unlink: unlinking inputselector0:src(0x4fc650) and playsink:audio_sink(0x4fff00)
0:00:05.794941333   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1795:gst_pad_unlink: unlinked inputselector0:src and playsink:audio_sink
0:00:05.795304926   766   0x46b050 INFO           GST_PARENTAGE gstbin.c:1465:gst_bin_remove_func:<playbin20> removed child "inputselector0"
0:00:05.795490111   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1750:gst_pad_unlink: unlinking uridecodebin0:src0(0x4ffe20) and inputselector0:sink0(0x4fe558)
0:00:05.795947740   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<inputselector0> removing pad 'sink0'
0:00:05.796656481   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<decodebin20> completed state change to READY
0:00:05.796791259   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<decodebin20> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:05.797064703   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<uridecodebin0> child 'decodebin20' changed state to 2(READY) successfully
0:00:05.797336593   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<decodebin20> current READY pending VOID_PENDING, desired next READY
0:00:05.797589000   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<typefind> current READY pending VOID_PENDING, desired next READY
0:00:05.797720296   766   0x46b050 INFO              GST_STATES gstbin.c:2292:gst_bin_element_set_state:<typefind> skipping transition from READY to  READY
0:00:05.797850407   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<decodebin20> child 'typefind' changed state to 2(READY) successfully
0:00:05.798018148   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<decodebin20> completed state change to READY
0:00:05.798152333   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<uridecodebin0> child 'decodebin20' changed state to 2(READY) successfully
0:00:05.798323074   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<source> current PAUSED pending VOID_PENDING, desired next READY
0:00:05.800021111   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<source> completed state change to READY
0:00:05.800186333   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<source> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:05.800863666   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<uridecodebin0> child 'source' changed state to 2(READY) successfully
0:00:05.801377741   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<typefind> current READY pending VOID_PENDING, desired next READY
0:00:05.801515445   766   0x46b050 INFO              GST_STATES gstbin.c:2292:gst_bin_element_set_state:<typefind> skipping transition from READY to  READY
0:00:05.801637333   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<decodebin20> child 'typefind' changed state to 2(READY) successfully
0:00:05.801799074   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<decodebin20> completed state change to READY
0:00:05.801943148   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1750:gst_pad_unlink: unlinking source:src(0x4fc010) and decodebin20:sink(0x4ff800)
0:00:05.802294111   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1795:gst_pad_unlink: unlinked source:src and decodebin20:sink
0:00:05.802630481   766   0x46b050 INFO           GST_PARENTAGE gstbin.c:1465:gst_bin_remove_func:<uridecodebin0> removed child "decodebin20"
0:00:05.803293519   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<source> completed state change to NULL
0:00:05.803429000   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<source> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:05.803737889   766   0x46b050 INFO           GST_PARENTAGE gstbin.c:1465:gst_bin_remove_func:<uridecodebin0> removed child "source"
0:00:05.803945481   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<uridecodebin0> completed state change to READY
0:00:05.804066407   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<uridecodebin0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:05.804348630   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playbin20> child 'uridecodebin0' changed state to 2(READY) successfully
0:00:05.804564407   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<playsink> current READY pending VOID_PENDING, desired next READY
0:00:05.804824111   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<audiotee> current READY pending VOID_PENDING, desired next READY
0:00:05.804952111   766   0x46b050 INFO              GST_STATES gstbin.c:2292:gst_bin_element_set_state:<audiotee> skipping transition from READY to  READY
0:00:05.805074777   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playsink> child 'audiotee' changed state to 2(READY) successfully
0:00:05.805242334   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<streamsynchronizer0> current READY pending VOID_PENDING, desired next READY
0:00:05.805368704   766   0x46b050 INFO              GST_STATES gstbin.c:2292:gst_bin_element_set_state:<streamsynchronizer0> skipping transition from READY to  READY
0:00:05.805493000   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playsink> child 'streamsynchronizer0' changed state to 2(READY) successfully
0:00:05.805663407   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<playsink> completed state change to READY
0:00:05.805791259   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playbin20> child 'playsink' changed state to 2(READY) successfully
0:00:05.805937889   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<uridecodebin0> current READY pending VOID_PENDING, desired next READY
0:00:05.806154407   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<uridecodebin0> completed state change to READY
0:00:05.806303223   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playbin20> child 'uridecodebin0' changed state to 2(READY) successfully
0:00:05.806539407   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:4141:save_current_group:<playbin20> save current group
0:00:05.806656630   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3994:deactivate_group:<playbin20> unlinking group 0x4f13dc
0:00:05.806774704   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:4001:deactivate_group:<playbin20> unlinking selector audio/
0:00:05.806891963   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:4001:deactivate_group:<playbin20> unlinking selector video/
0:00:05.807005481   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:4001:deactivate_group:<playbin20> unlinking selector text/
0:00:05.807183925   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<dvbaudiosink0> completed state change to NULL
0:00:05.807460815   766   0x46b050 INFO           GST_PARENTAGE gstbin.c:1465:gst_bin_remove_func:<playbin20> removed child "uridecodebin0"
0:00:05.807672444   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<playbin20> completed state change to READY
0:00:05.807795481   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<playbin20> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:05.808205407   766   0x46b050 INFO              GST_STATES gstbin.c:1784:gst_bin_get_state_func:<playbin20> getting state
Setting pipeline to NULL ...
0:00:05.808726074   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:4164:groups_set_locked_state:<playbin20> setting locked state to 0 on all groups
0:00:05.808852481   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3740:group_set_locked_state_unlocked:<playbin20> locked_state 0 on group 0x4f1298
0:00:05.808974592   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3740:group_set_locked_state_unlocked:<playbin20> locked_state 0 on group 0x4f13dc
0:00:05.809265704   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<playsink> current READY pending VOID_PENDING, desired next NULL
0:00:05.809543815   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<audiotee> current READY pending VOID_PENDING, desired next NULL
0:00:05.809784148   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<audiotee> completed state change to NULL
0:00:05.809906185   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<audiotee> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:05.810177814   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playsink> child 'audiotee' changed state to 1(NULL) successfully
0:00:05.810352704   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<streamsynchronizer0> current READY pending VOID_PENDING, desired next NULL
0:00:05.810572556   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<streamsynchronizer0> completed state change to NULL
0:00:05.810694852   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<streamsynchronizer0> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:05.811470111   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playsink> child 'streamsynchronizer0' changed state to 1(NULL) successfully
0:00:05.811788629   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1750:gst_pad_unlink: unlinking aconv:src(0x766010e0) and dvbaudiosink0:sink(0x4fc3f8)
0:00:05.812064926   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1795:gst_pad_unlink: unlinked aconv:src and dvbaudiosink0:sink
0:00:05.812437630   766   0x46b050 INFO           GST_PARENTAGE gstbin.c:1465:gst_bin_remove_func:<abin> removed child "dvbaudiosink0"
0:00:05.812671037   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<dvbaudiosink0> completed state change to NULL
0:00:05.812802926   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<playsink> completed state change to NULL
0:00:05.812918370   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<playsink> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:05.813165148   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<playbin20> child 'playsink' changed state to 1(NULL) successfully
0:00:05.814654815   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1750:gst_pad_unlink: unlinking aqueue:src(0x52a0d8) and aconv:sink(0x76601000)
0:00:05.814973555   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1795:gst_pad_unlink: unlinked aqueue:src and aconv:sink
0:00:05.815341593   766   0x46b050 INFO           GST_PARENTAGE gstbin.c:1465:gst_bin_remove_func:<abin> removed child "aconv"
0:00:05.815604519   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<conv> completed state change to NULL
0:00:05.815746667   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1750:gst_pad_unlink: unlinking conv:src(0x6a60c8) and resample:sink(0x6a6190)
0:00:05.815987037   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1795:gst_pad_unlink: unlinked conv:src and resample:sink
0:00:05.816358111   766   0x46b050 INFO           GST_PARENTAGE gstbin.c:1465:gst_bin_remove_func:<aconv> removed child "conv"
0:00:05.816555630   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3067:gst_element_dispose:<conv> dispose
0:00:05.816670259   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<conv> removing pad 'src'
0:00:05.816870296   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<conv> removing pad 'sink'
0:00:05.817052666   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3091:gst_element_dispose:<conv> parent class dispose
0:00:05.817189518   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3122:gst_element_finalize:<conv> finalize
0:00:05.817335593   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3133:gst_element_finalize:<conv> finalize parent
0:00:05.817484926   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<resample> completed state change to NULL
0:00:05.817625593   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1750:gst_pad_unlink: unlinking resample:src(0x6a6258) and volume:sink(0x52a330)
0:00:05.817879000   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1795:gst_pad_unlink: unlinked resample:src and volume:sink
0:00:05.818287223   766   0x46b050 INFO           GST_PARENTAGE gstbin.c:1465:gst_bin_remove_func:<aconv> removed child "resample"
0:00:05.818483371   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3067:gst_element_dispose:<resample> dispose
0:00:05.818596889   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<resample> removing pad 'src'
0:00:05.818790629   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<resample> removing pad 'sink'
0:00:05.818975666   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3091:gst_element_dispose:<resample> parent class dispose
0:00:05.819110148   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3122:gst_element_finalize:<resample> finalize
0:00:05.819249223   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3133:gst_element_finalize:<resample> finalize parent
0:00:05.819406334   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<volume> completed state change to NULL
0:00:05.819581000   766   0x46b050 INFO           GST_PARENTAGE gstbin.c:1465:gst_bin_remove_func:<aconv> removed child "volume"
0:00:05.820080888   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1750:gst_pad_unlink: unlinking identity:src(0x52a268) and src:proxypad6(0x766018e8)
0:00:05.820386667   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1795:gst_pad_unlink: unlinked identity:src and src:proxypad6
0:00:05.820676222   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1750:gst_pad_unlink: unlinking sink:proxypad5(0x76601818) and identity:sink(0x52a1a0)
0:00:05.820818370   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1795:gst_pad_unlink: unlinked sink:proxypad5 and identity:sink
0:00:05.820959703   766   0x46b050 INFO           GST_PARENTAGE gstbin.c:1465:gst_bin_remove_func:<aconv> removed child "identity"
0:00:05.821255260   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3067:gst_element_dispose:<identity> dispose
0:00:05.821377778   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<identity> removing pad 'src'
0:00:05.821600222   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<identity> removing pad 'sink'
0:00:05.821783741   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3091:gst_element_dispose:<identity> parent class dispose
0:00:05.821923000   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3122:gst_element_finalize:<identity> finalize
0:00:05.822039370   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3133:gst_element_finalize:<identity> finalize parent
0:00:05.822168666   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3067:gst_element_dispose:<aconv> dispose
0:00:05.822311445   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<aconv> removing pad 'src'
0:00:05.822628556   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<aconv> removing pad 'sink'
0:00:05.822858555   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3091:gst_element_dispose:<aconv> parent class dispose
0:00:05.823013481   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3067:gst_element_dispose:<volume> dispose
0:00:05.823127592   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<volume> removing pad 'src'
0:00:05.823333630   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<volume> removing pad 'sink'
0:00:05.823519185   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3091:gst_element_dispose:<volume> parent class dispose
0:00:05.823669407   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3122:gst_element_finalize:<volume> finalize
0:00:05.823791889   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3133:gst_element_finalize:<volume> finalize parent
0:00:05.824008444   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3122:gst_element_finalize:<aconv> finalize
0:00:05.824128629   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3133:gst_element_finalize:<aconv> finalize parent
0:00:05.824304593   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1750:gst_pad_unlink: unlinking sink:proxypad7(0x766019b8) and aqueue:sink(0x52a010)
0:00:05.824450556   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1795:gst_pad_unlink: unlinked sink:proxypad7 and aqueue:sink
0:00:05.824598815   766   0x46b050 INFO           GST_PARENTAGE gstbin.c:1465:gst_bin_remove_func:<abin> removed child "aqueue"
0:00:05.824817741   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3067:gst_element_dispose:<aqueue> dispose
0:00:05.824934444   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<aqueue> removing pad 'src'
0:00:05.825152185   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<aqueue> removing pad 'sink'
0:00:05.825395371   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3091:gst_element_dispose:<aqueue> parent class dispose
0:00:05.825535852   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3122:gst_element_finalize:<aqueue> finalize
0:00:05.825653593   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3133:gst_element_finalize:<aqueue> finalize parent
0:00:05.825783222   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3067:gst_element_dispose:<abin> dispose
0:00:05.825896666   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<abin> removing pad 'sink'
0:00:05.826167962   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3091:gst_element_dispose:<abin> parent class dispose
0:00:05.826314074   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3122:gst_element_finalize:<abin> finalize
0:00:05.826433815   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3133:gst_element_finalize:<abin> finalize parent
0:00:05.826679074   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3067:gst_element_dispose:<id3demux0> dispose
0:00:05.826797629   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<id3demux0> removing pad 'sink'
0:00:05.827015777   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3091:gst_element_dispose:<id3demux0> parent class dispose
0:00:05.827149518   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3122:gst_element_finalize:<id3demux0> finalize
0:00:05.827297889   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3133:gst_element_finalize:<id3demux0> finalize parent
0:00:05.827502296   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3067:gst_element_dispose:<inputselector0> dispose
0:00:05.827624370   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<inputselector0> removing pad 'src'
0:00:05.827816000   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3091:gst_element_dispose:<inputselector0> parent class dispose
0:00:05.827961926   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3122:gst_element_finalize:<inputselector0> finalize
0:00:05.828084222   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3133:gst_element_finalize:<inputselector0> finalize parent
0:00:05.828917407   766   0x46b050 INFO              GST_STATES gstbin.c:2166:gst_bin_element_set_state:<typefind> current READY pending VOID_PENDING, desired next NULL
0:00:05.829177444   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<typefind> completed state change to NULL
0:00:05.829334741   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<typefind> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:05.829596000   766   0x46b050 INFO              GST_STATES gstbin.c:2587:gst_bin_change_state_func:<decodebin20> child 'typefind' changed state to 1(NULL) successfully
0:00:05.829842778   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<decodebin20> completed state change to NULL
0:00:05.829970000   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<decodebin20> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:05.830249482   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1750:gst_pad_unlink: unlinking sink:proxypad0(0x500020) and typefind:sink(0x4fc0d8)
0:00:05.830402630   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1795:gst_pad_unlink: unlinked sink:proxypad0 and typefind:sink
0:00:05.830551593   766   0x46b050 INFO           GST_PARENTAGE gstbin.c:1465:gst_bin_remove_func:<decodebin20> removed child "typefind"
0:00:05.830788518   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3067:gst_element_dispose:<typefind> dispose
0:00:05.831397593   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<typefind> removing pad 'src'
0:00:05.831646444   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<typefind> removing pad 'sink'
0:00:05.831857444   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3091:gst_element_dispose:<typefind> parent class dispose
0:00:05.832022444   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3122:gst_element_finalize:<typefind> finalize
0:00:05.832149037   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3133:gst_element_finalize:<typefind> finalize parent
0:00:05.832311630   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3067:gst_element_dispose:<decodebin20> dispose
0:00:05.832433482   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<decodebin20> removing pad 'sink'
0:00:05.832790185   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3091:gst_element_dispose:<decodebin20> parent class dispose
0:00:05.832986926   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3122:gst_element_finalize:<decodebin20> finalize
0:00:05.833111148   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3133:gst_element_finalize:<decodebin20> finalize parent
0:00:05.833276297   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<uridecodebin0> completed state change to NULL
0:00:05.833436334   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<uridecodebin0> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:05.833733592   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3067:gst_element_dispose:<uridecodebin0> dispose
0:00:05.833852444   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3091:gst_element_dispose:<uridecodebin0> parent class dispose
0:00:05.834006666   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3122:gst_element_finalize:<uridecodebin0> finalize
0:00:05.834129814   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3133:gst_element_finalize:<uridecodebin0> finalize parent
0:00:05.834292519   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:4164:groups_set_locked_state:<playbin20> setting locked state to 1 on all groups
0:00:05.834421296   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3740:group_set_locked_state_unlocked:<playbin20> locked_state 1 on group 0x4f1298
0:00:05.834551259   766   0x46b050 DEBUG               playbin2 gstplaybin2.c:3740:group_set_locked_state_unlocked:<playbin20> locked_state 1 on group 0x4f13dc
0:00:05.834681407   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<playbin20> completed state change to NULL
0:00:05.834804778   766   0x46b050 INFO              GST_STATES gstelement.c:2368:_priv_gst_element_state_changed:<playbin20> notifying about state-changed READY to NULL (VOID_PENDING pending)
Freeing pipeline ...
0:00:05.835546407   766   0x46b050 INFO           GST_PARENTAGE gstbin.c:1465:gst_bin_remove_func:<playbin20> removed child "playsink"
0:00:05.835880778   766   0x46b050 INFO              GST_STATES gstelement.c:2463:gst_element_continue_state:<dvbaudiosink0> completed state change to NULL
0:00:05.836037148   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3067:gst_element_dispose:<dvbaudiosink0> dispose
0:00:05.836161925   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<dvbaudiosink0> removing pad 'sink'
0:00:05.836394556   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3091:gst_element_dispose:<dvbaudiosink0> parent class dispose
0:00:05.836534889   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3122:gst_element_finalize:<dvbaudiosink0> finalize
0:00:05.836660259   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3133:gst_element_finalize:<dvbaudiosink0> finalize parent
0:00:05.836818407   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<audiotee> removing pad 'src0'
0:00:05.837119222   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1750:gst_pad_unlink: unlinking audio_sink:proxypad4(0x5006a0) and audiotee:sink(0x4fc718)
0:00:05.837295667   766   0x46b050 INFO        GST_ELEMENT_PADS gstpad.c:1795:gst_pad_unlink: unlinked audio_sink:proxypad4 and audiotee:sink
0:00:05.837448074   766   0x46b050 INFO           GST_PARENTAGE gstbin.c:1465:gst_bin_remove_func:<playsink> removed child "audiotee"
0:00:05.837640704   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3067:gst_element_dispose:<audiotee> dispose
0:00:05.837761778   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<audiotee> removing pad 'sink'
0:00:05.838003852   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3091:gst_element_dispose:<audiotee> parent class dispose
0:00:05.838137481   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3122:gst_element_finalize:<audiotee> finalize
0:00:05.838284852   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3133:gst_element_finalize:<audiotee> finalize parent
0:00:05.838447741   766   0x46b050 INFO           GST_PARENTAGE gstbin.c:1465:gst_bin_remove_func:<playsink> removed child "streamsynchronizer0"
0:00:05.838669556   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3067:gst_element_dispose:<streamsynchronizer0> dispose
0:00:05.838814666   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3091:gst_element_dispose:<streamsynchronizer0> parent class dispose
0:00:05.838948815   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3122:gst_element_finalize:<streamsynchronizer0> finalize
0:00:05.839068926   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3133:gst_element_finalize:<streamsynchronizer0> finalize parent
0:00:05.839202148   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3067:gst_element_dispose:<playsink> dispose
0:00:05.839346000   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<playsink> removing pad 'audio_sink'
0:00:05.839681556   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3091:gst_element_dispose:<playsink> parent class dispose
0:00:05.839847815   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3122:gst_element_finalize:<playsink> finalize
0:00:05.839970926   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3133:gst_element_finalize:<playsink> finalize parent
0:00:05.840101963   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3067:gst_element_dispose:<playbin20> dispose
0:00:05.840284111   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3091:gst_element_dispose:<playbin20> parent class dispose
0:00:05.840446852   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3067:gst_element_dispose:<source> dispose
0:00:05.840566222   766   0x46b050 INFO        GST_ELEMENT_PADS gstelement.c:847:gst_element_remove_pad:<source> removing pad 'src'
0:00:05.840753148   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3091:gst_element_dispose:<source> parent class dispose
0:00:05.840899778   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3122:gst_element_finalize:<source> finalize
0:00:05.841025074   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3133:gst_element_finalize:<source> finalize parent
0:00:05.841551926   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3122:gst_element_finalize:<playbin20> finalize
0:00:05.841718222   766   0x46b050 INFO         GST_REFCOUNTING gstelement.c:3133:gst_element_finalize:<playbin20> finalize parent
0:00:05.841864222   766   0x46b050 INFO                GST_INIT gst.c:1023:gst_deinit: deinitializing GStreamer
0:00:05.864239519   766   0x46b050 INFO                GST_INIT gst.c:1129:gst_deinit: deinitialized GStreamer
