0:00:00.000043567 18322      0x1a60c00 INFO                GST_INIT gst.c:499:init_pre: Initializing GStreamer Core Library version 1.4.5
0:00:00.000099946 18322      0x1a60c00 INFO                GST_INIT gst.c:500:init_pre: Using library installed in /tmp/install/lib
0:00:00.000111058 18322      0x1a60c00 INFO                GST_INIT gst.c:511:init_pre: Linux helix.bellet.info 4.0.7-300.fc22.x86_64 #1 SMP Mon Jun 29 22:15:06 UTC 2015 x86_64
0:00:00.000375417 18322      0x1a60c00 INFO                GST_INIT gstmessage.c:119:_priv_gst_message_initialize: init messages
0:00:00.000888053 18322      0x1a60c00 INFO                GST_INIT gstcontext.c:77:_priv_gst_context_initialize: init contexts
0:00:00.001127462 18322      0x1a60c00 INFO      GST_PLUGIN_LOADING gstplugin.c:317:_priv_gst_plugin_initialize: registering 0 static plugins
0:00:00.001201461 18322      0x1a60c00 INFO      GST_PLUGIN_LOADING gstplugin.c:225:gst_plugin_register_static: registered static plugin "staticelements"
0:00:00.001210777 18322      0x1a60c00 INFO      GST_PLUGIN_LOADING gstplugin.c:227:gst_plugin_register_static: added static plugin "staticelements", result: 1
0:00:00.001232383 18322      0x1a60c00 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.015122326 18322      0x1a60c00 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.013876 seconds
0:00:00.015174722 18322      0x1a60c00 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.016182956 18322      0x1a60c00 INFO            GST_REGISTRY gstregistry.c:1689:scan_and_update_registry: Registry cache changed. Writing new registry cache
0:00:00.016191815 18322      0x1a60c00 INFO            GST_REGISTRY gstregistrybinary.c:368:priv_gst_registry_binary_write_cache: Building binary registry cache image
0:00:00.023434545 18322      0x1a60c00 INFO            GST_REGISTRY gstregistrybinary.c:400:priv_gst_registry_binary_write_cache: Writing binary registry cache
0:00:00.054391542 18322      0x1a60c00 INFO            GST_REGISTRY gstregistrybinary.c:261:gst_registry_binary_cache_finish: Wrote binary registry cache
0:00:00.054415525 18322      0x1a60c00 INFO            GST_REGISTRY gstregistry.c:1698:scan_and_update_registry: Registry cache written successfully
0:00:00.054422386 18322      0x1a60c00 INFO            GST_REGISTRY gstregistry.c:1757:ensure_current_registry: registry reading and updating done, result = 1
0:00:00.054468044 18322      0x1a60c00 INFO                GST_INIT gst.c:709:init_post: GLib runtime version: 2.44.1
0:00:00.054474039 18322      0x1a60c00 INFO                GST_INIT gst.c:711:init_post: GLib headers version: 2.44.1
0:00:00.054501872 18322      0x1a60c00 INFO            GST_PIPELINE gstparse.c:323:gst_parse_launch_full: parsing pipeline description 'playbin uri=dvd:///dev/sr0 '
0:00:00.055628242 18322      0x1a60c00 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/tmp/install/lib/gstreamer-1.0/libgstplayback.so" loaded
0:00:00.055642777 18322      0x1a60c00 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "playbin"
Setting pipeline to PAUSED ...
0:00:00.056107089 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<playsink> current NULL pending VOID_PENDING, desired next READY
0:00:00.056123555 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<streamsynchronizer0> current NULL pending VOID_PENDING, desired next READY
0:00:00.056134552 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<streamsynchronizer0> completed state change to READY
0:00:00.056140541 18322      0x1a60c00 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.056166407 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playsink> child 'streamsynchronizer0' changed state to 2(READY) successfully
0:00:00.056179392 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<playsink> completed state change to READY
0:00:00.056185137 18322      0x1a60c00 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.056197521 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playbin0> child 'playsink' changed state to 2(READY) successfully
0:00:00.056206849 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2303:gst_element_continue_state:<playbin0> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:00.056215539 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<playbin0> notifying about state-changed NULL to READY (PAUSED pending)
0:00:00.056239215 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2310:gst_element_continue_state:<playbin0> continue state change READY to PAUSED, final PAUSED
0:00:00.056250092 18322      0x1a60c00 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "uridecodebin"
0:00:00.056370976 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<uridecodebin0> completed state change to READY
0:00:00.056380073 18322      0x1a60c00 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.056399989 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<playsink> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.056409743 18322      0x1a60c00 INFO                playsink gstplaysink.c:1400:do_async_start:<playsink> Sending async_start message
0:00:00.056421261 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<streamsynchronizer0> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.056432574 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<streamsynchronizer0> completed state change to PAUSED
0:00:00.056438506 18322      0x1a60c00 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.056448811 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playsink> child 'streamsynchronizer0' changed state to 3(PAUSED) successfully
0:00:00.056460524 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2679:gst_bin_change_state_func:<playbin0> child 'playsink' is changing state asynchronously to PAUSED
0:00:00.056469885 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<uridecodebin0> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.056823305 18322      0x1a60c00 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/tmp/install/lib/gstreamer-1.0/libgstdvdread.so" loaded
0:00:00.056833965 18322      0x1a60c00 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "dvdreadsrc" named "source"
0:00:00.056921287 18322      0x1a60c00 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseSrc@0x1c84ed0> adding pad 'src'
0:00:00.056933106 18322      0x1a60c00 INFO               GST_EVENT gstevent.c:678:gst_event_new_caps: creating caps event video/mpeg, mpegversion=(int)2, systemstream=(boolean)true
0:00:00.057013999 18322      0x1a60c00 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "decodebin"
0:00:00.057381376 18322      0x1a60c00 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/tmp/install/lib/gstreamer-1.0/libgstcoreelements.so" loaded
0:00:00.057391547 18322      0x1a60c00 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "typefind" named "typefind"
0:00:00.057462848 18322      0x1a60c00 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstTypeFindElement@0x1cd40a0> adding pad 'sink'
0:00:00.057489541 18322      0x1a60c00 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstTypeFindElement@0x1cd40a0> adding pad 'src'
0:00:00.057515524 18322      0x1a60c00 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad typefind:sink
0:00:00.057560388 18322      0x1a60c00 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link sink:proxypad0 and typefind:sink
0:00:00.057576423 18322      0x1a60c00 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked sink:proxypad0 and typefind:sink, successful
0:00:00.057589183 18322      0x1a60c00 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.057603158 18322      0x1a60c00 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstDecodeBin@0x1cce300> adding pad 'sink'
0:00:00.057646491 18322      0x1a60c00 INFO        GST_ELEMENT_PADS gstutils.c:1545:gst_element_link_pads_full: trying to link element source:(any) to element decodebin0:sink
0:00:00.057660596 18322      0x1a60c00 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad decodebin0:sink
0:00:00.057671635 18322      0x1a60c00 INFO                GST_PADS gstutils.c:1445:prepare_link_maybe_ghosting: source and decodebin0 in same bin, no need for ghost pads
0:00:00.057682280 18322      0x1a60c00 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link source:src and decodebin0:sink
0:00:00.057702208 18322      0x1a60c00 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked source:src and decodebin0:sink, successful
0:00:00.057711088 18322      0x1a60c00 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.057715741 18322      0x1a60c00 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<source:src> Received event on flushing pad. Discarding
0:00:00.057741336 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<decodebin0> current NULL pending VOID_PENDING, desired next PAUSED
0:00:00.057759026 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<typefind> current NULL pending VOID_PENDING, desired next READY
0:00:00.057769043 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<typefind> completed state change to READY
0:00:00.057774658 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<typefind> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.057786303 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<decodebin0> child 'typefind' changed state to 2(READY) successfully
0:00:00.057797519 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2303:gst_element_continue_state:<decodebin0> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:00.057806185 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<decodebin0> notifying about state-changed NULL to READY (PAUSED pending)
0:00:00.057816289 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2310:gst_element_continue_state:<decodebin0> continue state change READY to PAUSED, final PAUSED
0:00:00.057832361 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<typefind> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.057853444 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<typefind> completed state change to PAUSED
0:00:00.057861114 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<typefind> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.057872367 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<decodebin0> child 'typefind' changed state to 3(PAUSED) successfully
0:00:00.057884540 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2679:gst_bin_change_state_func:<uridecodebin0> child 'decodebin0' is changing state asynchronously to PAUSED
0:00:00.057894193 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<source> current NULL pending VOID_PENDING, desired next PAUSED
0:00:00.057902489 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2303:gst_element_continue_state:<source> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:00.057910779 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<source> notifying about state-changed NULL to READY (PAUSED pending)
0:00:00.057920239 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2310:gst_element_continue_state:<source> continue state change READY to PAUSED, final PAUSED
0:00:00.076606199 18322      0x1a60c00 INFO              dvdreadsrc dvdreadsrc.c:448:gst_dvd_read_src_goto_title:<source> There are 13 titles on this DVD
0:00:00.076616956 18322      0x1a60c00 INFO              dvdreadsrc dvdreadsrc.c:454:gst_dvd_read_src_goto_title:<source> Title 1 has 1 chapters

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

libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x00000167
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_0.VOB at 0x000001cd
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x0000cdd8
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_0.VOB at 0x0000fabd
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_1.VOB at 0x0000fb0a
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_03_0.VOB at 0x00278c0e
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_03_1.VOB at 0x00278c5b
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_04_0.VOB at 0x0028235c
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_04_1.VOB at 0x002823a9
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_05_0.VOB at 0x00287815
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_05_1.VOB at 0x00287862
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_06_0.VOB at 0x0028cb02
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_06_1.VOB at 0x0028cb4f
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_07_0.VOB at 0x0029042f
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_07_1.VOB at 0x0029047c
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_08_0.VOB at 0x00297794
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_08_1.VOB at 0x002977e1
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_09_0.VOB at 0x0029f56a
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_09_1.VOB at 0x0029f5b7
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_10_0.VOB at 0x002a6c05
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_10_1.VOB at 0x002a6c52
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_11_0.VOB at 0x002ad042
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_11_1.VOB at 0x002ad08f
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_12_0.VOB at 0x002b2d41
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_12_1.VOB at 0x002b2d8e
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_13_0.VOB at 0x002bdcb3
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_13_1.VOB at 0x002bdd00
libdvdread: Elapsed time 0
libdvdread: Found 13 VTS's
libdvdread: Elapsed time 0
0:00:00.076936467 18322      0x1a60c00 INFO              dvdreadsrc dvdreadsrc.c:485:gst_dvd_read_src_goto_title:<source> Opened title 1, angle 0
0:00:00.076949627 18322      0x1a60c00 INFO              dvdreadsrc dvdreadsrc.c:530:gst_dvd_read_src_goto_title:<source> [01] Audio    00: lang='', format=0
0:00:00.076959734 18322      0x1a60c00 INFO              dvdreadsrc dvdreadsrc.c:429:gst_dvd_read_src_get_chapter_starts:<source> [01] Chapter 01 starts at 0:00:00.000000000, dur = 0:00:39.000000000, cells 0-1
0:00:00.076989342 18322      0x1a60c00 INFO                 basesrc gstbasesrc.c:1328:gst_base_src_do_seek:<source> seeking: bytes segment start=0, offset=0, stop=-1, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0, base=0, position 0, duration -1
0:00:00.077003521 18322      0x1a60c00 INFO              dvdreadsrc dvdreadsrc.c:1650:gst_dvd_read_src_goto_sector:<source> Seek succeeded, going to chapter 1, cell 0
0:00:00.077031664 18322      0x1a60c00 INFO                    task gsttask.c:431:gst_task_set_lock: setting stream lock 0x1cd2100 on task 0x1cdf050
0:00:00.077041109 18322      0x1a60c00 INFO                GST_PADS gstpad.c:5515:gst_pad_start_task:<source:src> created task 0x1cdf050
0:00:00.077108999 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<source> completed state change to PAUSED
0:00:00.077118252 18322      0x1a60c00 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.077132275 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<uridecodebin0> child 'source' changed state to 3(PAUSED) successfully
0:00:00.077145922 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2679:gst_bin_change_state_func:<playbin0> child 'uridecodebin0' is changing state asynchronously to PAUSED
Pipeline is PREROLLING ...
0:00:00.077239931 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstelement.c:892:gst_element_get_static_pad: no such pad 'sink' in element "source"
0:00:00.077367755 18322      0x1cc94f0 INFO               GST_EVENT gstevent.c:678:gst_event_new_caps: creating caps event video/mpeg, mpegversion=(int)2, systemstream=(boolean)true
0:00:00.077450544 18322      0x1cc94f0 INFO                typefind gsttypefindelement.c:182:gst_type_find_element_have_type:<typefind> found caps video/mpeg, mpegversion=(int)2, systemstream=(boolean)true, probability=100
0:00:00.077474674 18322      0x1cc94f0 INFO               GST_EVENT gstevent.c:678:gst_event_new_caps: creating caps event video/mpeg, mpegversion=(int)2, systemstream=(boolean)true
0:00:00.077501129 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad typefind:src
0:00:00.077513188 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad typefind:sink
0:00:00.077601668 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link typefind:src and decodepad0:proxypad1
0:00:00.077617779 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked typefind:src and decodepad0:proxypad1, successful
0:00:00.077628027 18322      0x1cc94f0 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.087551509 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking typefind:src(0x1cd24f0) and decodepad0:proxypad1(0x1cd8970)
0:00:00.087597571 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked typefind:src and decodepad0:proxypad1
0:00:00.087625208 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link typefind:src and decodepad0:proxypad1
0:00:00.087636437 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked typefind:src and decodepad0:proxypad1, successful
0:00:00.087643851 18322      0x1cc94f0 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.087670261 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking typefind:src(0x1cd24f0) and decodepad0:proxypad1(0x1cd8970)
0:00:00.087690316 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked typefind:src and decodepad0:proxypad1
0:00:00.087985375 18322      0x1cc94f0 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/tmp/install/lib/gstreamer-1.0/libgstmpegpsdemux.so" loaded
0:00:00.088000580 18322      0x1cc94f0 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "mpegpsdemux"
0:00:00.088106275 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstMpegPSDemux@0x7fe0980a9000> adding pad 'sink'
0:00:00.088163595 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link typefind:src and mpegpsdemux0:sink
0:00:00.088188095 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked typefind:src and mpegpsdemux0:sink, successful
0:00:00.088200473 18322      0x1cc94f0 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.088219459 18322      0x1cc94f0 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<mpegpsdemux0> completed state change to READY
0:00:00.088228026 18322      0x1cc94f0 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<mpegpsdemux0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.088272622 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstelement.c:892:gst_element_get_static_pad: no such pad 'video_%02x' in element "mpegpsdemux0"
0:00:00.088282377 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstelement.c:892:gst_element_get_static_pad: no such pad 'audio_%02x' in element "mpegpsdemux0"
0:00:00.088288219 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstelement.c:892:gst_element_get_static_pad: no such pad 'subpicture_%02x' in element "mpegpsdemux0"
0:00:00.088293552 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstelement.c:892:gst_element_get_static_pad: no such pad 'private_%d' in element "mpegpsdemux0"
0:00:00.088328399 18322      0x1cc94f0 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<mpegpsdemux0> completed state change to PAUSED
0:00:00.088336346 18322      0x1cc94f0 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<mpegpsdemux0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.088401641 18322      0x1cc94f0 INFO               GST_EVENT gstevent.c:759:gst_event_new_segment: creating segment event bytes segment start=0, offset=0, stop=-1, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0, base=0, position 0, duration -1
0:00:00.088431721 18322      0x1cc94f0 INFO             mpegpsdemux gstmpegdemux.c:968:gst_flups_demux_sink_event:<mpegpsdemux0> received segment bytes segment start=0, offset=0, stop=-1, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0, base=0, position 0, duration -1
0:00:00.088477258 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad mpegpsdemux0:sink
0:00:00.088496510 18322      0x1cc94f0 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.088535144 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<mpegpsdemux0> adding pad 'video_e0'
0:00:00.088570545 18322      0x1cc94f0 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "multiqueue"
0:00:00.088653256 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad mpegpsdemux0:sink
0:00:00.088702833 18322      0x1cc94f0 INFO              GST_STATES gstelement.c:2303:gst_element_continue_state:<multiqueue0> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:00.088712636 18322      0x1cc94f0 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<multiqueue0> notifying about state-changed NULL to READY (PAUSED pending)
0:00:00.088795142 18322      0x1cc94f0 INFO              GST_STATES gstelement.c:2310:gst_element_continue_state:<multiqueue0> continue state change READY to PAUSED, final PAUSED
0:00:00.088807616 18322      0x1cc94f0 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<multiqueue0> completed state change to PAUSED
0:00:00.088813804 18322      0x1cc94f0 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<multiqueue0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.088860551 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link mpegpsdemux0:video_e0 and decodepad1:proxypad2
0:00:00.088871503 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked mpegpsdemux0:video_e0 and decodepad1:proxypad2, successful
0:00:00.088878897 18322      0x1cc94f0 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.089029137 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking mpegpsdemux0:video_e0(0x1cd2b80) and decodepad1:proxypad2(0x1cd8bb0)
0:00:00.089045455 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked mpegpsdemux0:video_e0 and decodepad1:proxypad2
0:00:00.089121809 18322      0x1cc94f0 INFO                    task gsttask.c:431:gst_task_set_lock: setting stream lock 0x1cd3050 on task 0x1cdf5f0
0:00:00.089132701 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:5515:gst_pad_start_task:<'':src_0> created task 0x1cdf5f0
0:00:00.089182799 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<multiqueue0> adding pad 'src_0'
0:00:00.089193216 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<multiqueue0> adding pad 'sink_0'
0:00:00.089204234 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link mpegpsdemux0:video_e0 and multiqueue0:sink_0
0:00:00.089221283 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<multiqueue0:src_0> pad has no peer
0:00:00.089231983 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked mpegpsdemux0:video_e0 and multiqueue0:sink_0, successful
0:00:00.089239810 18322      0x1cc94f0 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.089257981 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link multiqueue0:src_0 and decodepad1:proxypad2
0:00:00.089268542 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked multiqueue0:src_0 and decodepad1:proxypad2, successful
0:00:00.089275692 18322      0x1cc94f0 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.089289041 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking multiqueue0:src_0(0x1cd2fe0) and decodepad1:proxypad2(0x1cd8bb0)
0:00:00.089303170 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked multiqueue0:src_0 and decodepad1:proxypad2
0:00:00.089316588 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link multiqueue0:src_0 and decodepad1:proxypad2
0:00:00.089325787 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked multiqueue0:src_0 and decodepad1:proxypad2, successful
0:00:00.089332606 18322      0x1cc94f0 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.089356231 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking multiqueue0:src_0(0x1cd2fe0) and decodepad1:proxypad2(0x1cd8bb0)
0:00:00.089371322 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked multiqueue0:src_0 and decodepad1:proxypad2
0:00:00.089691260 18322      0x1cc94f0 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/tmp/install/lib/gstreamer-1.0/libgstvideoparsersbad.so" loaded
0:00:00.089701652 18322      0x1cc94f0 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "mpegvideoparse"
0:00:00.089784907 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseParse@0x7fe0980b5a30> adding pad 'sink'
0:00:00.089806998 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseParse@0x7fe0980b5a30> adding pad 'src'
0:00:00.089822176 18322      0x1cc94f0 INFO               baseparse gstbaseparse.c:3604:gst_base_parse_set_pts_interpolation:<GstMpegvParse@0x7fe0980b5a30> PTS interpolation: no
0:00:00.089848117 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link multiqueue0:src_0 and mpegvparse0:sink
0:00:00.089868966 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<mpegvparse0:src> pad has no peer
0:00:00.089880101 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked multiqueue0:src_0 and mpegvparse0:sink, successful
0:00:00.089890506 18322      0x1cc94f0 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.089907113 18322      0x1cc94f0 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<mpegvparse0> completed state change to READY
0:00:00.089915286 18322      0x1cc94f0 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<mpegvparse0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.089975235 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<mpegvparse0:src> pad has no peer
0:00:00.089992362 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad mpegvparse0:src
0:00:00.090007005 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link mpegvparse0:src and decodepad1:proxypad2
0:00:00.090015554 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked mpegvparse0:src and decodepad1:proxypad2, successful
0:00:00.090022138 18322      0x1cc94f0 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.090026729 18322      0x1cc94f0 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<mpegvparse0:src> Received event on flushing pad. Discarding
0:00:00.090105192 18322      0x1cc94f0 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<mpegvparse0> completed state change to PAUSED
0:00:00.090112594 18322      0x1cc94f0 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<mpegvparse0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.090169731 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad mpegpsdemux0:sink
0:00:00.090182334 18322      0x1cc94f0 INFO               GST_EVENT gstevent.c:678:gst_event_new_caps: creating caps event audio/x-private1-ac3
0:00:00.090238542 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<mpegpsdemux0> adding pad 'audio_80'
0:00:00.090277847 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link mpegpsdemux0:audio_80 and decodepad2:proxypad3
0:00:00.090287190 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked mpegpsdemux0:audio_80 and decodepad2:proxypad3, successful
0:00:00.090293774 18322      0x1cc94f0 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.090412872 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking mpegpsdemux0:audio_80(0x1cd3670) and decodepad2:proxypad3(0x1cd8df0)
0:00:00.090433008 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked mpegpsdemux0:audio_80 and decodepad2:proxypad3
0:00:00.090494750 18322      0x1cc94f0 INFO                    task gsttask.c:431:gst_task_set_lock: setting stream lock 0x1cd3b40 on task 0x1cdf710
0:00:00.090503118 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:5515:gst_pad_start_task:<'':src_1> created task 0x1cdf710
0:00:00.090542724 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<multiqueue0> adding pad 'src_1'
0:00:00.090551883 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<multiqueue0> adding pad 'sink_1'
0:00:00.090562607 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link mpegpsdemux0:audio_80 and multiqueue0:sink_1
0:00:00.090579307 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<multiqueue0:src_1> pad has no peer
0:00:00.090587712 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked mpegpsdemux0:audio_80 and multiqueue0:sink_1, successful
0:00:00.090595619 18322      0x1cc94f0 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.090613695 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link multiqueue0:src_1 and decodepad2:proxypad3
0:00:00.090622598 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked multiqueue0:src_1 and decodepad2:proxypad3, successful
0:00:00.090629586 18322      0x1cc94f0 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.090643444 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking multiqueue0:src_1(0x1cd3ad0) and decodepad2:proxypad3(0x1cd8df0)
0:00:00.090655764 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked multiqueue0:src_1 and decodepad2:proxypad3
0:00:00.090669062 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link multiqueue0:src_1 and decodepad2:proxypad3
0:00:00.090676760 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked multiqueue0:src_1 and decodepad2:proxypad3, successful
0:00:00.090683245 18322      0x1cc94f0 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.091515010 18322      0x1cc94f0 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/tmp/install/lib/gstreamer-1.0/libgstalsa.so" loaded
0:00:00.091527797 18322      0x1cc94f0 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "alsasink"
0:00:00.091702946 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseSink@0x7fe0980c4ff0> adding pad 'sink'
0:00:00.099536802 18322      0x1cc94f0 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<alsasink0> completed state change to READY
0:00:00.099558060 18322      0x1cc94f0 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<alsasink0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.099675732 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad alsasink0:sink
0:00:00.099826492 18322      0x1cc94f0 WARN                    alsa conf.c:4706:snd_config_expand: alsalib error: Unknown parameters {AES0 0x02 AES1 0x82 AES2 0x00 AES3 0x02}
0:00:00.099837730 18322      0x1cc94f0 WARN                    alsa pcm.c:2267:snd_pcm_open_noupdate: alsalib error: Unknown PCM default:{AES0 0x02 AES1 0x82 AES2 0x00 AES3 0x02}
0:00:00.099845816 18322      0x1cc94f0 INFO                    alsa gstalsasink.c:330:gst_alsasink_getcaps:<alsasink0> returning caps audio/x-raw, format=(string){ S16LE, S32LE }, layout=(string)interleaved, rate=(int)[ 4000, 192000 ], channels=(int)2, channel-mask=(bitmask)0x0000000000000003; audio/x-raw, format=(string){ S16LE, S32LE }, layout=(string)interleaved, rate=(int)[ 4000, 192000 ], channels=(int)1; audio/x-raw, format=(string){ S16LE, S32LE }, layout=(string)interleaved, rate=(int)[ 4000, 192000 ], channels=(int)3, channel-mask=(bitmask)0x000000000000000b; audio/x-raw, format=(string){ S16LE, S32LE }, layout=(string)interleaved, rate=(int)[ 4000, 192000 ], channels=(int)4, channel-mask=(bitmask)0x0000000000000033; audio/x-raw, format=(string){ S16LE, S32LE }, layout=(string)interleaved, rate=(int)[ 4000, 192000 ], channels=(int)6, channel-mask=(bitmask)0x000000000000003f; audio/x-raw, format=(string){ S16LE, S32LE }, layout=(string)interleaved, rate=(int)[ 4000, 192000 ], channels=(int)8, channel-mask=(bitmask)0x0000000000000c3f
0:00:00.099910210 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking multiqueue0:src_1(0x1cd3ad0) and decodepad2:proxypad3(0x1cd8df0)
0:00:00.099931531 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked multiqueue0:src_1 and decodepad2:proxypad3
0:00:00.100204945 18322      0x1cc94f0 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/tmp/install/lib/gstreamer-1.0/libgsta52dec.so" loaded
0:00:00.100216222 18322      0x1cc94f0 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "a52dec"
0:00:00.100308874 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstAudioDecoder@0x7fe0980f10d0> adding pad 'sink'
0:00:00.100326170 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstAudioDecoder@0x7fe0980f10d0> adding pad 'src'
0:00:00.100370086 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link multiqueue0:src_1 and a52dec0:sink
0:00:00.100404801 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked multiqueue0:src_1 and a52dec0:sink, successful
0:00:00.100414881 18322      0x1cc94f0 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.100442582 18322      0x1cc94f0 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<a52dec0> completed state change to READY
0:00:00.100450793 18322      0x1cc94f0 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<a52dec0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.100507644 18322      0x1cc94f0 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad a52dec0:src
0:00:00.100524940 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link a52dec0:src and decodepad2:proxypad3
0:00:00.100533829 18322      0x1cc94f0 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked a52dec0:src and decodepad2:proxypad3, successful
0:00:00.100540801 18322      0x1cc94f0 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.100545804 18322      0x1cc94f0 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<a52dec0:src> Received event on flushing pad. Discarding
0:00:00.100915719 18322      0x1cc94f0 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<a52dec0> completed state change to PAUSED
0:00:00.100925246 18322      0x1cc94f0 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<a52dec0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.102158703 18322      0x1cc94f0 INFO               GST_EVENT gstevent.c:759:gst_event_new_segment: creating segment event bytes segment start=0, offset=0, stop=-1, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0, base=0, position 0, duration -1
0:00:00.102187928 18322      0x1cc94f0 INFO             mpegpsdemux gstmpegdemux.c:968:gst_flups_demux_sink_event:<mpegpsdemux0> received segment bytes segment start=0, offset=0, stop=-1, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0, base=0, position 0, duration -1
0:00:00.102204298 18322      0x1cc94f0 INFO                 basesrc gstbasesrc.c:2828:gst_base_src_loop:<source> marking pending DISCONT
0:00:00.102234817 18322      0x1cc94f0 INFO             mpegpsdemux gstmpegdemux.c:590:gst_flups_demux_send_segment:<mpegpsdemux0> sending segment event 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 to pad <mpegpsdemux0:video_e0>
0:00:00.102251626 18322      0x1cc94f0 INFO               GST_EVENT gstevent.c:759:gst_event_new_segment: creating segment event 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.102455167 18322      0x1cc94f0 INFO             mpegpsdemux gstmpegdemux.c:590:gst_flups_demux_send_segment:<mpegpsdemux0> sending segment event 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.066633333, duration 99:99:99.999999999 to pad <mpegpsdemux0:audio_80>
0:00:00.102471182 18322      0x1cc94f0 INFO               GST_EVENT gstevent.c:759:gst_event_new_segment: creating segment event 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.066633333, duration 99:99:99.999999999
0:00:00.102828127 18322 0x7fe0980a8a80 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad alsasink0:sink
0:00:00.102923573 18322 0x7fe0980a8a80 INFO                  a52dec gsta52dec.c:432:gst_a52dec_reneg:<a52dec0> reneg channels:2 rate:48000
0:00:00.103072995 18322 0x7fe0980a8d90 INFO          mpegvideoparse gstmpegvideoparse.c:778:gst_mpegv_parse_update_src_caps:<mpegvparse0> stream has display extension: display_width=720 display_height=576
0:00:00.103098954 18322 0x7fe0980a8d90 INFO               baseparse gstbaseparse.c:3644:gst_base_parse_set_latency:<mpegvparse0> min/max latency 0:00:00.040000000, 0:00:00.040000000
0:00:00.103126378 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:678:gst_event_new_caps: creating caps event video/mpeg, mpegversion=(int)2, systemstream=(boolean)false, parsed=(boolean)true, width=(int)720, height=(int)576, framerate=(fraction)25/1, pixel-aspect-ratio=(fraction)16/15, codec_data=(buffer)000001b32d02402313882380000001b5148a00010000000001b5230505060b421200, profile=(string)main, level=(string)main, interlace-mode=(string)progressive
0:00:00.103188345 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking mpegvparse0:src(0x1cd3440) and decodepad1:proxypad2(0x1cd8bb0)
0:00:00.103218503 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked mpegvparse0:src and decodepad1:proxypad2
0:00:00.103196683 18322 0x7fe0980a8a80 INFO               GST_EVENT gstevent.c:678:gst_event_new_caps: creating caps event audio/x-raw, format=(string)F32LE, layout=(string)interleaved, rate=(int)48000, channels=(int)2, channel-mask=(bitmask)0x0000000000000003
0:00:00.103242949 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link mpegvparse0:src and decodepad1:proxypad2
0:00:00.103285368 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked mpegvparse0:src and decodepad1:proxypad2, successful
0:00:00.103296611 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.103344676 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad alsasink0:sink
0:00:00.103388701 18322 0x7fe0980a8a80 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<'':decodepad2> pad has no peer
0:00:00.103532016 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking mpegvparse0:src(0x1cd3440) and decodepad1:proxypad2(0x1cd8bb0)
0:00:00.103555073 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked mpegvparse0:src and decodepad1:proxypad2
0:00:00.103575605 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link mpegvparse0:src and decodepad1:proxypad2
0:00:00.103588443 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked mpegvparse0:src and decodepad1:proxypad2, successful
0:00:00.103598598 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.103627024 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking mpegvparse0:src(0x1cd3440) and decodepad1:proxypad2(0x1cd8bb0)
0:00:00.103647950 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked mpegvparse0:src and decodepad1:proxypad2
0:00:00.103669683 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link mpegvparse0:src and decodepad1:proxypad2
0:00:00.103682211 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked mpegvparse0:src and decodepad1:proxypad2, successful
0:00:00.103692177 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.104096759 18322      0x1cc94f0 INFO               GST_EVENT gstevent.c:678:gst_event_new_caps: creating caps event video/mpeg, mpegversion=(int)2, systemstream=(boolean)true
0:00:00.114999756 18322 0x7fe0980a8d90 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/tmp/install/lib/gstreamer-1.0/libgstvaapi.so" loaded
0:00:00.115019434 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "vaapisink"
0:00:00.115262392 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseSink@0x7fe08c02d840> adding pad 'sink'
0:00:00.115274791 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad (null):sink
0:00:00.115371380 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<vaapisink0:sink> pad has no peer
0:00:00.115379490 18322 0x7fe0980a8d90 INFO             GST_CONTEXT gstvaapivideocontext.c:81:context_pad_query:<vaapisink0:sink> context pad peer query failed
0:00:00.115384763 18322 0x7fe0980a8d90 INFO             GST_CONTEXT gstvaapivideocontext.c:149:gst_vaapi_video_context_prepare:<vaapisink0> posting `need-context' message
0:00:00.115397877 18322 0x7fe0980a8d90 INFO                   vaapi gstvaapidisplay.c:119:libgstvaapi_init_once: gstreamer-vaapi version drop_0.10_1.0-132-g3ccb198
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns 0
libva info: Trying to open /usr/lib64/dri/radeonsi_drv_video.so
libva info: va_openDriver() returns -1
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns 0
libva info: Trying to open /usr/lib64/dri/radeonsi_drv_video.so
libva info: va_openDriver() returns -1
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns -1
libva error: va_getDriverName() failed with unknown libva error,driver_name=(null)
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns -1
libva error: va_getDriverName() failed with unknown libva error,driver_name=(null)
0:00:00.214972482 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<vaapisink0:sink> pad has no peer
0:00:00.214986899 18322 0x7fe0980a8d90 INFO             GST_CONTEXT gstvaapivideocontext.c:81:context_pad_query:<vaapisink0:sink> context pad peer query failed
0:00:00.214994756 18322 0x7fe0980a8d90 INFO             GST_CONTEXT gstvaapivideocontext.c:149:gst_vaapi_video_context_prepare:<vaapisink0> posting `need-context' message
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns 0
libva info: Trying to open /usr/lib64/dri/radeonsi_drv_video.so
libva info: va_openDriver() returns -1
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns 0
libva info: Trying to open /usr/lib64/dri/radeonsi_drv_video.so
libva info: va_openDriver() returns -1
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns -1
libva error: va_getDriverName() failed with unknown libva error,driver_name=(null)
0:00:00.310333011 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2609:gst_element_change_state:<vaapisink0> have FAILURE change_state return
0:00:00.310347175 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2203:gst_element_abort_state:<vaapisink0> aborting state from NULL to READY
0:00:00.310381292 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2873:gst_element_dispose:<vaapisink0> dispose
0:00:00.310389431 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<vaapisink0> removing pad 'sink'
0:00:00.310405055 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2917:gst_element_dispose:<vaapisink0> parent class dispose
0:00:00.310421644 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2948:gst_element_finalize:<vaapisink0> finalize
0:00:00.310428415 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2953:gst_element_finalize:<vaapisink0> finalize parent
0:00:00.310435276 18322 0x7fe0980a8d90 WARN                 playbin gstplaybin2.c:4498:autoplug_select_cb:<playbin0> Could not activate sink vaapisink
0:00:00.310903758 18322 0x7fe0980a8d90 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/tmp/install/lib/gstreamer-1.0/libgstxvimagesink.so" loaded
0:00:00.310916794 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "xvimagesink"
0:00:00.311052993 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseSink@0x7fe08c031470> adding pad 'sink'
0:00:00.312615770 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<xvimagesink0> completed state change to READY
0:00:00.312638216 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<xvimagesink0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.312676219 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad xvimagesink0:sink
0:00:00.312715575 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking mpegvparse0:src(0x1cd3440) and decodepad1:proxypad2(0x1cd8bb0)
0:00:00.312759651 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked mpegvparse0:src and decodepad1:proxypad2
0:00:00.312777976 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "vaapidecode"
0:00:00.312894208 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstVideoDecoder@0x7fe08c043ff0> adding pad 'sink'
0:00:00.312915140 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstVideoDecoder@0x7fe08c043ff0> adding pad 'src'
0:00:00.312935748 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad (null):sink
0:00:00.312944275 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad (null):src
0:00:00.312973727 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link mpegvparse0:src and vaapidecode0:sink
0:00:00.313000121 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<vaapidecode0:src> pad has no peer
0:00:00.313006965 18322 0x7fe0980a8d90 INFO             GST_CONTEXT gstvaapivideocontext.c:81:context_pad_query:<vaapidecode0:src> context pad peer query failed
0:00:00.313014006 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<vaapidecode0:sink> pad has no peer
0:00:00.313019270 18322 0x7fe0980a8d90 INFO             GST_CONTEXT gstvaapivideocontext.c:81:context_pad_query:<vaapidecode0:sink> context pad peer query failed
0:00:00.313024453 18322 0x7fe0980a8d90 INFO             GST_CONTEXT gstvaapivideocontext.c:149:gst_vaapi_video_context_prepare:<vaapidecode0> posting `need-context' message
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns 0
libva info: Trying to open /usr/lib64/dri/radeonsi_drv_video.so
libva info: va_openDriver() returns -1
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns 0
libva info: Trying to open /usr/lib64/dri/radeonsi_drv_video.so
libva info: va_openDriver() returns -1
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns -1
libva error: va_getDriverName() failed with unknown libva error,driver_name=(null)
0:00:00.402372364 18322 0x7fe0980a8d90 ERROR            vaapidecode gstvaapidecode.c:958:gst_vaapidecode_ensure_allowed_caps: failed to retrieve VA display
0:00:00.402398227 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2242:gst_pad_link_prepare: caps are incompatible
0:00:00.402423891 18322 0x7fe0980a8d90 WARN               decodebin gstdecodebin2.c:2090:connect_pad:<decodebin0> Link failed on pad vaapidecode0:sink
0:00:00.402446881 18322 0x7fe0980a8d90 INFO           GST_PARENTAGE gstbin.c:1554:gst_bin_remove_func:<decodebin0> removed child "vaapidecode0"
0:00:00.402461825 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2873:gst_element_dispose:<vaapidecode0> dispose
0:00:00.402468728 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<vaapidecode0> removing pad 'sink'
0:00:00.402476476 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<vaapidecode0> removing pad 'src'
0:00:00.402484610 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2917:gst_element_dispose:<vaapidecode0> parent class dispose
0:00:00.402506069 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2948:gst_element_finalize:<vaapidecode0> finalize
0:00:00.402512281 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2953:gst_element_finalize:<vaapidecode0> finalize parent
0:00:00.402523675 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link mpegvparse0:src and decodepad1:proxypad2
0:00:00.402536179 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked mpegvparse0:src and decodepad1:proxypad2, successful
0:00:00.402544926 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.402611484 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad xvimagesink0:sink
0:00:00.402638081 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking mpegvparse0:src(0x1cd3440) and decodepad1:proxypad2(0x1cd8bb0)
0:00:00.402653081 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked mpegvparse0:src and decodepad1:proxypad2
0:00:00.402668100 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "vaapidecodebin"
0:00:00.402840527 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "vaapidecode" named "vaapidecode"
0:00:00.402865854 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstVideoDecoder@0x7fe08c047810> adding pad 'sink'
0:00:00.402882272 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstVideoDecoder@0x7fe08c047810> adding pad 'src'
0:00:00.402895212 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad (null):sink
0:00:00.402903592 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad (null):src
0:00:00.402913480 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "queue" named "queue"
0:00:00.403007632 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstQueue@0x7fe08c050210> adding pad 'sink'
0:00:00.403030180 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstQueue@0x7fe08c050210> adding pad 'src'
0:00:00.403059469 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstutils.c:1545:gst_element_link_pads_full: trying to link element vaapidecode:src to element queue:sink
0:00:00.403070845 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad vaapidecode:src
0:00:00.403077586 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad queue:sink
0:00:00.403085341 18322 0x7fe0980a8d90 INFO                GST_PADS gstutils.c:1445:prepare_link_maybe_ghosting: vaapidecode and queue in same bin, no need for ghost pads
0:00:00.403096326 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link vaapidecode:src and queue:sink
0:00:00.403106138 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked vaapidecode:src and queue:sink, successful
0:00:00.403113140 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.403118221 18322 0x7fe0980a8d90 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<vaapidecode:src> Received event on flushing pad. Discarding
0:00:00.403129784 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad vaapidecode:sink
0:00:00.403157905 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link sink:proxypad4 and vaapidecode:sink
0:00:00.403167648 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked sink:proxypad4 and vaapidecode:sink, successful
0:00:00.403174753 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.403183163 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstVaapiDecodeBin@0x7fe08c048050> adding pad 'sink'
0:00:00.403207357 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link mpegvparse0:src and vaapidecodebin0:sink
0:00:00.403232711 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<queue:src> pad has no peer
0:00:00.403240950 18322 0x7fe0980a8d90 INFO             GST_CONTEXT gstvaapivideocontext.c:81:context_pad_query:<vaapidecode:src> context pad peer query failed
0:00:00.403248713 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<vaapidecodebin0:sink> pad has no peer
0:00:00.403255502 18322 0x7fe0980a8d90 INFO             GST_CONTEXT gstvaapivideocontext.c:81:context_pad_query:<vaapidecode:sink> context pad peer query failed
0:00:00.403260360 18322 0x7fe0980a8d90 INFO             GST_CONTEXT gstvaapivideocontext.c:149:gst_vaapi_video_context_prepare:<vaapidecode> posting `need-context' message
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns 0
libva info: Trying to open /usr/lib64/dri/radeonsi_drv_video.so
libva info: va_openDriver() returns -1
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns 0
libva info: Trying to open /usr/lib64/dri/radeonsi_drv_video.so
libva info: va_openDriver() returns -1
libva info: VA-API version 0.37.0
libva info: va_getDriverName() returns -1
libva error: va_getDriverName() failed with unknown libva error,driver_name=(null)
0:00:00.496486694 18322 0x7fe0980a8d90 ERROR            vaapidecode gstvaapidecode.c:958:gst_vaapidecode_ensure_allowed_caps: failed to retrieve VA display
0:00:00.496527209 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2242:gst_pad_link_prepare: caps are incompatible
0:00:00.496559557 18322 0x7fe0980a8d90 WARN               decodebin gstdecodebin2.c:2090:connect_pad:<decodebin0> Link failed on pad vaapidecodebin0:sink
0:00:00.496588418 18322 0x7fe0980a8d90 INFO           GST_PARENTAGE gstbin.c:1554:gst_bin_remove_func:<decodebin0> removed child "vaapidecodebin0"
0:00:00.496613890 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking vaapidecode:src(0x7fe0980bc970) and queue:sink(0x7fe0980bc510)
0:00:00.496639966 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked vaapidecode:src and queue:sink
0:00:00.496663925 18322 0x7fe0980a8d90 INFO           GST_PARENTAGE gstbin.c:1554:gst_bin_remove_func:<vaapidecodebin0> removed child "queue"
0:00:00.496674317 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2873:gst_element_dispose:<queue> dispose
0:00:00.496679648 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<queue> removing pad 'sink'
0:00:00.496692825 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<queue> removing pad 'src'
0:00:00.496707911 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2917:gst_element_dispose:<queue> parent class dispose
0:00:00.496717741 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2948:gst_element_finalize:<queue> finalize
0:00:00.496729568 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2953:gst_element_finalize:<queue> finalize parent
0:00:00.496738968 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking sink:proxypad4(0x1cd9270) and vaapidecode:sink(0x7fe0980bcba0)
0:00:00.496750804 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked sink:proxypad4 and vaapidecode:sink
0:00:00.496759934 18322 0x7fe0980a8d90 INFO           GST_PARENTAGE gstbin.c:1554:gst_bin_remove_func:<vaapidecodebin0> removed child "vaapidecode"
0:00:00.496770188 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2873:gst_element_dispose:<vaapidecode> dispose
0:00:00.496774823 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<vaapidecode> removing pad 'sink'
0:00:00.496781330 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<vaapidecode> removing pad 'src'
0:00:00.496789316 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2917:gst_element_dispose:<vaapidecode> parent class dispose
0:00:00.496805375 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2948:gst_element_finalize:<vaapidecode> finalize
0:00:00.496813115 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2953:gst_element_finalize:<vaapidecode> finalize parent
0:00:00.496823008 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2873:gst_element_dispose:<vaapidecodebin0> dispose
0:00:00.496827713 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<vaapidecodebin0> removing pad 'sink'
0:00:00.496840364 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2917:gst_element_dispose:<vaapidecodebin0> parent class dispose
0:00:00.496845418 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2948:gst_element_finalize:<vaapidecodebin0> finalize
0:00:00.496849496 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2953:gst_element_finalize:<vaapidecodebin0> finalize parent
0:00:00.496858839 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link mpegvparse0:src and decodepad1:proxypad2
0:00:00.496875443 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked mpegvparse0:src and decodepad1:proxypad2, successful
0:00:00.496883249 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.496949479 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad xvimagesink0:sink
0:00:00.496978524 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking mpegvparse0:src(0x1cd3440) and decodepad1:proxypad2(0x1cd8bb0)
0:00:00.496997760 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked mpegvparse0:src and decodepad1:proxypad2
0:00:00.500741684 18322 0x7fe0980a8d90 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_avs
0:00:00.500810317 18322 0x7fe0980a8d90 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_daud
0:00:00.500843420 18322 0x7fe0980a8d90 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_ea
0:00:00.500872426 18322 0x7fe0980a8d90 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_ffm
0:00:00.500905321 18322 0x7fe0980a8d90 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_4xm
0:00:00.500932435 18322 0x7fe0980a8d90 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_gxf
0:00:00.500954788 18322 0x7fe0980a8d90 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_idcin
0:00:00.500993897 18322 0x7fe0980a8d90 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_ipmovie
0:00:00.501026928 18322 0x7fe0980a8d90 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_mm
0:00:00.501047232 18322 0x7fe0980a8d90 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_mmf
0:00:00.501091215 18322 0x7fe0980a8d90 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_nsv
0:00:00.501120738 18322 0x7fe0980a8d90 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_nut
0:00:00.501172199 18322 0x7fe0980a8d90 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_film_cpk
0:00:00.501195020 18322 0x7fe0980a8d90 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_smk
0:00:00.501219571 18322 0x7fe0980a8d90 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_sol
0:00:00.501247380 18322 0x7fe0980a8d90 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_psxstr
0:00:00.501284923 18322 0x7fe0980a8d90 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_vmd
0:00:00.501314076 18322 0x7fe0980a8d90 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_wc3movie
0:00:00.501334206 18322 0x7fe0980a8d90 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_wsaud
0:00:00.501358417 18322 0x7fe0980a8d90 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_wsvqa
0:00:00.501393860 18322 0x7fe0980a8d90 INFO            GST_TYPEFIND gsttypefind.c:71:gst_type_find_register: registering typefind function for avtype_yuv4mpegpipe
0:00:00.501718034 18322 0x7fe0980a8d90 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/tmp/install/lib/gstreamer-1.0/libgstlibav.so" loaded
0:00:00.501738400 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "avdec_mpeg2video"
0:00:00.501848845 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstVideoDecoder@0x7fe08c09cb20> adding pad 'sink'
0:00:00.501869753 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstVideoDecoder@0x7fe08c09cb20> adding pad 'src'
0:00:00.502290363 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link mpegvparse0:src and avdec_mpeg2video0:sink
0:00:00.502316782 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked mpegvparse0:src and avdec_mpeg2video0:sink, successful
0:00:00.502326904 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.502358524 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<avdec_mpeg2video0> completed state change to READY
0:00:00.502371816 18322 0x7fe0980a8d90 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.502439574 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad avdec_mpeg2video0:src
0:00:00.502461328 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link avdec_mpeg2video0:src and decodepad1:proxypad2
0:00:00.502480711 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked avdec_mpeg2video0:src and decodepad1:proxypad2, successful
0:00:00.502487963 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.502495456 18322 0x7fe0980a8d90 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<avdec_mpeg2video0:src> Received event on flushing pad. Discarding
0:00:00.502889253 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<avdec_mpeg2video0> completed state change to PAUSED
0:00:00.502903865 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<avdec_mpeg2video0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
Redistribute latency...
0:00:00.503516733 18322      0x1a60c00 INFO               GST_EVENT gstevent.c:1244:gst_event_new_latency: creating latency event 0:00:00.000000000
0:00:00.503552044 18322      0x1a60c00 INFO                     bin gstbin.c:2502:gst_bin_do_latency_func:<playbin0> configured latency of 0:00:00.000000000
0:00:00.503619429 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:678:gst_event_new_caps: creating caps event video/x-raw, format=(string)I420, width=(int)720, height=(int)576, pixel-aspect-ratio=(fraction)16/15, interlace-mode=(string)progressive, chroma-site=(string)mpeg2, colorimetry=(string)bt601, framerate=(fraction)25/1
0:00:00.503668967 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<decodebin0> adding pad 'src_0'
0:00:00.503738341 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link decodebin0:src_0 and src_0:proxypad5
0:00:00.503752580 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked decodebin0:src_0 and src_0:proxypad5, successful
0:00:00.503764188 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.503797649 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<uridecodebin0> adding pad 'src_0'
0:00:00.504212937 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "input-selector"
0:00:00.504281871 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstInputSelector@0x7fe08c0a82a0> adding pad 'src'
0:00:00.504309078 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2303:gst_element_continue_state:<inputselector0> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:00.504320933 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<inputselector0> notifying about state-changed NULL to READY (PAUSED pending)
0:00:00.504333317 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2310:gst_element_continue_state:<inputselector0> continue state change READY to PAUSED, final PAUSED
0:00:00.504350191 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<inputselector0> completed state change to PAUSED
0:00:00.504363881 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<inputselector0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.504381601 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad inputselector0:src
0:00:00.504429888 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<inputselector0> adding pad 'sink_0'
0:00:00.504451335 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link uridecodebin0:src_0 and inputselector0:sink_0
0:00:00.504478654 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<inputselector0:src> pad has no peer
0:00:00.504488673 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked uridecodebin0:src_0 and inputselector0:sink_0, successful
0:00:00.504499740 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.504539315 18322 0x7fe0980a8d90 INFO               decodebin gstdecodebin2.c:4063:gst_decode_bin_expose:<decodebin0:src_0> added new decoded pad
0:00:00.504550051 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<decodebin0> adding pad 'src_1'
0:00:00.504595977 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link decodebin0:src_1 and src_1:proxypad6
0:00:00.504613718 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked decodebin0:src_1 and src_1:proxypad6, successful
0:00:00.504623774 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.504657960 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<uridecodebin0> adding pad 'src_1'
0:00:00.504673640 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "input-selector"
0:00:00.504692738 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstInputSelector@0x7fe08c0a83f0> adding pad 'src'
0:00:00.504715144 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2303:gst_element_continue_state:<inputselector1> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:00.504733427 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<inputselector1> notifying about state-changed NULL to READY (PAUSED pending)
0:00:00.504761148 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2310:gst_element_continue_state:<inputselector1> continue state change READY to PAUSED, final PAUSED
0:00:00.504773803 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<inputselector1> completed state change to PAUSED
0:00:00.504782565 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<inputselector1> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.504799545 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad inputselector1:src
0:00:00.504822538 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<inputselector1> adding pad 'sink_0'
0:00:00.504841834 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link uridecodebin0:src_1 and inputselector1:sink_0
0:00:00.504867057 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<inputselector1:src> pad has no peer
0:00:00.504876616 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked uridecodebin0:src_1 and inputselector1:sink_0, successful
0:00:00.504887321 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.504919738 18322 0x7fe0980a8d90 INFO               decodebin gstdecodebin2.c:4063:gst_decode_bin_expose:<decodebin0:src_1> added new decoded pad
0:00:00.504934983 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "tee" named "audiotee"
0:00:00.505001679 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstTee@0x7fe08c0a8540> adding pad 'sink'
0:00:00.505020334 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad audiotee:sink
0:00:00.505038509 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2303:gst_element_continue_state:<audiotee> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:00.505049286 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<audiotee> notifying about state-changed NULL to READY (PAUSED pending)
0:00:00.505068965 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2310:gst_element_continue_state:<audiotee> continue state change READY to PAUSED, final PAUSED
0:00:00.505083180 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<audiotee> completed state change to PAUSED
0:00:00.505093158 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<audiotee> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.505129353 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link audio_sink:proxypad7 and audiotee:sink
0:00:00.505142016 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked audio_sink:proxypad7 and audiotee:sink, successful
0:00:00.505150595 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.505166998 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<playsink> adding pad 'audio_sink'
0:00:00.505184426 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link inputselector1:src and playsink:audio_sink
0:00:00.505208980 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked inputselector1:src and playsink:audio_sink, successful
0:00:00.505220062 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.505263876 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<playsink> adding pad 'video_sink'
0:00:00.505280594 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link inputselector0:src and playsink:video_sink
0:00:00.505300940 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<video_sink:proxypad8> pad has no peer
0:00:00.505312440 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked inputselector0:src and playsink:video_sink, successful
0:00:00.505322754 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.505347534 18322 0x7fe0980a8d90 INFO                 playbin gstplaybin2.c:3536:no_more_pads_cb:<playbin0> setting custom audio sink <alsasink0>
0:00:00.505359100 18322 0x7fe0980a8d90 INFO                 playbin gstplaybin2.c:3543:no_more_pads_cb:<playbin0> setting custom video sink <xvimagesink0>
0:00:00.505422628 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:3129:bin_handle_async_done:<decodebin0> committing state from READY to PAUSED, old pending PAUSED
0:00:00.505439723 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:3149:bin_handle_async_done:<decodebin0> completed state change, pending VOID
0:00:00.505445447 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<decodebin0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.505469598 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:3129:bin_handle_async_done:<uridecodebin0> committing state from READY to PAUSED, old pending PAUSED
0:00:00.505480198 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:3149:bin_handle_async_done:<uridecodebin0> completed state change, pending VOID
0:00:00.505485823 18322 0x7fe0980a8d90 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.505570753 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<xvimagesink0> completed state change to READY
0:00:00.505593629 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "bin" named "vbin"
0:00:00.505631232 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "queue" named "vqueue"
0:00:00.505661606 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstQueue@0x7fe08c050500> adding pad 'sink'
0:00:00.505686850 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstQueue@0x7fe08c050500> adding pad 'src'
0:00:00.505868830 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstPlaySinkConvertBin@0x7fe08c0d61d0> adding pad 'sink'
0:00:00.505905256 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstPlaySinkConvertBin@0x7fe08c0d61d0> adding pad 'src'
0:00:00.505916482 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "identity" named "identity"
0:00:00.506017815 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseTransform@0x1ccaed0> adding pad 'sink'
0:00:00.506037511 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseTransform@0x1ccaed0> adding pad 'src'
0:00:00.506060029 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:456:gst_element_factory_make: no such element factory "videobalance"!
0:00:00.506070686 18322 0x7fe0980a8d90 INFO      playsinkconvertbin gstplaysinkconvertbin.c:558:gst_play_sink_convert_bin_cache_converter_caps:<GstPlaySinkVideoConvert@0x7fe08c0d61d0> No conversion elements
0:00:00.506281191 18322 0x7fe0980a8d90 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/tmp/install/lib/gstreamer-1.0/libgstvideoconvert.so" loaded
0:00:00.506297272 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "videoconvert" named "conv"
0:00:00.506490463 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseTransform@0x7fe08c0e0940> adding pad 'sink'
0:00:00.506511016 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseTransform@0x7fe08c0e0940> adding pad 'src'
0:00:00.506810997 18322 0x7fe0980a8d90 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/tmp/install/lib/gstreamer-1.0/libgstvideoscale.so" loaded
0:00:00.506823212 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "videoscale" named "scale"
0:00:00.506958707 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseTransform@0x7fe08c0e4780> adding pad 'sink'
0:00:00.506979332 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseTransform@0x7fe08c0e4780> adding pad 'src'
0:00:00.507007830 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstutils.c:1545:gst_element_link_pads_full: trying to link element conv:src to element scale:sink
0:00:00.507020689 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad conv:src
0:00:00.507028158 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad scale:sink
0:00:00.507037374 18322 0x7fe0980a8d90 INFO                GST_PADS gstutils.c:1445:prepare_link_maybe_ghosting: conv and scale in same bin, no need for ghost pads
0:00:00.507050285 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link conv:src and scale:sink
0:00:00.507066002 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked conv:src and scale:sink, successful
0:00:00.507076066 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.507082335 18322 0x7fe0980a8d90 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<conv:src> Received event on flushing pad. Discarding
0:00:00.507094878 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad conv:sink
0:00:00.507112195 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<scale:src> pad has no peer
0:00:00.509705718 18322 0x7fe0980a8d90 INFO      playsinkconvertbin gstplaysinkconvertbin.c:571:gst_play_sink_convert_bin_cache_converter_caps:<vconv> Converter caps: video/x-raw, format=(string){ I420, YV12, YUY2, UYVY, AYUV, RGBx, BGRx, xRGB, xBGR, RGBA, BGRA, ARGB, ABGR, RGB, BGR, Y41B, Y42B, YVYU, Y444, GRAY8, GRAY16_BE, GRAY16_LE, v308, RGB16, RGB15, ARGB64, AYUV64, NV12 }, width=(int)[ 1, 32767 ], height=(int)[ 1, 32767 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-raw(ANY), format=(string){ I420, YV12, YUY2, UYVY, AYUV, RGBx, BGRx, xRGB, xBGR, RGBA, BGRA, ARGB, ABGR, RGB, BGR, Y41B, Y42B, YVYU, Y444, GRAY8, GRAY16_BE, GRAY16_LE, v308, RGB16, RGB15, ARGB64, AYUV64, NV12 }, width=(int)[ 1, 32767 ], height=(int)[ 1, 32767 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-raw, width=(int)[ 1, 32767 ], height=(int)[ 1, 32767 ], framerate=(fraction)[ 0/1, 2147483647/1 ], format=(string){ I420, YV12, YUY2, UYVY, AYUV, RGBx, BGRx, xRGB, xBGR, RGBA, BGRA, ARGB, ABGR, RGB, BGR, Y41B, Y42B, YVYU, Y444, v210, v216, NV12, NV21, NV16, NV24, GRAY8, GRAY16_BE, GRAY16_LE, v308, RGB16, BGR16, RGB15, BGR15, UYVP, A420, RGB8P, YUV9, YVU9, IYU1, ARGB64, AYUV64, r210, I420_10LE, I420_10BE, I422_10LE, I422_10BE, Y444_10LE, Y444_10BE, GBR, GBR_10LE, GBR_10BE, NV12_64Z32 }
0:00:00.509785063 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstutils.c:1545:gst_element_link_pads_full: trying to link element vqueue:src to element vconv:sink
0:00:00.509800362 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad vqueue:src
0:00:00.509808706 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad vconv:sink
0:00:00.509814159 18322 0x7fe0980a8d90 INFO                GST_PADS gstutils.c:1445:prepare_link_maybe_ghosting: vqueue and vconv in same bin, no need for ghost pads
0:00:00.509823935 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link vqueue:src and vconv:sink
0:00:00.509837354 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked vqueue:src and vconv:sink, successful
0:00:00.509845572 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.509851515 18322 0x7fe0980a8d90 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<vqueue:src> Received event on flushing pad. Discarding
0:00:00.509879017 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstutils.c:1545:gst_element_link_pads_full: trying to link element vconv:src to element xvimagesink0:(any)
0:00:00.509888523 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad vconv:src
0:00:00.509896590 18322 0x7fe0980a8d90 INFO                GST_PADS gstutils.c:937:gst_pad_check_link: trying to link vconv:src and xvimagesink0:sink
0:00:00.509911684 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<vqueue:sink> pad has no peer
0:00:00.509933161 18322 0x7fe0980a8d90 INFO                GST_PADS gstutils.c:1445:prepare_link_maybe_ghosting: vconv and xvimagesink0 in same bin, no need for ghost pads
0:00:00.509948093 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link vconv:src and xvimagesink0:sink
0:00:00.509956675 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked vconv:src and xvimagesink0:sink, successful
0:00:00.509970198 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.509975138 18322 0x7fe0980a8d90 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<vconv:src> Received event on flushing pad. Discarding
0:00:00.509985434 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad vqueue:sink
0:00:00.510012882 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link sink:proxypad11 and vqueue:sink
0:00:00.510022498 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked sink:proxypad11 and vqueue:sink, successful
0:00:00.510034172 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.510044393 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<vbin> adding pad 'sink'
0:00:00.510082977 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<streamsynchronizer0> adding pad 'src_0'
0:00:00.510096110 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<streamsynchronizer0> adding pad 'sink_0'
0:00:00.510104923 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link video_sink:proxypad8 and streamsynchronizer0:sink_0
0:00:00.510117659 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked video_sink:proxypad8 and streamsynchronizer0:sink_0, successful
0:00:00.510125690 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.510148581 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "bin" named "vdbin"
0:00:00.510170551 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "videoconvert" named "vdconv"
0:00:00.510191556 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseTransform@0x7fe08c0e8640> adding pad 'sink'
0:00:00.510207023 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseTransform@0x7fe08c0e8640> adding pad 'src'
0:00:00.510224674 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:456:gst_element_factory_make: no such element factory "deinterlace"!
0:00:00.510234167 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "avdeinterlace" named "deinterlace"
0:00:00.510302466 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstFFMpegDeinterlace@0x7fe08c0d63b0> adding pad 'sink'
0:00:00.510323585 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstFFMpegDeinterlace@0x7fe08c0d63b0> adding pad 'src'
0:00:00.510346937 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstutils.c:1545:gst_element_link_pads_full: trying to link element vdconv:src to element deinterlace:sink
0:00:00.510358289 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad vdconv:src
0:00:00.510367090 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad deinterlace:sink
0:00:00.510372571 18322 0x7fe0980a8d90 INFO                GST_PADS gstutils.c:1445:prepare_link_maybe_ghosting: vdconv and deinterlace in same bin, no need for ghost pads
0:00:00.510384482 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link vdconv:src and deinterlace:sink
0:00:00.510400767 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked vdconv:src and deinterlace:sink, successful
0:00:00.510407981 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.510413049 18322 0x7fe0980a8d90 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<vdconv:src> Received event on flushing pad. Discarding
0:00:00.510424100 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad vdconv:sink
0:00:00.510452772 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link sink:proxypad12 and vdconv:sink
0:00:00.510462314 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked sink:proxypad12 and vdconv:sink, successful
0:00:00.510470910 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.510478039 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad deinterlace:src
0:00:00.510526348 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link deinterlace:src and src:proxypad13
0:00:00.510536482 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked deinterlace:src and src:proxypad13, successful
0:00:00.510544305 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.510549088 18322 0x7fe0980a8d90 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<deinterlace:src> Received event on flushing pad. Discarding
0:00:00.510561276 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<vdbin> adding pad 'sink'
0:00:00.510575002 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<vdbin> adding pad 'src'
0:00:00.510604958 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<deinterlace> current NULL pending VOID_PENDING, desired next READY
0:00:00.510620058 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<deinterlace> completed state change to READY
0:00:00.510625707 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<deinterlace> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.510645795 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vdbin> child 'deinterlace' changed state to 2(READY) successfully
0:00:00.510658350 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<vdconv> current NULL pending VOID_PENDING, desired next READY
0:00:00.510669678 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<vdconv> completed state change to READY
0:00:00.510675565 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<vdconv> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.510691305 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vdbin> child 'vdconv' changed state to 2(READY) successfully
0:00:00.510703894 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2303:gst_element_continue_state:<vdbin> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:00.510713631 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<vdbin> notifying about state-changed NULL to READY (PAUSED pending)
0:00:00.510734365 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2310:gst_element_continue_state:<vdbin> continue state change READY to PAUSED, final PAUSED
0:00:00.510750205 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<deinterlace> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.510762637 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<deinterlace> completed state change to PAUSED
0:00:00.510769712 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<deinterlace> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.510785269 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vdbin> child 'deinterlace' changed state to 3(PAUSED) successfully
0:00:00.510797253 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<vdconv> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.510810337 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<vdconv> completed state change to PAUSED
0:00:00.510819371 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<vdconv> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.510834964 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vdbin> child 'vdconv' changed state to 3(PAUSED) successfully
0:00:00.510848079 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<vdbin> completed state change to PAUSED
0:00:00.510854801 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<vdbin> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.510870025 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking streamsynchronizer0:src_0(0x7fe08c0e66d0) and vbin:sink(0x1ccf880)
0:00:00.510892756 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link streamsynchronizer0:src_0 and vdbin:sink
0:00:00.510902111 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked streamsynchronizer0:src_0 and vdbin:sink, successful
0:00:00.510909273 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.510949938 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<xvimagesink0> current READY pending VOID_PENDING, desired next READY
0:00:00.510959898 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<xvimagesink0> skipping transition from READY to  READY
0:00:00.510966271 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vbin> child 'xvimagesink0' changed state to 2(READY) successfully
0:00:00.510975389 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<vconv> current NULL pending VOID_PENDING, desired next READY
0:00:00.510989264 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<scale> current NULL pending VOID_PENDING, desired next READY
0:00:00.510999263 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<scale> completed state change to READY
0:00:00.511004819 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<scale> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.511021922 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vconv> child 'scale' changed state to 2(READY) successfully
0:00:00.511031376 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<conv> current NULL pending VOID_PENDING, desired next READY
0:00:00.511040334 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<conv> completed state change to READY
0:00:00.511046368 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<conv> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.511062386 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vconv> child 'conv' changed state to 2(READY) successfully
0:00:00.511075142 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<identity> current NULL pending VOID_PENDING, desired next READY
0:00:00.511085297 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<identity> completed state change to READY
0:00:00.511091268 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<identity> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.511106648 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vconv> child 'identity' changed state to 2(READY) successfully
0:00:00.511117871 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<vconv> completed state change to READY
0:00:00.511124570 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<vconv> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.511141117 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vbin> child 'vconv' changed state to 2(READY) successfully
0:00:00.511156388 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<vqueue> current NULL pending VOID_PENDING, desired next READY
0:00:00.511166753 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<vqueue> completed state change to READY
0:00:00.511172291 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<vqueue> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.511189271 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vbin> child 'vqueue' changed state to 2(READY) successfully
0:00:00.511198876 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2303:gst_element_continue_state:<vbin> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:00.511206904 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<vbin> notifying about state-changed NULL to READY (PAUSED pending)
0:00:00.511222594 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2310:gst_element_continue_state:<vbin> continue state change READY to PAUSED, final PAUSED
0:00:00.511236740 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<xvimagesink0> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.511257348 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2679:gst_bin_change_state_func:<vbin> child 'xvimagesink0' is changing state asynchronously to PAUSED
0:00:00.511268902 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<vconv> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.511277706 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad identity:sink
0:00:00.511284185 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link sink:proxypad9 and identity:sink
0:00:00.511291762 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked sink:proxypad9 and identity:sink, successful
0:00:00.511298347 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.511305425 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad identity:src
0:00:00.511315201 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link identity:src and src:proxypad10
0:00:00.511322790 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked identity:src and src:proxypad10, successful
0:00:00.511329221 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.511333585 18322 0x7fe0980a8d90 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<identity:src> Received event on flushing pad. Discarding
0:00:00.511349973 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<scale> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.511362763 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<scale> completed state change to PAUSED
0:00:00.511368713 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<scale> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.511385142 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vconv> child 'scale' changed state to 3(PAUSED) successfully
0:00:00.511395682 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<conv> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.511407257 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<conv> completed state change to PAUSED
0:00:00.511413390 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<conv> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.511429173 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vconv> child 'conv' changed state to 3(PAUSED) successfully
0:00:00.511441632 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<identity> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.511454898 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<identity> completed state change to PAUSED
0:00:00.511461121 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<identity> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.511476940 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vconv> child 'identity' changed state to 3(PAUSED) successfully
0:00:00.511490190 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<vconv> completed state change to PAUSED
0:00:00.511496663 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<vconv> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.511513066 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vbin> child 'vconv' changed state to 3(PAUSED) successfully
0:00:00.511525014 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<vqueue> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.511550933 18322 0x7fe0980a8d90 INFO                    task gsttask.c:431:gst_task_set_lock: setting stream lock 0x7fe0980bc580 on task 0x1cdfb90
0:00:00.511560896 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:5515:gst_pad_start_task:<vqueue:src> created task 0x1cdfb90
0:00:00.511613692 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<vqueue> completed state change to PAUSED
0:00:00.511624391 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<vqueue> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.511642496 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vbin> child 'vqueue' changed state to 3(PAUSED) successfully
0:00:00.511655680 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking streamsynchronizer0:src_0(0x7fe08c0e66d0) and vbin:sink(0x1ccf880)
0:00:00.511675235 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking vdbin:src(0x1ccfd40) and vbin:sink(0x1ccf880)
0:00:00.511692724 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link vdbin:src and vbin:sink
0:00:00.511700731 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked vdbin:src and vbin:sink, successful
0:00:00.511706937 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.511747707 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<alsasink0> completed state change to READY
0:00:00.511755970 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "bin" named "abin"
0:00:00.511781541 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "queue" named "aqueue"
0:00:00.511807848 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstQueue@0x7fe08c0507f0> adding pad 'sink'
0:00:00.511828928 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstQueue@0x7fe08c0507f0> adding pad 'src'
0:00:00.511905178 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstPlaySinkConvertBin@0x7fe08c0d65d0> adding pad 'sink'
0:00:00.511934108 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstPlaySinkConvertBin@0x7fe08c0d65d0> adding pad 'src'
0:00:00.511943346 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "identity" named "identity"
0:00:00.511965653 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseTransform@0x1ccb250> adding pad 'sink'
0:00:00.511980335 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseTransform@0x1ccb250> adding pad 'src'
0:00:00.512140965 18322 0x7fe0980a8d90 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/tmp/install/lib/gstreamer-1.0/libgstvolume.so" loaded
0:00:00.512150773 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "volume" named "volume"
0:00:00.512229071 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseTransform@0x7fe08c0f5030> adding pad 'sink'
0:00:00.512245805 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseTransform@0x7fe08c0f5030> adding pad 'src'
0:00:00.512257009 18322 0x7fe0980a8d90 INFO      playsinkconvertbin gstplaysinkconvertbin.c:558:gst_play_sink_convert_bin_cache_converter_caps:<GstPlaySinkAudioConvert@0x7fe08c0d65d0> No conversion elements
0:00:00.512394835 18322 0x7fe0980a8d90 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/tmp/install/lib/gstreamer-1.0/libgstaudioconvert.so" loaded
0:00:00.512404262 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "audioconvert" named "conv"
0:00:00.512484612 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseTransform@0x7fe08c0fa4e0> adding pad 'sink'
0:00:00.512501023 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseTransform@0x7fe08c0fa4e0> adding pad 'src'
0:00:00.512640422 18322 0x7fe0980a8d90 WARN           audioresample gstaudioresample.c:1537:plugin_init: Orc disabled, can't benchmark int vs. float resampler
0:00:00.512650315 18322 0x7fe0980a8d90 WARN         GST_PERFORMANCE gstaudioresample.c:1540:plugin_init: orc disabled, no benchmarking done
0:00:00.512659541 18322 0x7fe0980a8d90 INFO      GST_PLUGIN_LOADING gstplugin.c:833:gst_plugin_load_file: plugin "/tmp/install/lib/gstreamer-1.0/libgstaudioresample.so" loaded
0:00:00.512666270 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "audioresample" named "resample"
0:00:00.512741412 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseTransform@0x7fe08c0fc0d0> adding pad 'sink'
0:00:00.512757758 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseTransform@0x7fe08c0fc0d0> adding pad 'src'
0:00:00.512774434 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstutils.c:1545:gst_element_link_pads_full: trying to link element conv:src to element resample:sink
0:00:00.512784625 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad conv:src
0:00:00.512790265 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad resample:sink
0:00:00.512795210 18322 0x7fe0980a8d90 INFO                GST_PADS gstutils.c:1445:prepare_link_maybe_ghosting: conv and resample in same bin, no need for ghost pads
0:00:00.512805503 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link conv:src and resample:sink
0:00:00.512816477 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked conv:src and resample:sink, successful
0:00:00.512823365 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.512828238 18322 0x7fe0980a8d90 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<conv:src> Received event on flushing pad. Discarding
0:00:00.512837851 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad conv:sink
0:00:00.512848704 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<resample:src> pad has no peer
0:00:00.512913761 18322 0x7fe0980a8d90 INFO               structure gststructure.c:2919:gst_structure_get_valist: Expected field 'channel-mask' in structure: audio/x-raw, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], layout=(string)interleaved;
0:00:00.512970311 18322 0x7fe0980a8d90 INFO      playsinkconvertbin gstplaysinkconvertbin.c:571:gst_play_sink_convert_bin_cache_converter_caps:<aconv> Converter caps: audio/x-raw, format=(string){ F32LE, F64LE, S32LE, S24LE, S16LE, S8 }, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], layout=(string)interleaved; audio/x-raw, rate=(int)[ 1, 2147483647 ], layout=(string)interleaved, format=(string){ S8, U8, S16LE, S16BE, U16LE, U16BE, S24_32LE, S24_32BE, U24_32LE, U24_32BE, S32LE, S32BE, U32LE, U32BE, S24LE, S24BE, U24LE, U24BE, S20LE, S20BE, U20LE, U20BE, S18LE, S18BE, U18LE, U18BE, F32LE, F32BE, F64LE, F64BE }, channels=(int)[ 1, 2147483647 ]
0:00:00.513003609 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<conv> completed state change to NULL
0:00:00.513013897 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking conv:src(0x7fe08c0f6340) and resample:sink(0x7fe08c0f6570)
0:00:00.513027126 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked conv:src and resample:sink
0:00:00.513038150 18322 0x7fe0980a8d90 INFO           GST_PARENTAGE gstbin.c:1554:gst_bin_remove_func:<aconv> removed child "conv"
0:00:00.513047246 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2873:gst_element_dispose:<conv> dispose
0:00:00.513052483 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<conv> removing pad 'sink'
0:00:00.513060966 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<conv> removing pad 'src'
0:00:00.513068777 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2917:gst_element_dispose:<conv> parent class dispose
0:00:00.513073602 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2948:gst_element_finalize:<conv> finalize
0:00:00.513078595 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2953:gst_element_finalize:<conv> finalize parent
0:00:00.513084232 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<resample> completed state change to NULL
0:00:00.513093010 18322 0x7fe0980a8d90 INFO           GST_PARENTAGE gstbin.c:1554:gst_bin_remove_func:<aconv> removed child "resample"
0:00:00.513100737 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2873:gst_element_dispose:<resample> dispose
0:00:00.513104834 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<resample> removing pad 'sink'
0:00:00.513112122 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:763:gst_element_remove_pad:<resample> removing pad 'src'
0:00:00.513119579 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2917:gst_element_dispose:<resample> parent class dispose
0:00:00.513124275 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2948:gst_element_finalize:<resample> finalize
0:00:00.513127851 18322 0x7fe0980a8d90 INFO         GST_REFCOUNTING gstelement.c:2953:gst_element_finalize:<resample> finalize parent
0:00:00.513136592 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "audioconvert" named "conv"
0:00:00.513155359 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseTransform@0x7fe08c0fa4e0> adding pad 'sink'
0:00:00.513169527 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseTransform@0x7fe08c0fa4e0> adding pad 'src'
0:00:00.513183439 18322 0x7fe0980a8d90 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:362:gst_element_factory_create: creating element "audioresample" named "resample"
0:00:00.513202170 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseTransform@0x7fe08c0fc200> adding pad 'sink'
0:00:00.513217288 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<GstBaseTransform@0x7fe08c0fc200> adding pad 'src'
0:00:00.513231067 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstutils.c:1545:gst_element_link_pads_full: trying to link element conv:src to element resample:sink
0:00:00.513240602 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad conv:src
0:00:00.513246348 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad resample:sink
0:00:00.513251347 18322 0x7fe0980a8d90 INFO                GST_PADS gstutils.c:1445:prepare_link_maybe_ghosting: conv and resample in same bin, no need for ghost pads
0:00:00.513261024 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link conv:src and resample:sink
0:00:00.513271663 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked conv:src and resample:sink, successful
0:00:00.513278753 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.513283325 18322 0x7fe0980a8d90 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<conv:src> Received event on flushing pad. Discarding
0:00:00.513297562 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstutils.c:1545:gst_element_link_pads_full: trying to link element resample:src to element volume:sink
0:00:00.513305825 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad resample:src
0:00:00.513310980 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad volume:sink
0:00:00.513316130 18322 0x7fe0980a8d90 INFO                GST_PADS gstutils.c:1445:prepare_link_maybe_ghosting: resample and volume in same bin, no need for ghost pads
0:00:00.513324715 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link resample:src and volume:sink
0:00:00.513334653 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked resample:src and volume:sink, successful
0:00:00.513341572 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.513346026 18322 0x7fe0980a8d90 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<resample:src> Received event on flushing pad. Discarding
0:00:00.513354828 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad conv:sink
0:00:00.513366763 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<volume:src> pad has no peer
0:00:00.513438401 18322 0x7fe0980a8d90 INFO               structure gststructure.c:2919:gst_structure_get_valist: Expected field 'channel-mask' in structure: audio/x-raw, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], layout=(string)interleaved;
0:00:00.513492509 18322 0x7fe0980a8d90 INFO      playsinkconvertbin gstplaysinkconvertbin.c:571:gst_play_sink_convert_bin_cache_converter_caps:<aconv> Converter caps: audio/x-raw, format=(string){ F32LE, F64LE, S8, S16LE, S24LE, S32LE }, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], layout=(string)interleaved; audio/x-raw, rate=(int)[ 1, 2147483647 ], layout=(string)interleaved, format=(string){ S8, U8, S16LE, S16BE, U16LE, U16BE, S24_32LE, S24_32BE, U24_32LE, U24_32BE, S32LE, S32BE, U32LE, U32BE, S24LE, S24BE, U24LE, U24BE, S20LE, S20BE, U20LE, U20BE, S18LE, S18BE, U18LE, U18BE, F32LE, F32BE, F64LE, F64BE }, channels=(int)[ 1, 2147483647 ]
0:00:00.513527137 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstutils.c:1545:gst_element_link_pads_full: trying to link element aqueue:src to element aconv:sink
0:00:00.513537250 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad aqueue:src
0:00:00.513543227 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad aconv:sink
0:00:00.513548449 18322 0x7fe0980a8d90 INFO                GST_PADS gstutils.c:1445:prepare_link_maybe_ghosting: aqueue and aconv in same bin, no need for ghost pads
0:00:00.513557752 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link aqueue:src and aconv:sink
0:00:00.513565913 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked aqueue:src and aconv:sink, successful
0:00:00.513572684 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.513577166 18322 0x7fe0980a8d90 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<aqueue:src> Received event on flushing pad. Discarding
0:00:00.513605817 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstutils.c:1545:gst_element_link_pads_full: trying to link element aconv:src to element alsasink0:(any)
0:00:00.513616098 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad aconv:src
0:00:00.513623654 18322 0x7fe0980a8d90 INFO                GST_PADS gstutils.c:937:gst_pad_check_link: trying to link aconv:src and alsasink0:sink
0:00:00.513636008 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:3745:gst_pad_peer_query:<aqueue:sink> pad has no peer
0:00:00.513648371 18322 0x7fe0980a8d90 INFO                GST_PADS gstutils.c:1445:prepare_link_maybe_ghosting: aconv and alsasink0 in same bin, no need for ghost pads
0:00:00.513658682 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link aconv:src and alsasink0:sink
0:00:00.513666996 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked aconv:src and alsasink0:sink, successful
0:00:00.513673385 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.513677891 18322 0x7fe0980a8d90 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<aconv:src> Received event on flushing pad. Discarding
0:00:00.513686939 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad aqueue:sink
0:00:00.513706966 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link sink:proxypad16 and aqueue:sink
0:00:00.513716366 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked sink:proxypad16 and aqueue:sink, successful
0:00:00.513728994 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.513755181 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<abin> adding pad 'sink'
0:00:00.513790480 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<streamsynchronizer0> adding pad 'src_1'
0:00:00.513799832 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<streamsynchronizer0> adding pad 'sink_1'
0:00:00.513823411 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:643:gst_element_add_pad:<audiotee> adding pad 'src_0'
0:00:00.513855564 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<alsasink0> current READY pending VOID_PENDING, desired next READY
0:00:00.513865451 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2347:gst_bin_element_set_state:<alsasink0> skipping transition from READY to  READY
0:00:00.513871581 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<abin> child 'alsasink0' changed state to 2(READY) successfully
0:00:00.513880819 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<aconv> current NULL pending VOID_PENDING, desired next READY
0:00:00.513895029 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<volume> current NULL pending VOID_PENDING, desired next READY
0:00:00.513903644 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<volume> completed state change to READY
0:00:00.513909365 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<volume> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.513927184 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<aconv> child 'volume' changed state to 2(READY) successfully
0:00:00.513937191 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<resample> current NULL pending VOID_PENDING, desired next READY
0:00:00.513945614 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<resample> completed state change to READY
0:00:00.513951741 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<resample> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.513968129 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<aconv> child 'resample' changed state to 2(READY) successfully
0:00:00.513977850 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<conv> current NULL pending VOID_PENDING, desired next READY
0:00:00.513987130 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<conv> completed state change to READY
0:00:00.513993098 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<conv> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.514008120 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<aconv> child 'conv' changed state to 2(READY) successfully
0:00:00.514018001 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<identity> current NULL pending VOID_PENDING, desired next READY
0:00:00.514027067 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<identity> completed state change to READY
0:00:00.514033002 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<identity> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.514050526 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<aconv> child 'identity' changed state to 2(READY) successfully
0:00:00.514061572 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<aconv> completed state change to READY
0:00:00.514068505 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<aconv> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.514085046 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<abin> child 'aconv' changed state to 2(READY) successfully
0:00:00.514097460 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<aqueue> current NULL pending VOID_PENDING, desired next READY
0:00:00.514107985 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<aqueue> completed state change to READY
0:00:00.514113483 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<aqueue> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.514129080 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<abin> child 'aqueue' changed state to 2(READY) successfully
0:00:00.514139364 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2303:gst_element_continue_state:<abin> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:00.514147275 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<abin> notifying about state-changed NULL to READY (PAUSED pending)
0:00:00.514162616 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2310:gst_element_continue_state:<abin> continue state change READY to PAUSED, final PAUSED
0:00:00.514177006 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<alsasink0> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.514208713 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2679:gst_bin_change_state_func:<abin> child 'alsasink0' is changing state asynchronously to PAUSED
0:00:00.514220898 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<aconv> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.514229880 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad identity:sink
0:00:00.514236525 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link sink:proxypad14 and identity:sink
0:00:00.514244171 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked sink:proxypad14 and identity:sink, successful
0:00:00.514250915 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.514258080 18322 0x7fe0980a8d90 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad identity:src
0:00:00.514268109 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link identity:src and src:proxypad15
0:00:00.514276703 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked identity:src and src:proxypad15, successful
0:00:00.514283167 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.514287877 18322 0x7fe0980a8d90 INFO               GST_EVENT gstpad.c:5182:gst_pad_send_event_unchecked:<identity:src> Received event on flushing pad. Discarding
0:00:00.514304869 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<volume> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.514317575 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<volume> completed state change to PAUSED
0:00:00.514323898 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<volume> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.514340279 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<aconv> child 'volume' changed state to 3(PAUSED) successfully
0:00:00.514352329 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<resample> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.514364915 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<resample> completed state change to PAUSED
0:00:00.514371165 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<resample> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.514387469 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<aconv> child 'resample' changed state to 3(PAUSED) successfully
0:00:00.514399552 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<conv> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.514412107 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<conv> completed state change to PAUSED
0:00:00.514418210 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<conv> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.514434011 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<aconv> child 'conv' changed state to 3(PAUSED) successfully
0:00:00.514445721 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<identity> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.514458009 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<identity> completed state change to PAUSED
0:00:00.514464193 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<identity> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.514480256 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<aconv> child 'identity' changed state to 3(PAUSED) successfully
0:00:00.514493515 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<aconv> completed state change to PAUSED
0:00:00.514502921 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<aconv> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.514519372 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<abin> child 'aconv' changed state to 3(PAUSED) successfully
0:00:00.514531506 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<aqueue> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.514547644 18322 0x7fe0980a8d90 INFO                    task gsttask.c:431:gst_task_set_lock: setting stream lock 0x7fe08c0e7460 on task 0x1cdfcb0
0:00:00.514557023 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:5515:gst_pad_start_task:<aqueue:src> created task 0x1cdfcb0
0:00:00.514600774 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<aqueue> completed state change to PAUSED
0:00:00.514609344 18322 0x7fe0980a8d90 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<aqueue> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.514626236 18322 0x7fe0980a8d90 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<abin> child 'aqueue' changed state to 3(PAUSED) successfully
0:00:00.514644531 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link audiotee:src_0 and streamsynchronizer0:sink_1
0:00:00.514655282 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked audiotee:src_0 and streamsynchronizer0:sink_1, successful
0:00:00.514662585 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.514688138 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link streamsynchronizer0:src_1 and abin:sink
0:00:00.514698263 18322 0x7fe0980a8d90 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked streamsynchronizer0:src_1 and abin:sink, successful
0:00:00.514705440 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.514743289 18322 0x7fe0980a8d90 INFO                playsink gstplaysink.c:1412:do_async_done:<playsink> Sending async_done message
0:00:00.515232466 18322 0x7fe0980a8a80 INFO               GST_EVENT gstevent.c:759:gst_event_new_segment: creating segment event 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.066633333, duration 99:99:99.999999999
0:00:00.516209703 18322 0x7fe0980a8d90 INFO           basetransform gstbasetransform.c:1359:gst_base_transform_setcaps:<vdconv> reuse caps
0:00:00.516239741 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:678:gst_event_new_caps: creating caps event video/x-raw, format=(string)I420, width=(int)720, height=(int)576, pixel-aspect-ratio=(fraction)16/15, interlace-mode=(string)progressive, chroma-site=(string)mpeg2, colorimetry=(string)bt601, framerate=(fraction)25/1
0:00:00.516641812 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:678:gst_event_new_caps: creating caps event video/x-raw, format=(string)I420, width=(int)720, height=(int)576, pixel-aspect-ratio=(fraction)16/15, interlace-mode=(string)progressive, chroma-site=(string)mpeg2, colorimetry=(string)bt601, framerate=(fraction)25/1
0:00:00.517366550 18322 0x7fe08c0e52d0 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking sink:proxypad9(0x1cd9b70) and identity:sink(0x7fe0980bd690)
0:00:00.517404216 18322 0x7fe08c0e52d0 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked sink:proxypad9 and identity:sink
0:00:00.517415977 18322 0x7fe08c0e52d0 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking identity:src(0x7fe0980bd8c0) and src:proxypad10(0x1cd9db0)
0:00:00.517435745 18322 0x7fe08c0e52d0 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked identity:src and src:proxypad10
0:00:00.517471907 18322 0x7fe08c0e52d0 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<conv> completed state change to PAUSED
0:00:00.517486126 18322 0x7fe08c0e52d0 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<scale> completed state change to PAUSED
0:00:00.517495544 18322 0x7fe08c0e52d0 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad conv:sink
0:00:00.517504767 18322 0x7fe08c0e52d0 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link sink:proxypad9 and conv:sink
0:00:00.517516206 18322 0x7fe08c0e52d0 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked sink:proxypad9 and conv:sink, successful
0:00:00.517525091 18322 0x7fe08c0e52d0 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.517591138 18322 0x7fe08c0e52d0 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad scale:src
0:00:00.517607203 18322 0x7fe08c0e52d0 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link scale:src and src:proxypad10
0:00:00.517619392 18322 0x7fe08c0e52d0 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked scale:src and src:proxypad10, successful
0:00:00.517628882 18322 0x7fe08c0e52d0 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.518672887 18322 0x7fe08c0a3680 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking sink:proxypad14(0x7fe08c0d8710) and identity:sink(0x7fe08c0e7620)
0:00:00.518694130 18322 0x7fe08c0a3680 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked sink:proxypad14 and identity:sink
0:00:00.518702534 18322 0x7fe08c0a3680 INFO        GST_ELEMENT_PADS gstpad.c:1943:gst_pad_unlink: unlinking identity:src(0x7fe08c0e7850) and src:proxypad15(0x7fe08c0d8950)
0:00:00.518715619 18322 0x7fe08c0a3680 INFO        GST_ELEMENT_PADS gstpad.c:1997:gst_pad_unlink: unlinked identity:src and src:proxypad15
0:00:00.518760272 18322 0x7fe08c0a3680 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<conv> completed state change to PAUSED
0:00:00.518769055 18322 0x7fe08c0a3680 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<resample> completed state change to PAUSED
0:00:00.518776109 18322 0x7fe08c0a3680 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<volume> completed state change to PAUSED
0:00:00.518782408 18322 0x7fe08c0a3680 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad conv:sink
0:00:00.518788728 18322 0x7fe08c0a3680 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link sink:proxypad14 and conv:sink
0:00:00.518796491 18322 0x7fe08c0a3680 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked sink:proxypad14 and conv:sink, successful
0:00:00.518803380 18322 0x7fe08c0a3680 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.518836964 18322 0x7fe08c0a3680 INFO        GST_ELEMENT_PADS gstelement.c:895:gst_element_get_static_pad: found pad volume:src
0:00:00.518848857 18322 0x7fe08c0a3680 INFO                GST_PADS gstpad.c:2186:gst_pad_link_prepare: trying to link volume:src and src:proxypad15
0:00:00.518857243 18322 0x7fe08c0a3680 INFO                GST_PADS gstpad.c:2388:gst_pad_link_full: linked volume:src and src:proxypad15, successful
0:00:00.518864282 18322 0x7fe08c0a3680 INFO               GST_EVENT gstevent.c:1373:gst_event_new_reconfigure: creating reconfigure event
0:00:00.520454080 18322 0x7fe08c0e52d0 INFO           basetransform gstbasetransform.c:1359:gst_base_transform_setcaps:<conv> reuse caps
0:00:00.520493325 18322 0x7fe08c0e52d0 INFO               GST_EVENT gstevent.c:678:gst_event_new_caps: creating caps event video/x-raw, format=(string)I420, width=(int)720, height=(int)576, pixel-aspect-ratio=(fraction)16/15, interlace-mode=(string)progressive, chroma-site=(string)mpeg2, colorimetry=(string)bt601, framerate=(fraction)25/1
0:00:00.520759460 18322 0x7fe08c0e52d0 INFO           basetransform gstbasetransform.c:1359:gst_base_transform_setcaps:<scale> reuse caps
0:00:00.520782742 18322 0x7fe08c0e52d0 INFO               GST_EVENT gstevent.c:678:gst_event_new_caps: creating caps event video/x-raw, format=(string)I420, width=(int)720, height=(int)576, pixel-aspect-ratio=(fraction)16/15, interlace-mode=(string)progressive, chroma-site=(string)mpeg2, colorimetry=(string)bt601, framerate=(fraction)25/1
0:00:00.521776895 18322 0x7fe0980a8d90 INFO               GST_EVENT gstevent.c:759:gst_event_new_segment: creating segment event 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.522017715 18322 0x7fe0980a8d90 INFO           basetransform gstbasetransform.c:1359:gst_base_transform_setcaps:<vdconv> reuse caps
0:00:00.523755695 18322 0x7fe08c0a3680 INFO            audioconvert audioconvert.c:620:audio_convert_prepare_context: depth in 32, out 32
0:00:00.523768837 18322 0x7fe08c0a3680 INFO            audioconvert audioconvert.c:634:audio_convert_prepare_context: using no dither and noise shaping
0:00:00.523786864 18322 0x7fe08c0a3680 INFO            audioconvert audioconvert.c:650:audio_convert_prepare_context: func index in 16, out 13
0:00:00.523792838 18322 0x7fe08c0a3680 INFO            audioconvert audioconvert.c:655:audio_convert_prepare_context: use int mixing
0:00:00.523796306 18322 0x7fe08c0a3680 INFO            audioconvert audioconvert.c:661:audio_convert_prepare_context: unitsizes: 8 -> 8
0:00:00.523801287 18322 0x7fe08c0a3680 INFO            audioconvert audioconvert.c:671:audio_convert_prepare_context: in default 0, mix passthrough 1, out default 1
0:00:00.523806753 18322 0x7fe08c0a3680 INFO            audioconvert audioconvert.c:678:audio_convert_prepare_context: scale in 0, out 0
0:00:00.523812787 18322 0x7fe08c0a3680 INFO               GST_EVENT gstevent.c:678:gst_event_new_caps: creating caps event audio/x-raw, layout=(string)interleaved, rate=(int)48000, format=(string)S32LE, channels=(int)2, channel-mask=(bitmask)0x0000000000000003
0:00:00.524491569 18322 0x7fe08c0a3680 INFO           basetransform gstbasetransform.c:1359:gst_base_transform_setcaps:<resample> reuse caps
0:00:00.524506320 18322 0x7fe08c0a3680 INFO               GST_EVENT gstevent.c:678:gst_event_new_caps: creating caps event audio/x-raw, layout=(string)interleaved, rate=(int)48000, format=(string)S32LE, channels=(int)2, channel-mask=(bitmask)0x0000000000000003
0:00:00.524607352 18322 0x7fe08c0a3680 INFO           basetransform gstbasetransform.c:1359:gst_base_transform_setcaps:<volume> reuse caps
0:00:00.524622446 18322 0x7fe08c0a3680 INFO               GST_EVENT gstevent.c:678:gst_event_new_caps: creating caps event audio/x-raw, layout=(string)interleaved, rate=(int)48000, format=(string)S32LE, channels=(int)2, channel-mask=(bitmask)0x0000000000000003
0:00:00.526537873 18322 0x7fe08c0e52d0 INFO              GST_STATES gstbin.c:3129:bin_handle_async_done:<vbin> committing state from READY to PAUSED, old pending PAUSED
0:00:00.526569376 18322 0x7fe08c0e52d0 INFO              GST_STATES gstbin.c:3149:bin_handle_async_done:<vbin> completed state change, pending VOID
0:00:00.526575837 18322 0x7fe08c0e52d0 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<vbin> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.531718011 18322      0x1cc94f0 INFO               GST_EVENT gstevent.c:678:gst_event_new_caps: creating caps event video/mpeg, mpegversion=(int)2, systemstream=(boolean)true
0:00:00.533677568 18322 0x7fe08c0a3680 INFO              ringbuffer gstaudioringbuffer.c:573:gst_audio_ring_buffer_acquire:<audiosinkringbuffer0> Allocating an array for 20 timestamps
0:00:00.536915838 18322 0x7fe08c0a3680 INFO            audioconvert gstaudioconvert.c:255:gst_audio_convert_get_unit_size:<conv> unit_size = 8
0:00:00.536936064 18322 0x7fe08c0a3680 INFO            audioconvert gstaudioconvert.c:255:gst_audio_convert_get_unit_size:<conv> unit_size = 8
0:00:00.537103016 18322 0x7fe08c0a3680 INFO              GST_STATES gstbin.c:3129:bin_handle_async_done:<abin> committing state from READY to PAUSED, old pending PAUSED
0:00:00.537115204 18322 0x7fe08c0a3680 INFO              GST_STATES gstbin.c:3149:bin_handle_async_done:<abin> completed state change, pending VOID
0:00:00.537120742 18322 0x7fe08c0a3680 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<abin> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.537144646 18322 0x7fe08c0a3680 INFO              GST_STATES gstbin.c:3129:bin_handle_async_done:<playsink> committing state from READY to PAUSED, old pending PAUSED
0:00:00.537152870 18322 0x7fe08c0a3680 INFO              GST_STATES gstbin.c:3149:bin_handle_async_done:<playsink> completed state change, pending VOID
0:00:00.537157508 18322 0x7fe08c0a3680 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<playsink> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.537176672 18322 0x7fe08c0a3680 INFO              GST_STATES gstbin.c:3129:bin_handle_async_done:<playbin0> committing state from READY to PAUSED, old pending PAUSED
0:00:00.537183909 18322 0x7fe08c0a3680 INFO              GST_STATES gstbin.c:3149:bin_handle_async_done:<playbin0> completed state change, pending VOID
0:00:00.537187748 18322 0x7fe08c0a3680 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<playbin0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
Pipeline is PREROLLED ...
Setting pipeline to PLAYING ...
0:00:00.537586092 18322      0x1a60c00 INFO               GST_EVENT gstevent.c:1244:gst_event_new_latency: creating latency event 0:00:00.000000000
0:00:00.537692445 18322      0x1a60c00 INFO                     bin gstbin.c:2502:gst_bin_do_latency_func:<playbin0> configured latency of 0:00:00.000000000
0:00:00.537713835 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<playsink> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.537740910 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<abin> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.537754807 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<alsasink0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.537770271 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<alsasink0> completed state change to PLAYING
0:00:00.537779602 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<alsasink0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.537797238 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<abin> child 'alsasink0' changed state to 4(PLAYING) successfully
0:00:00.537808764 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<aconv> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.537823214 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<volume> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.537831763 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<volume> completed state change to PLAYING
0:00:00.537837033 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<volume> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.537848909 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<aconv> child 'volume' changed state to 4(PLAYING) successfully
0:00:00.537859172 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<resample> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.537867507 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<resample> completed state change to PLAYING
0:00:00.537872861 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<resample> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.537884024 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<aconv> child 'resample' changed state to 4(PLAYING) successfully
0:00:00.537893370 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<conv> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.537901545 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<conv> completed state change to PLAYING
0:00:00.537906713 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<conv> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.537917268 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<aconv> child 'conv' changed state to 4(PLAYING) successfully
0:00:00.537926785 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<identity> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.537935226 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<identity> completed state change to PLAYING
0:00:00.537940583 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<identity> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.537953641 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<aconv> child 'identity' changed state to 4(PLAYING) successfully
0:00:00.537963429 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<aconv> completed state change to PLAYING
0:00:00.537968864 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<aconv> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.537980751 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<abin> child 'aconv' changed state to 4(PLAYING) successfully
0:00:00.537990296 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<aqueue> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.537998592 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<aqueue> completed state change to PLAYING
0:00:00.538003757 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<aqueue> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538014050 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<abin> child 'aqueue' changed state to 4(PLAYING) successfully
0:00:00.538023609 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<abin> completed state change to PLAYING
0:00:00.538029544 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<abin> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538041615 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playsink> child 'abin' changed state to 4(PLAYING) successfully
0:00:00.538051310 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<vbin> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.538064626 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<xvimagesink0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.538077732 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<xvimagesink0> completed state change to PLAYING
0:00:00.538083525 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<xvimagesink0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538095052 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vbin> child 'xvimagesink0' changed state to 4(PLAYING) successfully
0:00:00.538105053 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<vconv> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.538119044 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<scale> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.538129199 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<scale> completed state change to PLAYING
0:00:00.538134643 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<scale> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538145992 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vconv> child 'scale' changed state to 4(PLAYING) successfully
0:00:00.538155416 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<conv> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.538163186 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<conv> completed state change to PLAYING
0:00:00.538169421 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<conv> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538180870 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vconv> child 'conv' changed state to 4(PLAYING) successfully
0:00:00.538189858 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<identity> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.538197687 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<identity> completed state change to PLAYING
0:00:00.538202936 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<identity> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538213849 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vconv> child 'identity' changed state to 4(PLAYING) successfully
0:00:00.538222988 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<vconv> completed state change to PLAYING
0:00:00.538228348 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<vconv> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538239318 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vbin> child 'vconv' changed state to 4(PLAYING) successfully
0:00:00.538248513 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<vqueue> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.538256806 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<vqueue> completed state change to PLAYING
0:00:00.538261977 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<vqueue> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538272487 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vbin> child 'vqueue' changed state to 4(PLAYING) successfully
0:00:00.538281273 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<vbin> completed state change to PLAYING
0:00:00.538286721 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<vbin> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538297814 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playsink> child 'vbin' changed state to 4(PLAYING) successfully
0:00:00.538307464 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<vdbin> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.538320182 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<deinterlace> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.538328514 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<deinterlace> completed state change to PLAYING
0:00:00.538333811 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<deinterlace> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538344718 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vdbin> child 'deinterlace' changed state to 4(PLAYING) successfully
0:00:00.538354563 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<vdconv> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.538363295 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<vdconv> completed state change to PLAYING
0:00:00.538369365 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<vdconv> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538379914 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<vdbin> child 'vdconv' changed state to 4(PLAYING) successfully
0:00:00.538388947 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<vdbin> completed state change to PLAYING
0:00:00.538394196 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<vdbin> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538404911 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playsink> child 'vdbin' changed state to 4(PLAYING) successfully
0:00:00.538415501 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<streamsynchronizer0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.538424793 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<streamsynchronizer0> completed state change to PLAYING
0:00:00.538430460 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<streamsynchronizer0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538441659 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playsink> child 'streamsynchronizer0' changed state to 4(PLAYING) successfully
0:00:00.538451402 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<audiotee> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.538459472 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<audiotee> completed state change to PLAYING
0:00:00.538464844 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<audiotee> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538479358 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playsink> child 'audiotee' changed state to 4(PLAYING) successfully
0:00:00.538489384 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<playsink> completed state change to PLAYING
0:00:00.538495081 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<playsink> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538505720 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playbin0> child 'playsink' changed state to 4(PLAYING) successfully
0:00:00.538515586 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<inputselector1> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.538523997 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<inputselector1> completed state change to PLAYING
0:00:00.538529297 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<inputselector1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538539310 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playbin0> child 'inputselector1' changed state to 4(PLAYING) successfully
0:00:00.538549270 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<inputselector0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.538557373 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<inputselector0> completed state change to PLAYING
0:00:00.538563603 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<inputselector0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538573664 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playbin0> child 'inputselector0' changed state to 4(PLAYING) successfully
0:00:00.538582279 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<uridecodebin0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.538595196 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<decodebin0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.538612798 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<avdec_mpeg2video0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.538621663 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<avdec_mpeg2video0> completed state change to PLAYING
0:00:00.538627044 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<avdec_mpeg2video0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538639332 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<decodebin0> child 'avdec_mpeg2video0' changed state to 4(PLAYING) successfully
0:00:00.538649267 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<mpegvparse0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.538658411 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<mpegvparse0> completed state change to PLAYING
0:00:00.538663811 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<mpegvparse0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538674667 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<decodebin0> child 'mpegvparse0' changed state to 4(PLAYING) successfully
0:00:00.538684954 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<a52dec0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.538693457 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<a52dec0> completed state change to PLAYING
0:00:00.538698637 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<a52dec0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538709246 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<decodebin0> child 'a52dec0' changed state to 4(PLAYING) successfully
0:00:00.538741723 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<multiqueue0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.538751951 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<multiqueue0> completed state change to PLAYING
0:00:00.538757413 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<multiqueue0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538768576 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<decodebin0> child 'multiqueue0' changed state to 4(PLAYING) successfully
0:00:00.538778661 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<mpegpsdemux0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.538787610 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<mpegpsdemux0> completed state change to PLAYING
0:00:00.538794029 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<mpegpsdemux0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538805258 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<decodebin0> child 'mpegpsdemux0' changed state to 4(PLAYING) successfully
0:00:00.538814772 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<typefind> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.538823234 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<typefind> completed state change to PLAYING
0:00:00.538828579 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<typefind> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538839221 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<decodebin0> child 'typefind' changed state to 4(PLAYING) successfully
0:00:00.538848651 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<decodebin0> completed state change to PLAYING
0:00:00.538854156 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<decodebin0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538865424 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<uridecodebin0> child 'decodebin0' changed state to 4(PLAYING) successfully
0:00:00.538874361 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2230:gst_bin_element_set_state:<source> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.538883881 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<source> completed state change to PLAYING
0:00:00.538889289 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<source> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538900211 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<uridecodebin0> child 'source' changed state to 4(PLAYING) successfully
0:00:00.538910114 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<uridecodebin0> completed state change to PLAYING
0:00:00.538915549 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<uridecodebin0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.538931518 18322      0x1a60c00 INFO              GST_STATES gstbin.c:2673:gst_bin_change_state_func:<playbin0> child 'uridecodebin0' changed state to 4(PLAYING) successfully
0:00:00.538941249 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2328:gst_element_continue_state:<playbin0> completed state change to PLAYING
0:00:00.538946693 18322      0x1a60c00 INFO              GST_STATES gstelement.c:2233:_priv_gst_element_state_changed:<playbin0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
New clock: GstAudioSinkClock
0:00:01.111050037 18322 0x7fe0980a8d90 WARN            videodecoder gstvideodecoder.c:2503:gst_video_decoder_prepare_finish_frame:<avdec_mpeg2video0> decreasing timestamp (0:00:00.760000000 < 0:00:00.800000000)
