0:00:00.000032715 18348      0x1e53c00 INFO                GST_INIT gst.c:499:init_pre: Initializing GStreamer Core Library version 1.4.5
0:00:00.000071352 18348      0x1e53c00 INFO                GST_INIT gst.c:500:init_pre: Using library installed in /tmp/install/lib
0:00:00.000079278 18348      0x1e53c00 INFO                GST_INIT gst.c:511:init_pre: Linux helix.bellet.info 4.0.7-300.fc22.x86_64 #1 SMP Mon Jun 29 22:15:06 UTC 2015 x86_64
0:00:00.000276987 18348      0x1e53c00 INFO                GST_INIT gstmessage.c:119:_priv_gst_message_initialize: init messages
0:00:00.000627995 18348      0x1e53c00 INFO                GST_INIT gstcontext.c:77:_priv_gst_context_initialize: init contexts
0:00:00.000847521 18348      0x1e53c00 INFO      GST_PLUGIN_LOADING gstplugin.c:317:_priv_gst_plugin_initialize: registering 0 static plugins
0:00:00.000924552 18348      0x1e53c00 INFO      GST_PLUGIN_LOADING gstplugin.c:225:gst_plugin_register_static: registered static plugin "staticelements"
0:00:00.000932220 18348      0x1e53c00 INFO      GST_PLUGIN_LOADING gstplugin.c:227:gst_plugin_register_static: added static plugin "staticelements", result: 1
0:00:00.000949672 18348      0x1e53c00 INFO            GST_REGISTRY gstregistry.c:1722:ensure_current_registry: reading registry cache: /home/bellet/.cache/gstreamer-1.0/registry.x86_64.bin
0:00:00.014850335 18348      0x1e53c00 INFO            GST_REGISTRY gstregistrybinary.c:619:priv_gst_registry_binary_read_cache: loaded /home/bellet/.cache/gstreamer-1.0/registry.x86_64.bin in 0.013886 seconds
0:00:00.014910071 18348      0x1e53c00 INFO            GST_REGISTRY gstregistry.c:1578:scan_and_update_registry: Validating plugins from registry cache: /home/bellet/.cache/gstreamer-1.0/registry.x86_64.bin
0:00:00.000039850 18350      0x22c6600 INFO                GST_INIT gst.c:499:init_pre: Initializing GStreamer Core Library version 1.4.5
0:00:00.000079673 18350      0x22c6600 INFO                GST_INIT gst.c:500:init_pre: Using library installed in /tmp/install/lib
0:00:00.000087036 18350      0x22c6600 INFO                GST_INIT gst.c:511:init_pre: Linux helix.bellet.info 4.0.7-300.fc22.x86_64 #1 SMP Mon Jun 29 22:15:06 UTC 2015 x86_64
0:00:00.000293179 18350      0x22c6600 INFO                GST_INIT gstmessage.c:119:_priv_gst_message_initialize: init messages
0:00:00.000649737 18350      0x22c6600 INFO                GST_INIT gstcontext.c:77:_priv_gst_context_initialize: init contexts
0:00:00.000822227 18350      0x22c6600 INFO      GST_PLUGIN_LOADING gstplugin.c:317:_priv_gst_plugin_initialize: registering 0 static plugins
0:00:00.000896620 18350      0x22c6600 INFO      GST_PLUGIN_LOADING gstplugin.c:225:gst_plugin_register_static: registered static plugin "staticelements"
0:00:00.000903899 18350      0x22c6600 INFO      GST_PLUGIN_LOADING gstplugin.c:227:gst_plugin_register_static: added static plugin "staticelements", result: 1
0:00:00.000919553 18350      0x22c6600 INFO            GST_REGISTRY gstregistry.c:1757:ensure_current_registry: registry reading and updating done, result = 1
0:00:00.000928881 18350      0x22c6600 INFO                GST_INIT gst.c:709:init_post: GLib runtime version: 2.44.1
0:00:00.000934942 18350      0x22c6600 INFO                GST_INIT gst.c:711:init_post: GLib headers version: 2.44.1
0:00:00.000941135 18350      0x22c6600 INFO                GST_INIT gst.c:348:gst_init_check: initialized GStreamer successfully
0:00:00.003263681 18350      0x22c6600 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/home/bellet/Development/gst-plugins-bad/ext/resindvd/.libs/libgstresindvd.so" loaded
0:00:00.020351469 18348      0x1e53c00 INFO            GST_REGISTRY gstregistry.c:1689:scan_and_update_registry: Registry cache changed. Writing new registry cache
0:00:00.020362623 18348      0x1e53c00 INFO            GST_REGISTRY gstregistrybinary.c:368:priv_gst_registry_binary_write_cache: Building binary registry cache image
0:00:00.027911304 18348      0x1e53c00 INFO            GST_REGISTRY gstregistrybinary.c:400:priv_gst_registry_binary_write_cache: Writing binary registry cache
0:00:00.060386518 18348      0x1e53c00 INFO            GST_REGISTRY gstregistrybinary.c:261:gst_registry_binary_cache_finish: Wrote binary registry cache
0:00:00.060407304 18348      0x1e53c00 INFO            GST_REGISTRY gstregistry.c:1698:scan_and_update_registry: Registry cache written successfully
0:00:00.060412297 18348      0x1e53c00 INFO            GST_REGISTRY gstregistry.c:1757:ensure_current_registry: registry reading and updating done, result = 1
0:00:00.060437422 18348      0x1e53c00 INFO                GST_INIT gst.c:709:init_post: GLib runtime version: 2.44.1
0:00:00.060443941 18348      0x1e53c00 INFO                GST_INIT gst.c:711:init_post: GLib headers version: 2.44.1
0:00:00.060477856 18348      0x1e53c00 INFO            GST_PIPELINE gstparse.c:323:gst_parse_launch_full: parsing pipeline description 'playbin uri=dvd:///dev/sr0 '
0:00:00.061617016 18348      0x1e53c00 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/tmp/install/lib/gstreamer-1.0/libgstplayback.so" loaded
0:00:00.061631574 18348      0x1e53c00 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "playbin"
Setting pipeline to PAUSED ...
0:00:00.062077968 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<playsink> current NULL pending VOID_PENDING, desired next READY
0:00:00.062111336 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<streamsynchronizer0> current NULL pending VOID_PENDING, desired next READY
0:00:00.062158344 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<streamsynchronizer0> completed state change to READY
0:00:00.062178008 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<streamsynchronizer0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.062215478 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playsink> child 'streamsynchronizer0' changed state to 2(READY) successfully
0:00:00.062240493 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<playsink> completed state change to READY
0:00:00.062258763 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<playsink> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.062284406 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playbin0> child 'playsink' changed state to 2(READY) successfully
0:00:00.062306608 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2303:gst_element_continue_state:<playbin0> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:00.062326617 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<playbin0> notifying about state-changed NULL to READY (PAUSED pending)
0:00:00.062366121 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2310:gst_element_continue_state:<playbin0> continue state change READY to PAUSED, final PAUSED
0:00:00.062391057 18348      0x1e53c00 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "uridecodebin"
0:00:00.062519083 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<uridecodebin0> completed state change to READY
0:00:00.062528074 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<uridecodebin0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.062561270 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<playsink> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.062583565 18348      0x1e53c00 INFO                playsink gstplaysink.c:1400:do_async_start:<playsink> Sending async_start message
0:00:00.062608309 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<streamsynchronizer0> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.062641815 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<streamsynchronizer0> completed state change to PAUSED
0:00:00.062660296 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<streamsynchronizer0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.062683987 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playsink> child 'streamsynchronizer0' changed state to 3(PAUSED) successfully
0:00:00.062707404 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2679:gst_bin_change_state_func:<playbin0> child 'playsink' is changing state asynchronously to PAUSED
0:00:00.062728078 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<uridecodebin0> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.063255095 18348      0x1e53c00 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/home/bellet/Development/gst-plugins-bad/ext/resindvd/.libs/libgstresindvd.so" loaded
0:00:00.063287837 18348      0x1e53c00 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "rsndvdbin" named "source"
0:00:00.063463178 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<source> current NULL pending VOID_PENDING, desired next PAUSED
0:00:00.063565705 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseSrc@0x20c10d0> adding pad 'src'
0:00:00.063664620 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<RsnDVDDemux@0x202fa00> adding pad 'sink'
0:00:00.063699928 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstutils.c:1545:gst_element_link_pads_full: trying to link element dvdsrc:(any) to element dvddemux:(any)
0:00:00.063726308 18348      0x1e53c00 INFO                GST_PADS gstutils.c:937:gst_pad_check_link: trying to link dvdsrc:src and dvddemux:sink
0:00:00.063759997 18348      0x1e53c00 INFO                GST_PADS gstutils.c:1445:prepare_link_maybe_ghosting: dvdsrc and dvddemux in same bin, no need for ghost pads
0:00:00.063794174 18348      0x1e53c00 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link dvdsrc:src and dvddemux:sink
0:00:00.063821646 18348      0x1e53c00 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked dvdsrc:src and dvddemux:sink, successful
0:00:00.063841336 18348      0x1e53c00 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.063859086 18348      0x1e53c00 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<dvdsrc:src> Received event on flushing pad. Discarding
0:00:00.064193583 18348      0x1e53c00 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/tmp/install/lib/gstreamer-1.0/libgstcoreelements.so" loaded
0:00:00.064204640 18348      0x1e53c00 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "multiqueue" named "rsnmq"
0:00:00.064528203 18348      0x1e53c00 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/tmp/install/lib/gstreamer-1.0/libgstvideoparsersbad.so" loaded
0:00:00.064538626 18348      0x1e53c00 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "mpegvideoparse" named "rsnvidparse"
0:00:00.064616421 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseParse@0x2036730> adding pad 'sink'
0:00:00.064633275 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseParse@0x2036730> adding pad 'src'
0:00:00.064665957 18348      0x1e53c00 INFO               baseparse gstbaseparse.c:3604:gst_base_parse_set_pts_interpolation:<GstMpegvParse@0x2036730> PTS interpolation: no
0:00:00.064817791 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<RsnDec@0x20d2020> adding pad 'sink'
0:00:00.064828776 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<RsnDec@0x20d2020> adding pad 'src'
0:00:00.064951258 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<RsnParSetter@0x20d9000> adding pad 'sink'
0:00:00.064973144 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<RsnParSetter@0x20d9000> adding pad 'src'
0:00:00.065002508 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad rsnvidparse:src
0:00:00.065024593 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad rsnviddec:sink
0:00:00.065047321 18348      0x1e53c00 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link rsnvidparse:src and rsnviddec:sink
0:00:00.065077037 18348      0x1e53c00 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<sink:proxypad0> pad has no peer
0:00:00.065100593 18348      0x1e53c00 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked rsnvidparse:src and rsnviddec:sink, successful
0:00:00.065119910 18348      0x1e53c00 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.065142668 18348      0x1e53c00 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<rsnvidparse:src> Received event on flushing pad. Discarding
0:00:00.065164840 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad rsnviddec:src
0:00:00.065183505 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad rsnparsetter:sink
0:00:00.065204531 18348      0x1e53c00 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link rsnviddec:src and rsnparsetter:sink
0:00:00.065228354 18348      0x1e53c00 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<src:proxypad1> pad has no peer
0:00:00.065250327 18348      0x1e53c00 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<rsnparsetter:src> pad has no peer
0:00:00.065277543 18348      0x1e53c00 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked rsnviddec:src and rsnparsetter:sink, successful
0:00:00.065299228 18348      0x1e53c00 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.065315519 18348      0x1e53c00 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<rsnviddec:src> Received event on flushing pad. Discarding
0:00:00.065336572 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad rsnparsetter:src
0:00:00.065382038 18348      0x1e53c00 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link rsnparsetter:src and video:proxypad2
0:00:00.065392746 18348      0x1e53c00 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked rsnparsetter:src and video:proxypad2, successful
0:00:00.065413393 18348      0x1e53c00 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.065429910 18348      0x1e53c00 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<rsnparsetter:src> Received event on flushing pad. Discarding
0:00:00.065509212 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<RsnInputSelector@0x20d9150> adding pad 'src'
0:00:00.065535968 18348      0x1e53c00 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "queue" named "spu_q"
0:00:00.065632792 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstQueue@0x20dc000> adding pad 'sink'
0:00:00.065654976 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstQueue@0x20dc000> adding pad 'src'
0:00:00.065696029 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad subpselect:src
0:00:00.065705246 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad spu_q:sink
0:00:00.065714688 18348      0x1e53c00 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link subpselect:src and spu_q:sink
0:00:00.065744449 18348      0x1e53c00 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<spu_q:src> pad has no peer
0:00:00.065765174 18348      0x1e53c00 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked subpselect:src and spu_q:sink, successful
0:00:00.065784918 18348      0x1e53c00 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.065801170 18348      0x1e53c00 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<subpselect:src> Received event on flushing pad. Discarding
0:00:00.065822623 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad spu_q:src
0:00:00.065867361 18348      0x1e53c00 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link spu_q:src and subpicture:proxypad3
0:00:00.065877943 18348      0x1e53c00 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked spu_q:src and subpicture:proxypad3, successful
0:00:00.065898271 18348      0x1e53c00 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.065914959 18348      0x1e53c00 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<spu_q:src> Received event on flushing pad. Discarding
0:00:00.065947412 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<RsnInputSelector@0x20d92a0> adding pad 'src'
0:00:00.066057072 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<RsnDec@0x20d21e0> adding pad 'sink'
0:00:00.066067212 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<RsnDec@0x20d21e0> adding pad 'src'
0:00:00.066097427 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad audioselect:src
0:00:00.066117905 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad auddec:sink
0:00:00.066150951 18348      0x1e53c00 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link audioselect:src and auddec:sink
0:00:00.066178483 18348      0x1e53c00 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<sink:proxypad4> pad has no peer
0:00:00.066198763 18348      0x1e53c00 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked audioselect:src and auddec:sink, successful
0:00:00.066218197 18348      0x1e53c00 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.066234380 18348      0x1e53c00 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<audioselect:src> Received event on flushing pad. Discarding
0:00:00.066256308 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad auddec:src
0:00:00.066300411 18348      0x1e53c00 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link auddec:src and audio:proxypad6
0:00:00.066311087 18348      0x1e53c00 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked auddec:src and audio:proxypad6, successful
0:00:00.066331791 18348      0x1e53c00 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.066348193 18348      0x1e53c00 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<auddec:src> Received event on flushing pad. Discarding
0:00:00.066387363 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<auddec> current NULL pending VOID_PENDING, desired next READY
0:00:00.066501516 18348      0x1e53c00 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/tmp/install/lib/gstreamer-1.0/libgstautoconvert.so" loaded
0:00:00.066510724 18348      0x1e53c00 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "autoconvert"
0:00:00.066574201 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstAutoConvert@0x20df040> adding pad 'sink'
0:00:00.066584272 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstAutoConvert@0x20df040> adding pad 'src'
0:00:00.067742130 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad autoconvert0:sink
0:00:00.067754958 18348      0x1e53c00 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link sink:proxypad4 and autoconvert0:sink
0:00:00.067779814 18348      0x1e53c00 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked sink:proxypad4 and autoconvert0:sink, successful
0:00:00.067799862 18348      0x1e53c00 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.067820223 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad autoconvert0:src
0:00:00.067843814 18348      0x1e53c00 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link autoconvert0:src and src:proxypad5
0:00:00.067863896 18348      0x1e53c00 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked autoconvert0:src and src:proxypad5, successful
0:00:00.067882473 18348      0x1e53c00 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.067899074 18348      0x1e53c00 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<autoconvert0:src> Received event on flushing pad. Discarding
0:00:00.067927954 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<autoconvert0> completed state change to READY
0:00:00.067947058 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<autoconvert0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.067978852 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<autoconvert0> current READY pending VOID_PENDING, desired next READY
0:00:00.068003884 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<autoconvert0> completed state change to READY
0:00:00.068022970 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<auddec> child 'autoconvert0' changed state to 2(READY) successfully
0:00:00.068044375 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<auddec> completed state change to READY
0:00:00.068062173 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<auddec> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.068086050 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'auddec' changed state to 2(READY) successfully
0:00:00.068107621 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<audioselect> current NULL pending VOID_PENDING, desired next READY
0:00:00.068134004 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<audioselect> completed state change to READY
0:00:00.068152485 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<audioselect> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.068175902 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'audioselect' changed state to 2(READY) successfully
0:00:00.068201064 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<spu_q> current NULL pending VOID_PENDING, desired next READY
0:00:00.068221503 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<spu_q> completed state change to READY
0:00:00.068238561 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<spu_q> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.068261768 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'spu_q' changed state to 2(READY) successfully
0:00:00.068282069 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<subpselect> current NULL pending VOID_PENDING, desired next READY
0:00:00.068301964 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<subpselect> completed state change to READY
0:00:00.068319160 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<subpselect> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.068347375 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'subpselect' changed state to 2(READY) successfully
0:00:00.068370022 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnparsetter> current NULL pending VOID_PENDING, desired next READY
0:00:00.068390648 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<rsnparsetter> completed state change to READY
0:00:00.068407911 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<rsnparsetter> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.068431421 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnparsetter' changed state to 2(READY) successfully
0:00:00.068453178 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnviddec> current NULL pending VOID_PENDING, desired next READY
0:00:00.068473942 18348      0x1e53c00 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "autoconvert"
0:00:00.068535741 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstAutoConvert@0x20df210> adding pad 'sink'
0:00:00.068556764 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstAutoConvert@0x20df210> adding pad 'src'
0:00:00.068843635 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad autoconvert1:sink
0:00:00.068865666 18348      0x1e53c00 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link sink:proxypad0 and autoconvert1:sink
0:00:00.068885269 18348      0x1e53c00 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked sink:proxypad0 and autoconvert1:sink, successful
0:00:00.068903419 18348      0x1e53c00 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.068922303 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad autoconvert1:src
0:00:00.068944532 18348      0x1e53c00 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link autoconvert1:src and src:proxypad1
0:00:00.068963895 18348      0x1e53c00 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked autoconvert1:src and src:proxypad1, successful
0:00:00.068981503 18348      0x1e53c00 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.068997148 18348      0x1e53c00 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<autoconvert1:src> Received event on flushing pad. Discarding
0:00:00.069028849 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<autoconvert1> completed state change to READY
0:00:00.069047330 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<autoconvert1> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.069077133 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<autoconvert1> current READY pending VOID_PENDING, desired next READY
0:00:00.069101546 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<autoconvert1> completed state change to READY
0:00:00.069120099 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<rsnviddec> child 'autoconvert1' changed state to 2(READY) successfully
0:00:00.069150405 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<rsnviddec> completed state change to READY
0:00:00.069167788 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<rsnviddec> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.069191647 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnviddec' changed state to 2(READY) successfully
0:00:00.069212261 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnvidparse> current NULL pending VOID_PENDING, desired next READY
0:00:00.069232661 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<rsnvidparse> completed state change to READY
0:00:00.069249457 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<rsnvidparse> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.069272634 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnvidparse' changed state to 2(READY) successfully
0:00:00.069293434 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnmq> current NULL pending VOID_PENDING, desired next READY
0:00:00.069313762 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<rsnmq> completed state change to READY
0:00:00.069330514 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<rsnmq> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.069353462 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnmq' changed state to 2(READY) successfully
0:00:00.069374900 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<dvddemux> current NULL pending VOID_PENDING, desired next READY
0:00:00.069395766 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<dvddemux> completed state change to READY
0:00:00.069412520 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<dvddemux> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.069435655 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'dvddemux' changed state to 2(READY) successfully
0:00:00.069456223 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<dvdsrc> current NULL pending VOID_PENDING, desired next READY
0:00:00.069525392 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<dvdsrc> completed state change to READY
0:00:00.069542748 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<dvdsrc> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.069569712 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'dvdsrc' changed state to 2(READY) successfully
0:00:00.069592070 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2303:gst_element_continue_state:<source> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:00.069611721 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<source> notifying about state-changed NULL to READY (PAUSED pending)
0:00:00.069634335 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2310:gst_element_continue_state:<source> continue state change READY to PAUSED, final PAUSED
0:00:00.069662971 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<auddec> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.069690335 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<autoconvert0> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.069716519 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<autoconvert0> completed state change to PAUSED
0:00:00.069734507 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<autoconvert0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.069758550 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<auddec> child 'autoconvert0' changed state to 3(PAUSED) successfully
0:00:00.069783110 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<auddec> completed state change to PAUSED
0:00:00.069800650 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<auddec> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.069824482 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'auddec' changed state to 3(PAUSED) successfully
0:00:00.069845264 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<audioselect> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.069867875 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<audioselect> completed state change to PAUSED
0:00:00.069885752 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<audioselect> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.069909581 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'audioselect' changed state to 3(PAUSED) successfully
0:00:00.069931401 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<spu_q> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.069965586 18348      0x1e53c00 INFO                    task gsttask.c:431:gst_task_set_lock: setting stream lock 0x20c9210 on task 0x20ea050
0:00:00.069986414 18348      0x1e53c00 INFO                GST_PADS gstpad.c:5515:gst_pad_start_task:<spu_q:src> created task 0x20ea050
0:00:00.070071931 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<spu_q> completed state change to PAUSED
0:00:00.070093712 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<spu_q> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.070126977 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'spu_q' changed state to 3(PAUSED) successfully
0:00:00.070155065 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<subpselect> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.070180633 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<subpselect> completed state change to PAUSED
0:00:00.070202553 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<subpselect> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.070237890 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'subpselect' changed state to 3(PAUSED) successfully
0:00:00.070260944 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnparsetter> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.070285269 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<rsnparsetter> completed state change to PAUSED
0:00:00.070303377 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<rsnparsetter> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.070327615 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnparsetter' changed state to 3(PAUSED) successfully
0:00:00.070349829 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnviddec> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.070380132 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<autoconvert1> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.070410777 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<autoconvert1> completed state change to PAUSED
0:00:00.070429439 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<autoconvert1> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.070454303 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<rsnviddec> child 'autoconvert1' changed state to 3(PAUSED) successfully
0:00:00.070475903 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<rsnviddec> completed state change to PAUSED
0:00:00.070482460 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<rsnviddec> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.070494526 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnviddec' changed state to 3(PAUSED) successfully
0:00:00.070503697 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnvidparse> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.070559588 18348      0x1e53c00 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<rsnvidparse:sink> pad has no peer
0:00:00.070569253 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<rsnvidparse> completed state change to PAUSED
0:00:00.070575485 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<rsnvidparse> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.070587385 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnvidparse' changed state to 3(PAUSED) successfully
0:00:00.070597032 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnmq> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.070607175 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<rsnmq> completed state change to PAUSED
0:00:00.070613091 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<rsnmq> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.070623791 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnmq' changed state to 3(PAUSED) successfully
0:00:00.070633639 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<dvddemux> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.070658560 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<dvddemux> completed state change to PAUSED
0:00:00.070664777 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<dvddemux> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.070676000 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'dvddemux' changed state to 3(PAUSED) successfully
0:00:00.070685208 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<dvdsrc> current READY pending VOID_PENDING, desired next PAUSED
libdvdnav: Using dvdnav version 5.0.3
libdvdnav: DVD Title: 
libdvdnav: DVD Serial Number: 41FA310D___MVB__
libdvdnav: DVD Title (Alternative): 
libdvdnav: DVD disk reports itself with Region mask 0x00fd0000. Regions: 2

libdvdread: Attempting to retrieve all CSS keys
libdvdread: This can take a _long_ time, please be patient

libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x00000167
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_0.VOB at 0x000001cd
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x0000cdd8
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_0.VOB at 0x0000fabd
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_1.VOB at 0x0000fb0a
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_03_0.VOB at 0x00278c0e
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_03_1.VOB at 0x00278c5b
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_04_0.VOB at 0x0028235c
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_04_1.VOB at 0x002823a9
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_05_0.VOB at 0x00287815
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_05_1.VOB at 0x00287862
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_06_0.VOB at 0x0028cb02
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_06_1.VOB at 0x0028cb4f
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_07_0.VOB at 0x0029042f
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_07_1.VOB at 0x0029047c
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_08_0.VOB at 0x00297794
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_08_1.VOB at 0x002977e1
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_09_0.VOB at 0x0029f56a
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_09_1.VOB at 0x0029f5b7
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_10_0.VOB at 0x002a6c05
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_10_1.VOB at 0x002a6c52
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_11_0.VOB at 0x002ad042
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_11_1.VOB at 0x002ad08f
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_12_0.VOB at 0x002b2d41
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_12_1.VOB at 0x002b2d8e
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_13_0.VOB at 0x002bdcb3
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_13_1.VOB at 0x002bdd00
libdvdread: Elapsed time 0
libdvdread: Found 13 VTS's
libdvdread: Elapsed time 0
0:00:00.114722964 18348      0x1e53c00 INFO                 basesrc gstbasesrc.c:1328:gst_base_src_do_seek:<dvdsrc> seeking: time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=99:99:99.999999999, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.000000000, duration 99:99:99.999999999
0:00:00.115235919 18348      0x1e53c00 INFO                    task gsttask.c:431:gst_task_set_lock: setting stream lock 0x20c8090 on task 0x20ea170
0:00:00.115260181 18348      0x1e53c00 INFO                GST_PADS gstpad.c:5515:gst_pad_start_task:<dvdsrc:src> created task 0x20ea170
0:00:00.115336440 18348      0x1e53c00 WARN               rsndvdsrc resindvdsrc.c:2490:rsn_dvdsrc_post_title_info:<dvdsrc> Failed to get title 0 info
0:00:00.115407617 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:892:gst_element_get_static_pad: no such pad 'sink' in element "dvdsrc"
0:00:00.115449934 18348      0x20e5a80 FIXME                default gstutils.c:3643:gst_pad_create_stream_id_internal:<dvdsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:00:00.115543305 18348      0x20e5a80 INFO               GST_EVENT gstevent.c:678:gst_event_new_caps: creating caps event application/x-resin-dvd
0:00:00.116475416 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<dvdsrc> completed state change to PAUSED
0:00:00.116508456 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<dvdsrc> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.116509295 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad dvddemux:sink
0:00:00.116545505 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'dvdsrc' changed state to 3(PAUSED) successfully
0:00:00.116556896 18348      0x20e5a80 INFO               GST_EVENT gstevent.c:678:gst_event_new_caps: creating caps event video/mpeg, mpegversion=(int)2, systemstream=(boolean)false, parsed=(boolean)false
0:00:00.116603700 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<source> completed state change to PAUSED
0:00:00.116611371 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<source> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.116626838 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<dvddemux> adding pad 'video_e0'
0:00:00.116655435 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<uridecodebin0> child 'source' changed state to 3(PAUSED) successfully
0:00:00.116669082 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<uridecodebin0> completed state change to PAUSED
0:00:00.116667464 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad rsnvidparse:sink
0:00:00.116705448 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<uridecodebin0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.116720305 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playbin0> child 'uridecodebin0' changed state to 3(PAUSED) successfully
Pipeline is PREROLLING ...
0:00:00.116747416 18348      0x20e5a80 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<'':video> pad has no peer
0:00:00.130047141 18348      0x20e5a80 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/tmp/install/lib/gstreamer-1.0/libgstvaapi.so" loaded
0:00:00.130071713 18348      0x20e5a80 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "vaapidecode"
0:00:00.130206979 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstVideoDecoder@0x7f8788035ff0> adding pad 'sink'
0:00:00.130225340 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstVideoDecoder@0x7f8788035ff0> adding pad 'src'
0:00:00.130249536 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad (null):sink
0:00:00.130258774 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad (null):src
0:00:00.130335492 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link '':src_internal and vaapidecode0:sink
0:00:00.130347792 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked '':src_internal and vaapidecode0:sink, successful
0:00:00.130356527 18348      0x20e5a80 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.130369501 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link vaapidecode0:src and '':sink_internal
0:00:00.130383554 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked vaapidecode0:src and '':sink_internal, successful
0:00:00.130391961 18348      0x20e5a80 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.130397021 18348      0x20e5a80 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<vaapidecode0:src> Received event on flushing pad. Discarding
0:00:00.130435905 18348      0x20e5a80 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<'':video> pad has no peer
0:00:00.130447862 18348      0x20e5a80 INFO             GST_CONTEXT gstvaapivideocontext.c:81:context_pad_query:<vaapidecode0:src> context pad peer query failed
0:00:00.130460143 18348      0x20e5a80 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<rsnvidparse:sink> pad has no peer
0:00:00.130467642 18348      0x20e5a80 INFO             GST_CONTEXT gstvaapivideocontext.c:81:context_pad_query:<vaapidecode0:sink> context pad peer query failed
0:00:00.130488755 18348      0x20e5a80 INFO             GST_CONTEXT gstvaapivideocontext.c:149:gst_vaapi_video_context_prepare:<vaapidecode0> posting `need-context' message
0:00:00.130531327 18348      0x20e5a80 INFO                   vaapi gstvaapidisplay.c:119:libgstvaapi_init_once: gstreamer-vaapi version drop_0.10_1.0-132-g3ccb198
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns 0
libva info: Trying to open /usr/lib64/dri/radeonsi_drv_video.so
libva info: va_openDriver() returns -1
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns 0
libva info: Trying to open /usr/lib64/dri/radeonsi_drv_video.so
libva info: va_openDriver() returns -1
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns -1
libva error: va_getDriverName() failed with unknown libva error,driver_name=(null)
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns -1
libva error: va_getDriverName() failed with unknown libva error,driver_name=(null)
0:00:00.223701731 18348      0x20e5a80 WARN            videodecoder gstvideodecoder.c:2261:gst_video_decoder_change_state:<vaapidecode0> error: Failed to open decoder
0:00:00.223741214 18348      0x20e5a80 INFO        GST_ERROR_SYSTEM gstelement.c:1835:gst_element_message_full:<vaapidecode0> posting message: Could not initialize supporting library.
0:00:00.223790343 18348      0x20e5a80 INFO        GST_ERROR_SYSTEM gstelement.c:1858:gst_element_message_full:<vaapidecode0> posted error message: Could not initialize supporting library.
0:00:00.223811853 18348      0x20e5a80 INFO              GST_STATES gstelement.c:2609:gst_element_change_state:<vaapidecode0> have FAILURE change_state return
0:00:00.223825960 18348      0x20e5a80 INFO              GST_STATES gstelement.c:2203:gst_element_abort_state:<vaapidecode0> aborting state from NULL to PAUSED
ERROR: from element /GstPlayBin:playbin0/GstURIDecodeBin:uridecodebin0/RsnDvdBin:source/RsnVideoDec:rsnviddec/GstAutoConvert:autoconvert1/GstVaapiDecode:vaapidecode0: Could not initialize supporting library.
Additional debug info:
gstvideodecoder.c(2261): gst_video_decoder_change_state (): /GstPlayBin:playbin0/GstURIDecodeBin:uridecodebin0/RsnDvdBin:source/RsnVideoDec:rsnviddec/GstAutoConvert:autoconvert1/GstVaapiDecode:vaapidecode0:
Failed to open decoder
0:00:00.223867651 18348      0x20e5a80 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<'':video> pad has no peer
ERROR: pipeline doesn't want to preroll.
Setting pipeline to NULL ...
0:00:00.223916134 18348      0x20e5a80 INFO             GST_CONTEXT gstvaapivideocontext.c:81:context_pad_query:<vaapidecode0:src> context pad peer query failed
0:00:00.223939590 18348      0x20e5a80 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<rsnvidparse:sink> pad has no peer
0:00:00.223937587 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<playsink> current READY pending PAUSED, desired next NULL
0:00:00.223956679 18348      0x20e5a80 INFO             GST_CONTEXT gstvaapivideocontext.c:81:context_pad_query:<vaapidecode0:sink> context pad peer query failed
0:00:00.223979527 18348      0x20e5a80 INFO             GST_CONTEXT gstvaapivideocontext.c:149:gst_vaapi_video_context_prepare:<vaapidecode0> posting `need-context' message
0:00:00.223978519 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<streamsynchronizer0> current PAUSED pending VOID_PENDING, desired next READY
0:00:00.224010927 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<streamsynchronizer0> completed state change to READY
0:00:00.224027171 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<streamsynchronizer0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:00.224051087 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playsink> child 'streamsynchronizer0' changed state to 2(READY) successfully
0:00:00.224073280 18348      0x1e53c00 INFO                playsink gstplaysink.c:1412:do_async_done:<playsink> Sending async_done message
0:00:00.224092811 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2303:gst_element_continue_state:<playsink> committing state from READY to READY, pending NULL, next NULL
0:00:00.224110176 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<playsink> notifying about state-changed READY to READY (NULL pending)
0:00:00.224137621 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2310:gst_element_continue_state:<playsink> continue state change READY to NULL, final NULL
0:00:00.224159736 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<streamsynchronizer0> current READY pending VOID_PENDING, desired next NULL
0:00:00.224179742 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<streamsynchronizer0> completed state change to NULL
0:00:00.224194439 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<streamsynchronizer0> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:00.224215320 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playsink> child 'streamsynchronizer0' changed state to 1(NULL) successfully
0:00:00.224235681 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<playsink> completed state change to NULL
0:00:00.224250273 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<playsink> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:00.224270877 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playbin0> child 'playsink' changed state to 1(NULL) successfully
0:00:00.224289560 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<uridecodebin0> current PAUSED pending VOID_PENDING, desired next NULL
0:00:00.224310971 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<source> current PAUSED pending VOID_PENDING, desired next READY
0:00:00.224344799 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<auddec> current PAUSED pending VOID_PENDING, desired next READY
0:00:00.224379857 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<autoconvert0> current PAUSED pending VOID_PENDING, desired next READY
0:00:00.224406962 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<autoconvert0> completed state change to READY
0:00:00.224422916 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<autoconvert0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:00.224446493 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<auddec> child 'autoconvert0' changed state to 2(READY) successfully
0:00:00.224463304 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<auddec> completed state change to READY
0:00:00.224470969 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<auddec> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:00.224484213 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'auddec' changed state to 2(READY) successfully
0:00:00.224495649 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<audioselect> current PAUSED pending VOID_PENDING, desired next READY
0:00:00.224509880 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<audioselect> completed state change to READY
0:00:00.224517009 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<audioselect> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:00.224529585 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'audioselect' changed state to 2(READY) successfully
0:00:00.224541503 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<spu_q> current PAUSED pending VOID_PENDING, desired next READY
0:00:00.224644295 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<spu_q> completed state change to READY
0:00:00.224652937 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<spu_q> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:00.224678643 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'spu_q' changed state to 2(READY) successfully
0:00:00.224690479 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<subpselect> current PAUSED pending VOID_PENDING, desired next READY
0:00:00.224704196 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<subpselect> completed state change to READY
0:00:00.224711409 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<subpselect> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:00.224724054 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'subpselect' changed state to 2(READY) successfully
0:00:00.224735816 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnparsetter> current PAUSED pending VOID_PENDING, desired next READY
0:00:00.224749442 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<rsnparsetter> completed state change to READY
0:00:00.224756736 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<rsnparsetter> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:00.224769198 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnparsetter' changed state to 2(READY) successfully
0:00:00.224780292 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnviddec> current PAUSED pending VOID_PENDING, desired next READY
0:00:00.224797458 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<autoconvert1> current PAUSED pending VOID_PENDING, desired next READY
0:00:00.224812613 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<vaapidecode0> current NULL pending PAUSED, desired next READY
0:00:00.224821278 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<vaapidecode0> skipping transition from NULL to  READY
0:00:00.224828443 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<autoconvert1> child 'vaapidecode0' changed state to 2(READY) successfully
0:00:00.224841342 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<autoconvert1> completed state change to READY
0:00:00.224848648 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<autoconvert1> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:00.224862211 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<rsnviddec> child 'autoconvert1' changed state to 2(READY) successfully
0:00:00.224877149 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<rsnviddec> completed state change to READY
0:00:00.224884467 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<rsnviddec> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:00.224897152 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnviddec' changed state to 2(READY) successfully
0:00:00.224907809 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnvidparse> current PAUSED pending VOID_PENDING, desired next READY
0:00:00.224926708 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<rsnvidparse> completed state change to READY
0:00:00.224933997 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<rsnvidparse> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:00.224946140 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnvidparse' changed state to 2(READY) successfully
0:00:00.224957402 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnmq> current PAUSED pending VOID_PENDING, desired next READY
0:00:00.224968742 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<rsnmq> completed state change to READY
0:00:00.224975814 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<rsnmq> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:00.224988420 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnmq' changed state to 2(READY) successfully
0:00:00.225000022 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<dvddemux> current PAUSED pending VOID_PENDING, desired next READY
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns 0
libva info: Trying to open /usr/lib64/dri/radeonsi_drv_video.so
libva info: va_openDriver() returns -1
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns 0
libva info: Trying to open /usr/lib64/dri/radeonsi_drv_video.so
libva info: va_openDriver() returns -1
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns -1
libva error: va_getDriverName() failed with unknown libva error,driver_name=(null)
0:00:00.323507180 18348      0x20e5a80 ERROR            vaapidecode gstvaapidecode.c:958:gst_vaapidecode_ensure_allowed_caps: failed to retrieve VA display
0:00:00.323616421 18348      0x20e5a80 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "vaapidecodebin"
0:00:00.323784979 18348      0x20e5a80 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "vaapidecode" named "vaapidecode"
0:00:00.323817447 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstVideoDecoder@0x7f878803e990> adding pad 'sink'
0:00:00.323835077 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstVideoDecoder@0x7f878803e990> adding pad 'src'
0:00:00.323849837 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad (null):sink
0:00:00.323858795 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad (null):src
0:00:00.323882029 18348      0x20e5a80 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "queue" named "queue"
0:00:00.323914731 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstQueue@0x20dcbc0> adding pad 'sink'
0:00:00.323936461 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstQueue@0x20dcbc0> adding pad 'src'
0:00:00.323966995 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstutils.c:1545:gst_element_link_pads_full: trying to link element vaapidecode:src to element queue:sink
0:00:00.323977929 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad vaapidecode:src
0:00:00.323985621 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad queue:sink
0:00:00.323992915 18348      0x20e5a80 INFO                GST_PADS gstutils.c:1445:prepare_link_maybe_ghosting: vaapidecode and queue in same bin, no need for ghost pads
0:00:00.324007010 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link vaapidecode:src and queue:sink
0:00:00.324017508 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked vaapidecode:src and queue:sink, successful
0:00:00.324025925 18348      0x20e5a80 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.324031751 18348      0x20e5a80 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<vaapidecode:src> Received event on flushing pad. Discarding
0:00:00.324043326 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad vaapidecode:sink
0:00:00.324070981 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link sink:proxypad7 and vaapidecode:sink
0:00:00.324080824 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked sink:proxypad7 and vaapidecode:sink, successful
0:00:00.324089126 18348      0x20e5a80 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.324098956 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstVaapiDecodeBin@0x7f878803d090> adding pad 'sink'
0:00:00.324129348 18348      0x20e5a80 ERROR            autoconvert gstautoconvert.c:365:get_pad_by_direction: Did not find pad of direction 1 in vaapidecodebin0
0:00:00.324139963 18348      0x20e5a80 ERROR            autoconvert gstautoconvert.c:443:gst_auto_convert_add_element:<autoconvert1> Could not find source in vaapidecodebin0
0:00:00.324159395 18348      0x20e5a80 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<queue> current NULL pending VOID_PENDING, desired next NULL
0:00:00.324174282 18348      0x20e5a80 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<queue> skipping transition from NULL to  NULL
0:00:00.324181675 18348      0x20e5a80 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vaapidecodebin0> child 'queue' changed state to 1(NULL) successfully
0:00:00.324192708 18348      0x20e5a80 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<vaapidecode> current NULL pending VOID_PENDING, desired next NULL
0:00:00.324201155 18348      0x20e5a80 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<vaapidecode> skipping transition from NULL to  NULL
0:00:00.324208669 18348      0x20e5a80 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vaapidecodebin0> child 'vaapidecode' changed state to 1(NULL) successfully
0:00:00.324220048 18348      0x20e5a80 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<vaapidecodebin0> completed state change to NULL
0:00:00.324233028 18348      0x20e5a80 INFO           GST_PARENTAGE gstbin.c:1554:gst_bin_remove_func:<autoconvert1> removed child "vaapidecodebin0"
0:00:00.324244735 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking vaapidecode:src(0x20e91e0) and queue:sink(0x20e9410)
0:00:00.324260160 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked vaapidecode:src and queue:sink
0:00:00.324275121 18348      0x20e5a80 INFO           GST_PARENTAGE gstbin.c:1554:gst_bin_remove_func:<vaapidecodebin0> removed child "queue"
0:00:00.324284244 18348      0x20e5a80 INFO         GST_REFCOUNTING gstelement.c:2873:gst_element_dispose:<queue> dispose
0:00:00.324290242 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<queue> removing pad 'sink'
0:00:00.324310291 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<queue> removing pad 'src'
0:00:00.324320376 18348      0x20e5a80 INFO         GST_REFCOUNTING gstelement.c:2917:gst_element_dispose:<queue> parent class dispose
0:00:00.324328016 18348      0x20e5a80 INFO         GST_REFCOUNTING gstelement.c:2948:gst_element_finalize:<queue> finalize
0:00:00.324333416 18348      0x20e5a80 INFO         GST_REFCOUNTING gstelement.c:2953:gst_element_finalize:<queue> finalize parent
0:00:00.324341047 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking sink:proxypad7(0x20d5230) and vaapidecode:sink(0x20e8fb0)
0:00:00.324351969 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked sink:proxypad7 and vaapidecode:sink
0:00:00.324361971 18348      0x20e5a80 INFO           GST_PARENTAGE gstbin.c:1554:gst_bin_remove_func:<vaapidecodebin0> removed child "vaapidecode"
0:00:00.324370832 18348      0x20e5a80 INFO         GST_REFCOUNTING gstelement.c:2873:gst_element_dispose:<vaapidecode> dispose
0:00:00.324376490 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<vaapidecode> removing pad 'sink'
0:00:00.324384064 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<vaapidecode> removing pad 'src'
0:00:00.324391539 18348      0x20e5a80 INFO         GST_REFCOUNTING gstelement.c:2917:gst_element_dispose:<vaapidecode> parent class dispose
0:00:00.324404043 18348      0x20e5a80 INFO         GST_REFCOUNTING gstelement.c:2948:gst_element_finalize:<vaapidecode> finalize
0:00:00.324409725 18348      0x20e5a80 INFO         GST_REFCOUNTING gstelement.c:2953:gst_element_finalize:<vaapidecode> finalize parent
0:00:00.324416677 18348      0x20e5a80 INFO         GST_REFCOUNTING gstelement.c:2873:gst_element_dispose:<vaapidecodebin0> dispose
0:00:00.324422140 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<vaapidecodebin0> removing pad 'sink'
0:00:00.324432746 18348      0x20e5a80 INFO         GST_REFCOUNTING gstelement.c:2917:gst_element_dispose:<vaapidecodebin0> parent class dispose
0:00:00.324439117 18348      0x20e5a80 INFO         GST_REFCOUNTING gstelement.c:2948:gst_element_finalize:<vaapidecodebin0> finalize
0:00:00.324444937 18348      0x20e5a80 INFO         GST_REFCOUNTING gstelement.c:2953:gst_element_finalize:<vaapidecodebin0> finalize parent
0:00:00.328162559 18348      0x20e5a80 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_avs
0:00:00.328238643 18348      0x20e5a80 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_daud
0:00:00.328270080 18348      0x20e5a80 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_ea
0:00:00.328295329 18348      0x20e5a80 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_ffm
0:00:00.328321113 18348      0x20e5a80 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_4xm
0:00:00.328350059 18348      0x20e5a80 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_gxf
0:00:00.328374514 18348      0x20e5a80 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_idcin
0:00:00.328399880 18348      0x20e5a80 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_ipmovie
0:00:00.328429626 18348      0x20e5a80 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_mm
0:00:00.328451010 18348      0x20e5a80 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_mmf
0:00:00.328489993 18348      0x20e5a80 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_nsv
0:00:00.328513627 18348      0x20e5a80 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_nut
0:00:00.328560043 18348      0x20e5a80 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_film_cpk
0:00:00.328591985 18348      0x20e5a80 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_smk
0:00:00.328615751 18348      0x20e5a80 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_sol
0:00:00.328640332 18348      0x20e5a80 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_psxstr
0:00:00.328674494 18348      0x20e5a80 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_vmd
0:00:00.328704499 18348      0x20e5a80 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_wc3movie
0:00:00.328726700 18348      0x20e5a80 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_wsaud
0:00:00.328748602 18348      0x20e5a80 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_wsvqa
0:00:00.328777541 18348      0x20e5a80 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_yuv4mpegpipe
0:00:00.329091731 18348      0x20e5a80 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/tmp/install/lib/gstreamer-1.0/libgstlibav.so" loaded
0:00:00.329102720 18348      0x20e5a80 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "avdec_mpeg2video"
0:00:00.329210321 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstVideoDecoder@0x7f878808ff60> adding pad 'sink'
0:00:00.329227536 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstVideoDecoder@0x7f878808ff60> adding pad 'src'
0:00:00.329660535 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link '':src_internal and avdec_mpeg2video0:sink
0:00:00.329672089 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked '':src_internal and avdec_mpeg2video0:sink, successful
0:00:00.329681158 18348      0x20e5a80 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.329691737 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link avdec_mpeg2video0:src and '':sink_internal
0:00:00.329701062 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked avdec_mpeg2video0:src and '':sink_internal, successful
0:00:00.329707899 18348      0x20e5a80 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.329712938 18348      0x20e5a80 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<avdec_mpeg2video0:src> Received event on flushing pad. Discarding
0:00:00.329725553 18348      0x20e5a80 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<avdec_mpeg2video0> completed state change to READY
0:00:00.329738054 18348      0x20e5a80 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<avdec_mpeg2video0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.329802178 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad rsnvidparse:sink
0:00:00.329878978 18348      0x20e5a80 INFO                    task gsttask.c:431:gst_task_set_lock: setting stream lock 0x20e96b0 on task 0x20ea5f0
0:00:00.329889813 18348      0x20e5a80 INFO                GST_PADS gstpad.c:5515:gst_pad_start_task:<'':src_0> created task 0x20ea5f0
0:00:00.329908086 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<rsnmq> adding pad 'src_0'
0:00:00.329916509 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<rsnmq> adding pad 'sink_0'
0:00:00.329932821 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link dvddemux:video_e0 and rsnmq:sink_0
0:00:00.329953038 18348      0x20e5a80 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<rsnmq:src_0> pad has no peer
0:00:00.329964282 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked dvddemux:video_e0 and rsnmq:sink_0, successful
0:00:00.329977111 18348      0x20e5a80 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.329982086 18348      0x20e5a80 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<dvddemux:video_e0> Received event on flushing pad. Discarding
0:00:00.329992581 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad rsnmq:src_0
0:00:00.330002369 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link rsnmq:src_0 and rsnvidparse:sink
0:00:00.330028493 18348      0x20e5a80 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<'':video> pad has no peer
0:00:00.330795589 18348      0x20e5a80 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<'':video> pad has no peer
0:00:00.330808472 18348      0x20e5a80 INFO             GST_CONTEXT gstvaapivideocontext.c:81:context_pad_query:<vaapidecode0:src> context pad peer query failed
0:00:00.330849721 18348      0x20e5a80 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<rsnvidparse:sink> pad has no peer
0:00:00.330860035 18348      0x20e5a80 INFO             GST_CONTEXT gstvaapivideocontext.c:81:context_pad_query:<vaapidecode0:sink> context pad peer query failed
0:00:00.330884981 18348      0x20e5a80 INFO             GST_CONTEXT gstvaapivideocontext.c:149:gst_vaapi_video_context_prepare:<vaapidecode0> posting `need-context' message
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns 0
libva info: Trying to open /usr/lib64/dri/radeonsi_drv_video.so
libva info: va_openDriver() returns -1
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns 0
libva info: Trying to open /usr/lib64/dri/radeonsi_drv_video.so
libva info: va_openDriver() returns -1
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns -1
libva error: va_getDriverName() failed with unknown libva error,driver_name=(null)
0:00:00.430593605 18348      0x20e5a80 ERROR            vaapidecode gstvaapidecode.c:958:gst_vaapidecode_ensure_allowed_caps: failed to retrieve VA display
0:00:00.430641869 18348      0x20e5a80 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "vaapidecodebin"
0:00:00.430683265 18348      0x20e5a80 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "vaapidecode" named "vaapidecode"
0:00:00.430723731 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstVideoDecoder@0x7f8788099390> adding pad 'sink'
0:00:00.430752815 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstVideoDecoder@0x7f8788099390> adding pad 'src'
0:00:00.430770932 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad (null):sink
0:00:00.430782047 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad (null):src
0:00:00.430809654 18348      0x20e5a80 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "queue" named "queue"
0:00:00.430846207 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstQueue@0x20dceb0> adding pad 'sink'
0:00:00.430872121 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstQueue@0x20dceb0> adding pad 'src'
0:00:00.430910057 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstutils.c:1545:gst_element_link_pads_full: trying to link element vaapidecode:src to element queue:sink
0:00:00.430923100 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad vaapidecode:src
0:00:00.430932196 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad queue:sink
0:00:00.430940736 18348      0x20e5a80 INFO                GST_PADS gstutils.c:1445:prepare_link_maybe_ghosting: vaapidecode and queue in same bin, no need for ghost pads
0:00:00.430958760 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link vaapidecode:src and queue:sink
0:00:00.430971002 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked vaapidecode:src and queue:sink, successful
0:00:00.430981368 18348      0x20e5a80 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.430988391 18348      0x20e5a80 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<vaapidecode:src> Received event on flushing pad. Discarding
0:00:00.431002312 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad vaapidecode:sink
0:00:00.431034729 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link sink:proxypad8 and vaapidecode:sink
0:00:00.431046614 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked sink:proxypad8 and vaapidecode:sink, successful
0:00:00.431056877 18348      0x20e5a80 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.431068482 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstVaapiDecodeBin@0x7f878803d270> adding pad 'sink'
0:00:00.431092594 18348      0x20e5a80 ERROR            autoconvert gstautoconvert.c:365:get_pad_by_direction: Did not find pad of direction 1 in vaapidecodebin1
0:00:00.431104238 18348      0x20e5a80 ERROR            autoconvert gstautoconvert.c:443:gst_auto_convert_add_element:<autoconvert1> Could not find source in vaapidecodebin1
0:00:00.431128055 18348      0x20e5a80 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<queue> current NULL pending VOID_PENDING, desired next NULL
0:00:00.431139879 18348      0x20e5a80 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<queue> skipping transition from NULL to  NULL
0:00:00.431149102 18348      0x20e5a80 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vaapidecodebin1> child 'queue' changed state to 1(NULL) successfully
0:00:00.431162126 18348      0x20e5a80 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<vaapidecode> current NULL pending VOID_PENDING, desired next NULL
0:00:00.431172429 18348      0x20e5a80 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<vaapidecode> skipping transition from NULL to  NULL
0:00:00.431181194 18348      0x20e5a80 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vaapidecodebin1> child 'vaapidecode' changed state to 1(NULL) successfully
0:00:00.431194589 18348      0x20e5a80 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<vaapidecodebin1> completed state change to NULL
0:00:00.431210904 18348      0x20e5a80 INFO           GST_PARENTAGE gstbin.c:1554:gst_bin_remove_func:<autoconvert1> removed child "vaapidecodebin1"
0:00:00.431224650 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking vaapidecode:src(0x7f878809a0f0) and queue:sink(0x7f878809a320)
0:00:00.431242728 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked vaapidecode:src and queue:sink
0:00:00.431260443 18348      0x20e5a80 INFO           GST_PARENTAGE gstbin.c:1554:gst_bin_remove_func:<vaapidecodebin1> removed child "queue"
0:00:00.431271623 18348      0x20e5a80 INFO         GST_REFCOUNTING gstelement.c:2873:gst_element_dispose:<queue> dispose
0:00:00.431278993 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<queue> removing pad 'sink'
0:00:00.431292751 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<queue> removing pad 'src'
0:00:00.431304762 18348      0x20e5a80 INFO         GST_REFCOUNTING gstelement.c:2917:gst_element_dispose:<queue> parent class dispose
0:00:00.431313819 18348      0x20e5a80 INFO         GST_REFCOUNTING gstelement.c:2948:gst_element_finalize:<queue> finalize
0:00:00.431320404 18348      0x20e5a80 INFO         GST_REFCOUNTING gstelement.c:2953:gst_element_finalize:<queue> finalize parent
0:00:00.431329735 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking sink:proxypad8(0x20d5470) and vaapidecode:sink(0x20e9410)
0:00:00.431343009 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked sink:proxypad8 and vaapidecode:sink
0:00:00.431354992 18348      0x20e5a80 INFO           GST_PARENTAGE gstbin.c:1554:gst_bin_remove_func:<vaapidecodebin1> removed child "vaapidecode"
0:00:00.431365647 18348      0x20e5a80 INFO         GST_REFCOUNTING gstelement.c:2873:gst_element_dispose:<vaapidecode> dispose
0:00:00.431372448 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<vaapidecode> removing pad 'sink'
0:00:00.431381550 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<vaapidecode> removing pad 'src'
0:00:00.431390715 18348      0x20e5a80 INFO         GST_REFCOUNTING gstelement.c:2917:gst_element_dispose:<vaapidecode> parent class dispose
0:00:00.431405716 18348      0x20e5a80 INFO         GST_REFCOUNTING gstelement.c:2948:gst_element_finalize:<vaapidecode> finalize
0:00:00.431412758 18348      0x20e5a80 INFO         GST_REFCOUNTING gstelement.c:2953:gst_element_finalize:<vaapidecode> finalize parent
0:00:00.431421003 18348      0x20e5a80 INFO         GST_REFCOUNTING gstelement.c:2873:gst_element_dispose:<vaapidecodebin1> dispose
0:00:00.431426197 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<vaapidecodebin1> removing pad 'sink'
0:00:00.431438972 18348      0x20e5a80 INFO         GST_REFCOUNTING gstelement.c:2917:gst_element_dispose:<vaapidecodebin1> parent class dispose
0:00:00.431446595 18348      0x20e5a80 INFO         GST_REFCOUNTING gstelement.c:2948:gst_element_finalize:<vaapidecodebin1> finalize
0:00:00.431453688 18348      0x20e5a80 INFO         GST_REFCOUNTING gstelement.c:2953:gst_element_finalize:<vaapidecodebin1> finalize parent
0:00:00.431500708 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked rsnmq:src_0 and rsnvidparse:sink, successful
0:00:00.431513345 18348      0x20e5a80 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.431521905 18348      0x20e5a80 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<dvddemux:video_e0> Received event on flushing pad. Discarding
0:00:00.431570867 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad dvddemux:sink
0:00:00.431590136 18348      0x20e5a80 INFO               GST_EVENT gstevent.c:678:gst_event_new_caps: creating caps event audio/x-private1-ac3
0:00:00.431659593 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<dvddemux> adding pad 'audio_80'
0:00:00.431677030 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad rsnvidparse:sink
0:00:00.431705122 18348      0x20e5a80 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<'':video> pad has no peer
0:00:00.432454176 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad auddec:sink
0:00:00.432482343 18348      0x20e5a80 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<'':audio> pad has no peer
0:00:00.432957096 18348      0x20e5a80 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/tmp/install/lib/gstreamer-1.0/libgsta52dec.so" loaded
0:00:00.432970879 18348      0x20e5a80 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "a52dec"
0:00:00.433060339 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstAudioDecoder@0x7f878809cec0> adding pad 'sink'
0:00:00.433081356 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstAudioDecoder@0x7f878809cec0> adding pad 'src'
0:00:00.433149529 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link '':src_internal and a52dec0:sink
0:00:00.433163137 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked '':src_internal and a52dec0:sink, successful
0:00:00.433173551 18348      0x20e5a80 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.433186617 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link a52dec0:src and '':sink_internal
0:00:00.433197828 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked a52dec0:src and '':sink_internal, successful
0:00:00.433207475 18348      0x20e5a80 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.433213337 18348      0x20e5a80 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<a52dec0:src> Received event on flushing pad. Discarding
0:00:00.433229301 18348      0x20e5a80 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<a52dec0> completed state change to READY
0:00:00.433238228 18348      0x20e5a80 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<a52dec0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.433308359 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<audioselect> adding pad 'sink_0'
0:00:00.433365099 18348      0x20e5a80 INFO                    task gsttask.c:431:gst_task_set_lock: setting stream lock 0x7f878809ac50 on task 0x20ea710
0:00:00.433378485 18348      0x20e5a80 INFO                GST_PADS gstpad.c:5515:gst_pad_start_task:<'':src_1> created task 0x20ea710
0:00:00.433429208 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<rsnmq> adding pad 'src_1'
0:00:00.433441658 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<rsnmq> adding pad 'sink_1'
0:00:00.433456226 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link dvddemux:audio_80 and rsnmq:sink_1
0:00:00.433477300 18348      0x20e5a80 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<rsnmq:src_1> pad has no peer
0:00:00.433490072 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked dvddemux:audio_80 and rsnmq:sink_1, successful
0:00:00.433502314 18348      0x20e5a80 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.433522793 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad rsnmq:src_1
0:00:00.433574121 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link rsnmq:src_1 and audioselect:sink_0
0:00:00.433599836 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked rsnmq:src_1 and audioselect:sink_0, successful
0:00:00.433611838 18348      0x20e5a80 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.433649667 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad dvddemux:sink
0:00:00.433666331 18348      0x20e5a80 INFO               GST_EVENT gstevent.c:678:gst_event_new_caps: creating caps event subpicture/x-dvd
0:00:00.433691081 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<dvddemux> adding pad 'subpicture_20'
0:00:00.433706689 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad rsnvidparse:sink
0:00:00.433730320 18348      0x20e5a80 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<'':video> pad has no peer
0:00:00.434483931 18348      0x20e5a80 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<subpselect> adding pad 'sink_0'
0:00:00.434500457 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link dvddemux:subpicture_20 and subpselect:sink_0
0:00:00.434519831 18348      0x20e5a80 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked dvddemux:subpicture_20 and subpselect:sink_0, successful
0:00:00.434530973 18348      0x20e5a80 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.434577693 18348      0x20e5a80 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<dvddemux:sink> Received event on flushing pad. Discarding
0:00:00.434590817 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<dvddemux> removing pad 'subpicture_20'
0:00:00.434613359 18348      0x20e5a80 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<dvddemux:sink> Received event on flushing pad. Discarding
0:00:00.434622012 18348      0x20e5a80 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<dvddemux:sink> Received event on flushing pad. Discarding
0:00:00.434615729 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking dvddemux:subpicture_20(0x7f878809ae10) and subpselect:sink_0(0x20dd4b0)
0:00:00.434634929 18348      0x20e5a80 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<dvddemux:sink> Received event on flushing pad. Discarding
0:00:00.434639679 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked dvddemux:subpicture_20 and subpselect:sink_0
0:00:00.434657669 18348      0x20e5a80 INFO               GST_EVENT gstevent.c:759:gst_event_new_segment: creating segment event time segment start=0:00:00.280000000, offset=0:00:00.000000000, stop=99:99:99.999999999, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.800000000, duration 99:99:99.999999999
0:00:00.434685725 18348      0x20e5a80 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<dvddemux:sink> Received event on flushing pad. Discarding
0:00:00.434692818 18348      0x20e5a80 INFO                 basesrc gstbasesrc.c:2828:gst_base_src_loop:<dvdsrc> marking pending DISCONT
0:00:00.434669623 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<dvddemux> removing pad 'audio_80'
0:00:00.434702993 18348      0x20e5a80 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<dvddemux:sink> Received event on flushing pad. Discarding
0:00:00.434714159 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking dvddemux:audio_80(0x7f878809a780) and rsnmq:sink_1(0x7f878809a9b0)
0:00:00.434733157 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked dvddemux:audio_80 and rsnmq:sink_1
0:00:00.434758109 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<dvddemux> removing pad 'video_e0'
0:00:00.434767085 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking dvddemux:video_e0(0x20e84c0) and rsnmq:sink_0(0x20e8fb0)
0:00:00.434783027 18348      0x1e53c00 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked dvddemux:video_e0 and rsnmq:sink_0
0:00:00.434807723 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<dvddemux> completed state change to READY
0:00:00.434816948 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<dvddemux> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:00.434833756 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'dvddemux' changed state to 2(READY) successfully
0:00:00.434857917 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<auddec> current READY pending VOID_PENDING, desired next READY
0:00:00.434877670 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<autoconvert0> current READY pending VOID_PENDING, desired next READY
0:00:00.434895321 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<a52dec0> current READY pending VOID_PENDING, desired next READY
0:00:00.434909735 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<a52dec0> skipping transition from READY to  READY
0:00:00.434919710 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<autoconvert0> child 'a52dec0' changed state to 2(READY) successfully
0:00:00.434930349 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<autoconvert0> completed state change to READY
0:00:00.434937896 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<auddec> child 'autoconvert0' changed state to 2(READY) successfully
0:00:00.434948000 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<auddec> completed state change to READY
0:00:00.434955393 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'auddec' changed state to 2(READY) successfully
0:00:00.434966559 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<audioselect> current READY pending VOID_PENDING, desired next READY
0:00:00.434975237 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<audioselect> skipping transition from READY to  READY
0:00:00.434981849 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'audioselect' changed state to 2(READY) successfully
0:00:00.434992765 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<spu_q> current READY pending VOID_PENDING, desired next READY
0:00:00.435001091 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<spu_q> skipping transition from READY to  READY
0:00:00.435007585 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'spu_q' changed state to 2(READY) successfully
0:00:00.435017238 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<subpselect> current READY pending VOID_PENDING, desired next READY
0:00:00.435025473 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<subpselect> skipping transition from READY to  READY
0:00:00.435032061 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'subpselect' changed state to 2(READY) successfully
0:00:00.435042857 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnparsetter> current READY pending VOID_PENDING, desired next READY
0:00:00.435051276 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<rsnparsetter> skipping transition from READY to  READY
0:00:00.435057975 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnparsetter' changed state to 2(READY) successfully
0:00:00.435068277 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnviddec> current READY pending VOID_PENDING, desired next READY
0:00:00.435083597 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<autoconvert1> current READY pending VOID_PENDING, desired next READY
0:00:00.435099789 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<avdec_mpeg2video0> current READY pending VOID_PENDING, desired next READY
0:00:00.435108491 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<avdec_mpeg2video0> skipping transition from READY to  READY
0:00:00.435115433 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<autoconvert1> child 'avdec_mpeg2video0' changed state to 2(READY) successfully
0:00:00.435132447 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<vaapidecode0> current NULL pending PAUSED, desired next READY
0:00:00.435155021 18348      0x1e53c00 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<'':video> pad has no peer
0:00:00.435163732 18348      0x1e53c00 INFO             GST_CONTEXT gstvaapivideocontext.c:81:context_pad_query:<vaapidecode0:src> context pad peer query failed
0:00:00.435176330 18348      0x1e53c00 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<rsnmq:sink_0> pad has no peer
0:00:00.435183997 18348      0x1e53c00 INFO             GST_CONTEXT gstvaapivideocontext.c:81:context_pad_query:<vaapidecode0:sink> context pad peer query failed
0:00:00.435189574 18348      0x1e53c00 INFO             GST_CONTEXT gstvaapivideocontext.c:149:gst_vaapi_video_context_prepare:<vaapidecode0> posting `need-context' message
0:00:00.435594921 18348      0x20e5a80 INFO                 basesrc gstbasesrc.c:2841:gst_base_src_loop:<dvdsrc> pausing after gst_pad_push() = flushing
0:00:00.435627239 18348      0x20e5a80 INFO                    task gsttask.c:300:gst_task_func:<dvdsrc:src> Task going to paused
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns 0
libva info: Trying to open /usr/lib64/dri/radeonsi_drv_video.so
libva info: va_openDriver() returns -1
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns 0
libva info: Trying to open /usr/lib64/dri/radeonsi_drv_video.so
libva info: va_openDriver() returns -1
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns -1
libva error: va_getDriverName() failed with unknown libva error,driver_name=(null)
0:00:00.533965236 18348      0x1e53c00 WARN            videodecoder gstvideodecoder.c:2261:gst_video_decoder_change_state:<vaapidecode0> error: Failed to open decoder
0:00:00.534005553 18348      0x1e53c00 INFO        GST_ERROR_SYSTEM gstelement.c:1835:gst_element_message_full:<vaapidecode0> posting message: Could not initialize supporting library.
0:00:00.534037088 18348      0x1e53c00 INFO        GST_ERROR_SYSTEM gstelement.c:1858:gst_element_message_full:<vaapidecode0> posted error message: Could not initialize supporting library.
0:00:00.534047147 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2609:gst_element_change_state:<vaapidecode0> have FAILURE change_state return
0:00:00.534052227 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2203:gst_element_abort_state:<vaapidecode0> aborting state from NULL to READY
0:00:00.534061357 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2689:gst_bin_change_state_func:<autoconvert1> child 'vaapidecode0' failed to go to state 2(READY)
0:00:00.534074811 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2609:gst_element_change_state:<autoconvert1> have FAILURE change_state return
0:00:00.534081176 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2689:gst_bin_change_state_func:<rsnviddec> child 'autoconvert1' failed to go to state 2(READY)
0:00:00.534091072 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2609:gst_element_change_state:<rsnviddec> have FAILURE change_state return
0:00:00.534097913 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2689:gst_bin_change_state_func:<source> child 'rsnviddec' failed to go to state 2(READY)
0:00:00.534108633 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2609:gst_element_change_state:<source> have FAILURE change_state return
0:00:00.534113223 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2689:gst_bin_change_state_func:<uridecodebin0> child 'source' failed to go to state 2(READY)
0:00:00.534164515 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<auddec> current READY pending VOID_PENDING, desired next READY
0:00:00.534181953 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<autoconvert0> current READY pending VOID_PENDING, desired next READY
0:00:00.534198082 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<a52dec0> current READY pending VOID_PENDING, desired next READY
0:00:00.534205454 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<a52dec0> skipping transition from READY to  READY
0:00:00.534211386 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<autoconvert0> child 'a52dec0' changed state to 2(READY) successfully
0:00:00.534221787 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<autoconvert0> completed state change to READY
0:00:00.534232120 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<auddec> child 'autoconvert0' changed state to 2(READY) successfully
0:00:00.534240846 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<auddec> completed state change to READY
0:00:00.534248477 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'auddec' changed state to 2(READY) successfully
0:00:00.534259104 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<audioselect> current READY pending VOID_PENDING, desired next READY
0:00:00.534267581 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<audioselect> skipping transition from READY to  READY
0:00:00.534273396 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'audioselect' changed state to 2(READY) successfully
0:00:00.534282841 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<spu_q> current READY pending VOID_PENDING, desired next READY
0:00:00.534291173 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<spu_q> skipping transition from READY to  READY
0:00:00.534299132 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'spu_q' changed state to 2(READY) successfully
0:00:00.534307975 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<subpselect> current READY pending VOID_PENDING, desired next READY
0:00:00.534314906 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<subpselect> skipping transition from READY to  READY
0:00:00.534320368 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'subpselect' changed state to 2(READY) successfully
0:00:00.534329892 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnparsetter> current READY pending VOID_PENDING, desired next READY
0:00:00.534336792 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<rsnparsetter> skipping transition from READY to  READY
0:00:00.534342621 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnparsetter' changed state to 2(READY) successfully
0:00:00.534351383 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnviddec> current READY pending READY, desired next READY
0:00:00.534364372 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<autoconvert1> current READY pending READY, desired next READY
0:00:00.534378440 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<avdec_mpeg2video0> current READY pending VOID_PENDING, desired next READY
0:00:00.534385590 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<avdec_mpeg2video0> skipping transition from READY to  READY
0:00:00.534391402 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<autoconvert1> child 'avdec_mpeg2video0' changed state to 2(READY) successfully
0:00:00.534400098 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<vaapidecode0> current NULL pending READY, desired next READY
0:00:00.534425479 18348      0x1e53c00 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<'':video> pad has no peer
0:00:00.534433456 18348      0x1e53c00 INFO             GST_CONTEXT gstvaapivideocontext.c:81:context_pad_query:<vaapidecode0:src> context pad peer query failed
0:00:00.534447819 18348      0x1e53c00 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<rsnmq:sink_0> pad has no peer
0:00:00.534454461 18348      0x1e53c00 INFO             GST_CONTEXT gstvaapivideocontext.c:81:context_pad_query:<vaapidecode0:sink> context pad peer query failed
0:00:00.534459163 18348      0x1e53c00 INFO             GST_CONTEXT gstvaapivideocontext.c:149:gst_vaapi_video_context_prepare:<vaapidecode0> posting `need-context' message
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns 0
libva info: Trying to open /usr/lib64/dri/radeonsi_drv_video.so
libva info: va_openDriver() returns -1
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns 0
libva info: Trying to open /usr/lib64/dri/radeonsi_drv_video.so
libva info: va_openDriver() returns -1
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns -1
libva error: va_getDriverName() failed with unknown libva error,driver_name=(null)
0:00:00.632698748 18348      0x1e53c00 WARN            videodecoder gstvideodecoder.c:2261:gst_video_decoder_change_state:<vaapidecode0> error: Failed to open decoder
0:00:00.632737930 18348      0x1e53c00 INFO        GST_ERROR_SYSTEM gstelement.c:1835:gst_element_message_full:<vaapidecode0> posting message: Could not initialize supporting library.
0:00:00.632768927 18348      0x1e53c00 INFO        GST_ERROR_SYSTEM gstelement.c:1858:gst_element_message_full:<vaapidecode0> posted error message: Could not initialize supporting library.
0:00:00.632778839 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2609:gst_element_change_state:<vaapidecode0> have FAILURE change_state return
0:00:00.632797961 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2203:gst_element_abort_state:<vaapidecode0> aborting state from NULL to READY
0:00:00.632808910 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2689:gst_bin_change_state_func:<autoconvert1> child 'vaapidecode0' failed to go to state 2(READY)
0:00:00.632822761 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2609:gst_element_change_state:<autoconvert1> have FAILURE change_state return
0:00:00.632829102 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2689:gst_bin_change_state_func:<rsnviddec> child 'autoconvert1' failed to go to state 2(READY)
0:00:00.632839040 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2609:gst_element_change_state:<rsnviddec> have FAILURE change_state return
0:00:00.632845035 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2689:gst_bin_change_state_func:<source> child 'rsnviddec' failed to go to state 2(READY)
0:00:00.632856619 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2609:gst_element_change_state:<source> have FAILURE change_state return
0:00:00.632892417 18348      0x1e53c00 INFO           GST_PARENTAGE gstbin.c:1554:gst_bin_remove_func:<uridecodebin0> removed child "source"
0:00:00.632905583 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2609:gst_element_change_state:<uridecodebin0> have FAILURE change_state return
0:00:00.632912086 18348      0x1e53c00 INFO              GST_STATES gstbin.c:2689:gst_bin_change_state_func:<playbin0> child 'uridecodebin0' failed to go to state 1(NULL)
0:00:00.632961778 18348      0x1e53c00 INFO              GST_STATES gstelement.c:2609:gst_element_change_state:<playbin0> have FAILURE change_state return
Freeing pipeline ...
0:00:00.632980515 18348      0x1e53c00 INFO           GST_PARENTAGE gstbin.c:1554:gst_bin_remove_func:<playbin0> removed child "uridecodebin0"
0:00:00.632996045 18348      0x1e53c00 INFO           GST_PARENTAGE gstbin.c:1554:gst_bin_remove_func:<playbin0> removed child "playsink"
0:00:00.633013555 18348      0x1e53c00 INFO           GST_PARENTAGE gstbin.c:1554:gst_bin_remove_func:<playsink> removed child "streamsynchronizer0"
0:00:00.633023574 18348      0x1e53c00 INFO         GST_REFCOUNTING gstelement.c:2873:gst_element_dispose:<streamsynchronizer0> dispose
0:00:00.633029696 18348      0x1e53c00 INFO         GST_REFCOUNTING gstelement.c:2917:gst_element_dispose:<streamsynchronizer0> parent class dispose
0:00:00.633036313 18348      0x1e53c00 INFO         GST_REFCOUNTING gstelement.c:2948:gst_element_finalize:<streamsynchronizer0> finalize
0:00:00.633041628 18348      0x1e53c00 INFO         GST_REFCOUNTING gstelement.c:2953:gst_element_finalize:<streamsynchronizer0> finalize parent
0:00:00.633046432 18348      0x1e53c00 INFO         GST_REFCOUNTING gstelement.c:2873:gst_element_dispose:<playsink> dispose
0:00:00.633051494 18348      0x1e53c00 INFO         GST_REFCOUNTING gstelement.c:2917:gst_element_dispose:<playsink> parent class dispose
0:00:00.633061749 18348      0x1e53c00 INFO         GST_REFCOUNTING gstelement.c:2948:gst_element_finalize:<playsink> finalize
0:00:00.633067163 18348      0x1e53c00 INFO         GST_REFCOUNTING gstelement.c:2953:gst_element_finalize:<playsink> finalize parent
0:00:00.633072015 18348      0x1e53c00 INFO         GST_REFCOUNTING gstelement.c:2873:gst_element_dispose:<playbin0> dispose

(gst-launch-1.0:18348): GStreamer-CRITICAL **: 
Trying to dispose element playbin0, but it is in READY instead of the NULL state.
You need to explicitly set elements to the NULL state before
dropping the final reference, to allow them to clean up.
This problem may also be caused by a refcounting bug in the
application or some element.

0:00:00.633103418 18348      0x1e53c00 INFO         GST_REFCOUNTING gstelement.c:2948:gst_element_finalize:<playbin0> finalize
0:00:00.633109350 18348      0x1e53c00 INFO         GST_REFCOUNTING gstelement.c:2953:gst_element_finalize:<playbin0> finalize parent
0:00:00.633115185 18348      0x1e53c00 INFO                GST_INIT gst.c:940:gst_deinit: deinitializing GStreamer
