0:00:00.000126527  2223      0x1b86800 INFO                GST_INIT gst.c:499:init_pre: Initializing GStreamer Core Library version 1.4.5
0:00:00.000225372  2223      0x1b86800 INFO                GST_INIT gst.c:500:init_pre: Using library installed in /usr/lib64
0:00:00.000248889  2223      0x1b86800 INFO                GST_INIT gst.c:511:init_pre: Linux IU-EB-418 4.0.4-303.fc22.x86_64 #1 SMP Thu May 28 12:37:06 UTC 2015 x86_64
0:00:00.000794960  2223      0x1b86800 INFO                GST_INIT gstmessage.c:119:_priv_gst_message_initialize: init messages
0:00:00.001891076  2223      0x1b86800 INFO                GST_INIT gstcontext.c:77:_priv_gst_context_initialize: init contexts
0:00:00.002564766  2223      0x1b86800 INFO      GST_PLUGIN_LOADING gstplugin.c:317:_priv_gst_plugin_initialize: registering 0 static plugins
0:00:00.002792067  2223      0x1b86800 INFO      GST_PLUGIN_LOADING gstplugin.c:225:gst_plugin_register_static: registered static plugin "staticelements"
0:00:00.002816391  2223      0x1b86800 INFO      GST_PLUGIN_LOADING gstplugin.c:227:gst_plugin_register_static: added static plugin "staticelements", result: 1
0:00:00.002864949  2223      0x1b86800 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.035163086  2223      0x1b86800 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.032261 seconds
0:00:00.035255733  2223      0x1b86800 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.036803933  2223      0x1b86800 INFO            GST_REGISTRY gstregistry.c:1680:scan_and_update_registry: Registry cache has not changed
0:00:00.036818686  2223      0x1b86800 INFO            GST_REGISTRY gstregistry.c:1757:ensure_current_registry: registry reading and updating done, result = 1
0:00:00.036829193  2223      0x1b86800 INFO                GST_INIT gst.c:709:init_post: GLib runtime version: 2.44.1
0:00:00.036840074  2223      0x1b86800 INFO                GST_INIT gst.c:711:init_post: GLib headers version: 2.43.3
0:00:00.036875406  2223      0x1b86800 INFO            GST_PIPELINE gstparse.c:323:gst_parse_launch_full: parsing pipeline description 'playbin uri=dvd:///dev/sr0 '
0:00:00.039167349  2223      0x1b86800 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/usr/lib64/gstreamer-1.0/libgstplayback.so" loaded
0:00:00.039193824  2223      0x1b86800 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "playbin"
0:00:00.039928550  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<playsink> current NULL pending VOID_PENDING, desired next READY
0:00:00.039958908  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<streamsynchronizer0> current NULL pending VOID_PENDING, desired next READY
0:00:00.039974646  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<streamsynchronizer0> completed state change to READY
0:00:00.039985380  2223      0x1b86800 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.040034820  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playsink> child 'streamsynchronizer0' changed state to 2(READY) successfully
0:00:00.040056863  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<playsink> completed state change to READY
0:00:00.040066668  2223      0x1b86800 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.040088720  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playbin0> child 'playsink' changed state to 2(READY) successfully
0:00:00.040110529  2223      0x1b86800 INFO              GST_STATES gstelement.c:2303:gst_element_continue_state:<playbin0> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:00.040123307  2223      0x1b86800 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<playbin0> notifying about state-changed NULL to READY (PAUSED pending)
0:00:00.040172223  2223      0x1b86800 INFO              GST_STATES gstbin.c:1829:gst_bin_get_state_func:<playbin0> getting state
0:00:00.040181991  2223      0x1b86800 INFO              GST_STATES gstelement.c:2046:gst_element_get_state_func:<playbin0> waiting for element to commit state
0:00:00.040192334  2223      0x1b86800 INFO              GST_STATES gstelement.c:2060:gst_element_get_state_func:<playbin0> timed out
0:00:00.040328745  2223      0x1b86800 INFO              GST_STATES gstbin.c:1829:gst_bin_get_state_func:<playsink> getting state
0:00:00.040490234  2223      0x1b86800 INFO                 default gstdebugutils.c:692:gst_debug_bin_to_dot_file: wrote bin graph to : '/tmp/0.00.00.040142974-gst-launch.NULL_READY.dot'
0:00:00.040525713  2223      0x1b86800 INFO              GST_STATES gstelement.c:2310:gst_element_continue_state:<playbin0> continue state change READY to PAUSED, final PAUSED
0:00:00.040544548  2223      0x1b86800 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "uridecodebin"
0:00:00.040780995  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<uridecodebin0> completed state change to READY
0:00:00.040795895  2223      0x1b86800 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.040825344  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<playsink> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.040840297  2223      0x1b86800 INFO                playsink gstplaysink.c:1400:do_async_start:<playsink> Sending async_start message
0:00:00.040860288  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<streamsynchronizer0> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.040877390  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<streamsynchronizer0> completed state change to PAUSED
0:00:00.040887302  2223      0x1b86800 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.040903558  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playsink> child 'streamsynchronizer0' changed state to 3(PAUSED) successfully
0:00:00.040920890  2223      0x1b86800 INFO              GST_STATES gstbin.c:2679:gst_bin_change_state_func:<playbin0> child 'playsink' is changing state asynchronously to PAUSED
0:00:00.040934470  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<uridecodebin0> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.041812953  2223      0x1b86800 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/usr/lib64/gstreamer-1.0/libgstresindvd.so" loaded
0:00:00.041831521  2223      0x1b86800 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "rsndvdbin" named "source"
0:00:00.042130463  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<source> current NULL pending VOID_PENDING, desired next PAUSED
0:00:00.042315488  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseSrc@0x1d466d0> adding pad 'src'
0:00:00.042478127  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<RsnDVDDemux@0x1d4a800> adding pad 'sink'
0:00:00.042545263  2223      0x1b86800 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.042565933  2223      0x1b86800 INFO                GST_PADS gstutils.c:937:gst_pad_check_link: trying to link dvdsrc:src and dvddemux:sink
0:00:00.042596467  2223      0x1b86800 INFO                GST_PADS gstutils.c:1445:prepare_link_maybe_ghosting: dvdsrc and dvddemux in same bin, no need for ghost pads
0:00:00.042622923  2223      0x1b86800 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link dvdsrc:src and dvddemux:sink
0:00:00.042647180  2223      0x1b86800 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked dvdsrc:src and dvddemux:sink, successful
0:00:00.042659441  2223      0x1b86800 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.042670492  2223      0x1b86800 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<dvdsrc:src> Received event on flushing pad. Discarding
0:00:00.043273034  2223      0x1b86800 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/usr/lib64/gstreamer-1.0/libgstcoreelements.so" loaded
0:00:00.043291335  2223      0x1b86800 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "multiqueue" named "rsnmq"
0:00:00.043934607  2223      0x1b86800 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/usr/lib64/gstreamer-1.0/libgstvideoparsersbad.so" loaded
0:00:00.043952209  2223      0x1b86800 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "mpegvideoparse" named "rsnvidparse"
0:00:00.044108270  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseParse@0x1d5ec30> adding pad 'sink'
0:00:00.044138497  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseParse@0x1d5ec30> adding pad 'src'
0:00:00.044160212  2223      0x1b86800 INFO               baseparse gstbaseparse.c:3604:gst_base_parse_set_pts_interpolation:<GstMpegvParse@0x1d5ec30> PTS interpolation: no
0:00:00.044398603  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<RsnDec@0x1d63020> adding pad 'sink'
0:00:00.044415053  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<RsnDec@0x1d63020> adding pad 'src'
0:00:00.044614511  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<RsnParSetter@0x1d69000> adding pad 'sink'
0:00:00.044649736  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<RsnParSetter@0x1d69000> adding pad 'src'
0:00:00.044679138  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad rsnvidparse:src
0:00:00.044691953  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad rsnviddec:sink
0:00:00.044709649  2223      0x1b86800 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link rsnvidparse:src and rsnviddec:sink
0:00:00.044738637  2223      0x1b86800 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<sink:proxypad0> pad has no peer
0:00:00.044756410  2223      0x1b86800 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked rsnvidparse:src and rsnviddec:sink, successful
0:00:00.044769492  2223      0x1b86800 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.044778686  2223      0x1b86800 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<rsnvidparse:src> Received event on flushing pad. Discarding
0:00:00.044794735  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad rsnviddec:src
0:00:00.044805700  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad rsnparsetter:sink
0:00:00.044825407  2223      0x1b86800 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link rsnviddec:src and rsnparsetter:sink
0:00:00.044844383  2223      0x1b86800 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<src:proxypad1> pad has no peer
0:00:00.044859961  2223      0x1b86800 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<rsnparsetter:src> pad has no peer
0:00:00.044883406  2223      0x1b86800 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked rsnviddec:src and rsnparsetter:sink, successful
0:00:00.044895600  2223      0x1b86800 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.044904142  2223      0x1b86800 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<rsnviddec:src> Received event on flushing pad. Discarding
0:00:00.044918745  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad rsnparsetter:src
0:00:00.044973573  2223      0x1b86800 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link rsnparsetter:src and video:proxypad2
0:00:00.044988123  2223      0x1b86800 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked rsnparsetter:src and video:proxypad2, successful
0:00:00.045000006  2223      0x1b86800 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.045008221  2223      0x1b86800 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<rsnparsetter:src> Received event on flushing pad. Discarding
0:00:00.045128712  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<RsnInputSelector@0x1d69150> adding pad 'src'
0:00:00.045174655  2223      0x1b86800 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "queue" named "spu_q"
0:00:00.045325738  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstQueue@0x1d6e150> adding pad 'sink'
0:00:00.045360655  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstQueue@0x1d6e150> adding pad 'src'
0:00:00.045400431  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad subpselect:src
0:00:00.045413025  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad spu_q:sink
0:00:00.045429712  2223      0x1b86800 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link subpselect:src and spu_q:sink
0:00:00.045454367  2223      0x1b86800 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<spu_q:src> pad has no peer
0:00:00.045467112  2223      0x1b86800 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked subpselect:src and spu_q:sink, successful
0:00:00.045478645  2223      0x1b86800 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.045487254  2223      0x1b86800 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<subpselect:src> Received event on flushing pad. Discarding
0:00:00.045501923  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad spu_q:src
0:00:00.045555376  2223      0x1b86800 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link spu_q:src and subpicture:proxypad3
0:00:00.045570205  2223      0x1b86800 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked spu_q:src and subpicture:proxypad3, successful
0:00:00.045580943  2223      0x1b86800 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.045588777  2223      0x1b86800 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<spu_q:src> Received event on flushing pad. Discarding
0:00:00.045620267  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<RsnInputSelector@0x1d692a0> adding pad 'src'
0:00:00.045821842  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<RsnDec@0x1d631e0> adding pad 'sink'
0:00:00.045837300  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<RsnDec@0x1d631e0> adding pad 'src'
0:00:00.045860582  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad audioselect:src
0:00:00.045873243  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad auddec:sink
0:00:00.045889530  2223      0x1b86800 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link audioselect:src and auddec:sink
0:00:00.045913216  2223      0x1b86800 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<sink:proxypad4> pad has no peer
0:00:00.045925794  2223      0x1b86800 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked audioselect:src and auddec:sink, successful
0:00:00.045937356  2223      0x1b86800 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.045945531  2223      0x1b86800 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<audioselect:src> Received event on flushing pad. Discarding
0:00:00.045959829  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad auddec:src
0:00:00.046010088  2223      0x1b86800 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link auddec:src and audio:proxypad6
0:00:00.046040095  2223      0x1b86800 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked auddec:src and audio:proxypad6, successful
0:00:00.046068324  2223      0x1b86800 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.046077538  2223      0x1b86800 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<auddec:src> Received event on flushing pad. Discarding
0:00:00.046118117  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<auddec> current NULL pending VOID_PENDING, desired next READY
0:00:00.046338886  2223      0x1b86800 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/usr/lib64/gstreamer-1.0/libgstautoconvert.so" loaded
0:00:00.046358095  2223      0x1b86800 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "autoconvert"
0:00:00.046474857  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstAutoConvert@0x1d75080> adding pad 'sink'
0:00:00.046488989  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstAutoConvert@0x1d75080> adding pad 'src'
0:00:00.048042754  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad autoconvert0:sink
0:00:00.048063575  2223      0x1b86800 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link sink:proxypad4 and autoconvert0:sink
0:00:00.048075716  2223      0x1b86800 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked sink:proxypad4 and autoconvert0:sink, successful
0:00:00.048086068  2223      0x1b86800 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.048099912  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad autoconvert0:src
0:00:00.048116943  2223      0x1b86800 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link autoconvert0:src and src:proxypad5
0:00:00.048128190  2223      0x1b86800 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked autoconvert0:src and src:proxypad5, successful
0:00:00.048137749  2223      0x1b86800 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.048151219  2223      0x1b86800 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<autoconvert0:src> Received event on flushing pad. Discarding
0:00:00.048176409  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<autoconvert0> completed state change to READY
0:00:00.048186890  2223      0x1b86800 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.048216075  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<autoconvert0> current READY pending VOID_PENDING, desired next READY
0:00:00.048233754  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<autoconvert0> completed state change to READY
0:00:00.048244452  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<auddec> child 'autoconvert0' changed state to 2(READY) successfully
0:00:00.048258212  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<auddec> completed state change to READY
0:00:00.048266816  2223      0x1b86800 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.048282654  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'auddec' changed state to 2(READY) successfully
0:00:00.048295411  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<audioselect> current NULL pending VOID_PENDING, desired next READY
0:00:00.048307870  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<audioselect> completed state change to READY
0:00:00.048315943  2223      0x1b86800 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.048330498  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'audioselect' changed state to 2(READY) successfully
0:00:00.048345514  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<spu_q> current NULL pending VOID_PENDING, desired next READY
0:00:00.048357383  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<spu_q> completed state change to READY
0:00:00.048365539  2223      0x1b86800 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.048380753  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'spu_q' changed state to 2(READY) successfully
0:00:00.048393260  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<subpselect> current NULL pending VOID_PENDING, desired next READY
0:00:00.048404938  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<subpselect> completed state change to READY
0:00:00.048413122  2223      0x1b86800 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.048437855  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'subpselect' changed state to 2(READY) successfully
0:00:00.048453592  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnparsetter> current NULL pending VOID_PENDING, desired next READY
0:00:00.048465541  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<rsnparsetter> completed state change to READY
0:00:00.048473605  2223      0x1b86800 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.048492287  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnparsetter' changed state to 2(READY) successfully
0:00:00.048506968  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnviddec> current NULL pending VOID_PENDING, desired next READY
0:00:00.048519902  2223      0x1b86800 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "autoconvert"
0:00:00.048598076  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstAutoConvert@0x1d75250> adding pad 'sink'
0:00:00.048611411  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstAutoConvert@0x1d75250> adding pad 'src'
0:00:00.048965744  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad autoconvert1:sink
0:00:00.048981937  2223      0x1b86800 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link sink:proxypad0 and autoconvert1:sink
0:00:00.048993316  2223      0x1b86800 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked sink:proxypad0 and autoconvert1:sink, successful
0:00:00.049003009  2223      0x1b86800 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.049015979  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad autoconvert1:src
0:00:00.049044178  2223      0x1b86800 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link autoconvert1:src and src:proxypad1
0:00:00.049056182  2223      0x1b86800 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked autoconvert1:src and src:proxypad1, successful
0:00:00.049066162  2223      0x1b86800 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.049073681  2223      0x1b86800 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<autoconvert1:src> Received event on flushing pad. Discarding
0:00:00.049096389  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<autoconvert1> completed state change to READY
0:00:00.049106143  2223      0x1b86800 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.049131054  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<autoconvert1> current READY pending VOID_PENDING, desired next READY
0:00:00.049148539  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<autoconvert1> completed state change to READY
0:00:00.049158891  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<rsnviddec> child 'autoconvert1' changed state to 2(READY) successfully
0:00:00.049171685  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<rsnviddec> completed state change to READY
0:00:00.049180181  2223      0x1b86800 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.049195527  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnviddec' changed state to 2(READY) successfully
0:00:00.049208330  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnvidparse> current NULL pending VOID_PENDING, desired next READY
0:00:00.049220499  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<rsnvidparse> completed state change to READY
0:00:00.049228572  2223      0x1b86800 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.049247949  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnvidparse' changed state to 2(READY) successfully
0:00:00.049261735  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnmq> current NULL pending VOID_PENDING, desired next READY
0:00:00.049273858  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<rsnmq> completed state change to READY
0:00:00.049282008  2223      0x1b86800 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.049296274  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnmq' changed state to 2(READY) successfully
0:00:00.049311838  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<dvddemux> current NULL pending VOID_PENDING, desired next READY
0:00:00.049324806  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<dvddemux> completed state change to READY
0:00:00.049333388  2223      0x1b86800 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.049348365  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'dvddemux' changed state to 2(READY) successfully
0:00:00.049360399  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<dvdsrc> current NULL pending VOID_PENDING, desired next READY
0:00:00.049372496  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<dvdsrc> completed state change to READY
0:00:00.049380819  2223      0x1b86800 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.049395042  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'dvdsrc' changed state to 2(READY) successfully
0:00:00.049409421  2223      0x1b86800 INFO              GST_STATES gstelement.c:2303:gst_element_continue_state:<source> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:00.049420545  2223      0x1b86800 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<source> notifying about state-changed NULL to READY (PAUSED pending)
0:00:00.049446294  2223      0x1b86800 INFO              GST_STATES gstelement.c:2310:gst_element_continue_state:<source> continue state change READY to PAUSED, final PAUSED
0:00:00.049471556  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<auddec> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.049493103  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<autoconvert0> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.049513185  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<autoconvert0> completed state change to PAUSED
0:00:00.049522366  2223      0x1b86800 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.049538551  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<auddec> child 'autoconvert0' changed state to 3(PAUSED) successfully
0:00:00.049555386  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<auddec> completed state change to PAUSED
0:00:00.049564311  2223      0x1b86800 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.049582961  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'auddec' changed state to 3(PAUSED) successfully
0:00:00.049595340  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<audioselect> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.049610413  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<audioselect> completed state change to PAUSED
0:00:00.049619064  2223      0x1b86800 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.049633579  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'audioselect' changed state to 3(PAUSED) successfully
0:00:00.049647740  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<spu_q> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.049680040  2223      0x1b86800 INFO                    task gsttask.c:431:gst_task_set_lock: setting stream lock 0x1d49330 on task 0x1d93050
0:00:00.049692766  2223      0x1b86800 INFO                GST_PADS gstpad.c:5515:gst_pad_start_task:<spu_q:src> created task 0x1d93050
0:00:00.049793146  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<spu_q> completed state change to PAUSED
0:00:00.049807871  2223      0x1b86800 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.049826405  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'spu_q' changed state to 3(PAUSED) successfully
0:00:00.049840775  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<subpselect> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.049857060  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<subpselect> completed state change to PAUSED
0:00:00.049866192  2223      0x1b86800 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.049882156  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'subpselect' changed state to 3(PAUSED) successfully
0:00:00.049898177  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnparsetter> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.049914571  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<rsnparsetter> completed state change to PAUSED
0:00:00.049923763  2223      0x1b86800 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.049939524  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnparsetter' changed state to 3(PAUSED) successfully
0:00:00.049953723  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnviddec> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.049975220  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<autoconvert1> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.049994887  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<autoconvert1> completed state change to PAUSED
0:00:00.050004279  2223      0x1b86800 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.050038255  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<rsnviddec> child 'autoconvert1' changed state to 3(PAUSED) successfully
0:00:00.050058877  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<rsnviddec> completed state change to PAUSED
0:00:00.050068442  2223      0x1b86800 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.050084632  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnviddec' changed state to 3(PAUSED) successfully
0:00:00.050098941  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnvidparse> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.050187415  2223      0x1b86800 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<rsnvidparse:sink> pad has no peer
0:00:00.050203105  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<rsnvidparse> completed state change to PAUSED
0:00:00.050213387  2223      0x1b86800 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.050230612  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnvidparse' changed state to 3(PAUSED) successfully
0:00:00.050245121  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnmq> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.050259865  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<rsnmq> completed state change to PAUSED
0:00:00.050268917  2223      0x1b86800 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.050283638  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnmq' changed state to 3(PAUSED) successfully
0:00:00.050298167  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<dvddemux> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.050334173  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<dvddemux> completed state change to PAUSED
0:00:00.050344682  2223      0x1b86800 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.050360724  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'dvddemux' changed state to 3(PAUSED) successfully
0:00:00.050375961  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<dvdsrc> current READY pending VOID_PENDING, desired next PAUSED
libdvdread: Encrypted DVD support unavailable.
libdvdnav: Using dvdnav version 5.0.3
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD Title: PALETTES
libdvdnav: DVD Serial Number: 3581802A
libdvdnav: DVD Title (Alternative): 
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdnav: Language 'en' not found, using 'fr' instead
libdvdnav: Menu Languages available: fr 
libdvdnav: Language 'en' not found, using 'fr' instead
libdvdnav: Menu Languages available: fr 
libdvdnav: Language 'en' not found, using 'fr' instead
libdvdnav: Menu Languages available: fr 
libdvdnav: Language 'en' not found, using 'fr' instead
libdvdnav: Menu Languages available: fr 
libdvdnav: Language 'en' not found, using 'fr' instead
libdvdnav: Menu Languages available: fr 
libdvdnav: Language 'en' not found, using 'fr' instead
libdvdnav: Menu Languages available: fr 
libdvdnav: Language 'en' not found, using 'fr' instead
libdvdnav: Menu Languages available: fr 
0:00:00.122569507  2223      0x1b86800 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.123062965  2223      0x1b86800 INFO                    task gsttask.c:431:gst_task_set_lock: setting stream lock 0x1d481b0 on task 0x1d93170
0:00:00.123088096  2223      0x1b86800 INFO                GST_PADS gstpad.c:5515:gst_pad_start_task:<dvdsrc:src> created task 0x1d93170
0:00:00.123219405  2223      0x1b86800 WARN               rsndvdsrc resindvdsrc.c:2490:rsn_dvdsrc_post_title_info:<dvdsrc> Failed to get title 0 info
0:00:00.123358684  2223      0x1d8d400 INFO        GST_ELEMENT_PADS gstelement.c:892:gst_element_get_static_pad: no such pad 'sink' in element "dvdsrc"
0:00:00.123462943  2223      0x1d8d400 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.123615791  2223      0x1d8d400 INFO               GST_EVENT gstevent.c:678:gst_event_new_caps: creating caps event application/x-resin-dvd
0:00:00.124652024  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<dvdsrc> completed state change to PAUSED
libdvdnav: Language 'en' not found, using 'fr' instead
libdvdnav: Menu Languages available: fr 
0:00:00.124683007  2223      0x1b86800 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.124758335  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'dvdsrc' changed state to 3(PAUSED) successfully
0:00:00.124795222  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<source> completed state change to PAUSED
0:00:00.124808026  2223      0x1b86800 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.124826403  2223      0x1d8d400 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad dvddemux:sink
0:00:00.124832677  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<uridecodebin0> child 'source' changed state to 3(PAUSED) successfully
0:00:00.124890400  2223      0x1d8d400 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.124931036  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<uridecodebin0> completed state change to PAUSED
0:00:00.124949641  2223      0x1b86800 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.124965381  2223      0x1d8d400 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<dvddemux> adding pad 'video_e0'
0:00:00.124977969  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playbin0> child 'uridecodebin0' changed state to 3(PAUSED) successfully
0:00:00.124996525  2223      0x1d8d400 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad rsnvidparse:sink
0:00:00.125088671  2223      0x1d8d400 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<'':video> pad has no peer
0:00:00.135102223  2223      0x1d8d400 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/usr/lib64/gstreamer-1.0/libgstvaapi.so" loaded
0:00:00.135137732  2223      0x1d8d400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "vaapidecode"
0:00:00.135350526  2223      0x1d8d400 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstVideoDecoder@0x7f57c401e440> adding pad 'sink'
0:00:00.135383880  2223      0x1d8d400 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstVideoDecoder@0x7f57c401e440> adding pad 'src'
0:00:00.135419630  2223      0x1d8d400 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad (null):sink
0:00:00.135439474  2223      0x1d8d400 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad (null):src
0:00:00.135599741  2223      0x1d8d400 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link '':src_internal and vaapidecode0:sink
0:00:00.135624115  2223      0x1d8d400 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked '':src_internal and vaapidecode0:sink, successful
0:00:00.135638117  2223      0x1d8d400 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.135662868  2223      0x1d8d400 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link vaapidecode0:src and '':sink_internal
0:00:00.135677461  2223      0x1d8d400 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked vaapidecode0:src and '':sink_internal, successful
0:00:00.135689772  2223      0x1d8d400 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.135699420  2223      0x1d8d400 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<vaapidecode0:src> Received event on flushing pad. Discarding
0:00:00.135756550  2223      0x1d8d400 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<'':video> pad has no peer
0:00:00.135772198  2223      0x1d8d400 INFO             GST_CONTEXT gstvaapivideocontext.c:83:context_pad_query:<vaapidecode0:src> context pad peer query failed
0:00:00.135789607  2223      0x1d8d400 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<rsnvidparse:sink> pad has no peer
0:00:00.135800772  2223      0x1d8d400 INFO             GST_CONTEXT gstvaapivideocontext.c:83:context_pad_query:<vaapidecode0:sink> context pad peer query failed
0:00:00.135809932  2223      0x1d8d400 INFO             GST_CONTEXT gstvaapivideocontext.c:151:gst_vaapi_video_context_prepare:<vaapidecode0> posting `need-context' message
0:00:00.135859750  2223      0x1d8d400 INFO                   vaapi gstvaapidisplay.c:119:libgstvaapi_init_once: gstreamer-vaapi version 0.5.10
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns 0
libva info: Trying to open /usr/lib64/dri/i965_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/i965_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/i965_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/i965_drv_video.so
libva info: va_openDriver() returns -1
0:00:00.142580895  2223      0x1d8d400 WARN            videodecoder gstvideodecoder.c:2261:gst_video_decoder_change_state:<vaapidecode0> error: Failed to open decoder
0:00:00.142637638  2223      0x1d8d400 INFO        GST_ERROR_SYSTEM gstelement.c:1835:gst_element_message_full:<vaapidecode0> posting message: Could not initialize supporting library.
0:00:00.142709478  2223      0x1d8d400 INFO        GST_ERROR_SYSTEM gstelement.c:1858:gst_element_message_full:<vaapidecode0> posted error message: Could not initialize supporting library.
0:00:00.142726476  2223      0x1d8d400 INFO              GST_STATES gstelement.c:2609:gst_element_change_state:<vaapidecode0> have FAILURE change_state return
0:00:00.142736649  2223      0x1d8d400 INFO              GST_STATES gstelement.c:2203:gst_element_abort_state:<vaapidecode0> aborting state from NULL to PAUSED
0:00:00.142766432  2223      0x1d8d400 INFO             vaapidecode gstvaapidecode.c:971:gst_vaapidecode_query:<vaapidecode0> query type caps
0:00:00.142788655  2223      0x1b86800 INFO              GST_STATES gstbin.c:1829:gst_bin_get_state_func:<playbin0> getting state
0:00:00.142808636  2223      0x1d8d400 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<'':video> pad has no peer
0:00:00.142824889  2223      0x1d8d400 INFO             GST_CONTEXT gstvaapivideocontext.c:83:context_pad_query:<vaapidecode0:src> context pad peer query failed
0:00:00.142842732  2223      0x1d8d400 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<rsnvidparse:sink> pad has no peer
0:00:00.142853953  2223      0x1d8d400 INFO             GST_CONTEXT gstvaapivideocontext.c:83:context_pad_query:<vaapidecode0:sink> context pad peer query failed
0:00:00.142863571  2223      0x1d8d400 INFO             GST_CONTEXT gstvaapivideocontext.c:151:gst_vaapi_video_context_prepare:<vaapidecode0> posting `need-context' message
0:00:00.142987990  2223      0x1b86800 INFO              GST_STATES gstbin.c:1829:gst_bin_get_state_func:<uridecodebin0> getting state
0:00:00.143139352  2223      0x1b86800 INFO              GST_STATES gstbin.c:1829:gst_bin_get_state_func:<source> getting state
0:00:00.143198741  2223      0x1b86800 INFO              GST_STATES gstbin.c:1829:gst_bin_get_state_func:<auddec> getting state
0:00:00.143273701  2223      0x1b86800 INFO              GST_STATES gstbin.c:1829:gst_bin_get_state_func:<autoconvert0> getting state
libva info: VA-API version 0.37.0
0:00:00.143805632  2223      0x1b86800 INFO              GST_STATES gstbin.c:1829:gst_bin_get_state_func:<rsnviddec> getting state
0:00:00.143875789  2223      0x1b86800 INFO              GST_STATES gstbin.c:1829:gst_bin_get_state_func:<autoconvert1> getting state
libva info: va_getDriverName() returns 0
libva info: Trying to open /usr/lib64/dri/i965_drv_video.so
libva info: va_openDriver() returns -1
0:00:00.144403129  2223      0x1b86800 INFO              GST_STATES gstbin.c:1829:gst_bin_get_state_func:<playsink> getting state
0:00:00.144414986  2223      0x1b86800 INFO              GST_STATES gstelement.c:2046:gst_element_get_state_func:<playsink> waiting for element to commit state
0:00:00.144428085  2223      0x1b86800 INFO              GST_STATES gstelement.c:2060:gst_element_get_state_func:<playsink> timed out
0:00:00.144592631  2223      0x1b86800 INFO                 default gstdebugutils.c:692:gst_debug_bin_to_dot_file: wrote bin graph to : '/tmp/0.00.00.142752848-gst-launch.error.dot'
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
ERROR: pipeline doesn't want to preroll.
0:00:00.144706502  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<playsink> current READY pending PAUSED, desired next NULL
0:00:00.144738961  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<streamsynchronizer0> current PAUSED pending VOID_PENDING, desired next READY
0:00:00.144761233  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<streamsynchronizer0> completed state change to READY
0:00:00.144772525  2223      0x1b86800 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.144797234  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playsink> child 'streamsynchronizer0' changed state to 2(READY) successfully
0:00:00.144818818  2223      0x1b86800 INFO                playsink gstplaysink.c:1412:do_async_done:<playsink> Sending async_done message
0:00:00.144841108  2223      0x1b86800 INFO              GST_STATES gstelement.c:2303:gst_element_continue_state:<playsink> committing state from READY to READY, pending NULL, next NULL
0:00:00.144855964  2223      0x1b86800 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<playsink> notifying about state-changed READY to READY (NULL pending)
0:00:00.144873533  2223      0x1b86800 INFO              GST_STATES gstelement.c:2310:gst_element_continue_state:<playsink> continue state change READY to NULL, final NULL
0:00:00.144893298  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<streamsynchronizer0> current READY pending VOID_PENDING, desired next NULL
0:00:00.144911090  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<streamsynchronizer0> completed state change to NULL
0:00:00.144921664  2223      0x1b86800 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.144939744  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playsink> child 'streamsynchronizer0' changed state to 1(NULL) successfully
0:00:00.144958292  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<playsink> completed state change to NULL
0:00:00.144969106  2223      0x1b86800 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.144987096  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playbin0> child 'playsink' changed state to 1(NULL) successfully
0:00:00.145003049  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<uridecodebin0> current PAUSED pending VOID_PENDING, desired next NULL
libva info: VA-API version 0.37.0
0:00:00.145038624  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<source> current PAUSED pending VOID_PENDING, desired next READY
0:00:00.145094051  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<auddec> current PAUSED pending VOID_PENDING, desired next READY
0:00:00.145125605  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<autoconvert0> current PAUSED pending VOID_PENDING, desired next READY
0:00:00.145152518  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<autoconvert0> completed state change to READY
0:00:00.145164328  2223      0x1b86800 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.145187102  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<auddec> child 'autoconvert0' changed state to 2(READY) successfully
0:00:00.145210323  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<auddec> completed state change to READY
0:00:00.145221552  2223      0x1b86800 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.145240507  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'auddec' changed state to 2(READY) successfully
0:00:00.145256102  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<audioselect> current PAUSED pending VOID_PENDING, desired next READY
0:00:00.145276674  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<audioselect> completed state change to READY
0:00:00.145287425  2223      0x1b86800 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.145322958  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'audioselect' changed state to 2(READY) successfully
0:00:00.145342458  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<spu_q> current PAUSED pending VOID_PENDING, desired next READY
0:00:00.145445290  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<spu_q> completed state change to READY
0:00:00.145459121  2223      0x1b86800 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.145480305  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'spu_q' changed state to 2(READY) successfully
0:00:00.145496925  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<subpselect> current PAUSED pending VOID_PENDING, desired next READY
0:00:00.145518018  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<subpselect> completed state change to READY
0:00:00.145528956  2223      0x1b86800 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.145547424  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'subpselect' changed state to 2(READY) successfully
libva info: va_getDriverName() returns 0
0:00:00.145566243  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnparsetter> current PAUSED pending VOID_PENDING, desired next READY
libva info: Trying to open /usr/lib64/dri/i965_drv_video.so
0:00:00.145611316  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<rsnparsetter> completed state change to READY
0:00:00.145624078  2223      0x1b86800 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<rsnparsetter> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
libva info: va_openDriver() returns -1
0:00:00.145643658  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnparsetter' changed state to 2(READY) successfully
0:00:00.145661398  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnviddec> current PAUSED pending VOID_PENDING, desired next READY
0:00:00.145688769  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<autoconvert1> current PAUSED pending VOID_PENDING, desired next READY
0:00:00.145712441  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<vaapidecode0> current NULL pending PAUSED, desired next READY
0:00:00.145725718  2223      0x1b86800 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<vaapidecode0> skipping transition from NULL to  READY
0:00:00.145736505  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<autoconvert1> child 'vaapidecode0' changed state to 2(READY) successfully
0:00:00.145756356  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<autoconvert1> completed state change to READY
0:00:00.145767120  2223      0x1b86800 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.145786747  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<rsnviddec> child 'autoconvert1' changed state to 2(READY) successfully
0:00:00.145809294  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<rsnviddec> completed state change to READY
0:00:00.145825109  2223      0x1b86800 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.145844405  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnviddec' changed state to 2(READY) successfully
0:00:00.145863354  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnvidparse> current PAUSED pending VOID_PENDING, desired next READY
0:00:00.145889339  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<rsnvidparse> completed state change to READY
0:00:00.145900149  2223      0x1b86800 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.145918019  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnvidparse' changed state to 2(READY) successfully
0:00:00.145934366  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnmq> current PAUSED pending VOID_PENDING, desired next READY
0:00:00.145952459  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<rsnmq> completed state change to READY
0:00:00.145963156  2223      0x1b86800 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.145981136  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnmq' changed state to 2(READY) successfully
0:00:00.145998241  2223      0x1b86800 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/i965_drv_video.so
libva info: va_openDriver() returns -1
0:00:00.147617122  2223      0x1d8d400 ERROR            vaapidecode gstvaapidecode.c:936:gst_vaapidecode_ensure_allowed_caps: failed to retrieve VA display
0:00:00.155294346  2223      0x1d8d400 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_avs
0:00:00.155420908  2223      0x1d8d400 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_daud
0:00:00.155470404  2223      0x1d8d400 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_ea
0:00:00.155507887  2223      0x1d8d400 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_ffm
0:00:00.155548784  2223      0x1d8d400 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_4xm
0:00:00.155583687  2223      0x1d8d400 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_gxf
0:00:00.155621028  2223      0x1d8d400 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_idcin
0:00:00.155658712  2223      0x1d8d400 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_ipmovie
0:00:00.155703932  2223      0x1d8d400 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_mm
0:00:00.155738884  2223      0x1d8d400 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_mmf
0:00:00.155813923  2223      0x1d8d400 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_nsv
0:00:00.155847171  2223      0x1d8d400 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_nut
0:00:00.155933182  2223      0x1d8d400 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_film_cpk
0:00:00.155969263  2223      0x1d8d400 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_smk
0:00:00.156004252  2223      0x1d8d400 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_sol
0:00:00.156054387  2223      0x1d8d400 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_psxstr
0:00:00.156108862  2223      0x1d8d400 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_vmd
0:00:00.156168530  2223      0x1d8d400 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_wc3movie
0:00:00.156202708  2223      0x1d8d400 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_wsaud
0:00:00.156234768  2223      0x1d8d400 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_wsvqa
0:00:00.156278764  2223      0x1d8d400 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_yuv4mpegpipe
0:00:00.156756936  2223      0x1d8d400 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/usr/lib64/gstreamer-1.0/libgstlibav.so" loaded
0:00:00.156773677  2223      0x1d8d400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "avdec_mpeg2video"
0:00:00.156964965  2223      0x1d8d400 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstVideoDecoder@0x7f57c4057520> adding pad 'sink'
0:00:00.156991570  2223      0x1d8d400 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstVideoDecoder@0x7f57c4057520> adding pad 'src'
0:00:00.157496463  2223      0x1d8d400 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link '':src_internal and avdec_mpeg2video0:sink
0:00:00.157514650  2223      0x1d8d400 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked '':src_internal and avdec_mpeg2video0:sink, successful
0:00:00.157526209  2223      0x1d8d400 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.157547330  2223      0x1d8d400 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link avdec_mpeg2video0:src and '':sink_internal
0:00:00.157559955  2223      0x1d8d400 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked avdec_mpeg2video0:src and '':sink_internal, successful
0:00:00.157570640  2223      0x1d8d400 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.157578775  2223      0x1d8d400 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<avdec_mpeg2video0:src> Received event on flushing pad. Discarding
0:00:00.157600334  2223      0x1d8d400 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<avdec_mpeg2video0> completed state change to READY
0:00:00.157610618  2223      0x1d8d400 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.157683514  2223      0x1d8d400 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad rsnvidparse:sink
0:00:00.157817329  2223      0x1d8d400 INFO                    task gsttask.c:431:gst_task_set_lock: setting stream lock 0x1d91b60 on task 0x1d935f0
0:00:00.157833009  2223      0x1d8d400 INFO                GST_PADS gstpad.c:5515:gst_pad_start_task:<'':src_0> created task 0x1d935f0
0:00:00.157857338  2223      0x1d8d400 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<rsnmq> adding pad 'src_0'
0:00:00.157887623  2223      0x1d8d400 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<rsnmq> adding pad 'sink_0'
0:00:00.157915917  2223      0x1d8d400 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link dvddemux:video_e0 and rsnmq:sink_0
0:00:00.157953942  2223      0x1d8d400 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<rsnmq:src_0> pad has no peer
0:00:00.157967887  2223      0x1d8d400 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked dvddemux:video_e0 and rsnmq:sink_0, successful
0:00:00.157979677  2223      0x1d8d400 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.157987457  2223      0x1d8d400 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<dvddemux:video_e0> Received event on flushing pad. Discarding
0:00:00.158003521  2223      0x1d8d400 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad rsnmq:src_0
0:00:00.158017111  2223      0x1d8d400 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link rsnmq:src_0 and rsnvidparse:sink
0:00:00.158067895  2223      0x1d8d400 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<'':video> pad has no peer
0:00:00.158610527  2223      0x1d8d400 INFO             vaapidecode gstvaapidecode.c:971:gst_vaapidecode_query:<vaapidecode0> query type caps
0:00:00.158636302  2223      0x1d8d400 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<'':video> pad has no peer
0:00:00.158647601  2223      0x1d8d400 INFO             GST_CONTEXT gstvaapivideocontext.c:83:context_pad_query:<vaapidecode0:src> context pad peer query failed
0:00:00.158661466  2223      0x1d8d400 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<rsnvidparse:sink> pad has no peer
0:00:00.158671124  2223      0x1d8d400 INFO             GST_CONTEXT gstvaapivideocontext.c:83:context_pad_query:<vaapidecode0:sink> context pad peer query failed
0:00:00.158679148  2223      0x1d8d400 INFO             GST_CONTEXT gstvaapivideocontext.c:151: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/i965_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/i965_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/i965_drv_video.so
libva info: va_openDriver() returns -1
0:00:00.163082955  2223      0x1d8d400 ERROR            vaapidecode gstvaapidecode.c:936:gst_vaapidecode_ensure_allowed_caps: failed to retrieve VA display
0:00:00.163162569  2223      0x1d8d400 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked rsnmq:src_0 and rsnvidparse:sink, successful
0:00:00.163183175  2223      0x1d8d400 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.163197677  2223      0x1d8d400 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<dvddemux:video_e0> Received event on flushing pad. Discarding
0:00:00.163273947  2223      0x1d8d400 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad dvddemux:sink
0:00:00.163302063  2223      0x1d8d400 INFO               GST_EVENT gstevent.c:678:gst_event_new_caps: creating caps event audio/x-private1-ac3
0:00:00.163367723  2223      0x1d8d400 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<dvddemux> adding pad 'audio_80'
0:00:00.163391078  2223      0x1d8d400 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad rsnvidparse:sink
0:00:00.163435534  2223      0x1d8d400 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<'':video> pad has no peer
0:00:00.164045692  2223      0x1d8d400 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad auddec:sink
0:00:00.164122052  2223      0x1d8d400 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<'':audio> pad has no peer
0:00:00.164353451  2223      0x1d8d400 WARN                resindvd resindvdbin.c:814:demux_pad_added:<source> warning: No audio decoder found
0:00:00.164387644  2223      0x1d8d400 INFO        GST_ERROR_SYSTEM gstelement.c:1835:gst_element_message_full:<source> posting message: There is no codec present that can handle the stream's type.
0:00:00.164408049  2223      0x1d8d400 INFO        GST_ERROR_SYSTEM gstelement.c:1858:gst_element_message_full:<source> posted warning message: There is no codec present that can handle the stream's type.
0:00:00.164464612  2223      0x1d8d400 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad dvddemux:sink
0:00:00.164485982  2223      0x1d8d400 INFO               GST_EVENT gstevent.c:678:gst_event_new_caps: creating caps event subpicture/x-dvd
0:00:00.164514616  2223      0x1d8d400 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<dvddemux> adding pad 'subpicture_20'
0:00:00.164536151  2223      0x1d8d400 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad rsnvidparse:sink
0:00:00.164567203  2223      0x1d8d400 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<'':video> pad has no peer
0:00:00.165217939  2223      0x1d8d400 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<subpselect> adding pad 'sink_0'
0:00:00.165245975  2223      0x1d8d400 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link dvddemux:subpicture_20 and subpselect:sink_0
0:00:00.165272049  2223      0x1d8d400 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked dvddemux:subpicture_20 and subpselect:sink_0, successful
0:00:00.165284861  2223      0x1d8d400 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.165352599  2223      0x1d8d400 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<dvddemux:sink> Received event on flushing pad. Discarding
0:00:00.165365678  2223      0x1d8d400 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<dvddemux:sink> Received event on flushing pad. Discarding
0:00:00.165375149  2223      0x1d8d400 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<dvddemux:sink> Received event on flushing pad. Discarding
0:00:00.165390831  2223      0x1d8d400 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<dvddemux:sink> Received event on flushing pad. Discarding
0:00:00.165417042  2223      0x1d8d400 INFO               GST_EVENT gstevent.c:759:gst_event_new_segment: creating segment event time segment start=0:00:00.280633333, 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.714400000, duration 99:99:99.999999999
0:00:00.165428762  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<dvddemux> removing pad 'subpicture_20'
0:00:00.165460215  2223      0x1d8d400 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<dvddemux:sink> Received event on flushing pad. Discarding
0:00:00.165480514  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking dvddemux:subpicture_20(0x7f57c40620d0) and subpselect:sink_0(0x1d6ed30)
0:00:00.165495677  2223      0x1d8d400 INFO                 basesrc gstbasesrc.c:2828:gst_base_src_loop:<dvdsrc> marking pending DISCONT
0:00:00.165520366  2223      0x1d8d400 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<dvddemux:sink> Received event on flushing pad. Discarding
0:00:00.165517656  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked dvddemux:subpicture_20 and subpselect:sink_0
0:00:00.165534818  2223      0x1d8d400 INFO                 basesrc gstbasesrc.c:2841:gst_base_src_loop:<dvdsrc> pausing after gst_pad_push() = flushing
0:00:00.165573357  2223      0x1d8d400 INFO                    task gsttask.c:300:gst_task_func:<dvdsrc:src> Task going to paused
0:00:00.165588935  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<dvddemux> removing pad 'audio_80'
0:00:00.165611392  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<dvddemux> removing pad 'video_e0'
0:00:00.165621578  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking dvddemux:video_e0(0x1d90510) and rsnmq:sink_0(0x1d918c0)
0:00:00.165641248  2223      0x1b86800 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked dvddemux:video_e0 and rsnmq:sink_0
0:00:00.165670413  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<dvddemux> completed state change to READY
0:00:00.165681304  2223      0x1b86800 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.165703580  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'dvddemux' changed state to 2(READY) successfully
0:00:00.165734232  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<auddec> current READY pending VOID_PENDING, desired next READY
0:00:00.165761857  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<autoconvert0> current READY pending VOID_PENDING, desired next READY
0:00:00.165782469  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<autoconvert0> completed state change to READY
0:00:00.165793971  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<auddec> child 'autoconvert0' changed state to 2(READY) successfully
0:00:00.165808270  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<auddec> completed state change to READY
0:00:00.165818543  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'auddec' changed state to 2(READY) successfully
0:00:00.165832397  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<audioselect> current READY pending VOID_PENDING, desired next READY
0:00:00.165843726  2223      0x1b86800 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<audioselect> skipping transition from READY to  READY
0:00:00.165853387  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'audioselect' changed state to 2(READY) successfully
0:00:00.165868848  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<spu_q> current READY pending VOID_PENDING, desired next READY
0:00:00.165880016  2223      0x1b86800 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<spu_q> skipping transition from READY to  READY
0:00:00.165889117  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'spu_q' changed state to 2(READY) successfully
0:00:00.165902537  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<subpselect> current READY pending VOID_PENDING, desired next READY
0:00:00.165913939  2223      0x1b86800 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<subpselect> skipping transition from READY to  READY
0:00:00.165923827  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'subpselect' changed state to 2(READY) successfully
0:00:00.165938804  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnparsetter> current READY pending VOID_PENDING, desired next READY
0:00:00.165955047  2223      0x1b86800 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<rsnparsetter> skipping transition from READY to  READY
0:00:00.165965557  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnparsetter' changed state to 2(READY) successfully
0:00:00.165980183  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnviddec> current READY pending VOID_PENDING, desired next READY
0:00:00.166001697  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<autoconvert1> current READY pending VOID_PENDING, desired next READY
0:00:00.166040313  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<avdec_mpeg2video0> current READY pending VOID_PENDING, desired next READY
0:00:00.166054749  2223      0x1b86800 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<avdec_mpeg2video0> skipping transition from READY to  READY
0:00:00.166065005  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<autoconvert1> child 'avdec_mpeg2video0' changed state to 2(READY) successfully
0:00:00.166079825  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<vaapidecode0> current NULL pending PAUSED, desired next READY
0:00:00.166112394  2223      0x1b86800 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<'':video> pad has no peer
0:00:00.166125927  2223      0x1b86800 INFO             GST_CONTEXT gstvaapivideocontext.c:83:context_pad_query:<vaapidecode0:src> context pad peer query failed
0:00:00.166144328  2223      0x1b86800 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<rsnmq:sink_0> pad has no peer
0:00:00.166155086  2223      0x1b86800 INFO             GST_CONTEXT gstvaapivideocontext.c:83:context_pad_query:<vaapidecode0:sink> context pad peer query failed
0:00:00.166163614  2223      0x1b86800 INFO             GST_CONTEXT gstvaapivideocontext.c:151: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/i965_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/i965_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/i965_drv_video.so
libva info: va_openDriver() returns -1
0:00:00.170126554  2223      0x1b86800 WARN            videodecoder gstvideodecoder.c:2261:gst_video_decoder_change_state:<vaapidecode0> error: Failed to open decoder
0:00:00.170166480  2223      0x1b86800 INFO        GST_ERROR_SYSTEM gstelement.c:1835:gst_element_message_full:<vaapidecode0> posting message: Could not initialize supporting library.
0:00:00.170193841  2223      0x1b86800 INFO        GST_ERROR_SYSTEM gstelement.c:1858:gst_element_message_full:<vaapidecode0> posted error message: Could not initialize supporting library.
0:00:00.170205390  2223      0x1b86800 INFO              GST_STATES gstelement.c:2609:gst_element_change_state:<vaapidecode0> have FAILURE change_state return
0:00:00.170212608  2223      0x1b86800 INFO              GST_STATES gstelement.c:2203:gst_element_abort_state:<vaapidecode0> aborting state from NULL to READY
0:00:00.170222671  2223      0x1b86800 INFO              GST_STATES gstbin.c:2689:gst_bin_change_state_func:<autoconvert1> child 'vaapidecode0' failed to go to state 2(READY)
0:00:00.170236373  2223      0x1b86800 INFO              GST_STATES gstelement.c:2609:gst_element_change_state:<autoconvert1> have FAILURE change_state return
0:00:00.170243732  2223      0x1b86800 INFO              GST_STATES gstbin.c:2689:gst_bin_change_state_func:<rsnviddec> child 'autoconvert1' failed to go to state 2(READY)
0:00:00.170260134  2223      0x1b86800 INFO              GST_STATES gstelement.c:2609:gst_element_change_state:<rsnviddec> have FAILURE change_state return
0:00:00.170267791  2223      0x1b86800 INFO              GST_STATES gstbin.c:2689:gst_bin_change_state_func:<source> child 'rsnviddec' failed to go to state 2(READY)
0:00:00.170280910  2223      0x1b86800 INFO              GST_STATES gstelement.c:2609:gst_element_change_state:<source> have FAILURE change_state return
0:00:00.170287971  2223      0x1b86800 INFO              GST_STATES gstbin.c:2689:gst_bin_change_state_func:<uridecodebin0> child 'source' failed to go to state 2(READY)
0:00:00.170318935  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<auddec> current READY pending VOID_PENDING, desired next READY
0:00:00.170338999  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<autoconvert0> current READY pending VOID_PENDING, desired next READY
0:00:00.170355221  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<autoconvert0> completed state change to READY
0:00:00.170364812  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<auddec> child 'autoconvert0' changed state to 2(READY) successfully
0:00:00.170376919  2223      0x1b86800 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<auddec> completed state change to READY
0:00:00.170385839  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'auddec' changed state to 2(READY) successfully
0:00:00.170397352  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<audioselect> current READY pending VOID_PENDING, desired next READY
0:00:00.170406984  2223      0x1b86800 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<audioselect> skipping transition from READY to  READY
0:00:00.170415635  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'audioselect' changed state to 2(READY) successfully
0:00:00.170428539  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<spu_q> current READY pending VOID_PENDING, desired next READY
0:00:00.170438669  2223      0x1b86800 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<spu_q> skipping transition from READY to  READY
0:00:00.170447025  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'spu_q' changed state to 2(READY) successfully
0:00:00.170458679  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<subpselect> current READY pending VOID_PENDING, desired next READY
0:00:00.170468292  2223      0x1b86800 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<subpselect> skipping transition from READY to  READY
0:00:00.170476501  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'subpselect' changed state to 2(READY) successfully
0:00:00.170488688  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnparsetter> current READY pending VOID_PENDING, desired next READY
0:00:00.170498101  2223      0x1b86800 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<rsnparsetter> skipping transition from READY to  READY
0:00:00.170506265  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<source> child 'rsnparsetter' changed state to 2(READY) successfully
0:00:00.170518071  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<rsnviddec> current READY pending READY, desired next READY
0:00:00.170534895  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<autoconvert1> current READY pending READY, desired next READY
0:00:00.170552142  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<avdec_mpeg2video0> current READY pending VOID_PENDING, desired next READY
0:00:00.170566113  2223      0x1b86800 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<avdec_mpeg2video0> skipping transition from READY to  READY
0:00:00.170574990  2223      0x1b86800 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<autoconvert1> child 'avdec_mpeg2video0' changed state to 2(READY) successfully
0:00:00.170587235  2223      0x1b86800 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<vaapidecode0> current NULL pending READY, desired next READY
0:00:00.170612729  2223      0x1b86800 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<'':video> pad has no peer
0:00:00.170624099  2223      0x1b86800 INFO             GST_CONTEXT gstvaapivideocontext.c:83:context_pad_query:<vaapidecode0:src> context pad peer query failed
0:00:00.170639544  2223      0x1b86800 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<rsnmq:sink_0> pad has no peer
0:00:00.170648995  2223      0x1b86800 INFO             GST_CONTEXT gstvaapivideocontext.c:83:context_pad_query:<vaapidecode0:sink> context pad peer query failed
0:00:00.170656266  2223      0x1b86800 INFO             GST_CONTEXT gstvaapivideocontext.c:151: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/i965_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/i965_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/i965_drv_video.so
libva info: va_openDriver() returns -1
0:00:00.174226084  2223      0x1b86800 WARN            videodecoder gstvideodecoder.c:2261:gst_video_decoder_change_state:<vaapidecode0> error: Failed to open decoder
0:00:00.174263528  2223      0x1b86800 INFO        GST_ERROR_SYSTEM gstelement.c:1835:gst_element_message_full:<vaapidecode0> posting message: Could not initialize supporting library.
0:00:00.174289696  2223      0x1b86800 INFO        GST_ERROR_SYSTEM gstelement.c:1858:gst_element_message_full:<vaapidecode0> posted error message: Could not initialize supporting library.
0:00:00.174301074  2223      0x1b86800 INFO              GST_STATES gstelement.c:2609:gst_element_change_state:<vaapidecode0> have FAILURE change_state return
0:00:00.174308009  2223      0x1b86800 INFO              GST_STATES gstelement.c:2203:gst_element_abort_state:<vaapidecode0> aborting state from NULL to READY
0:00:00.174318195  2223      0x1b86800 INFO              GST_STATES gstbin.c:2689:gst_bin_change_state_func:<autoconvert1> child 'vaapidecode0' failed to go to state 2(READY)
0:00:00.174332075  2223      0x1b86800 INFO              GST_STATES gstelement.c:2609:gst_element_change_state:<autoconvert1> have FAILURE change_state return
0:00:00.174339442  2223      0x1b86800 INFO              GST_STATES gstbin.c:2689:gst_bin_change_state_func:<rsnviddec> child 'autoconvert1' failed to go to state 2(READY)
0:00:00.174350321  2223      0x1b86800 INFO              GST_STATES gstelement.c:2609:gst_element_change_state:<rsnviddec> have FAILURE change_state return
0:00:00.174357131  2223      0x1b86800 INFO              GST_STATES gstbin.c:2689:gst_bin_change_state_func:<source> child 'rsnviddec' failed to go to state 2(READY)
0:00:00.174370000  2223      0x1b86800 INFO              GST_STATES gstelement.c:2609:gst_element_change_state:<source> have FAILURE change_state return
0:00:00.174402692  2223      0x1b86800 INFO           GST_PARENTAGE gstbin.c:1554:gst_bin_remove_func:<uridecodebin0> removed child "source"
0:00:00.174419773  2223      0x1b86800 INFO              GST_STATES gstelement.c:2609:gst_element_change_state:<uridecodebin0> have FAILURE change_state return
0:00:00.174432524  2223      0x1b86800 INFO              GST_STATES gstbin.c:2689:gst_bin_change_state_func:<playbin0> child 'uridecodebin0' failed to go to state 1(NULL)
0:00:00.174481969  2223      0x1b86800 INFO              GST_STATES gstelement.c:2609:gst_element_change_state:<playbin0> have FAILURE change_state return
0:00:00.174508345  2223      0x1b86800 INFO           GST_PARENTAGE gstbin.c:1554:gst_bin_remove_func:<playbin0> removed child "uridecodebin0"
0:00:00.174530254  2223      0x1b86800 INFO           GST_PARENTAGE gstbin.c:1554:gst_bin_remove_func:<playbin0> removed child "playsink"
0:00:00.174553857  2223      0x1b86800 INFO           GST_PARENTAGE gstbin.c:1554:gst_bin_remove_func:<playsink> removed child "streamsynchronizer0"
0:00:00.174566678  2223      0x1b86800 INFO         GST_REFCOUNTING gstelement.c:2873:gst_element_dispose:<streamsynchronizer0> dispose
0:00:00.174574185  2223      0x1b86800 INFO         GST_REFCOUNTING gstelement.c:2917:gst_element_dispose:<streamsynchronizer0> parent class dispose
0:00:00.174581822  2223      0x1b86800 INFO         GST_REFCOUNTING gstelement.c:2948:gst_element_finalize:<streamsynchronizer0> finalize
0:00:00.174588354  2223      0x1b86800 INFO         GST_REFCOUNTING gstelement.c:2953:gst_element_finalize:<streamsynchronizer0> finalize parent
0:00:00.174598488  2223      0x1b86800 INFO         GST_REFCOUNTING gstelement.c:2873:gst_element_dispose:<playsink> dispose
0:00:00.174604850  2223      0x1b86800 INFO         GST_REFCOUNTING gstelement.c:2917:gst_element_dispose:<playsink> parent class dispose
0:00:00.174616225  2223      0x1b86800 INFO         GST_REFCOUNTING gstelement.c:2948:gst_element_finalize:<playsink> finalize
0:00:00.174622507  2223      0x1b86800 INFO         GST_REFCOUNTING gstelement.c:2953:gst_element_finalize:<playsink> finalize parent
0:00:00.174629380  2223      0x1b86800 INFO         GST_REFCOUNTING gstelement.c:2873:gst_element_dispose:<playbin0> dispose

(gst-launch-1.0:2223): 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.174671987  2223      0x1b86800 INFO         GST_REFCOUNTING gstelement.c:2948:gst_element_finalize:<playbin0> finalize
0:00:00.174679116  2223      0x1b86800 INFO         GST_REFCOUNTING gstelement.c:2953:gst_element_finalize:<playbin0> finalize parent
0:00:00.174688166  2223      0x1b86800 INFO                GST_INIT gst.c:940:gst_deinit: deinitializing GStreamer
