--------- beginning of /dev/log/system

--------- beginning of /dev/log/main

W/GStreamer(25504): 0:00:02.887634277 0x4e52d820 gstamc.c:3506:gst_amc_codec_info_to_caps Unknown color format 0x7f000789 for codec video/mp4v-es

W/GStreamer(25504): 0:00:02.891143798 0x4e52d820 gstamc.c:3506:gst_amc_codec_info_to_caps Unknown color format 0x7f000789 for codec video/asf

W/GStreamer(25504): 0:00:02.891204833 0x4e52d820 gstamc.c:3823:gst_amc_codec_info_to_caps Unsupported mimetype 'video/asf'

E/GStreamer(25504): 0:00:02.891937256 0x4e52d820 gstamc.c:3240:register_codecs Type 'GstAmcVideoDec-OmxBrcmVideoVc1HwDecoder' already exists for codec 'OMX.brcm.video.vc1.hw.decoder'

E/GStreamer(25504): 0:00:02.892028809 0x4e52d820 gstamc.c:3240:register_codecs Type 'GstAmcVideoDec-OmxBrcmVideoVc1HwDecoder' already exists for codec 'OMX.brcm.video.vc1.hw.decoder'

E/GStreamer(25504): 0:00:02.892089844 0x4e52d820 gstamc.c:3240:register_codecs Type 'GstAmcVideoDec-OmxBrcmVideoVc1HwDecoder' already exists for codec 'OMX.brcm.video.vc1.hw.decoder'

E/GStreamer(25504): 0:00:02.892181397 0x4e52d820 gstamc.c:3240:register_codecs Type 'GstAmcVideoDec-OmxBrcmVideoVc1HwDecoder' already exists for codec 'OMX.brcm.video.vc1.hw.decoder'

W/GStreamer(25504): 0:00:02.892333985 0x4e52d820 gstamc.c:3506:gst_amc_codec_info_to_caps Unknown color format 0x7f000789 for codec video/mp4v-es

W/GStreamer(25504): 0:00:02.893005371 0x4e52d820 gstamc.c:3506:gst_amc_codec_info_to_caps Unknown color format 0x7f000789 for codec video/3gpp

W/GStreamer(25504): 0:00:02.893646240 0x4e52d820 gstamc.c:3506:gst_amc_codec_info_to_caps Unknown color format 0x7f000789 for codec video/avc

W/GStreamer(25504): 0:00:02.894439697 0x4e52d820 gstamc.c:3506:gst_amc_codec_info_to_caps Unknown color format 0x7f000789 for codec video/avc

W/GStreamer(25504): 0:00:02.895233154 0x4e52d820 gstamc.c:3823:gst_amc_codec_info_to_caps Unsupported mimetype 'video/x-ms-wmv7'

W/GStreamer(25504): 0:00:02.895751953 0x4e52d820 gstamc.c:3823:gst_amc_codec_info_to_caps Unsupported mimetype 'video/x-ms-wmv8'

W/GStreamer(25504): 0:00:02.896240234 0x4e52d820 gstamc.c:3823:gst_amc_codec_info_to_caps Unsupported mimetype 'video/sorenson'

W/GStreamer(25504): 0:00:02.896667480 0x4e52d820 gstamc.c:3823:gst_amc_codec_info_to_caps Unsupported mimetype 'video/mp43'

E/GStreamer(25504): 0:00:02.899780273 0x4e52d820 gstamc.c:3240:register_codecs Type 'GstAmcAudioDec-OmxSecMp3Dec' already exists for codec 'OMX.SEC.mp3.dec'

E/GStreamer(25504): 0:00:02.899871826 0x4e52d820 gstamc.c:3240:register_codecs Type 'GstAmcAudioDec-OmxSecMp3Dec' already exists for codec 'OMX.SEC.mp3.dec'

W/GStreamer(25504): 0:00:02.902770997 0x4e52d820 gstamc.c:3491:gst_amc_codec_info_to_caps Unsupported mimetype 'audio/x-ms-wma'

W/GStreamer(25504): 0:00:02.908050537 0x4e52d820 gstamc.c:3506:gst_amc_codec_info_to_caps Unknown color format 0x7f000789 for codec video/avc

W/GStreamer(25504): 0:00:02.909790039 0x4e52d820 gstamc.c:3506:gst_amc_codec_info_to_caps Unknown color format 0x7f000789 for codec video/mp4v-es

W/GStreamer(25504): 0:00:02.910430908 0x4e52d820 gstamc.c:3506:gst_amc_codec_info_to_caps Unknown color format 0x7f000789 for codec video/3gpp

W/GStreamer(25504): 0:00:02.910980225 0x4e52d820 gstamc.c:3506:gst_amc_codec_info_to_caps Unknown color format 0x7f000789 for codec video/avc

I/GStreamer(25504): GStreamer initialization complete

I/GStreamer(25504): 0:00:33.083679199 0x4ec50e60 gstelementfactory.c:362:gst_element_factory_create creating element "playbin" named "playbin"

I/GStreamer(25504): 0:00:33.223785400 0x4ec50e60 gstbin.c:2316:gst_bin_element_set_state:<playsink> current NULL pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:33.224121093 0x4ec50e60 gstbin.c:2316:gst_bin_element_set_state:<streamsynchronizer0> current NULL pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:33.224670409 0x4ec50e60 gstelement.c:2372:gst_element_continue_state:<streamsynchronizer0> completed state change to READY

I/GStreamer(25504): 0:00:33.225006103 0x4ec50e60 gstelement.c:2277:_priv_gst_element_state_changed:<streamsynchronizer0> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:33.228942870 0x4ec50e60 gstbin.c:2764:gst_bin_change_state_func:<playsink> child 'streamsynchronizer0' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:33.229431151 0x4ec50e60 gstelement.c:2372:gst_element_continue_state:<playsink> completed state change to READY

I/GStreamer(25504): 0:00:33.229705809 0x4ec50e60 gstelement.c:2277:_priv_gst_element_state_changed:<playsink> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:33.230010985 0x4ec50e60 gstbin.c:2764:gst_bin_change_state_func:<playbin> child 'playsink' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:33.230438231 0x4ec50e60 gstelement.c:2347:gst_element_continue_state:<playbin> committing state from NULL to READY, pending PAUSED, next PAUSED

I/GStreamer(25504): 0:00:33.230682372 0x4ec50e60 gstelement.c:2277:_priv_gst_element_state_changed:<playbin> notifying about state-changed NULL to READY (PAUSED pending)

I/GStreamer(25504): 0:00:33.231109618 0x4ec50e60 gstelement.c:2354:gst_element_continue_state:<playbin> continue state change READY to PAUSED, final PAUSED

I/GStreamer(25504): 0:00:33.232360840 0x4ec50e60 gstbin.c:2316:gst_bin_element_set_state:<playsink> current READY pending VOID_PENDING, desired next PAUSED

I/GStreamer(25504): 0:00:33.255187989 0x4ec50e60 gstplaysink.c:1400:do_async_start:<playsink> Sending async_start message

I/GStreamer(25504): 0:00:33.255493164 0x4ec50e60 gstbin.c:2316:gst_bin_element_set_state:<streamsynchronizer0> current READY pending VOID_PENDING, desired next PAUSED

I/GStreamer(25504): 0:00:33.255981446 0x4ec50e60 gstelement.c:2372:gst_element_continue_state:<streamsynchronizer0> completed state change to PAUSED

I/GStreamer(25504): 0:00:33.256439209 0x4ec50e60 gstelement.c:2277:_priv_gst_element_state_changed:<streamsynchronizer0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:33.256805420 0x4ec50e60 gstbin.c:2764:gst_bin_change_state_func:<playsink> child 'streamsynchronizer0' changed state to 3(PAUSED) successfully

I/GStreamer(25504): 0:00:33.257232666 0x4ec50e60 gstbin.c:2770:gst_bin_change_state_func:<playbin> child 'playsink' is changing state asynchronously to PAUSED

I/GStreamer(25504): 0:00:33.257507324 0x4ec50e60 gstelementfactory.c:364:gst_element_factory_create creating element "uridecodebin"

I/GStreamer(25504): 0:00:33.277587892 0x4ec50e60 gstelement.c:2347:gst_element_continue_state:<uridecodebin0> committing state from NULL to READY, pending PAUSED, next PAUSED

I/GStreamer(25504): 0:00:33.277770997 0x4ec50e60 gstelement.c:2277:_priv_gst_element_state_changed:<uridecodebin0> notifying about state-changed NULL to READY (PAUSED pending)

I/GStreamer(25504): 0:00:33.278289796 0x4ec50e60 gstelement.c:2354:gst_element_continue_state:<uridecodebin0> continue state change READY to PAUSED, final PAUSED

I/GStreamer(25504): 0:00:33.278900147 0x4ec50e60 gstelementfactory.c:362:gst_element_factory_create creating element "filesrc" named "source"

I/GStreamer(25504): 0:00:33.282165529 0x4ec50e60 gstelement.c:659:gst_element_add_pad:<GstBaseSrc@0x4fcca1e8> adding pad 'src'

I/GStreamer(25504): 0:00:33.292510987 0x4ec50e60 gstfilesrc.c:262:gst_file_src_set_location filename : /storage/sdcard0/Movies/Transformer_3D_720p.mp4

I/GStreamer(25504): 0:00:33.292694093 0x4ec50e60 gstfilesrc.c:263:gst_file_src_set_location uri      : file:///storage/sdcard0/Movies/Transformer_3D_720p.mp4

I/GStreamer(25504): 0:00:33.293304444 0x4ec50e60 gstfilesrc.c:465:gst_file_src_start:<source> opening file /storage/sdcard0/Movies/Transformer_3D_720p.mp4

W/GStreamer(25504): 0:00:33.294555665 0x4ec50e60 gstbasesrc.c:3486:gst_base_src_start_complete:<source> pad not activated yet

I/GStreamer(25504): 0:00:33.295135499 0x4ec50e60 gstelementfactory.c:364:gst_element_factory_create creating element "decodebin"

I/GStreamer(25504): 0:00:33.295532227 0x4ec50e60 gstelementfactory.c:362:gst_element_factory_create creating element "typefind" named "typefind"

I/GStreamer(25504): 0:00:33.295959473 0x4ec50e60 gstelement.c:659:gst_element_add_pad:<GstTypeFindElement@0x4fcce0b0> adding pad 'sink'

I/GStreamer(25504): 0:00:33.296386719 0x4ec50e60 gstelement.c:659:gst_element_add_pad:<GstTypeFindElement@0x4fcce0b0> adding pad 'src'

I/GStreamer(25504): 0:00:33.299682618 0x4ec50e60 gstelement.c:907:gst_element_get_static_pad found pad typefind:sink

I/GStreamer(25504): 0:00:33.377655029 0x4ec50e60 gstpad.c:2292:gst_pad_link_prepare trying to link sink:proxypad0 and typefind:sink

I/GStreamer(25504): 0:00:33.377838134 0x4ec50e60 gstpad.c:2498:gst_pad_link_full linked sink:proxypad0 and typefind:sink, successful

I/GStreamer(25504): 0:00:33.378295898 0x4ec50e60 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:33.378631591 0x4ec50e60 gstelement.c:659:gst_element_add_pad:<GstDecodeBin@0x4fccd060> adding pad 'sink'

I/GStreamer(25504): 0:00:33.379211425 0x4ec50e60 gstutils.c:1571:gst_element_link_pads_full trying to link element source:(any) to element decodebin0:sink

I/GStreamer(25504): 0:00:33.379455566 0x4ec50e60 gstelement.c:907:gst_element_get_static_pad found pad decodebin0:sink

I/GStreamer(25504): 0:00:33.379699707 0x4ec50e60 gstutils.c:1444:prepare_link_maybe_ghosting source and decodebin0 in same bin, no need for ghost pads

I/GStreamer(25504): 0:00:33.379974365 0x4ec50e60 gstpad.c:2292:gst_pad_link_prepare trying to link source:src and decodebin0:sink

I/GStreamer(25504): 0:00:33.380279541 0x4ec50e60 gstpad.c:2498:gst_pad_link_full linked source:src and decodebin0:sink, successful

I/GStreamer(25504): 0:00:33.380493164 0x4ec50e60 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:33.380706787 0x4ec50e60 gstpad.c:5601:gst_pad_send_event_unchecked:<source:src> Received event on flushing pad. Discarding

I/GStreamer(25504): 0:00:33.381042480 0x4ec50e60 gstbin.c:2316:gst_bin_element_set_state:<typefind> current NULL pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:33.381317138 0x4ec50e60 gstelement.c:2372:gst_element_continue_state:<typefind> completed state change to READY

I/GStreamer(25504): 0:00:33.382568359 0x4ec50e60 gstelement.c:2277:_priv_gst_element_state_changed:<typefind> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:33.382904052 0x4ec50e60 gstbin.c:2764:gst_bin_change_state_func:<decodebin0> child 'typefind' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:33.383331298 0x4ec50e60 gstelement.c:2347:gst_element_continue_state:<decodebin0> committing state from NULL to READY, pending PAUSED, next PAUSED

I/GStreamer(25504): 0:00:33.383575439 0x4ec50e60 gstelement.c:2277:_priv_gst_element_state_changed:<decodebin0> notifying about state-changed NULL to READY (PAUSED pending)

I/GStreamer(25504): 0:00:33.383941650 0x4ec50e60 gstelement.c:2354:gst_element_continue_state:<decodebin0> continue state change READY to PAUSED, final PAUSED

I/GStreamer(25504): 0:00:33.384307861 0x4ec50e60 gstbin.c:2316:gst_bin_element_set_state:<typefind> current READY pending VOID_PENDING, desired next PAUSED

I/GStreamer(25504): 0:00:33.384796142 0x4ec50e60 gstfilesrc.c:465:gst_file_src_start:<source> opening file /storage/sdcard0/Movies/Transformer_3D_720p.mp4

W/GStreamer(25504): 0:00:33.387176513 0x4ec50e60 gstbasesrc.c:3486:gst_base_src_start_complete:<source> pad not activated yet

I/GStreamer(25504): 0:00:33.387603759 0x4ec50e60 gstfilesrc.c:465:gst_file_src_start:<source> opening file /storage/sdcard0/Movies/Transformer_3D_720p.mp4

I/GStreamer(25504): 0:00:33.388183593 0x4ec50e60 gsttask.c:451:gst_task_set_lock setting stream lock 0x4fccc1dc on task 0x4fcd1828

I/GStreamer(25504): 0:00:33.388427733 0x4ec50e60 gstpad.c:5947:gst_pad_start_task:<typefind:sink> created task 0x4fcd1828

I/GStreamer(25504): 0:00:33.390289306 0x4ec50e60 gstelement.c:2372:gst_element_continue_state:<typefind> completed state change to PAUSED

I/GStreamer(25504): 0:00:33.390747069 0x4ec50e60 gstelement.c:2277:_priv_gst_element_state_changed:<typefind> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:33.391052245 0x4ec50e60 gstbin.c:2764:gst_bin_change_state_func:<decodebin0> child 'typefind' changed state to 3(PAUSED) successfully

I/GStreamer(25504): 0:00:33.392913818 0x4ec50e60 gstelement.c:2347:gst_element_continue_state:<source> committing state from NULL to READY, pending PAUSED, next PAUSED

I/GStreamer(25504): 0:00:33.393463134 0x4ec50e60 gstelement.c:2277:_priv_gst_element_state_changed:<source> notifying about state-changed NULL to READY (PAUSED pending)

I/GStreamer(25504): 0:00:33.393798828 0x4ec50e60 gstelement.c:2354:gst_element_continue_state:<source> continue state change READY to PAUSED, final PAUSED

I/GStreamer(25504): 0:00:33.394256591 0x4ec50e60 gstelement.c:2372:gst_element_continue_state:<source> completed state change to PAUSED

I/GStreamer(25504): 0:00:33.394531250 0x4ec50e60 gstelement.c:2277:_priv_gst_element_state_changed:<source> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:33.396911621 0x4ec50e60 gstbin.c:1915:gst_bin_get_state_func:<playbin> getting state

I/GStreamer(25504): 0:00:33.397308349 0x4ec50e60 gstelement.c:2090:gst_element_get_state_func:<playbin> waiting for element to commit state

I/GStreamer(25504): 0:00:33.398071288 0x4ec50e60 gstelement.c:2104:gst_element_get_state_func:<playbin> timed out

I/GStreamer(25504): 0:00:33.398773193 0x4fcc90f0 gstelement.c:907:gst_element_get_static_pad found pad typefind:sink

I/GStreamer(25504): 0:00:33.413818360 0x4fcc90f0 gstevent.c:679:gst_event_new_caps creating caps event video/quicktime, variant=(string)iso

I/GStreamer(25504): 0:00:33.414093018 0x4fcc90f0 gstelement.c:907:gst_element_get_static_pad found pad typefind:src

I/GStreamer(25504): 0:00:33.414642334 0x4fcc90f0 gstelement.c:907:gst_element_get_static_pad found pad typefind:sink

I/GStreamer(25504): 0:00:33.418823242 0x4fcc90f0 gstpad.c:2292:gst_pad_link_prepare trying to link typefind:src and decodepad0:proxypad1

I/GStreamer(25504): 0:00:33.420227051 0x4fcc90f0 gstpad.c:2498:gst_pad_link_full linked typefind:src and decodepad0:proxypad1, successful

I/GStreamer(25504): 0:00:33.420471191 0x4fcc90f0 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:33.593749999 0x4fcc90f0 gstpad.c:2048:gst_pad_unlink unlinking typefind:src(0x4fccc2e0) and decodepad0:proxypad1(0x4fcd0630)

I/GStreamer(25504): 0:00:33.595245360 0x4fcc90f0 gstpad.c:2102:gst_pad_unlink unlinked typefind:src and decodepad0:proxypad1

I/GStreamer(25504): 0:00:33.595825194 0x4fcc90f0 gstpad.c:2292:gst_pad_link_prepare trying to link typefind:src and decodepad0:proxypad1

I/GStreamer(25504): 0:00:33.596160888 0x4fcc90f0 gstpad.c:2498:gst_pad_link_full linked typefind:src and decodepad0:proxypad1, successful

I/GStreamer(25504): 0:00:33.596435546 0x4fcc90f0 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:33.598571776 0x4fcc90f0 gstpad.c:2048:gst_pad_unlink unlinking typefind:src(0x4fccc2e0) and decodepad0:proxypad1(0x4fcd0630)

I/GStreamer(25504): 0:00:33.598846434 0x4fcc90f0 gstpad.c:2102:gst_pad_unlink unlinked typefind:src and decodepad0:proxypad1

I/GStreamer(25504): 0:00:33.599273681 0x4fcc90f0 gstelementfactory.c:364:gst_element_factory_create creating element "qtdemux"

I/GStreamer(25504): 0:00:33.678161621 0x4fcc90f0 gstelement.c:659:gst_element_add_pad:<GstQTDemux@0x4fdf8030> adding pad 'sink'

I/GStreamer(25504): 0:00:33.678527832 0x4fcc90f0 gstpad.c:2292:gst_pad_link_prepare trying to link typefind:src and qtdemux0:sink

I/GStreamer(25504): 0:00:33.679016113 0x4fcc90f0 gstpad.c:2498:gst_pad_link_full linked typefind:src and qtdemux0:sink, successful

I/GStreamer(25504): 0:00:33.679260254 0x4fcc90f0 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:33.679534912 0x4fcc90f0 gstelement.c:2372:gst_element_continue_state:<qtdemux0> completed state change to READY

I/GStreamer(25504): 0:00:33.679779053 0x4fcc90f0 gstelement.c:2277:_priv_gst_element_state_changed:<qtdemux0> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:33.680419922 0x4fcc90f0 gstelement.c:904:gst_element_get_static_pad no such pad 'video_%u' in element "qtdemux0"

I/GStreamer(25504): 0:00:33.680664062 0x4fcc90f0 gstelement.c:904:gst_element_get_static_pad no such pad 'audio_%u' in element "qtdemux0"

I/GStreamer(25504): 0:00:33.680877685 0x4fcc90f0 gstelement.c:904:gst_element_get_static_pad no such pad 'subtitle_%u' in element "qtdemux0"

I/GStreamer(25504): 0:00:33.681304931 0x4fcc90f0 gsttask.c:451:gst_task_set_lock setting stream lock 0x4fccc5cc on task 0x4fcd1e10

I/GStreamer(25504): 0:00:33.682495117 0x4fcc90f0 gstpad.c:5947:gst_pad_start_task:<qtdemux0:sink> created task 0x4fcd1e10

I/GStreamer(25504): 0:00:33.691253661 0x4fcc90f0 gstelement.c:2372:gst_element_continue_state:<qtdemux0> completed state change to PAUSED

I/GStreamer(25504): 0:00:33.691986083 0x4fcc90f0 gstelement.c:2277:_priv_gst_element_state_changed:<qtdemux0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:33.693054198 0x4fcc90f0 gsttypefindelement.c:180:gst_type_find_element_have_type:<typefind> found caps video/quicktime, variant=(string)iso, probability=100

I/GStreamer(25504): 0:00:33.693389892 0x4fcc90f0 gstevent.c:679:gst_event_new_caps creating caps event video/quicktime, variant=(string)iso

I/GStreamer(25504): 0:00:33.946258545 0x4fcc90f0 gsttask.c:316:gst_task_func:<typefind:sink> Task going to paused

W/GStreamer(25504): 0:00:34.032196045 0x4fdf0f80 qtdemux_types.c:222:qtdemux_type_get unknown QuickTime node type iods

W/GStreamer(25504): 0:00:34.033508301 0x4fdf0f80 qtdemux_types.c:222:qtdemux_type_get unknown QuickTime node type gsst

W/GStreamer(25504): 0:00:34.034118652 0x4fdf0f80 qtdemux_types.c:222:qtdemux_type_get unknown QuickTime node type gstd

W/GStreamer(25504): 0:00:34.034576416 0x4fdf0f80 qtdemux_types.c:222:qtdemux_type_get unknown QuickTime node type gssd

W/GStreamer(25504): 0:00:34.035278320 0x4fdf0f80 qtdemux_types.c:222:qtdemux_type_get unknown QuickTime node type gspu

W/GStreamer(25504): 0:00:34.036529541 0x4fdf0f80 qtdemux_types.c:222:qtdemux_type_get unknown QuickTime node type gspm

W/GStreamer(25504): 0:00:34.038085937 0x4fdf0f80 qtdemux_types.c:222:qtdemux_type_get unknown QuickTime node type gshh

I/GStreamer(25504): 0:00:34.042449951 0x4fdf0f80 qtdemux.c:11938:qtdemux_parse_tree:<qtdemux0> timescale: 600

I/GStreamer(25504): 0:00:34.043853760 0x4fdf0f80 qtdemux.c:11939:qtdemux_parse_tree:<qtdemux0> duration: 91923

W/GStreamer(25504): 0:00:34.044128418 0x4fdf0f80 qtdemux.c:2639:qtdemux_parse_trex:<qtdemux0> failed to find fragment defaults for stream 1

I/GStreamer(25504): 0:00:34.045257568 0x4fdf0f80 qtdemux.c:9648:qtdemux_parse_trak:<qtdemux0> type avc1 caps video/x-h264, stream-format=(string)avc, alignment=(string)au, level=(string)3.1, profile=(string)high, codec_data=(buffer)0164001fffe100186764001facb402802dd80880000003008000001e078c195001000468ee3cb0

W/GStreamer(25504): 0:00:34.045806885 0x4fdf0f80 qtdemux.c:2639:qtdemux_parse_trex:<qtdemux0> failed to find fragment defaults for stream 2

I/GStreamer(25504): 0:00:34.049468994 0x4fdf0f80 qtdemux.c:10266:qtdemux_parse_trak:<qtdemux0> type mp4a caps audio/mpeg, mpegversion=(int)4, framed=(boolean)true, stream-format=(string)raw, level=(string)2, base-profile=(string)lc, profile=(string)lc, codec_data=(buffer)12100000000000000000000000000000

W/GStreamer(25504): 0:00:34.050689697 0x4fdf0f80 gstbasesrc.c:2396:gst_base_src_update_length:<source> processing at or past EOS

I/GStreamer(25504): 0:00:34.053985596 0x4fdf0f80 gstpad.c:4724:gst_pad_get_range_unchecked:<source:src> getrange failed, flow: eos

I/GStreamer(25504): 0:00:34.057281494 0x4fdf0f80 gstpad.c:4939:gst_pad_pull_range:<decodebin0:sink> pullrange failed, flow: eos

I/GStreamer(25504): 0:00:34.057830811 0x4fdf0f80 gstpad.c:4724:gst_pad_get_range_unchecked:<sink:proxypad0> getrange failed, flow: eos

I/GStreamer(25504): 0:00:34.068023682 0x4fdf0f80 gstpad.c:4939:gst_pad_pull_range:<typefind:sink> pullrange failed, flow: eos

I/GStreamer(25504): 0:00:34.068237305 0x4fdf0f80 gstpad.c:4724:gst_pad_get_range_unchecked:<typefind:src> getrange failed, flow: eos

I/GStreamer(25504): 0:00:34.068603516 0x4fdf0f80 gstpad.c:4939:gst_pad_pull_range:<qtdemux0:sink> pullrange failed, flow: eos

I/GStreamer(25504): 0:00:34.076568605 0x4fdf0f80 gstelement.c:907:gst_element_get_static_pad found pad qtdemux0:sink

I/GStreamer(25504): 0:00:34.077301026 0x4fdf0f80 gstevent.c:679:gst_event_new_caps creating caps event video/x-h264, stream-format=(string)avc, alignment=(string)au, level=(string)3.1, profile=(string)high, codec_data=(buffer)0164001fffe100186764001facb402802dd80880000003008000001e078c195001000468ee3cb0, width=(int)1280, height=(int)720, framerate=(fraction)30/1, pixel-aspect-ratio=(fraction)1/1

I/GStreamer(25504): 0:00:34.079803468 0x4fdf0f80 gstelement.c:659:gst_element_add_pad:<qtdemux0> adding pad 'video_0'

I/GStreamer(25504): 0:00:34.080352784 0x4fdf0f80 gstelementfactory.c:364:gst_element_factory_create creating element "multiqueue"

I/GStreamer(25504): 0:00:34.080688477 0x4fdf0f80 gstelement.c:907:gst_element_get_static_pad found pad qtdemux0:sink

I/GStreamer(25504): 0:00:34.087921144 0x4fdf0f80 gstelement.c:2347:gst_element_continue_state:<multiqueue0> committing state from NULL to READY, pending PAUSED, next PAUSED

I/GStreamer(25504): 0:00:34.088195802 0x4fdf0f80 gstelement.c:2277:_priv_gst_element_state_changed:<multiqueue0> notifying about state-changed NULL to READY (PAUSED pending)

I/GStreamer(25504): 0:00:34.088867189 0x4fdf0f80 gstelement.c:2354:gst_element_continue_state:<multiqueue0> continue state change READY to PAUSED, final PAUSED

I/GStreamer(25504): 0:00:34.095336915 0x4fdf0f80 gstelement.c:2372:gst_element_continue_state:<multiqueue0> completed state change to PAUSED

I/GStreamer(25504): 0:00:34.095550538 0x4fdf0f80 gstelement.c:2277:_priv_gst_element_state_changed:<multiqueue0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:34.099090577 0x4fdf0f80 gstpad.c:2292:gst_pad_link_prepare trying to link qtdemux0:video_0 and decodepad1:proxypad2

I/GStreamer(25504): 0:00:34.099304200 0x4fdf0f80 gstpad.c:2498:gst_pad_link_full linked qtdemux0:video_0 and decodepad1:proxypad2, successful

I/GStreamer(25504): 0:00:34.099426270 0x4fdf0f80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:34.100311280 0x4fdf0f80 gstpad.c:2048:gst_pad_unlink unlinking qtdemux0:video_0(0x4fcccac0) and decodepad1:proxypad2(0x4fcd0630)

I/GStreamer(25504): 0:00:34.100952149 0x4fdf0f80 gstpad.c:2102:gst_pad_unlink unlinked qtdemux0:video_0 and decodepad1:proxypad2

I/GStreamer(25504): 0:00:34.102203370 0x4fdf0f80 gsttask.c:451:gst_task_set_lock setting stream lock 0x4fcccdac on task 0x4fdfc418

I/GStreamer(25504): 0:00:34.102416993 0x4fdf0f80 gstpad.c:5947:gst_pad_start_task:<'':src_0> created task 0x4fdfc418

I/GStreamer(25504): 0:00:34.156829835 0x4fdf0f80 gstelement.c:659:gst_element_add_pad:<multiqueue0> adding pad 'src_0'

I/GStreamer(25504): 0:00:34.157073976 0x4fdf0f80 gstelement.c:659:gst_element_add_pad:<multiqueue0> adding pad 'sink_0'

I/GStreamer(25504): 0:00:34.157257081 0x4fdf0f80 gstpad.c:2292:gst_pad_link_prepare trying to link qtdemux0:video_0 and multiqueue0:sink_0

I/GStreamer(25504): 0:00:34.157409669 0x4fdf0f80 gstpad.c:2498:gst_pad_link_full linked qtdemux0:video_0 and multiqueue0:sink_0, successful

I/GStreamer(25504): 0:00:34.157501222 0x4fdf0f80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:34.157775880 0x4fdf0f80 gstpad.c:2292:gst_pad_link_prepare trying to link multiqueue0:src_0 and decodepad1:proxypad2

I/GStreamer(25504): 0:00:34.157897950 0x4fdf0f80 gstpad.c:2498:gst_pad_link_full linked multiqueue0:src_0 and decodepad1:proxypad2, successful

I/GStreamer(25504): 0:00:34.158020020 0x4fdf0f80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:34.158233644 0x4fdf0f80 gstpad.c:2048:gst_pad_unlink unlinking multiqueue0:src_0(0x4fcccd60) and decodepad1:proxypad2(0x4fcd0630)

I/GStreamer(25504): 0:00:34.158386231 0x4fdf0f80 gstpad.c:2102:gst_pad_unlink unlinked multiqueue0:src_0 and decodepad1:proxypad2

I/GStreamer(25504): 0:00:34.158538819 0x4fdf0f80 gstpad.c:2292:gst_pad_link_prepare trying to link multiqueue0:src_0 and decodepad1:proxypad2

I/GStreamer(25504): 0:00:34.158660890 0x4fdf0f80 gstpad.c:2498:gst_pad_link_full linked multiqueue0:src_0 and decodepad1:proxypad2, successful

I/GStreamer(25504): 0:00:34.158782960 0x4fdf0f80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:34.159057618 0x4fdf0f80 gstpad.c:2048:gst_pad_unlink unlinking multiqueue0:src_0(0x4fcccd60) and decodepad1:proxypad2(0x4fcd0630)

I/GStreamer(25504): 0:00:34.159210206 0x4fdf0f80 gstpad.c:2102:gst_pad_unlink unlinked multiqueue0:src_0 and decodepad1:proxypad2

I/GStreamer(25504): 0:00:34.159362794 0x4fdf0f80 gstelementfactory.c:364:gst_element_factory_create creating element "h264parse"

I/GStreamer(25504): 0:00:34.159820557 0x4fdf0f80 gstelement.c:659:gst_element_add_pad:<GstBaseParse@0x4ff04208> adding pad 'sink'

I/GStreamer(25504): 0:00:34.160125733 0x4fdf0f80 gstelement.c:659:gst_element_add_pad:<GstBaseParse@0x4ff04208> adding pad 'src'

I/GStreamer(25504): 0:00:34.175018310 0x4fdf0f80 gstbaseparse.c:3910:gst_base_parse_set_pts_interpolation:<GstH264Parse@0x4ff04208> PTS interpolation: no

I/GStreamer(25504): 0:00:34.175415039 0x4fdf0f80 gstpad.c:2292:gst_pad_link_prepare trying to link multiqueue0:src_0 and h264parse0:sink

I/GStreamer(25504): 0:00:34.175933838 0x4fdf0f80 gstpad.c:2498:gst_pad_link_full linked multiqueue0:src_0 and h264parse0:sink, successful

I/GStreamer(25504): 0:00:34.176269531 0x4fdf0f80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:34.176666260 0x4fdf0f80 gstelement.c:2372:gst_element_continue_state:<h264parse0> completed state change to READY

I/GStreamer(25504): 0:00:34.176940918 0x4fdf0f80 gstelement.c:2277:_priv_gst_element_state_changed:<h264parse0> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:34.177520752 0x4fdf0f80 gstelement.c:907:gst_element_get_static_pad found pad h264parse0:src

I/GStreamer(25504): 0:00:34.178009033 0x4fdf0f80 gstpad.c:2292:gst_pad_link_prepare trying to link h264parse0:src and decodepad1:proxypad2

I/GStreamer(25504): 0:00:34.178253174 0x4fdf0f80 gstpad.c:2498:gst_pad_link_full linked h264parse0:src and decodepad1:proxypad2, successful

I/GStreamer(25504): 0:00:34.178466797 0x4fdf0f80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:34.178710937 0x4fdf0f80 gstpad.c:5601:gst_pad_send_event_unchecked:<h264parse0:src> Received event on flushing pad. Discarding

I/GStreamer(25504): 0:00:34.180267334 0x4fdf0f80 gstelementfactory.c:364:gst_element_factory_create creating element "capsfilter"

I/GStreamer(25504): 0:00:34.180633544 0x4fdf0f80 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x4ff0a0b0> adding pad 'sink'

I/GStreamer(25504): 0:00:34.181396484 0x4fdf0f80 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x4ff0a0b0> adding pad 'src'

I/GStreamer(25504): 0:00:34.182769775 0x4fdf0f80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:34.183563232 0x4fdf0f80 gstelement.c:2347:gst_element_continue_state:<capsfilter0> committing state from NULL to READY, pending PAUSED, next PAUSED

I/GStreamer(25504): 0:00:34.184600829 0x4fdf0f80 gstelement.c:2277:_priv_gst_element_state_changed:<capsfilter0> notifying about state-changed NULL to READY (PAUSED pending)

I/GStreamer(25504): 0:00:34.185272216 0x4fdf0f80 gstelement.c:2354:gst_element_continue_state:<capsfilter0> continue state change READY to PAUSED, final PAUSED

I/GStreamer(25504): 0:00:34.186126708 0x4fdf0f80 gstelement.c:2372:gst_element_continue_state:<capsfilter0> completed state change to PAUSED

I/GStreamer(25504): 0:00:34.186584472 0x4fdf0f80 gstelement.c:2277:_priv_gst_element_state_changed:<capsfilter0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:34.186920165 0x4fdf0f80 gstpad.c:2048:gst_pad_unlink unlinking h264parse0:src(0x4ff05038) and decodepad1:proxypad2(0x4fcd0630)

I/GStreamer(25504): 0:00:34.188323974 0x4fdf0f80 gstpad.c:2102:gst_pad_unlink unlinked h264parse0:src and decodepad1:proxypad2

I/GStreamer(25504): 0:00:34.188934325 0x4fdf0f80 gstelement.c:907:gst_element_get_static_pad found pad capsfilter0:sink

I/GStreamer(25504): 0:00:34.189331054 0x4fdf0f80 gstpad.c:2292:gst_pad_link_prepare trying to link h264parse0:src and capsfilter0:sink

I/GStreamer(25504): 0:00:34.190612792 0x4fdf0f80 gstpad.c:2498:gst_pad_link_full linked h264parse0:src and capsfilter0:sink, successful

I/GStreamer(25504): 0:00:34.190856933 0x4fdf0f80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:34.191223143 0x4fdf0f80 gstpad.c:5601:gst_pad_send_event_unchecked:<h264parse0:src> Received event on flushing pad. Discarding

I/GStreamer(25504): 0:00:34.192108154 0x4fdf0f80 gstelement.c:907:gst_element_get_static_pad found pad capsfilter0:src

I/GStreamer(25504): 0:00:34.195556640 0x4fdf0f80 gstpad.c:2292:gst_pad_link_prepare trying to link capsfilter0:src and decodepad1:proxypad2

I/GStreamer(25504): 0:00:34.196044921 0x4fdf0f80 gstpad.c:2498:gst_pad_link_full linked capsfilter0:src and decodepad1:proxypad2, successful

I/GStreamer(25504): 0:00:34.196319580 0x4fdf0f80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:34.196624755 0x4fdf0f80 gstpad.c:5601:gst_pad_send_event_unchecked:<h264parse0:src> Received event on flushing pad. Discarding

I/GStreamer(25504): 0:00:34.288574219 0x4fdf0f80 gstelement.c:2372:gst_element_continue_state:<h264parse0> completed state change to PAUSED

I/GStreamer(25504): 0:00:34.288757324 0x4fdf0f80 gstelement.c:2277:_priv_gst_element_state_changed:<h264parse0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:34.289489746 0x4fdf0f80 gstelement.c:907:gst_element_get_static_pad found pad qtdemux0:sink

I/GStreamer(25504): 0:00:34.290222168 0x4fdf0f80 gstevent.c:679:gst_event_new_caps creating caps event audio/mpeg, mpegversion=(int)4, framed=(boolean)true, stream-format=(string)raw, level=(string)2, base-profile=(string)lc, profile=(string)lc, codec_data=(buffer)12100000000000000000000000000000, rate=(int)44100, channels=(int)2

I/GStreamer(25504): 0:00:34.290527343 0x4fdf0f80 gstelement.c:659:gst_element_add_pad:<qtdemux0> adding pad 'audio_0'

I/GStreamer(25504): 0:00:34.292114258 0x4fdf0f80 gstpad.c:2292:gst_pad_link_prepare trying to link qtdemux0:audio_0 and decodepad2:proxypad3

I/GStreamer(25504): 0:00:34.292633057 0x4fdf0f80 gstpad.c:2498:gst_pad_link_full linked qtdemux0:audio_0 and decodepad2:proxypad3, successful

I/GStreamer(25504): 0:00:34.292938233 0x4fdf0f80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:34.293853760 0x4fdf0f80 gstpad.c:2048:gst_pad_unlink unlinking qtdemux0:audio_0(0x4ff05428) and decodepad2:proxypad3(0x4fcd0bb0)

I/GStreamer(25504): 0:00:34.294372559 0x4fdf0f80 gstpad.c:2102:gst_pad_unlink unlinked qtdemux0:audio_0 and decodepad2:proxypad3

I/GStreamer(25504): 0:00:34.295349121 0x4fdf0f80 gsttask.c:451:gst_task_set_lock setting stream lock 0x4ff05714 on task 0x4fdfc4c0

I/GStreamer(25504): 0:00:34.295654297 0x4fdf0f80 gstpad.c:5947:gst_pad_start_task:<'':src_1> created task 0x4fdfc4c0

I/GStreamer(25504): 0:00:34.304016114 0x4fdf0f80 gstelement.c:659:gst_element_add_pad:<multiqueue0> adding pad 'src_1'

I/GStreamer(25504): 0:00:34.304443360 0x4fdf0f80 gstelement.c:659:gst_element_add_pad:<multiqueue0> adding pad 'sink_1'

I/GStreamer(25504): 0:00:34.305053712 0x4fdf0f80 gstpad.c:2292:gst_pad_link_prepare trying to link qtdemux0:audio_0 and multiqueue0:sink_1

I/GStreamer(25504): 0:00:34.306304932 0x4fdf0f80 gstpad.c:2498:gst_pad_link_full linked qtdemux0:audio_0 and multiqueue0:sink_1, successful

I/GStreamer(25504): 0:00:34.307037354 0x4fdf0f80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:34.308197022 0x4fdf0f80 gstpad.c:2292:gst_pad_link_prepare trying to link multiqueue0:src_1 and decodepad2:proxypad3

I/GStreamer(25504): 0:00:34.308593751 0x4fdf0f80 gstpad.c:2498:gst_pad_link_full linked multiqueue0:src_1 and decodepad2:proxypad3, successful

I/GStreamer(25504): 0:00:34.309112549 0x4fdf0f80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:34.309661866 0x4fdf0f80 gstpad.c:2048:gst_pad_unlink unlinking multiqueue0:src_1(0x4ff056c8) and decodepad2:proxypad3(0x4fcd0bb0)

I/GStreamer(25504): 0:00:34.310272217 0x4fdf0f80 gstpad.c:2102:gst_pad_unlink unlinked multiqueue0:src_1 and decodepad2:proxypad3

I/GStreamer(25504): 0:00:34.310668946 0x4fdf0f80 gstpad.c:2292:gst_pad_link_prepare trying to link multiqueue0:src_1 and decodepad2:proxypad3

I/GStreamer(25504): 0:00:34.311004639 0x4fdf0f80 gstpad.c:2498:gst_pad_link_full linked multiqueue0:src_1 and decodepad2:proxypad3, successful

I/GStreamer(25504): 0:00:34.311920167 0x4fdf0f80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:34.313385011 0x4fdf0f80 gstpad.c:2048:gst_pad_unlink unlinking multiqueue0:src_1(0x4ff056c8) and decodepad2:proxypad3(0x4fcd0bb0)

I/GStreamer(25504): 0:00:34.313903810 0x4fdf0f80 gstpad.c:2102:gst_pad_unlink unlinked multiqueue0:src_1 and decodepad2:proxypad3

I/GStreamer(25504): 0:00:34.314239503 0x4fdf0f80 gstelementfactory.c:364:gst_element_factory_create creating element "aacparse"

I/GStreamer(25504): 0:00:34.316864015 0x4fdf0f80 gstelement.c:659:gst_element_add_pad:<GstBaseParse@0x501b8428> adding pad 'sink'

I/GStreamer(25504): 0:00:34.317596437 0x4fdf0f80 gstelement.c:659:gst_element_add_pad:<GstBaseParse@0x501b8428> adding pad 'src'

I/GStreamer(25504): 0:00:34.437103271 0x4fdf0f80 gstpad.c:2292:gst_pad_link_prepare trying to link multiqueue0:src_1 and aacparse0:sink

I/GStreamer(25504): 0:00:34.437255859 0x4fdf0f80 gstpad.c:2498:gst_pad_link_full linked multiqueue0:src_1 and aacparse0:sink, successful

I/GStreamer(25504): 0:00:34.438140869 0x4fdf0f80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:34.438873290 0x4fdf0f80 gstelement.c:2372:gst_element_continue_state:<aacparse0> completed state change to READY

I/GStreamer(25504): 0:00:34.439147949 0x4fdf0f80 gstelement.c:2277:_priv_gst_element_state_changed:<aacparse0> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:34.439666747 0x4fdf0f80 gstelement.c:907:gst_element_get_static_pad found pad aacparse0:src

I/GStreamer(25504): 0:00:34.440643310 0x4fdf0f80 gstpad.c:2292:gst_pad_link_prepare trying to link aacparse0:src and decodepad2:proxypad3

I/GStreamer(25504): 0:00:34.441070556 0x4fdf0f80 gstpad.c:2498:gst_pad_link_full linked aacparse0:src and decodepad2:proxypad3, successful

I/GStreamer(25504): 0:00:34.442626953 0x4fdf0f80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:34.443725586 0x4fdf0f80 gstpad.c:5601:gst_pad_send_event_unchecked:<aacparse0:src> Received event on flushing pad. Discarding

I/GStreamer(25504): 0:00:34.444946289 0x4fdf0f80 gstelement.c:2372:gst_element_continue_state:<aacparse0> completed state change to PAUSED

I/GStreamer(25504): 0:00:34.445434570 0x4fdf0f80 gstelement.c:2277:_priv_gst_element_state_changed:<aacparse0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:34.447235107 0x4fdf0f80 gstevent.c:760:gst_event_new_segment creating segment event time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=0:02:33.200000000, 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

I/GStreamer(25504): 0:00:34.462829591 0x4fdf0f80 gstevent.c:760:gst_event_new_segment creating segment event time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=0:02:33.205000000, 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

I/GStreamer(25504): 0:00:34.472808839 0x4ff0daf0 gstevent.c:679:gst_event_new_caps creating caps event audio/mpeg, mpegversion=(int)4, framed=(boolean)true, stream-format=(string)raw, level=(string)2, base-profile=(string)lc, profile=(string)lc, codec_data=(buffer)12100000000000000000000000000000, rate=(int)44100, channels=(int)2

I/GStreamer(25504): 0:00:34.475372316 0x4ff0daf0 gstpad.c:2048:gst_pad_unlink unlinking aacparse0:src(0x4ff05968) and decodepad2:proxypad3(0x4fcd0bb0)

I/GStreamer(25504): 0:00:34.478759767 0x4ff0daf0 gstpad.c:2102:gst_pad_unlink unlinked aacparse0:src and decodepad2:proxypad3

I/GStreamer(25504): 0:00:34.479370118 0x4ff0daf0 gstpad.c:2292:gst_pad_link_prepare trying to link aacparse0:src and decodepad2:proxypad3

I/GStreamer(25504): 0:00:34.479797364 0x4ff0daf0 gstpad.c:2498:gst_pad_link_full linked aacparse0:src and decodepad2:proxypad3, successful

I/GStreamer(25504): 0:00:34.480010987 0x4ff0daf0 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:34.483367921 0x4ff0daf0 gstpad.c:2048:gst_pad_unlink unlinking aacparse0:src(0x4ff05968) and decodepad2:proxypad3(0x4fcd0bb0)

I/GStreamer(25504): 0:00:34.485687256 0x4ff0daf0 gstpad.c:2102:gst_pad_unlink unlinked aacparse0:src and decodepad2:proxypad3

I/GStreamer(25504): 0:00:34.486694336 0x4ff0daf0 gstpad.c:2292:gst_pad_link_prepare trying to link aacparse0:src and decodepad2:proxypad3

I/GStreamer(25504): 0:00:34.488250733 0x4ff0daf0 gstpad.c:2498:gst_pad_link_full linked aacparse0:src and decodepad2:proxypad3, successful

I/GStreamer(25504): 0:00:34.489196778 0x4ff0daf0 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:34.490814209 0x4ff0daf0 gstpad.c:2048:gst_pad_unlink unlinking aacparse0:src(0x4ff05968) and decodepad2:proxypad3(0x4fcd0bb0)

I/GStreamer(25504): 0:00:34.491821289 0x4ff0daf0 gstpad.c:2102:gst_pad_unlink unlinked aacparse0:src and decodepad2:proxypad3

I/GStreamer(25504): 0:00:34.494567871 0x4ff0daf0 gstpad.c:2292:gst_pad_link_prepare trying to link aacparse0:src and decodepad2:proxypad3

I/GStreamer(25504): 0:00:34.495849609 0x4ff0daf0 gstpad.c:2498:gst_pad_link_full linked aacparse0:src and decodepad2:proxypad3, successful

I/GStreamer(25504): 0:00:34.496093750 0x4ff0daf0 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:34.497650146 0x4ff0daf0 gstelementfactory.c:364:gst_element_factory_create creating element "openslessink"

I/GStreamer(25504): 0:00:34.502624511 0x4ff0daf0 gstelement.c:659:gst_element_add_pad:<GstBaseSink@0x501c3a50> adding pad 'sink'

I/GStreamer(25504): 0:00:34.504913329 0x4ff0d600 gstbaseparse.c:4726:gst_base_parse_set_upstream_tags:<h264parse0> upstream tags: taglist, video-codec=(string)"H.264\ /\ AVC", maximum-bitrate=(uint)4606304, bitrate=(uint)1656640;

I/GStreamer(25504): 0:00:34.508300780 0x4ff0d600 gsth264parse.c:1758:gst_h264_parse_update_src_caps:<h264parse0> pixel aspect ratio has been changed 1/1

I/GStreamer(25504): 0:00:34.537292481 0x4ff0d600 gstbaseparse.c:3953:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.033333333, 0:00:00.033333333

I/GStreamer(25504): 0:00:34.539245606 0x4ff0daf0 gstelement.c:2372:gst_element_continue_state:<openslessink0> completed state change to READY

I/GStreamer(25504): 0:00:34.539703369 0x4ff0daf0 gstelement.c:2277:_priv_gst_element_state_changed:<openslessink0> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:34.540069580 0x4ff0daf0 gstelement.c:907:gst_element_get_static_pad found pad openslessink0:sink

I/GStreamer(25504): 0:00:34.540527344 0x4ff0daf0 gstpad.c:2048:gst_pad_unlink unlinking aacparse0:src(0x4ff05968) and decodepad2:proxypad3(0x4fcd0bb0)

I/GStreamer(25504): 0:00:34.542602540 0x4ff0daf0 gstpad.c:2102:gst_pad_unlink unlinked aacparse0:src and decodepad2:proxypad3

I/GStreamer(25504): 0:00:34.547149659 0x4ff0daf0 gstelementfactory.c:364:gst_element_factory_create creating element "amcauddec-omxgoogleaacdecoder"

I/GStreamer(25504): 0:00:34.547790528 0x4ff0daf0 gstelement.c:659:gst_element_add_pad:<GstAudioDecoder@0x501c9a70> adding pad 'sink'

I/GStreamer(25504): 0:00:34.548400879 0x4ff0daf0 gstelement.c:659:gst_element_add_pad:<GstAudioDecoder@0x501c9a70> adding pad 'src'

I/GStreamer(25504): 0:00:34.555297853 0x4ff0daf0 gstpad.c:2292:gst_pad_link_prepare trying to link aacparse0:src and amcaudiodec-omxgoogleaacdecoder0:sink

I/GStreamer(25504): 0:00:34.555541993 0x4ff0daf0 gstpad.c:2498:gst_pad_link_full linked aacparse0:src and amcaudiodec-omxgoogleaacdecoder0:sink, successful

I/GStreamer(25504): 0:00:34.555847169 0x4ff0daf0 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:34.609405517 0x4ff0d600 gstevent.c:679:gst_event_new_caps creating caps event video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, level=(string)3.1, profile=(string)high, width=(int)1280, height=(int)720, framerate=(fraction)30/1, pixel-aspect-ratio=(fraction)1/1, multiview-mode=(string)side-by-side, multiview-flags=(GstVideoMultiviewFlagsSet)4001:ffffffff:+right-view-first/left-flipped/left-flopped/right-flipped/right-flopped+half-aspect/mixed-mono, parsed=(boolean)true

I/GStreamer(25504): 0:00:34.672485351 0x4ff0d600 gstpad.c:4065:gst_pad_peer_query:<'':decodepad1> pad has no peer

I/GStreamer(25504): 0:00:34.685119629 0x4ff0d600 gstbasetransform.c:1367:gst_base_transform_setcaps:<capsfilter0> reuse caps

I/GStreamer(25504): 0:00:34.685821533 0x4ff0d600 gstevent.c:679:gst_event_new_caps creating caps event video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, level=(string)3.1, profile=(string)high, width=(int)1280, height=(int)720, framerate=(fraction)30/1, pixel-aspect-ratio=(fraction)1/1, multiview-mode=(string)side-by-side, multiview-flags=(GstVideoMultiviewFlagsSet)4001:ffffffff:+right-view-first/left-flipped/left-flopped/right-flipped/right-flopped+half-aspect/mixed-mono, parsed=(boolean)true

I/GStreamer(25504): 0:00:34.686340332 0x4ff0d600 gstpad.c:2048:gst_pad_unlink unlinking capsfilter0:src(0x4ff052d8) and decodepad1:proxypad2(0x4fcd0630)

I/GStreamer(25504): 0:00:34.686767578 0x4ff0d600 gstpad.c:2102:gst_pad_unlink unlinked capsfilter0:src and decodepad1:proxypad2

I/GStreamer(25504): 0:00:34.687042236 0x4ff0d600 gstpad.c:2292:gst_pad_link_prepare trying to link capsfilter0:src and decodepad1:proxypad2

I/GStreamer(25504): 0:00:34.687286377 0x4ff0d600 gstpad.c:2498:gst_pad_link_full linked capsfilter0:src and decodepad1:proxypad2, successful

I/GStreamer(25504): 0:00:34.687500000 0x4ff0d600 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:34.687927246 0x4ff0d600 gstelement.c:907:gst_element_get_static_pad found pad openslessink0:sink

I/GStreamer(25504): 0:00:34.688781738 0x4ff0d600 gstpad.c:2048:gst_pad_unlink unlinking capsfilter0:src(0x4ff052d8) and decodepad1:proxypad2(0x4fcd0630)

I/GStreamer(25504): 0:00:34.696014405 0x4ff0d600 gstpad.c:2102:gst_pad_unlink unlinked capsfilter0:src and decodepad1:proxypad2

I/GStreamer(25504): 0:00:34.697326660 0x4ff0d600 gstpad.c:2292:gst_pad_link_prepare trying to link capsfilter0:src and decodepad1:proxypad2

I/GStreamer(25504): 0:00:34.697784424 0x4ff0d600 gstpad.c:2498:gst_pad_link_full linked capsfilter0:src and decodepad1:proxypad2, successful

I/GStreamer(25504): 0:00:34.697998047 0x4ff0d600 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:34.698394776 0x4ff0d600 gstpad.c:2048:gst_pad_unlink unlinking capsfilter0:src(0x4ff052d8) and decodepad1:proxypad2(0x4fcd0630)

I/GStreamer(25504): 0:00:34.699188233 0x4ff0d600 gstpad.c:2102:gst_pad_unlink unlinked capsfilter0:src and decodepad1:proxypad2

I/GStreamer(25504): 0:00:34.701934815 0x4ff0d600 gstpad.c:2292:gst_pad_link_prepare trying to link capsfilter0:src and decodepad1:proxypad2

I/GStreamer(25504): 0:00:34.704406739 0x4ff0d600 gstpad.c:2498:gst_pad_link_full linked capsfilter0:src and decodepad1:proxypad2, successful

I/GStreamer(25504): 0:00:34.704772950 0x4ff0d600 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:34.706909180 0x4ff0d600 gstelementfactory.c:364:gst_element_factory_create creating element "glimagesink"

I/GStreamer(25504): 0:00:34.727233888 0x4ff0d600 gstelementfactory.c:364:gst_element_factory_create creating element "glupload"

I/GStreamer(25504): 0:00:34.727874757 0x4ff0d600 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x5034e140> adding pad 'sink'

I/GStreamer(25504): 0:00:34.728210451 0x4ff0d600 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x5034e140> adding pad 'src'

I/GStreamer(25504): 0:00:34.728485109 0x4ff0d600 gstelementfactory.c:364:gst_element_factory_create creating element "glcolorconvert"

I/GStreamer(25504): 0:00:34.728790285 0x4ff0d600 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x5034e3a0> adding pad 'sink'

I/GStreamer(25504): 0:00:34.729095460 0x4ff0d600 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x5034e3a0> adding pad 'src'

I/GStreamer(25504): 0:00:34.729370118 0x4ff0d600 gstelementfactory.c:364:gst_element_factory_create creating element "glcolorbalance"

I/GStreamer(25504): 0:00:34.729644777 0x4ff0d600 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x503520b0> adding pad 'sink'

I/GStreamer(25504): 0:00:34.729949952 0x4ff0d600 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x503520b0> adding pad 'src'

I/GStreamer(25504): 0:00:34.730346681 0x4ff0d600 gstutils.c:1571:gst_element_link_pads_full trying to link element gluploadelement0:src to element glcolorconvertelement0:sink

I/GStreamer(25504): 0:00:34.730590821 0x4ff0d600 gstelement.c:907:gst_element_get_static_pad found pad gluploadelement0:src

I/GStreamer(25504): 0:00:34.730804445 0x4ff0d600 gstelement.c:907:gst_element_get_static_pad found pad glcolorconvertelement0:sink

I/GStreamer(25504): 0:00:34.731018068 0x4ff0d600 gstutils.c:1444:prepare_link_maybe_ghosting gluploadelement0 and glcolorconvertelement0 in same bin, no need for ghost pads

I/GStreamer(25504): 0:00:34.731292726 0x4ff0d600 gstpad.c:2292:gst_pad_link_prepare trying to link gluploadelement0:src and glcolorconvertelement0:sink

I/GStreamer(25504): 0:00:34.732055665 0x4ff0d600 gstpad.c:4065:gst_pad_peer_query:<gluploadelement0:sink> pad has no peer

I/GStreamer(25504): 0:00:34.868011475 0x4ff0d600 gstpad.c:4065:gst_pad_peer_query:<glcolorconvertelement0:src> pad has no peer

I/GStreamer(25504): 0:00:34.884063722 0x4ff0d600 gstpad.c:2498:gst_pad_link_full linked gluploadelement0:src and glcolorconvertelement0:sink, successful

I/GStreamer(25504): 0:00:34.884307863 0x4ff0d600 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:34.885009767 0x4ff0d600 gstpad.c:5601:gst_pad_send_event_unchecked:<gluploadelement0:src> Received event on flushing pad. Discarding

I/GStreamer(25504): 0:00:34.885345461 0x4ff0d600 gstutils.c:1571:gst_element_link_pads_full trying to link element glcolorconvertelement0:src to element glcolorbalance0:sink

I/GStreamer(25504): 0:00:34.885620119 0x4ff0d600 gstelement.c:907:gst_element_get_static_pad found pad glcolorconvertelement0:src

I/GStreamer(25504): 0:00:34.885925294 0x4ff0d600 gstelement.c:907:gst_element_get_static_pad found pad glcolorbalance0:sink

I/GStreamer(25504): 0:00:34.886199953 0x4ff0d600 gstutils.c:1444:prepare_link_maybe_ghosting glcolorconvertelement0 and glcolorbalance0 in same bin, no need for ghost pads

I/GStreamer(25504): 0:00:34.886505128 0x4ff0d600 gstpad.c:2292:gst_pad_link_prepare trying to link glcolorconvertelement0:src and glcolorbalance0:sink

I/GStreamer(25504): 0:00:34.892211915 0x4ff0d600 gstpad.c:4065:gst_pad_peer_query:<gluploadelement0:sink> pad has no peer

I/GStreamer(25504): 0:00:35.017791748 0x4ff0d600 gstpad.c:4065:gst_pad_peer_query:<glcolorbalance0:src> pad has no peer

I/GStreamer(25504): 0:00:35.018157959 0x4ff0d600 gstpad.c:2498:gst_pad_link_full linked glcolorconvertelement0:src and glcolorbalance0:sink, successful

I/GStreamer(25504): 0:00:35.018585205 0x4ff0d600 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:35.019714356 0x4ff0d600 gstpad.c:5601:gst_pad_send_event_unchecked:<glcolorconvertelement0:src> Received event on flushing pad. Discarding

I/GStreamer(25504): 0:00:35.020782471 0x4ff0d600 gstelement.c:907:gst_element_get_static_pad found pad gluploadelement0:sink

I/GStreamer(25504): 0:00:35.021209717 0x4ff0d600 gstpad.c:2292:gst_pad_link_prepare trying to link sink:proxypad4 and gluploadelement0:sink

I/GStreamer(25504): 0:00:35.023254395 0x4ff0d600 gstpad.c:2498:gst_pad_link_full linked sink:proxypad4 and gluploadelement0:sink, successful

I/GStreamer(25504): 0:00:35.023986817 0x4ff0d600 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:35.024353028 0x4ff0d600 gstelement.c:659:gst_element_add_pad:<GstGLSinkBin@0x5034d0f0> adding pad 'sink'

I/GStreamer(25504): 0:00:35.033935548 0x4ff0d600 gstcontrolbinding.c:125:gst_control_binding_constructor:<glcolorbalance0> trying to put property 'contrast' under control

I/GStreamer(25504): 0:00:35.045074464 0x4ff0d600 gstcontrolbinding.c:125:gst_control_binding_constructor:<glcolorbalance0> trying to put property 'brightness' under control

I/GStreamer(25504): 0:00:35.045410158 0x4ff0d600 gstcontrolbinding.c:125:gst_control_binding_constructor:<glcolorbalance0> trying to put property 'hue' under control

I/GStreamer(25504): 0:00:35.046722414 0x4ff0d600 gstcontrolbinding.c:125:gst_control_binding_constructor:<glcolorbalance0> trying to put property 'saturation' under control

I/GStreamer(25504): 0:00:35.047607423 0x4ff0d600 gstelement.c:659:gst_element_add_pad:<GstBaseSink@0x5035ba18> adding pad 'sink'

I/GStreamer(25504): 0:00:35.052825929 0x4ff0d600 gstutils.c:1571:gst_element_link_pads_full trying to link element glcolorbalance0:src to element sink:sink

I/GStreamer(25504): 0:00:35.054260255 0x4ff0d600 gstelement.c:907:gst_element_get_static_pad found pad glcolorbalance0:src

I/GStreamer(25504): 0:00:35.054809571 0x4ff0d600 gstelement.c:907:gst_element_get_static_pad found pad sink:sink

I/GStreamer(25504): 0:00:35.060699463 0x4ff0d600 gstutils.c:1444:prepare_link_maybe_ghosting glcolorbalance0 and sink in same bin, no need for ghost pads

I/GStreamer(25504): 0:00:35.060882569 0x4ff0d600 gstpad.c:2292:gst_pad_link_prepare trying to link glcolorbalance0:src and sink:sink

I/GStreamer(25504): 0:00:35.061218262 0x4ff0d600 gstpad.c:4065:gst_pad_peer_query:<(NULL):sink> pad has no peer

I/GStreamer(25504): 0:00:35.223480225 0x4ff0d600 gstpad.c:2498:gst_pad_link_full linked glcolorbalance0:src and sink:sink, successful

I/GStreamer(25504): 0:00:35.224853516 0x4ff0d600 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:35.225341797 0x4ff0d600 gstpad.c:5601:gst_pad_send_event_unchecked:<glcolorbalance0:src> Received event on flushing pad. Discarding

I/GStreamer(25504): 0:00:35.230407715 0x4ff0d600 gstbin.c:2316:gst_bin_element_set_state:<sink> current NULL pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:35.233398437 0x4ff0d600 gstpad.c:4065:gst_pad_peer_query:<glimagesinkbin0:sink> pad has no peer

I/GStreamer(25504): 0:00:35.241821289 0x4ff0d600 gstglutils.c:528:pad_query:<sink:sink> pad peer query failed

I/GStreamer(25504): 0:00:35.242614746 0x4ff0d600 gstglutils.c:590:_gst_context_query:<sink> posting need context message

I/GStreamer(25504): 0:00:35.245788574 0x4ff0d600 gstgldisplay.c:191:gst_gl_display_new creating a display, user choice:(NULL) (platform: (NULL))

I/GStreamer(25504): 0:00:35.252899171 0x4ff0d600 gstglutils.c:653:gst_gl_display_context_propagate:<sink> posting have context (0x500b6920) message with display (0x4ff03d78)

I/GStreamer(25504): 0:00:35.260620117 0x4ff0d600 gstpad.c:4065:gst_pad_peer_query:<glimagesinkbin0:sink> pad has no peer

I/GStreamer(25504): 0:00:35.263397218 0x4ff0d600 gstglutils.c:528:pad_query:<sink:sink> pad peer query failed

I/GStreamer(25504): 0:00:35.265625001 0x4ff0d600 gstglutils.c:590:_gst_context_query:<sink> posting need context message

I/GStreamer(25504): 0:00:35.268066407 0x4ff0d600 gstglcontext.c:330:gst_gl_context_new creating a context for display <gldisplayegl0>, user choice:(null)

I/GStreamer(25504): 0:00:35.269714356 0x4ff0d600 gstglwindow.c:275:gst_gl_window_new creating a window, user choice:(null)

I/GStreamer(25504): 0:00:35.308593749 0x501b7800 gstglcontext.c:1157:gst_gl_context_create_thread:<glcontextegl0> Attempting to create opengl context. user chosen api(s) (any), compiled api support (gles2) display api (opengl opengl3 gles2)

I/GStreamer(25504): 0:00:35.310089110 0x501b7800 gstglcontext_egl.c:367:gst_gl_context_egl_create_context egl initialized, version: 1.4

I/GStreamer(25504): 0:00:35.311157226 0x501b7800 gstglcontext_egl.c:479:gst_gl_context_egl_create_context Bound OpenGL|ES

I/GStreamer(25504): 0:00:35.313598633 0x501b7800 gstglcontext_egl.c:226:gst_gl_context_egl_choose_config config set: 20, 1

I/GStreamer(25504): 0:00:35.318664550 0x501b7800 gstglcontext_egl.c:518:gst_gl_context_egl_create_context gl context created: 1341221152

I/GStreamer(25504): 0:00:35.319641113 0x501b7800 gstglcontext_egl.c:592:gst_gl_context_egl_create_context surface created

I/GStreamer(25504): 0:00:35.397186279 0x501b7800 gstglcontext.c:1168:gst_gl_context_create_thread:<glcontextegl0> created context

I/GStreamer(25504): 0:00:35.400482177 0x501b7800 gstglcontext.c:1184:gst_gl_context_create_thread:<glcontextegl0> available GL APIs: gles2

I/GStreamer(25504): 0:00:35.547302246 0x501b7800 gstglcontext.c:997:_create_context_info:<glcontextegl0> GL_VERSION: OpenGL ES 2.0

I/GStreamer(25504): 0:00:35.549041748 0x501b7800 gstglcontext.c:1000:_create_context_info:<glcontextegl0> GL_SHADING_LANGUAGE_VERSION: OpenGL ES GLSL ES 1.00

I/GStreamer(25504): 0:00:35.549255371 0x501b7800 gstglcontext.c:1002:_create_context_info:<glcontextegl0> GL_VENDOR: Broadcom

I/GStreamer(25504): 0:00:35.549713135 0x501b7800 gstglcontext.c:1004:_create_context_info:<glcontextegl0> GL_RENDERER: VideoCore IV HW

I/GStreamer(25504): 0:00:35.566711426 0x501b7800 gstgldebug.c:336:_gst_gl_debug_enable:<glcontextegl0> No debugging support available

I/GStreamer(25504): 0:00:35.566864014 0x501b7800 gstglcontext.c:1057:_unlock_create_thread:<glcontextegl0> gl thread running

I/GStreamer(25504): 0:00:35.568145752 0x4ff0d600 gstglcontext.c:963:gst_gl_context_create:<glcontextegl0> gl thread created

I/GStreamer(25504): 0:00:35.570434570 0x4ff0d600 gstelement.c:2372:gst_element_continue_state:<sink> completed state change to READY

I/GStreamer(25504): 0:00:35.570861816 0x4ff0d600 gstelement.c:2277:_priv_gst_element_state_changed:<sink> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:35.571166992 0x4ff0d600 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'sink' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:35.572204590 0x4ff0d600 gstbin.c:2316:gst_bin_element_set_state:<glcolorbalance0> current NULL pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:35.572784424 0x4ff0d600 gstglutils.c:573:_gst_context_query:<glcolorbalance0> found context (0x500b68c0) in downstream query

I/GStreamer(25504): 0:00:35.573120118 0x4ff0d600 gstglutils.c:528:pad_query:<glcolorbalance0:src> pad peer query failed

I/GStreamer(25504): 0:00:35.573669434 0x4ff0d600 gstpad.c:4065:gst_pad_peer_query:<glimagesinkbin0:sink> pad has no peer

I/GStreamer(25504): 0:00:35.573974610 0x4ff0d600 gstglutils.c:528:pad_query:<glcolorbalance0:sink> pad peer query failed

I/GStreamer(25504): 0:00:35.574249268 0x4ff0d600 gstglutils.c:590:_gst_context_query:<glcolorbalance0> posting need context message

I/GStreamer(25504): 0:00:35.574981690 0x4ff0d600 gstelement.c:2372:gst_element_continue_state:<glcolorbalance0> completed state change to READY

I/GStreamer(25504): 0:00:35.576232911 0x4ff0d600 gstelement.c:2277:_priv_gst_element_state_changed:<glcolorbalance0> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:35.577514649 0x4ff0d600 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'glcolorbalance0' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:35.578155518 0x4ff0d600 gstbin.c:2316:gst_bin_element_set_state:<glcolorconvertelement0> current NULL pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:35.579101563 0x4ff0d600 gstglutils.c:573:_gst_context_query:<glcolorconvertelement0> found context (0x500b6b20) in downstream query

I/GStreamer(25504): 0:00:35.580993652 0x4ff0d600 gstglutils.c:528:pad_query:<glcolorconvertelement0:src> pad peer query failed

I/GStreamer(25504): 0:00:35.581359863 0x4ff0d600 gstpad.c:4065:gst_pad_peer_query:<glimagesinkbin0:sink> pad has no peer

I/GStreamer(25504): 0:00:35.586334229 0x4ff0d600 gstglutils.c:528:pad_query:<glcolorconvertelement0:sink> pad peer query failed

I/GStreamer(25504): 0:00:35.587860108 0x4ff0d600 gstglutils.c:590:_gst_context_query:<glcolorconvertelement0> posting need context message

I/GStreamer(25504): 0:00:35.594146730 0x4ff0d600 gstelement.c:2372:gst_element_continue_state:<glcolorconvertelement0> completed state change to READY

I/GStreamer(25504): 0:00:35.595062257 0x4ff0d600 gstelement.c:2277:_priv_gst_element_state_changed:<glcolorconvertelement0> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:35.597381593 0x4ff0d600 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'glcolorconvertelement0' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:35.599761964 0x4ff0d600 gstbin.c:2316:gst_bin_element_set_state:<gluploadelement0> current NULL pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:35.600585938 0x4ff0d600 gstglutils.c:573:_gst_context_query:<gluploadelement0> found context (0x500b69b0) in downstream query

I/GStreamer(25504): 0:00:35.602478028 0x4ff0d600 gstglutils.c:528:pad_query:<gluploadelement0:src> pad peer query failed

I/GStreamer(25504): 0:00:35.605010987 0x4ff0d600 gstpad.c:4065:gst_pad_peer_query:<glimagesinkbin0:sink> pad has no peer

I/GStreamer(25504): 0:00:35.607330323 0x4ff0d600 gstglutils.c:528:pad_query:<gluploadelement0:sink> pad peer query failed

I/GStreamer(25504): 0:00:35.609954834 0x4ff0d600 gstglutils.c:590:_gst_context_query:<gluploadelement0> posting need context message

I/GStreamer(25504): 0:00:35.618103027 0x4ff0d600 gstelement.c:2372:gst_element_continue_state:<gluploadelement0> completed state change to READY

I/GStreamer(25504): 0:00:35.618286133 0x4ff0d600 gstelement.c:2277:_priv_gst_element_state_changed:<gluploadelement0> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:35.620544433 0x4ff0d600 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'gluploadelement0' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:35.622192382 0x4ff0d600 gstelement.c:2372:gst_element_continue_state:<glimagesinkbin0> completed state change to READY

I/GStreamer(25504): 0:00:35.622985839 0x4ff0d600 gstelement.c:2277:_priv_gst_element_state_changed:<glimagesinkbin0> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:35.627105712 0x4ff0d600 gstelement.c:907:gst_element_get_static_pad found pad glimagesinkbin0:sink

I/GStreamer(25504): 0:00:35.645294190 0x4ff0d600 gstpad.c:2048:gst_pad_unlink unlinking capsfilter0:src(0x4ff052d8) and decodepad1:proxypad2(0x4fcd0630)

I/GStreamer(25504): 0:00:35.645507813 0x4ff0d600 gstpad.c:2102:gst_pad_unlink unlinked capsfilter0:src and decodepad1:proxypad2

I/GStreamer(25504): 0:00:35.646026611 0x4ff0d600 gstelementfactory.c:364:gst_element_factory_create creating element "amcviddec-omxbrcmvideoh264decoder"

I/GStreamer(25504): 0:00:35.663421632 0x4ff0d600 gstelement.c:659:gst_element_add_pad:<GstVideoDecoder@0x5030a130> adding pad 'sink'

I/GStreamer(25504): 0:00:35.664367677 0x4ff0d600 gstelement.c:659:gst_element_add_pad:<GstVideoDecoder@0x5030a130> adding pad 'src'

I/GStreamer(25504): 0:00:35.665130616 0x4ff0d600 gstpad.c:2292:gst_pad_link_prepare trying to link capsfilter0:src and amcvideodec-omxbrcmvideoh264decoder0:sink

I/GStreamer(25504): 0:00:35.665466309 0x4ff0d600 gstpad.c:2498:gst_pad_link_full linked capsfilter0:src and amcvideodec-omxbrcmvideoh264decoder0:sink, successful

I/GStreamer(25504): 0:00:35.665924073 0x4ff0d600 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:35.668853760 0x4ff0daf0 gstelement.c:2372:gst_element_continue_state:<amcaudiodec-omxgoogleaacdecoder0> completed state change to READY

I/GStreamer(25504): 0:00:35.669464112 0x4ff0daf0 gstelement.c:2277:_priv_gst_element_state_changed:<amcaudiodec-omxgoogleaacdecoder0> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:35.669921876 0x4ff0daf0 gstpad.c:4065:gst_pad_peer_query:<amcaudiodec-omxgoogleaacdecoder0:src> pad has no peer

I/GStreamer(25504): 0:00:35.670593262 0x4ff0daf0 gstelement.c:907:gst_element_get_static_pad found pad amcaudiodec-omxgoogleaacdecoder0:src

I/GStreamer(25504): 0:00:35.671081543 0x4ff0daf0 gstpad.c:2292:gst_pad_link_prepare trying to link amcaudiodec-omxgoogleaacdecoder0:src and decodepad2:proxypad3

I/GStreamer(25504): 0:00:35.671325684 0x4ff0daf0 gstpad.c:2498:gst_pad_link_full linked amcaudiodec-omxgoogleaacdecoder0:src and decodepad2:proxypad3, successful

I/GStreamer(25504): 0:00:35.672149660 0x4ff0daf0 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:35.672454835 0x4ff0daf0 gstpad.c:5601:gst_pad_send_event_unchecked:<amcaudiodec-omxgoogleaacdecoder0:src> Received event on flushing pad. Discarding

I/GStreamer(25504): 0:00:35.672790529 0x4ff0daf0 gstelement.c:2372:gst_element_continue_state:<amcaudiodec-omxgoogleaacdecoder0> completed state change to PAUSED

I/GStreamer(25504): 0:00:35.673217775 0x4ff0daf0 gstelement.c:2277:_priv_gst_element_state_changed:<amcaudiodec-omxgoogleaacdecoder0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:35.673767091 0x4ff0daf0 gstelement.c:907:gst_element_get_static_pad found pad openslessink0:sink

I/GStreamer(25504): 0:00:35.692749025 0x4ff0d600 gstelement.c:2372:gst_element_continue_state:<amcvideodec-omxbrcmvideoh264decoder0> completed state change to READY

I/GStreamer(25504): 0:00:35.693267823 0x4ff0d600 gstelement.c:2277:_priv_gst_element_state_changed:<amcvideodec-omxbrcmvideoh264decoder0> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:35.694488526 0x4ff0d600 gstpad.c:4065:gst_pad_peer_query:<amcvideodec-omxbrcmvideoh264decoder0:src> pad has no peer

I/GStreamer(25504): 0:00:35.696014405 0x4ff0d600 gstelement.c:907:gst_element_get_static_pad found pad amcvideodec-omxbrcmvideoh264decoder0:src

I/GStreamer(25504): 0:00:35.702270508 0x4ff0daf0 gsttask.c:451:gst_task_set_lock setting stream lock 0x4ff05da4 on task 0x4ff03220

I/GStreamer(25504): 0:00:35.706359864 0x4ff0daf0 gstpad.c:5947:gst_pad_start_task:<amcaudiodec-omxgoogleaacdecoder0:src> created task 0x4ff03220

I/GStreamer(25504): 0:00:35.708343506 0x4ff0daf0 gstelement.c:907:gst_element_get_static_pad found pad openslessink0:sink

I/GStreamer(25504): 0:00:35.710510254 0x4ff0d600 gstpad.c:2292:gst_pad_link_prepare trying to link amcvideodec-omxbrcmvideoh264decoder0:src and decodepad1:proxypad2

I/GStreamer(25504): 0:00:35.710906982 0x4ff0d600 gstpad.c:2498:gst_pad_link_full linked amcvideodec-omxbrcmvideoh264decoder0:src and decodepad1:proxypad2, successful

I/GStreamer(25504): 0:00:35.713623047 0x4ff0d600 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:35.714233398 0x4ff0daf0 gstbaseparse.c:3891:gst_base_parse_set_passthrough:<aacparse0> passthrough: yes

I/GStreamer(25504): 0:00:35.714843750 0x4ff0daf0 gstbaseparse.c:4726:gst_base_parse_set_upstream_tags:<aacparse0> upstream tags: taglist, audio-codec=(string)"MPEG-4\ AAC\ audio", maximum-bitrate=(uint)200616, bitrate=(uint)191992;

I/GStreamer(25504): 0:00:35.715240478 0x4ff0d600 gstpad.c:5601:gst_pad_send_event_unchecked:<amcvideodec-omxbrcmvideoh264decoder0:src> Received event on flushing pad. Discarding

I/GStreamer(25504): 0:00:35.715728759 0x4ff0d600 gstelement.c:2372:gst_element_continue_state:<amcvideodec-omxbrcmvideoh264decoder0> completed state change to PAUSED

I/GStreamer(25504): 0:00:35.722625733 0x4ff0daf0 gstaudiodecoder.c:2281:gst_audio_decoder_sink_eventfunc:<amcaudiodec-omxgoogleaacdecoder0> upstream stream tags: taglist, audio-codec=(string)"MPEG-4\ AAC\ audio", maximum-bitrate=(uint)200616, bitrate=(uint)191992;

I/GStreamer(25504): 0:00:35.723022461 0x4ff0d600 gstelement.c:2277:_priv_gst_element_state_changed:<amcvideodec-omxbrcmvideoh264decoder0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

V/GStreamer(25504): 0:00:35.724060059 0x4ff0d600 gstvideodecoder.c:1057:gst_video_decoder_drain_out:<amcvideodec-omxbrcmvideoh264decoder0> Sub-class should implement drain()

I/GStreamer(25504): 0:00:35.724822998 0x4ff0d600 gstelement.c:907:gst_element_get_static_pad found pad glimagesinkbin0:sink

I/GStreamer(25504): 0:00:35.756591798 0x4ff0d600 gstelement.c:907:gst_element_get_static_pad found pad glimagesinkbin0:sink

I/GStreamer(25504): 0:00:35.763916016 0x4ff0d600 gstevent.c:679:gst_event_new_caps creating caps event video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)1280, height=(int)720, interlace-mode=(string)progressive, multiview-mode=(string)side-by-side, multiview-flags=(GstVideoMultiviewFlagsSet)1:ffffffff:+right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono, pixel-aspect-ratio=(fraction)2/1, colorimetry=(string)sRGB, framerate=(fraction)30/1

I/GStreamer(25504): 0:00:35.764434815 0x4ff0d600 gstpad.c:4065:gst_pad_peer_query:<'':decodepad1> pad has no peer

I/GStreamer(25504): 0:00:35.764953614 0x4ff0d600 gstpad.c:4065:gst_pad_peer_query:<'':decodepad1> pad has no peer

I/GStreamer(25504): 0:00:35.767761231 0x4ff0d600 gstelement.c:907:gst_element_get_static_pad found pad glimagesinkbin0:sink

I/GStreamer(25504): 0:00:35.769226075 0x4ff0daf0 gstaudiodecoder.c:2281:gst_audio_decoder_sink_eventfunc:<amcaudiodec-omxgoogleaacdecoder0> upstream stream tags: taglist, audio-codec=(string)"MPEG-4\ AAC", maximum-bitrate=(uint)200616, bitrate=(uint)191992;

I/GStreamer(25504): 0:00:35.770233155 0x4ff0d600 gstglutils.c:573:_gst_context_query:<amcvideodec-omxbrcmvideoh264decoder0> found context (0x500b6830) in downstream query

I/GStreamer(25504): 0:00:35.770629883 0x4ff0d600 gstelement.c:907:gst_element_get_static_pad found pad glimagesinkbin0:sink

I/GStreamer(25504): 0:00:35.770996094 0x4ff0d600 gstpad.c:4065:gst_pad_peer_query:<'':decodepad1> pad has no peer

I/GStreamer(25504): 0:00:35.772705078 0x4ff0d600 gstglutils.c:528:pad_query:<amcvideodec-omxbrcmvideoh264decoder0:src> pad peer query failed

I/GStreamer(25504): 0:00:35.773132324 0x4ff0d600 gstglutils.c:528:pad_query:<amcvideodec-omxbrcmvideoh264decoder0:sink> pad peer query failed

I/GStreamer(25504): 0:00:35.774963379 0x4ff0d600 gstglutils.c:590:_gst_context_query:<amcvideodec-omxbrcmvideoh264decoder0> posting need context message

I/GStreamer(25504): 0:00:35.776031494 0x4ff0d600 gstelement.c:907:gst_element_get_static_pad found pad glimagesinkbin0:sink

I/GStreamer(25504): 0:00:35.778259277 0x4ff0d600 gstamcvideodec.c:1974:gst_amc_video_dec_set_format:<amcvideodec-omxbrcmvideoh264decoder0> GL output: enabled

I/GStreamer(25504): 0:00:35.814239502 0x501bd8c0 gstevent.c:679:gst_event_new_caps creating caps event audio/x-raw, format=(string)S16LE, layout=(string)interleaved, rate=(int)44100, channels=(int)2, channel-mask=(bitmask)0x0000000000000003

I/GStreamer(25504): 0:00:35.814636231 0x501bd8c0 gstpad.c:4065:gst_pad_peer_query:<'':decodepad2> pad has no peer

I/GStreamer(25504): 0:00:36.106170654 0x4ff0d600 gsttask.c:451:gst_task_set_lock setting stream lock 0x50350afc on task 0x503bd828

I/GStreamer(25504): 0:00:36.106658935 0x4ff0d600 gstpad.c:5947:gst_pad_start_task:<amcvideodec-omxbrcmvideoh264decoder0:src> created task 0x503bd828

V/GStreamer(25504): 0:00:36.115570068 0x4ff0d600 gstvideodecoder.c:1057:gst_video_decoder_drain_out:<amcvideodec-omxbrcmvideoh264decoder0> Sub-class should implement drain()

I/GStreamer(25504): 0:00:36.116607666 0x4ff0d600 gstelement.c:907:gst_element_get_static_pad found pad glimagesinkbin0:sink

I/GStreamer(25504): 0:00:36.136230469 0x4ff0d600 gstbaseparse.c:3953:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.033333333, 0:00:00.033333333

I/GStreamer(25504): 0:00:36.137573243 0x4ff0d600 gstvideodecoder.c:1432:gst_video_decoder_sink_event_default:<amcvideodec-omxbrcmvideoh264decoder0> upstream tags: taglist, video-codec=(string)"H.264\ /\ AVC", maximum-bitrate=(uint)4606304, bitrate=(uint)1656640;

I/GStreamer(25504): 0:00:36.138427735 0x4ff0d600 gstvideodecoder.c:1432:gst_video_decoder_sink_event_default:<amcvideodec-omxbrcmvideoh264decoder0> upstream tags: taglist, video-codec=(string)"H.264\ \(High\ Profile\)", maximum-bitrate=(uint)4606304, bitrate=(uint)1656640;

I/GStreamer(25504): 0:00:36.139495850 0x4ff0d600 gstelement.c:907:gst_element_get_static_pad found pad glimagesinkbin0:sink

I/GStreamer(25504): 0:00:36.156250001 0x4ff0d600 gstbasetransform.c:1367:gst_base_transform_setcaps:<capsfilter0> reuse caps

E/GStreamer(25504): 0:00:36.375061036 0x4ff0d600 gstamcvideodec.c:2166:gst_amc_video_dec_handle_frame:<amcvideodec-omxbrcmvideoh264decoder0> Failed to dequeue input buffer

W/GStreamer(25504): 0:00:36.375366211 0x4ff0d600 gstamcvideodec.c:2284:gst_amc_video_dec_handle_frame:<amcvideodec-omxbrcmvideoh264decoder0> error: Failed to call Java method: java.lang.IllegalStateException

W/GStreamer(25504): java.lang.IllegalStateException

W/GStreamer(25504): 	at android.media.MediaCodec.dequeueInputBuffer(Native Method)

W/GStreamer(25504): 	at dalvik.system.NativeStart.run(Native Method)

W/GStreamer(25504): 

I/GStreamer(25504): 0:00:36.413299561 0x4ff0d600 gstelement.c:1879:gst_element_message_full:<amcvideodec-omxbrcmvideoh264decoder0> posting message: GStreamer encountered a general supporting library error.

I/GStreamer(25504): 0:00:36.413665772 0x4ff0d600 gstelement.c:1902:gst_element_message_full:<amcvideodec-omxbrcmvideoh264decoder0> posted error message: GStreamer encountered a general supporting library error.

E/GStreamer(25504): 0:00:36.415039063 0x500b6b80 gstamcvideodec.c:1293:gst_amc_video_dec_loop:<amcvideodec-omxbrcmvideoh264decoder0> Failure dequeueing output buffer

W/GStreamer(25504): 0:00:36.416778564 0x4fdf0f80 qtdemux.c:5420:gst_qtdemux_loop:<qtdemux0> error: streaming stopped, reason error

W/GStreamer(25504): 0:00:36.416290283 0x500b6b80 gstamcvideodec.c:1533:gst_amc_video_dec_loop:<amcvideodec-omxbrcmvideoh264decoder0> error: Failed to call Java method: java.lang.IllegalStateException

W/GStreamer(25504): java.lang.IllegalStateException

W/GStreamer(25504): 	at android.media.MediaCodec.dequeueOutputBuffer(Native Method)

W/GStreamer(25504): 	at dalvik.system.NativeStart.run(Native Method)

W/GStreamer(25504): 

I/GStreamer(25504): 0:00:36.417724609 0x4ff0d600 gsttask.c:316:gst_task_func:<multiqueue0:src_0> Task going to paused

I/GStreamer(25504): 0:00:36.418090820 0x500b6b80 gstelement.c:1879:gst_element_message_full:<amcvideodec-omxbrcmvideoh264decoder0> posting message: GStreamer encountered a general supporting library error.

I/GStreamer(25504): 0:00:36.419158935 0x4fdf0f80 gstelement.c:1879:gst_element_message_full:<qtdemux0> posting message: GStreamer encountered a general stream error.

I/GStreamer(25504): 0:00:36.419860840 0x500b6b80 gstelement.c:1902:gst_element_message_full:<amcvideodec-omxbrcmvideoh264decoder0> posted error message: GStreamer encountered a general supporting library error.

I/GStreamer(25504): 0:00:36.420257568 0x4fdf0f80 gstelement.c:1902:gst_element_message_full:<qtdemux0> posted error message: GStreamer encountered a general stream error.

I/GStreamer(25504): 0:00:36.433410644 0x500b6b80 gstelement.c:659:gst_element_add_pad:<decodebin0> adding pad 'src_0'

I/GStreamer(25504): 0:00:36.436065674 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link decodebin0:src_0 and src_0:proxypad5

I/GStreamer(25504): 0:00:36.436553955 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked decodebin0:src_0 and src_0:proxypad5, successful

I/GStreamer(25504): 0:00:36.436828613 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.437835693 0x500b6b80 gstelement.c:659:gst_element_add_pad:<uridecodebin0> adding pad 'src_0'

I/GStreamer(25504): 0:00:36.438171386 0x500b6b80 gstelementfactory.c:364:gst_element_factory_create creating element "input-selector"

I/GStreamer(25504): 0:00:36.457092286 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstInputSelector@0x50435810> adding pad 'src'

I/GStreamer(25504): 0:00:36.457641602 0x500b6b80 gstelement.c:2347:gst_element_continue_state:<inputselector0> committing state from NULL to READY, pending PAUSED, next PAUSED

I/GStreamer(25504): 0:00:36.460601807 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<inputselector0> notifying about state-changed NULL to READY (PAUSED pending)

I/GStreamer(25504): 0:00:36.461700441 0x500b6b80 gstelement.c:2354:gst_element_continue_state:<inputselector0> continue state change READY to PAUSED, final PAUSED

I/GStreamer(25504): 0:00:36.462768556 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<inputselector0> completed state change to PAUSED

I/GStreamer(25504): 0:00:36.463562013 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<inputselector0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.464752198 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad inputselector0:src

I/GStreamer(25504): 0:00:36.465515138 0x500b6b80 gstelement.c:659:gst_element_add_pad:<inputselector0> adding pad 'sink_0'

I/GStreamer(25504): 0:00:36.466461182 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link uridecodebin0:src_0 and inputselector0:sink_0

I/GStreamer(25504): 0:00:36.467895509 0x500b6b80 gstpad.c:4065:gst_pad_peer_query:<inputselector0:src> pad has no peer

I/GStreamer(25504): 0:00:36.468231202 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked uridecodebin0:src_0 and inputselector0:sink_0, successful

I/GStreamer(25504): 0:00:36.468627930 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.472412111 0x500b6b80 gstdecodebin2.c:4661:gst_decode_bin_expose:<decodebin0:src_0> added new decoded pad

I/GStreamer(25504): 0:00:36.473449708 0x500b6b80 gstelement.c:659:gst_element_add_pad:<decodebin0> adding pad 'src_1'

I/GStreamer(25504): 0:00:36.476684571 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link decodebin0:src_1 and src_1:proxypad6

I/GStreamer(25504): 0:00:36.477172853 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked decodebin0:src_1 and src_1:proxypad6, successful

I/GStreamer(25504): 0:00:36.477539064 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.478027345 0x500b6b80 gstelement.c:659:gst_element_add_pad:<uridecodebin0> adding pad 'src_1'

I/GStreamer(25504): 0:00:36.480895997 0x500b6b80 gstelementfactory.c:364:gst_element_factory_create creating element "input-selector"

I/GStreamer(25504): 0:00:36.481689455 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstInputSelector@0x50435908> adding pad 'src'

I/GStreamer(25504): 0:00:36.482147219 0x500b6b80 gstelement.c:2347:gst_element_continue_state:<inputselector1> committing state from NULL to READY, pending PAUSED, next PAUSED

I/GStreamer(25504): 0:00:36.482635500 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<inputselector1> notifying about state-changed NULL to READY (PAUSED pending)

I/GStreamer(25504): 0:00:36.482971193 0x500b6b80 gstelement.c:2354:gst_element_continue_state:<inputselector1> continue state change READY to PAUSED, final PAUSED

I/GStreamer(25504): 0:00:36.483459474 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<inputselector1> completed state change to PAUSED

I/GStreamer(25504): 0:00:36.483886720 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<inputselector1> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.484252931 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad inputselector1:src

I/GStreamer(25504): 0:00:36.484802248 0x500b6b80 gstelement.c:659:gst_element_add_pad:<inputselector1> adding pad 'sink_0'

I/GStreamer(25504): 0:00:36.485290529 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link uridecodebin0:src_1 and inputselector1:sink_0

I/GStreamer(25504): 0:00:36.486083986 0x500b6b80 gstpad.c:4065:gst_pad_peer_query:<inputselector1:src> pad has no peer

I/GStreamer(25504): 0:00:36.486663820 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked uridecodebin0:src_1 and inputselector1:sink_0, successful

I/GStreamer(25504): 0:00:36.490203859 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.490966798 0x500b6b80 gstdecodebin2.c:4661:gst_decode_bin_expose:<decodebin0:src_1> added new decoded pad

I/GStreamer(25504): 0:00:36.491333009 0x500b6b80 gstelementfactory.c:362:gst_element_factory_create creating element "tee" named "audiotee"

I/GStreamer(25504): 0:00:36.494812013 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstTee@0x4fbc2ea0> adding pad 'sink'

I/GStreamer(25504): 0:00:36.495910645 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad audiotee:sink

I/GStreamer(25504): 0:00:36.496582032 0x500b6b80 gstelement.c:2347:gst_element_continue_state:<audiotee> committing state from NULL to READY, pending PAUSED, next PAUSED

I/GStreamer(25504): 0:00:36.497253419 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<audiotee> notifying about state-changed NULL to READY (PAUSED pending)

I/GStreamer(25504): 0:00:36.498321534 0x500b6b80 gstelement.c:2354:gst_element_continue_state:<audiotee> continue state change READY to PAUSED, final PAUSED

I/GStreamer(25504): 0:00:36.498626710 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<audiotee> completed state change to PAUSED

I/GStreamer(25504): 0:00:36.500030518 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<audiotee> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.500793458 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link audio_sink:proxypad7 and audiotee:sink

I/GStreamer(25504): 0:00:36.501312256 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked audio_sink:proxypad7 and audiotee:sink, successful

I/GStreamer(25504): 0:00:36.501861573 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.502471924 0x500b6b80 gstelement.c:659:gst_element_add_pad:<playsink> adding pad 'audio_sink'

I/GStreamer(25504): 0:00:36.503265381 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link inputselector1:src and playsink:audio_sink

I/GStreamer(25504): 0:00:36.504425049 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked inputselector1:src and playsink:audio_sink, successful

I/GStreamer(25504): 0:00:36.504882813 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.507324219 0x500b6b80 gstelement.c:659:gst_element_add_pad:<playsink> adding pad 'video_sink'

I/GStreamer(25504): 0:00:36.507873535 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link inputselector0:src and playsink:video_sink

I/GStreamer(25504): 0:00:36.508209229 0x500b6b80 gstpad.c:4065:gst_pad_peer_query:<video_sink:proxypad8> pad has no peer

I/GStreamer(25504): 0:00:36.509399414 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked inputselector0:src and playsink:video_sink, successful

I/GStreamer(25504): 0:00:36.509826660 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.510467529 0x500b6b80 gstplaybin2.c:3742:no_more_pads_cb:<playbin> setting custom audio sink <openslessink0>

I/GStreamer(25504): 0:00:36.511810303 0x500b6b80 gstplaybin2.c:3749:no_more_pads_cb:<playbin> setting custom video sink <glimagesinkbin0>

I/GStreamer(25504): 0:00:36.513122558 0x4fdf0f80 gsttask.c:316:gst_task_func:<qtdemux0:sink> Task going to paused

I/GStreamer(25504): 0:00:36.519104003 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<sink> current READY pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:36.519287109 0x500b6b80 gstbin.c:2433:gst_bin_element_set_state:<sink> skipping transition from READY to  READY

I/GStreamer(25504): 0:00:36.519561767 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'sink' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:36.519989013 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<glcolorbalance0> current READY pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:36.520263671 0x500b6b80 gstbin.c:2433:gst_bin_element_set_state:<glcolorbalance0> skipping transition from READY to  READY

I/GStreamer(25504): 0:00:36.520629882 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'glcolorbalance0' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:36.520904540 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<glcolorconvertelement0> current READY pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:36.521331786 0x500b6b80 gstbin.c:2433:gst_bin_element_set_state:<glcolorconvertelement0> skipping transition from READY to  READY

I/GStreamer(25504): 0:00:36.521759034 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'glcolorconvertelement0' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:36.522216797 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<gluploadelement0> current READY pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:36.522552491 0x500b6b80 gstbin.c:2433:gst_bin_element_set_state:<gluploadelement0> skipping transition from READY to  READY

I/GStreamer(25504): 0:00:36.522857666 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'gluploadelement0' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:36.523345948 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<glimagesinkbin0> completed state change to READY

I/GStreamer(25504): 0:00:36.525726318 0x500b6b80 gstelementfactory.c:362:gst_element_factory_create creating element "bin" named "vbin"

I/GStreamer(25504): 0:00:36.526977539 0x500b6b80 gstelementfactory.c:362:gst_element_factory_create creating element "queue" named "vqueue"

I/GStreamer(25504): 0:00:36.530120849 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstQueue@0x4fcc69c0> adding pad 'sink'

I/GStreamer(25504): 0:00:36.530731201 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstQueue@0x4fcc69c0> adding pad 'src'

I/GStreamer(25504): 0:00:36.532165528 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstPlaySinkConvertBin@0x504366c0> adding pad 'sink'

I/GStreamer(25504): 0:00:36.532714844 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstPlaySinkConvertBin@0x504366c0> adding pad 'src'

I/GStreamer(25504): 0:00:36.536010743 0x500b6b80 gstelementfactory.c:362:gst_element_factory_create creating element "identity" named "identity"

I/GStreamer(25504): 0:00:36.536682129 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x5043e0a8> adding pad 'sink'

I/GStreamer(25504): 0:00:36.537109375 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x5043e0a8> adding pad 'src'

I/GStreamer(25504): 0:00:36.537841797 0x500b6b80 gstelementfactory.c:362:gst_element_factory_create creating element "videobalance" named "videobalance"

I/GStreamer(25504): 0:00:36.538360596 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x5043d090> adding pad 'sink'

I/GStreamer(25504): 0:00:36.538696289 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x5043d090> adding pad 'src'

I/GStreamer(25504): 0:00:36.539215088 0x500b6b80 gstplaysinkconvertbin.c:579:gst_play_sink_convert_bin_cache_converter_caps:<GstPlaySinkVideoConvert@0x504366c0> No conversion elements

I/GStreamer(25504): 0:00:36.539642334 0x500b6b80 gstelementfactory.c:362:gst_element_factory_create creating element "videoconvert" named "conv"

I/GStreamer(25504): 0:00:36.540161133 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x50440210> adding pad 'sink'

I/GStreamer(25504): 0:00:36.540466309 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x50440210> adding pad 'src'

I/GStreamer(25504): 0:00:36.540954590 0x500b6b80 gstelementfactory.c:362:gst_element_factory_create creating element "videoscale" named "scale"

I/GStreamer(25504): 0:00:36.541992189 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x504680e0> adding pad 'sink'

I/GStreamer(25504): 0:00:36.544616700 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x504680e0> adding pad 'src'

I/GStreamer(25504): 0:00:36.553619386 0x500b6b80 gstutils.c:1571:gst_element_link_pads_full trying to link element conv:src to element scale:sink

I/GStreamer(25504): 0:00:36.554168702 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad conv:src

I/GStreamer(25504): 0:00:36.554351808 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad scale:sink

I/GStreamer(25504): 0:00:36.554595949 0x500b6b80 gstutils.c:1444:prepare_link_maybe_ghosting conv and scale in same bin, no need for ghost pads

I/GStreamer(25504): 0:00:36.554840089 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link conv:src and scale:sink

I/GStreamer(25504): 0:00:36.555206300 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked conv:src and scale:sink, successful

I/GStreamer(25504): 0:00:36.555358888 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.555511476 0x500b6b80 gstpad.c:5601:gst_pad_send_event_unchecked:<conv:src> Received event on flushing pad. Discarding

I/GStreamer(25504): 0:00:36.555847169 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad conv:sink

I/GStreamer(25504): 0:00:36.556030275 0x500b6b80 gstpad.c:4065:gst_pad_peer_query:<scale:src> pad has no peer

I/GStreamer(25504): 0:00:36.650085451 0x500b6b80 gstplaysinkconvertbin.c:592: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, v210, v216, NV12, NV21, NV16, NV61, 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, A420_10LE, A420_10BE, A422_10LE, A422_10BE, A444_10LE, A444_10BE }, 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, v210, v216, NV12, NV21, NV16, NV61, NV24, GRAY8, GRAY16_BE, GRAY16_LE, v308, RGB16, BGR16, RGB15, BGR15, UYVP, A420, RGB8P, YUV9, YVU9, IYU1, ARGB64, AYUV64, r210, I420_10LE, I4

I/GStreamer(25504): 0:00:36.653198243 0x500b6b80 gstutils.c:1571:gst_element_link_pads_full trying to link element vqueue:src to element vconv:sink

I/GStreamer(25504): 0:00:36.655761720 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad vqueue:src

I/GStreamer(25504): 0:00:36.656768800 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad vconv:sink

I/GStreamer(25504): 0:00:36.657196046 0x500b6b80 gstutils.c:1444:prepare_link_maybe_ghosting vqueue and vconv in same bin, no need for ghost pads

I/GStreamer(25504): 0:00:36.658477784 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link vqueue:src and vconv:sink

I/GStreamer(25504): 0:00:36.659881593 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked vqueue:src and vconv:sink, successful

I/GStreamer(25504): 0:00:36.660308839 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.661895753 0x500b6b80 gstpad.c:5601:gst_pad_send_event_unchecked:<vqueue:src> Received event on flushing pad. Discarding

I/GStreamer(25504): 0:00:36.663909913 0x500b6b80 gstutils.c:1571:gst_element_link_pads_full trying to link element vconv:src to element glimagesinkbin0:(any)

I/GStreamer(25504): 0:00:36.665924073 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad vconv:src

I/GStreamer(25504): 0:00:36.667419434 0x500b6b80 gstutils.c:932:gst_pad_check_link trying to link vconv:src and glimagesinkbin0:sink

I/GStreamer(25504): 0:00:36.667816162 0x500b6b80 gstpad.c:4065:gst_pad_peer_query:<vqueue:sink> pad has no peer

I/GStreamer(25504): 0:00:36.680206298 0x500b6b80 gstutils.c:1444:prepare_link_maybe_ghosting vconv and glimagesinkbin0 in same bin, no need for ghost pads

I/GStreamer(25504): 0:00:36.680877685 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link vconv:src and glimagesinkbin0:sink

I/GStreamer(25504): 0:00:36.681365966 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked vconv:src and glimagesinkbin0:sink, successful

I/GStreamer(25504): 0:00:36.682495118 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.683044434 0x500b6b80 gstpad.c:5601:gst_pad_send_event_unchecked:<vconv:src> Received event on flushing pad. Discarding

I/GStreamer(25504): 0:00:36.683715821 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad vqueue:sink

I/GStreamer(25504): 0:00:36.684722901 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link sink:proxypad11 and vqueue:sink

I/GStreamer(25504): 0:00:36.685180664 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked sink:proxypad11 and vqueue:sink, successful

I/GStreamer(25504): 0:00:36.685485840 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.685943604 0x500b6b80 gstelement.c:659:gst_element_add_pad:<vbin> adding pad 'sink'

I/GStreamer(25504): 0:00:36.686706543 0x500b6b80 gstelement.c:659:gst_element_add_pad:<streamsynchronizer0> adding pad 'src_0'

I/GStreamer(25504): 0:00:36.687805176 0x500b6b80 gstelement.c:659:gst_element_add_pad:<streamsynchronizer0> adding pad 'sink_0'

I/GStreamer(25504): 0:00:36.688140869 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link video_sink:proxypad8 and streamsynchronizer0:sink_0

I/GStreamer(25504): 0:00:36.688537598 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked video_sink:proxypad8 and streamsynchronizer0:sink_0, successful

I/GStreamer(25504): 0:00:36.688781738 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.689849853 0x500b6b80 gstelementfactory.c:362:gst_element_factory_create creating element "bin" named "vdbin"

I/GStreamer(25504): 0:00:36.690521240 0x500b6b80 gstelementfactory.c:362:gst_element_factory_create creating element "videoconvert" named "vdconv"

I/GStreamer(25504): 0:00:36.692382813 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x50440550> adding pad 'sink'

I/GStreamer(25504): 0:00:36.693756104 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x50440550> adding pad 'src'

I/GStreamer(25504): 0:00:36.694366456 0x500b6b80 gstelementfactory.c:362:gst_element_factory_create creating element "deinterlace" named "deinterlace"

I/GStreamer(25504): 0:00:36.698486328 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstDeinterlace@0x5046b800> adding pad 'sink'

I/GStreamer(25504): 0:00:36.699554444 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstDeinterlace@0x5046b800> adding pad 'src'

I/GStreamer(25504): 0:00:36.700775147 0x500b6b80 gstutils.c:1571:gst_element_link_pads_full trying to link element vdconv:src to element deinterlace:sink

I/GStreamer(25504): 0:00:36.702545167 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad vdconv:src

I/GStreamer(25504): 0:00:36.703613282 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad deinterlace:sink

I/GStreamer(25504): 0:00:36.704406739 0x500b6b80 gstutils.c:1444:prepare_link_maybe_ghosting vdconv and deinterlace in same bin, no need for ghost pads

I/GStreamer(25504): 0:00:36.705200196 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link vdconv:src and deinterlace:sink

I/GStreamer(25504): 0:00:36.705871583 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked vdconv:src and deinterlace:sink, successful

I/GStreamer(25504): 0:00:36.706573487 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.717315675 0x500b6b80 gstpad.c:5601:gst_pad_send_event_unchecked:<vdconv:src> Received event on flushing pad. Discarding

I/GStreamer(25504): 0:00:36.718566896 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad vdconv:sink

I/GStreamer(25504): 0:00:36.719573976 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link sink:proxypad12 and vdconv:sink

I/GStreamer(25504): 0:00:36.720123292 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked sink:proxypad12 and vdconv:sink, successful

I/GStreamer(25504): 0:00:36.722015382 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.722564698 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad deinterlace:src

I/GStreamer(25504): 0:00:36.723144532 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link deinterlace:src and src:proxypad13

I/GStreamer(25504): 0:00:36.724517823 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked deinterlace:src and src:proxypad13, successful

I/GStreamer(25504): 0:00:36.725250245 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.725555421 0x500b6b80 gstpad.c:5601:gst_pad_send_event_unchecked:<deinterlace:src> Received event on flushing pad. Discarding

I/GStreamer(25504): 0:00:36.726654053 0x500b6b80 gstelement.c:659:gst_element_add_pad:<vdbin> adding pad 'sink'

I/GStreamer(25504): 0:00:36.726989747 0x500b6b80 gstelement.c:659:gst_element_add_pad:<vdbin> adding pad 'src'

I/GStreamer(25504): 0:00:36.727691651 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<deinterlace> current NULL pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:36.728637696 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<deinterlace> completed state change to READY

I/GStreamer(25504): 0:00:36.729553223 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<deinterlace> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.729980469 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<vdbin> child 'deinterlace' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:36.730255127 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<vdconv> current NULL pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:36.731933594 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<vdconv> completed state change to READY

I/GStreamer(25504): 0:00:36.740264892 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<vdconv> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.743499755 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<vdbin> child 'vdconv' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:36.744232177 0x500b6b80 gstelement.c:2347:gst_element_continue_state:<vdbin> committing state from NULL to READY, pending PAUSED, next PAUSED

I/GStreamer(25504): 0:00:36.744995117 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<vdbin> notifying about state-changed NULL to READY (PAUSED pending)

I/GStreamer(25504): 0:00:36.747406005 0x500b6b80 gstelement.c:2354:gst_element_continue_state:<vdbin> continue state change READY to PAUSED, final PAUSED

I/GStreamer(25504): 0:00:36.747985839 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<deinterlace> current READY pending VOID_PENDING, desired next PAUSED

I/GStreamer(25504): 0:00:36.748291015 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<deinterlace> completed state change to PAUSED

I/GStreamer(25504): 0:00:36.748565673 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<deinterlace> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.749603271 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<vdbin> child 'deinterlace' changed state to 3(PAUSED) successfully

I/GStreamer(25504): 0:00:36.750061034 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<vdconv> current READY pending VOID_PENDING, desired next PAUSED

I/GStreamer(25504): 0:00:36.750976562 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<vdconv> completed state change to PAUSED

I/GStreamer(25504): 0:00:36.751739502 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<vdconv> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.758026123 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<vdbin> child 'vdconv' changed state to 3(PAUSED) successfully

I/GStreamer(25504): 0:00:36.758270263 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<vdbin> completed state change to PAUSED

I/GStreamer(25504): 0:00:36.759033203 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<vdbin> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.759613037 0x500b6b80 gstpad.c:2048:gst_pad_unlink unlinking streamsynchronizer0:src_0(0x50442410) and vbin:sink(0x505c2a40)

I/GStreamer(25504): 0:00:36.764007569 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link streamsynchronizer0:src_0 and vdbin:sink

I/GStreamer(25504): 0:00:36.765258789 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked streamsynchronizer0:src_0 and vdbin:sink, successful

I/GStreamer(25504): 0:00:36.765686035 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.767028809 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<glimagesinkbin0> current READY pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:36.772216798 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<sink> current READY pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:36.775054932 0x500b6b80 gstbin.c:2433:gst_bin_element_set_state:<sink> skipping transition from READY to  READY

I/GStreamer(25504): 0:00:36.777008057 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'sink' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:36.777496338 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<glcolorbalance0> current READY pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:36.777770996 0x500b6b80 gstbin.c:2433:gst_bin_element_set_state:<glcolorbalance0> skipping transition from READY to  READY

I/GStreamer(25504): 0:00:36.778167725 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'glcolorbalance0' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:36.778686524 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<glcolorconvertelement0> current READY pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:36.779846192 0x500b6b80 gstbin.c:2433:gst_bin_element_set_state:<glcolorconvertelement0> skipping transition from READY to  READY

I/GStreamer(25504): 0:00:36.780517578 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'glcolorconvertelement0' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:36.780822754 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<gluploadelement0> current READY pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:36.787139893 0x500b6b80 gstbin.c:2433:gst_bin_element_set_state:<gluploadelement0> skipping transition from READY to  READY

I/GStreamer(25504): 0:00:36.788208009 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'gluploadelement0' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:36.788696290 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<glimagesinkbin0> completed state change to READY

I/GStreamer(25504): 0:00:36.788970948 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<vbin> child 'glimagesinkbin0' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:36.789306641 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<vconv> current NULL pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:36.789794922 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<scale> current NULL pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:36.792266847 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<scale> completed state change to READY

I/GStreamer(25504): 0:00:36.792755128 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<scale> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.794158937 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<vconv> child 'scale' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:36.794555665 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<conv> current NULL pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:36.795013429 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<conv> completed state change to READY

I/GStreamer(25504): 0:00:36.795318605 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<conv> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.796020509 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<vconv> child 'conv' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:36.796356202 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<identity> current NULL pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:36.796813966 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<identity> completed state change to READY

I/GStreamer(25504): 0:00:36.797180177 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<identity> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.799072267 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<vconv> child 'identity' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:36.800262452 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<vconv> completed state change to READY

I/GStreamer(25504): 0:00:36.800994874 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<vconv> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.802124025 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<vbin> child 'vconv' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:36.802642824 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<vqueue> current NULL pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:36.804504396 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<vqueue> completed state change to READY

I/GStreamer(25504): 0:00:36.805297853 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<vqueue> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.806640627 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<vbin> child 'vqueue' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:36.807617189 0x500b6b80 gstelement.c:2347:gst_element_continue_state:<vbin> committing state from NULL to READY, pending PAUSED, next PAUSED

I/GStreamer(25504): 0:00:36.808380128 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<vbin> notifying about state-changed NULL to READY (PAUSED pending)

I/GStreamer(25504): 0:00:36.809814454 0x500b6b80 gstelement.c:2354:gst_element_continue_state:<vbin> continue state change READY to PAUSED, final PAUSED

I/GStreamer(25504): 0:00:36.811004640 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<glimagesinkbin0> current READY pending VOID_PENDING, desired next PAUSED

I/GStreamer(25504): 0:00:36.812408448 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<sink> current READY pending VOID_PENDING, desired next PAUSED

I/GStreamer(25504): 0:00:36.813415528 0x500b6b80 gstbin.c:2770:gst_bin_change_state_func:<glimagesinkbin0> child 'sink' is changing state asynchronously to PAUSED

I/GStreamer(25504): 0:00:36.813903810 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<glcolorbalance0> current READY pending VOID_PENDING, desired next PAUSED

I/GStreamer(25504): 0:00:36.815521241 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<glcolorbalance0> completed state change to PAUSED

I/GStreamer(25504): 0:00:36.817199708 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<glcolorbalance0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.818389893 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'glcolorbalance0' changed state to 3(PAUSED) successfully

I/GStreamer(25504): 0:00:36.824157715 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<glcolorconvertelement0> current READY pending VOID_PENDING, desired next PAUSED

I/GStreamer(25504): 0:00:36.824401856 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<glcolorconvertelement0> completed state change to PAUSED

I/GStreamer(25504): 0:00:36.827545166 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<glcolorconvertelement0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.828186035 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'glcolorconvertelement0' changed state to 3(PAUSED) successfully

I/GStreamer(25504): 0:00:36.828643799 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<gluploadelement0> current READY pending VOID_PENDING, desired next PAUSED

I/GStreamer(25504): 0:00:36.828948975 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<gluploadelement0> completed state change to PAUSED

I/GStreamer(25504): 0:00:36.829345703 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<gluploadelement0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.829833984 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'gluploadelement0' changed state to 3(PAUSED) successfully

I/GStreamer(25504): 0:00:36.830230713 0x500b6b80 gstbin.c:2770:gst_bin_change_state_func:<vbin> child 'glimagesinkbin0' is changing state asynchronously to PAUSED

I/GStreamer(25504): 0:00:36.830688477 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<vconv> current READY pending VOID_PENDING, desired next PAUSED

I/GStreamer(25504): 0:00:36.833251953 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad identity:sink

I/GStreamer(25504): 0:00:36.833618164 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link sink:proxypad9 and identity:sink

I/GStreamer(25504): 0:00:36.833892822 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked sink:proxypad9 and identity:sink, successful

I/GStreamer(25504): 0:00:36.834381103 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.834838867 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad identity:src

I/GStreamer(25504): 0:00:36.835296631 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link identity:src and src:proxypad10

I/GStreamer(25504): 0:00:36.835601806 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked identity:src and src:proxypad10, successful

I/GStreamer(25504): 0:00:36.836120605 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.840789794 0x500b6b80 gstpad.c:5601:gst_pad_send_event_unchecked:<identity:src> Received event on flushing pad. Discarding

I/GStreamer(25504): 0:00:36.843872071 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<scale> current READY pending VOID_PENDING, desired next PAUSED

I/GStreamer(25504): 0:00:36.844451905 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<scale> completed state change to PAUSED

I/GStreamer(25504): 0:00:36.844787598 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<scale> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.845855713 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<vconv> child 'scale' changed state to 3(PAUSED) successfully

I/GStreamer(25504): 0:00:36.846282959 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<conv> current READY pending VOID_PENDING, desired next PAUSED

I/GStreamer(25504): 0:00:36.846588135 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<conv> completed state change to PAUSED

I/GStreamer(25504): 0:00:36.847198486 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<conv> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.852966309 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<vconv> child 'conv' changed state to 3(PAUSED) successfully

I/GStreamer(25504): 0:00:36.854095460 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<identity> current READY pending VOID_PENDING, desired next PAUSED

I/GStreamer(25504): 0:00:36.854919434 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<identity> completed state change to PAUSED

I/GStreamer(25504): 0:00:36.855224610 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<identity> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.855804444 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<vconv> child 'identity' changed state to 3(PAUSED) successfully

I/GStreamer(25504): 0:00:36.856079102 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<vconv> completed state change to PAUSED

I/GStreamer(25504): 0:00:36.856811524 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<vconv> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.857269288 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<vbin> child 'vconv' changed state to 3(PAUSED) successfully

I/GStreamer(25504): 0:00:36.857604981 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<vqueue> current READY pending VOID_PENDING, desired next PAUSED

I/GStreamer(25504): 0:00:36.858764649 0x500b6b80 gsttask.c:451:gst_task_set_lock setting stream lock 0x504365c4 on task 0x50434418

I/GStreamer(25504): 0:00:36.858917237 0x500b6b80 gstpad.c:5947:gst_pad_start_task:<vqueue:src> created task 0x50434418

I/GStreamer(25504): 0:00:36.887451172 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<vqueue> completed state change to PAUSED

I/GStreamer(25504): 0:00:36.887634278 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<vqueue> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.889617920 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<vbin> child 'vqueue' changed state to 3(PAUSED) successfully

I/GStreamer(25504): 0:00:36.890625000 0x500b6b80 gstpad.c:2048:gst_pad_unlink unlinking streamsynchronizer0:src_0(0x50442410) and vbin:sink(0x505c2a40)

I/GStreamer(25504): 0:00:36.891235352 0x500b6b80 gstpad.c:2048:gst_pad_unlink unlinking vdbin:src(0x505c2d20) and vbin:sink(0x505c2a40)

I/GStreamer(25504): 0:00:36.891662598 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link vdbin:src and vbin:sink

I/GStreamer(25504): 0:00:36.893035889 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked vdbin:src and vbin:sink, successful

I/GStreamer(25504): 0:00:36.893768311 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.895050049 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<openslessink0> completed state change to READY

I/GStreamer(25504): 0:00:36.896240234 0x500b6b80 gstelementfactory.c:362:gst_element_factory_create creating element "bin" named "abin"

I/GStreamer(25504): 0:00:36.897796631 0x500b6b80 gstelementfactory.c:362:gst_element_factory_create creating element "queue" named "aqueue"

I/GStreamer(25504): 0:00:36.898468017 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstQueue@0x4fcc6c10> adding pad 'sink'

I/GStreamer(25504): 0:00:36.899902343 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstQueue@0x4fcc6c10> adding pad 'src'

I/GStreamer(25504): 0:00:36.908020019 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstPlaySinkConvertBin@0x50442d38> adding pad 'sink'

I/GStreamer(25504): 0:00:36.908905028 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstPlaySinkConvertBin@0x50442d38> adding pad 'src'

I/GStreamer(25504): 0:00:36.909393310 0x500b6b80 gstelementfactory.c:362:gst_element_factory_create creating element "identity" named "identity"

I/GStreamer(25504): 0:00:36.909729003 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x5043e340> adding pad 'sink'

I/GStreamer(25504): 0:00:36.911529541 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x5043e340> adding pad 'src'

I/GStreamer(25504): 0:00:36.912109375 0x500b6b80 gstelementfactory.c:362:gst_element_factory_create creating element "volume" named "volume"

I/GStreamer(25504): 0:00:36.913024902 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x51ef60a8> adding pad 'sink'

I/GStreamer(25504): 0:00:36.915802002 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x51ef60a8> adding pad 'src'

I/GStreamer(25504): 0:00:36.917846679 0x500b6b80 gstplaysinkconvertbin.c:579:gst_play_sink_convert_bin_cache_converter_caps:<GstPlaySinkAudioConvert@0x50442d38> No conversion elements

I/GStreamer(25504): 0:00:36.918853759 0x500b6b80 gstelementfactory.c:362:gst_element_factory_create creating element "audioconvert" named "conv"

I/GStreamer(25504): 0:00:36.919860839 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x51df3890> adding pad 'sink'

I/GStreamer(25504): 0:00:36.920684814 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x51df3890> adding pad 'src'

I/GStreamer(25504): 0:00:36.927764893 0x500b6b80 gstelementfactory.c:362:gst_element_factory_create creating element "audioresample" named "resample"

I/GStreamer(25504): 0:00:36.928192139 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x51ef80c8> adding pad 'sink'

I/GStreamer(25504): 0:00:36.929351807 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x51ef80c8> adding pad 'src'

I/GStreamer(25504): 0:00:36.932647706 0x500b6b80 gstutils.c:1571:gst_element_link_pads_full trying to link element conv:src to element resample:sink

I/GStreamer(25504): 0:00:36.933105469 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad conv:src

I/GStreamer(25504): 0:00:36.933380128 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad resample:sink

I/GStreamer(25504): 0:00:36.936004639 0x500b6b80 gstutils.c:1444:prepare_link_maybe_ghosting conv and resample in same bin, no need for ghost pads

I/GStreamer(25504): 0:00:36.936492920 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link conv:src and resample:sink

I/GStreamer(25504): 0:00:36.936828614 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked conv:src and resample:sink, successful

I/GStreamer(25504): 0:00:36.937194825 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.937438965 0x500b6b80 gstpad.c:5601:gst_pad_send_event_unchecked:<conv:src> Received event on flushing pad. Discarding

I/GStreamer(25504): 0:00:36.937713623 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad conv:sink

I/GStreamer(25504): 0:00:36.938110352 0x500b6b80 gstpad.c:4065:gst_pad_peer_query:<resample:src> pad has no peer

I/GStreamer(25504): 0:00:36.939147950 0x500b6b80 gststructure.c:3049:gst_structure_get_valist Expected field 'channel-mask' in structure: audio/x-raw, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], layout=(string)interleaved;

I/GStreamer(25504): 0:00:36.940490723 0x500b6b80 gstplaysinkconvertbin.c:592: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 ]

I/GStreamer(25504): 0:00:36.940826416 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<conv> completed state change to NULL

I/GStreamer(25504): 0:00:36.942749025 0x500b6b80 gstpad.c:2048:gst_pad_unlink unlinking conv:src(0x51ef5560) and resample:sink(0x51ef56b0)

I/GStreamer(25504): 0:00:36.943603517 0x500b6b80 gstpad.c:2102:gst_pad_unlink unlinked conv:src and resample:sink

I/GStreamer(25504): 0:00:36.944061280 0x500b6b80 gstbin.c:1630:gst_bin_remove_func:<aconv> removed child "conv"

I/GStreamer(25504): 0:00:36.944702149 0x500b6b80 gstelement.c:2947:gst_element_dispose:<conv> dispose

I/GStreamer(25504): 0:00:36.945983888 0x500b6b80 gstelement.c:776:gst_element_remove_pad:<conv> removing pad 'sink'

I/GStreamer(25504): 0:00:36.946533204 0x500b6b80 gstelement.c:776:gst_element_remove_pad:<conv> removing pad 'src'

I/GStreamer(25504): 0:00:36.949584962 0x500b6b80 gstelement.c:2992:gst_element_dispose:<conv> parent class dispose

I/GStreamer(25504): 0:00:36.949859620 0x500b6b80 gstelement.c:3023:gst_element_finalize:<conv> finalize

I/GStreamer(25504): 0:00:36.950592042 0x500b6b80 gstelement.c:3028:gst_element_finalize:<conv> finalize parent

I/GStreamer(25504): 0:00:36.951049805 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<resample> completed state change to NULL

I/GStreamer(25504): 0:00:36.951324463 0x500b6b80 gstbin.c:1630:gst_bin_remove_func:<aconv> removed child "resample"

I/GStreamer(25504): 0:00:36.952362062 0x500b6b80 gstelement.c:2947:gst_element_dispose:<resample> dispose

I/GStreamer(25504): 0:00:36.952697755 0x500b6b80 gstelement.c:776:gst_element_remove_pad:<resample> removing pad 'sink'

I/GStreamer(25504): 0:00:36.953155519 0x500b6b80 gstelement.c:776:gst_element_remove_pad:<resample> removing pad 'src'

I/GStreamer(25504): 0:00:36.953460695 0x500b6b80 gstelement.c:2992:gst_element_dispose:<resample> parent class dispose

I/GStreamer(25504): 0:00:36.953887941 0x500b6b80 gstelement.c:3023:gst_element_finalize:<resample> finalize

I/GStreamer(25504): 0:00:36.954315187 0x500b6b80 gstelement.c:3028:gst_element_finalize:<resample> finalize parent

I/GStreamer(25504): 0:00:36.955047609 0x500b6b80 gstelementfactory.c:362:gst_element_factory_create creating element "audioconvert" named "conv"

I/GStreamer(25504): 0:00:36.955566408 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x51df3890> adding pad 'sink'

I/GStreamer(25504): 0:00:36.956085206 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x51df3890> adding pad 'src'

I/GStreamer(25504): 0:00:36.956787111 0x500b6b80 gstelementfactory.c:362:gst_element_factory_create creating element "audioresample" named "resample"

I/GStreamer(25504): 0:00:36.957366945 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x51ef8358> adding pad 'sink'

I/GStreamer(25504): 0:00:36.961822512 0x500b6b80 gstelement.c:659:gst_element_add_pad:<GstBaseTransform@0x51ef8358> adding pad 'src'

I/GStreamer(25504): 0:00:36.962829592 0x500b6b80 gstutils.c:1571:gst_element_link_pads_full trying to link element conv:src to element resample:sink

I/GStreamer(25504): 0:00:36.963439943 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad conv:src

I/GStreamer(25504): 0:00:36.963897707 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad resample:sink

I/GStreamer(25504): 0:00:36.964660646 0x500b6b80 gstutils.c:1444:prepare_link_maybe_ghosting conv and resample in same bin, no need for ghost pads

I/GStreamer(25504): 0:00:36.965820314 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link conv:src and resample:sink

I/GStreamer(25504): 0:00:36.966156007 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked conv:src and resample:sink, successful

I/GStreamer(25504): 0:00:36.966430666 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.966705324 0x500b6b80 gstpad.c:5601:gst_pad_send_event_unchecked:<conv:src> Received event on flushing pad. Discarding

I/GStreamer(25504): 0:00:36.967010500 0x500b6b80 gstutils.c:1571:gst_element_link_pads_full trying to link element resample:src to element volume:sink

I/GStreamer(25504): 0:00:36.967803957 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad resample:src

I/GStreamer(25504): 0:00:36.968078615 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad volume:sink

I/GStreamer(25504): 0:00:36.968292238 0x500b6b80 gstutils.c:1444:prepare_link_maybe_ghosting resample and volume in same bin, no need for ghost pads

I/GStreamer(25504): 0:00:36.968536378 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link resample:src and volume:sink

I/GStreamer(25504): 0:00:36.968780519 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked resample:src and volume:sink, successful

I/GStreamer(25504): 0:00:36.968994142 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.969207765 0x500b6b80 gstpad.c:5601:gst_pad_send_event_unchecked:<resample:src> Received event on flushing pad. Discarding

I/GStreamer(25504): 0:00:36.969482423 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad conv:sink

I/GStreamer(25504): 0:00:36.970153810 0x500b6b80 gstpad.c:4065:gst_pad_peer_query:<volume:src> pad has no peer

I/GStreamer(25504): 0:00:36.970855714 0x500b6b80 gststructure.c:3049:gst_structure_get_valist Expected field 'channel-mask' in structure: audio/x-raw, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 2147483647 ], layout=(string)interleaved;

I/GStreamer(25504): 0:00:36.971557618 0x500b6b80 gstplaysinkconvertbin.c:592: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 ]

I/GStreamer(25504): 0:00:36.971984865 0x500b6b80 gstutils.c:1571:gst_element_link_pads_full trying to link element aqueue:src to element aconv:sink

I/GStreamer(25504): 0:00:36.972381593 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad aqueue:src

I/GStreamer(25504): 0:00:36.972686769 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad aconv:sink

I/GStreamer(25504): 0:00:36.973144532 0x500b6b80 gstutils.c:1444:prepare_link_maybe_ghosting aqueue and aconv in same bin, no need for ghost pads

I/GStreamer(25504): 0:00:36.973449708 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link aqueue:src and aconv:sink

I/GStreamer(25504): 0:00:36.973815919 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked aqueue:src and aconv:sink, successful

I/GStreamer(25504): 0:00:36.974243165 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.974548341 0x500b6b80 gstpad.c:5601:gst_pad_send_event_unchecked:<aqueue:src> Received event on flushing pad. Discarding

I/GStreamer(25504): 0:00:36.975189210 0x500b6b80 gstutils.c:1571:gst_element_link_pads_full trying to link element aconv:src to element openslessink0:(any)

I/GStreamer(25504): 0:00:36.975494386 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad aconv:src

I/GStreamer(25504): 0:00:36.975830079 0x500b6b80 gstutils.c:932:gst_pad_check_link trying to link aconv:src and openslessink0:sink

I/GStreamer(25504): 0:00:36.976287843 0x500b6b80 gstpad.c:4065:gst_pad_peer_query:<aqueue:sink> pad has no peer

I/GStreamer(25504): 0:00:36.976623536 0x500b6b80 gstutils.c:1444:prepare_link_maybe_ghosting aconv and openslessink0 in same bin, no need for ghost pads

I/GStreamer(25504): 0:00:36.977020265 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link aconv:src and openslessink0:sink

I/GStreamer(25504): 0:00:36.977416993 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked aconv:src and openslessink0:sink, successful

I/GStreamer(25504): 0:00:36.977630616 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.977874757 0x500b6b80 gstpad.c:5601:gst_pad_send_event_unchecked:<aconv:src> Received event on flushing pad. Discarding

I/GStreamer(25504): 0:00:36.978149415 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad aqueue:sink

I/GStreamer(25504): 0:00:36.978790284 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link sink:proxypad16 and aqueue:sink

I/GStreamer(25504): 0:00:36.979034425 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked sink:proxypad16 and aqueue:sink, successful

I/GStreamer(25504): 0:00:36.979278565 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.979644776 0x500b6b80 gstelement.c:659:gst_element_add_pad:<abin> adding pad 'sink'

I/GStreamer(25504): 0:00:36.980285645 0x500b6b80 gstelement.c:659:gst_element_add_pad:<streamsynchronizer0> adding pad 'src_1'

I/GStreamer(25504): 0:00:36.980560303 0x500b6b80 gstelement.c:659:gst_element_add_pad:<streamsynchronizer0> adding pad 'sink_1'

I/GStreamer(25504): 0:00:36.981079102 0x500b6b80 gstelement.c:659:gst_element_add_pad:<audiotee> adding pad 'src_0'

I/GStreamer(25504): 0:00:36.981811524 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<openslessink0> current READY pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:36.982147217 0x500b6b80 gstbin.c:2433:gst_bin_element_set_state:<openslessink0> skipping transition from READY to  READY

I/GStreamer(25504): 0:00:36.982574463 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<abin> child 'openslessink0' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:36.982910157 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<aconv> current NULL pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:36.983276368 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<volume> current NULL pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:36.983551026 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<volume> completed state change to READY

I/GStreamer(25504): 0:00:36.983856202 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<volume> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.984283448 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<aconv> child 'volume' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:36.984741211 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<resample> current NULL pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:36.985015870 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<resample> completed state change to READY

I/GStreamer(25504): 0:00:36.985321045 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<resample> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.985748291 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<aconv> child 'resample' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:36.986022950 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<conv> current NULL pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:36.986328125 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<conv> completed state change to READY

I/GStreamer(25504): 0:00:36.986633301 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<conv> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.986968994 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<aconv> child 'conv' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:36.987213135 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<identity> current NULL pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:36.987457276 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<identity> completed state change to READY

I/GStreamer(25504): 0:00:36.987670899 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<identity> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.988006592 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<aconv> child 'identity' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:36.988250733 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<aconv> completed state change to READY

I/GStreamer(25504): 0:00:36.988494873 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<aconv> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.988800049 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<abin> child 'aconv' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:36.989044190 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<aqueue> current NULL pending VOID_PENDING, desired next READY

I/GStreamer(25504): 0:00:36.989288330 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<aqueue> completed state change to READY

I/GStreamer(25504): 0:00:36.989532471 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<aqueue> notifying about state-changed NULL to READY (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.989868164 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<abin> child 'aqueue' changed state to 2(READY) successfully

I/GStreamer(25504): 0:00:36.990112305 0x500b6b80 gstelement.c:2347:gst_element_continue_state:<abin> committing state from NULL to READY, pending PAUSED, next PAUSED

I/GStreamer(25504): 0:00:36.990356445 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<abin> notifying about state-changed NULL to READY (PAUSED pending)

I/GStreamer(25504): 0:00:36.990875244 0x500b6b80 gstelement.c:2354:gst_element_continue_state:<abin> continue state change READY to PAUSED, final PAUSED

I/GStreamer(25504): 0:00:36.991119385 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<openslessink0> current READY pending VOID_PENDING, desired next PAUSED

I/GStreamer(25504): 0:00:36.991546631 0x500b6b80 gstbin.c:2770:gst_bin_change_state_func:<abin> child 'openslessink0' is changing state asynchronously to PAUSED

I/GStreamer(25504): 0:00:36.991912842 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<aconv> current READY pending VOID_PENDING, desired next PAUSED

I/GStreamer(25504): 0:00:36.992187500 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad identity:sink

I/GStreamer(25504): 0:00:36.992462158 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link sink:proxypad14 and identity:sink

I/GStreamer(25504): 0:00:36.992736816 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked sink:proxypad14 and identity:sink, successful

I/GStreamer(25504): 0:00:36.993377685 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.993682861 0x500b6b80 gstelement.c:907:gst_element_get_static_pad found pad identity:src

I/GStreamer(25504): 0:00:36.994323730 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link identity:src and src:proxypad15

I/GStreamer(25504): 0:00:36.994628906 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked identity:src and src:proxypad15, successful

I/GStreamer(25504): 0:00:36.994873047 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:36.995513916 0x500b6b80 gstpad.c:5601:gst_pad_send_event_unchecked:<identity:src> Received event on flushing pad. Discarding

I/GStreamer(25504): 0:00:36.996063232 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<volume> current READY pending VOID_PENDING, desired next PAUSED

I/GStreamer(25504): 0:00:36.996490478 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<volume> completed state change to PAUSED

I/GStreamer(25504): 0:00:36.996887207 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<volume> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.997253418 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<aconv> child 'volume' changed state to 3(PAUSED) successfully

I/GStreamer(25504): 0:00:36.997528076 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<resample> current READY pending VOID_PENDING, desired next PAUSED

I/GStreamer(25504): 0:00:36.997802734 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<resample> completed state change to PAUSED

I/GStreamer(25504): 0:00:36.998046875 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<resample> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.998352050 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<aconv> child 'resample' changed state to 3(PAUSED) successfully

I/GStreamer(25504): 0:00:36.998626709 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<conv> current READY pending VOID_PENDING, desired next PAUSED

I/GStreamer(25504): 0:00:36.998870849 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<conv> completed state change to PAUSED

I/GStreamer(25504): 0:00:36.999114990 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<conv> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:36.999420166 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<aconv> child 'conv' changed state to 3(PAUSED) successfully

I/GStreamer(25504): 0:00:36.999908447 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<identity> current READY pending VOID_PENDING, desired next PAUSED

I/GStreamer(25504): 0:00:37.000183105 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<identity> completed state change to PAUSED

I/GStreamer(25504): 0:00:37.001129150 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<identity> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:37.001617432 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<aconv> child 'identity' changed state to 3(PAUSED) successfully

I/GStreamer(25504): 0:00:37.001983643 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<aconv> completed state change to PAUSED

I/GStreamer(25504): 0:00:37.002258301 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<aconv> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:37.002655030 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<abin> child 'aconv' changed state to 3(PAUSED) successfully

I/GStreamer(25504): 0:00:37.003173828 0x500b6b80 gstbin.c:2316:gst_bin_element_set_state:<aqueue> current READY pending VOID_PENDING, desired next PAUSED

I/GStreamer(25504): 0:00:37.003509522 0x500b6b80 gsttask.c:451:gst_task_set_lock setting stream lock 0x50442c3c on task 0x504344c0

I/GStreamer(25504): 0:00:37.003814698 0x500b6b80 gstpad.c:5947:gst_pad_start_task:<aqueue:src> created task 0x504344c0

I/GStreamer(25504): 0:00:37.007934570 0x500b6b80 gstelement.c:2372:gst_element_continue_state:<aqueue> completed state change to PAUSED

I/GStreamer(25504): 0:00:37.008972168 0x500b6b80 gstelement.c:2277:_priv_gst_element_state_changed:<aqueue> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:37.009582519 0x500b6b80 gstbin.c:2764:gst_bin_change_state_func:<abin> child 'aqueue' changed state to 3(PAUSED) successfully

I/GStreamer(25504): 0:00:37.010070801 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link audiotee:src_0 and streamsynchronizer0:sink_1

I/GStreamer(25504): 0:00:37.010345459 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked audiotee:src_0 and streamsynchronizer0:sink_1, successful

I/GStreamer(25504): 0:00:37.010681152 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:37.011291504 0x500b6b80 gstpad.c:2292:gst_pad_link_prepare trying to link streamsynchronizer0:src_1 and abin:sink

I/GStreamer(25504): 0:00:37.011749268 0x500b6b80 gstpad.c:2498:gst_pad_link_full linked streamsynchronizer0:src_1 and abin:sink, successful

I/GStreamer(25504): 0:00:37.012176514 0x500b6b80 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:37.012908936 0x500b6b80 gstplaysink.c:1412:do_async_done:<playsink> Sending async_done message

I/GStreamer(25504): 0:00:37.015655518 0x501bd8c0 gstevent.c:760:gst_event_new_segment creating segment event time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=0:02:33.205000000, 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

I/GStreamer(25504): 0:00:37.022583009 0x4ff0daf0 gstaudiodecoder.c:2281:gst_audio_decoder_sink_eventfunc:<amcaudiodec-omxgoogleaacdecoder0> upstream stream tags: taglist, audio-codec=(string)"MPEG-4\ AAC", maximum-bitrate=(uint)200616, bitrate=(uint)191992, minimum-bitrate=(uint)191903;

I/GStreamer(25504): 0:00:37.026641847 0x500b6b80 gstbasetransform.c:1367:gst_base_transform_setcaps:<vdconv> reuse caps

I/GStreamer(25504): 0:00:37.028015137 0x503be920 gstpad.c:2048:gst_pad_unlink unlinking sink:proxypad14(0x5043c458) and identity:sink(0x50442e90)

I/GStreamer(25504): 0:00:37.030487061 0x503be920 gstpad.c:2102:gst_pad_unlink unlinked sink:proxypad14 and identity:sink

I/GStreamer(25504): 0:00:37.033416749 0x503be920 gstpad.c:2048:gst_pad_unlink unlinking identity:src(0x51ef5020) and src:proxypad15(0x5043c5b8)

I/GStreamer(25504): 0:00:37.034301759 0x503be920 gstpad.c:2102:gst_pad_unlink unlinked identity:src and src:proxypad15

I/GStreamer(25504): 0:00:37.034942628 0x503be920 gstelement.c:2372:gst_element_continue_state:<conv> completed state change to PAUSED

I/GStreamer(25504): 0:00:37.037750245 0x503be920 gstelement.c:2372:gst_element_continue_state:<resample> completed state change to PAUSED

I/GStreamer(25504): 0:00:37.042388917 0x503be920 gstelement.c:2372:gst_element_continue_state:<volume> completed state change to PAUSED

I/GStreamer(25504): 0:00:37.043518067 0x503be920 gstelement.c:907:gst_element_get_static_pad found pad conv:sink

I/GStreamer(25504): 0:00:37.044281007 0x503be920 gstpad.c:2292:gst_pad_link_prepare trying to link sink:proxypad14 and conv:sink

I/GStreamer(25504): 0:00:37.047454835 0x503be920 gstpad.c:2498:gst_pad_link_full linked sink:proxypad14 and conv:sink, successful

I/GStreamer(25504): 0:00:37.048126221 0x500b6b80 gstevent.c:679:gst_event_new_caps creating caps event video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)1280, height=(int)720, interlace-mode=(string)progressive, multiview-mode=(string)side-by-side, multiview-flags=(GstVideoMultiviewFlagsSet)1:ffffffff:+right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono, pixel-aspect-ratio=(fraction)2/1, colorimetry=(string)sRGB, framerate=(fraction)30/1

I/GStreamer(25504): 0:00:37.048370362 0x503be920 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:37.050567627 0x500b6b80 gstevent.c:679:gst_event_new_caps creating caps event video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)1280, height=(int)720, interlace-mode=(string)progressive, multiview-mode=(string)side-by-side, multiview-flags=(GstVideoMultiviewFlagsSet)1:ffffffff:+right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono, pixel-aspect-ratio=(fraction)2/1, colorimetry=(string)sRGB, framerate=(fraction)30/1

I/GStreamer(25504): 0:00:37.051239014 0x503be920 gstelement.c:907:gst_element_get_static_pad found pad volume:src

I/GStreamer(25504): 0:00:37.052246094 0x503be920 gstpad.c:2292:gst_pad_link_prepare trying to link volume:src and src:proxypad15

I/GStreamer(25504): 0:00:37.054077149 0x503be920 gstpad.c:2498:gst_pad_link_full linked volume:src and src:proxypad15, successful

I/GStreamer(25504): 0:00:37.055328369 0x503be920 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:37.059875488 0x503be920 gstbasetransform.c:1367:gst_base_transform_setcaps:<conv> reuse caps

I/GStreamer(25504): 0:00:37.073486328 0x50432200 gstpad.c:2048:gst_pad_unlink unlinking sink:proxypad9(0x500b5d48) and identity:sink(0x50436818)

I/GStreamer(25504): 0:00:37.073699951 0x50432200 gstpad.c:2102:gst_pad_unlink unlinked sink:proxypad9 and identity:sink

I/GStreamer(25504): 0:00:37.074188232 0x50432200 gstpad.c:2048:gst_pad_unlink unlinking identity:src(0x50436968) and src:proxypad10(0x500b5ea8)

I/GStreamer(25504): 0:00:37.074554443 0x50432200 gstpad.c:2102:gst_pad_unlink unlinked identity:src and src:proxypad10

I/GStreamer(25504): 0:00:37.075103760 0x50432200 gstelement.c:2372:gst_element_continue_state:<conv> completed state change to PAUSED

I/GStreamer(25504): 0:00:37.075592041 0x50432200 gstelement.c:2372:gst_element_continue_state:<scale> completed state change to PAUSED

I/GStreamer(25504): 0:00:37.075866699 0x50432200 gstelement.c:907:gst_element_get_static_pad found pad conv:sink

I/GStreamer(25504): 0:00:37.076965332 0x50432200 gstpad.c:2292:gst_pad_link_prepare trying to link sink:proxypad9 and conv:sink

I/GStreamer(25504): 0:00:37.077484131 0x50432200 gstpad.c:2498:gst_pad_link_full linked sink:proxypad9 and conv:sink, successful

I/GStreamer(25504): 0:00:37.077697754 0x50432200 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:37.078399658 0x50432200 gstelement.c:907:gst_element_get_static_pad found pad scale:src

I/GStreamer(25504): 0:00:37.084411621 0x50432200 gstpad.c:2292:gst_pad_link_prepare trying to link scale:src and src:proxypad10

I/GStreamer(25504): 0:00:37.088714600 0x50432200 gstpad.c:2498:gst_pad_link_full linked scale:src and src:proxypad10, successful

I/GStreamer(25504): 0:00:37.089263916 0x50432200 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event

I/GStreamer(25504): 0:00:37.091949464 0x500b6b80 gsttask.c:316:gst_task_func:<amcvideodec-omxbrcmvideoh264decoder0:src> Task going to paused

I/GStreamer(25504): 0:00:37.094573975 0x503be920 audio-converter.c:863:gst_audio_converter_new unitsizes: 4 -> 4

I/GStreamer(25504): 0:00:37.096527100 0x503be920 audio-converter.c:566:chain_unpack unpack format S16LE to S16LE

I/GStreamer(25504): 0:00:37.098052979 0x50432200 gstbasetransform.c:1367:gst_base_transform_setcaps:<conv> reuse caps

I/GStreamer(25504): 0:00:37.098876953 0x50432200 gstevent.c:679:gst_event_new_caps creating caps event video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)1280, height=(int)720, interlace-mode=(string)progressive, multiview-mode=(string)side-by-side, multiview-flags=(GstVideoMultiviewFlagsSet)1:ffffffff:+right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono, pixel-aspect-ratio=(fraction)2/1, colorimetry=(string)sRGB, framerate=(fraction)30/1

I/GStreamer(25504): 0:00:37.102294923 0x50432200 gstbasetransform.c:1367:gst_base_transform_setcaps:<scale> reuse caps

I/GStreamer(25504): 0:00:37.109924317 0x503be920 audio-converter.c:623:chain_mix mix format S16LE, passthrough 1, in_channels 2, out_channels 2

I/GStreamer(25504): 0:00:37.110076905 0x503be920 audio-converter.c:674:chain_quantize depth in 16, out 16

I/GStreamer(25504): 0:00:37.110473633 0x503be920 audio-converter.c:686:chain_quantize using no dither and noise shaping

I/GStreamer(25504): 0:00:37.111602785 0x503be920 audio-converter.c:721:chain_pack pack format S16LE to S16LE

I/GStreamer(25504): 0:00:37.111907960 0x503be920 audio-converter.c:881:gst_audio_converter_new same formats and passthrough mixing -> passthrough

I/GStreamer(25504): 0:00:37.112426759 0x503be920 gstevent.c:679:gst_event_new_caps creating caps event audio/x-raw, format=(string)S16LE, layout=(string)interleaved, rate=(int)44100, channels=(int)2, channel-mask=(bitmask)0x0000000000000003

I/GStreamer(25504): 0:00:37.119934083 0x50432200 gstevent.c:679:gst_event_new_caps creating caps event video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)1280, height=(int)720, interlace-mode=(string)progressive, multiview-mode=(string)side-by-side, multiview-flags=(GstVideoMultiviewFlagsSet)1:ffffffff:+right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono, pixel-aspect-ratio=(fraction)2/1, colorimetry=(string)sRGB, framerate=(fraction)30/1

I/GStreamer(25504): 0:00:37.120635987 0x503be920 gstbasetransform.c:1367:gst_base_transform_setcaps:<resample> reuse caps

I/GStreamer(25504): 0:00:37.124877931 0x503be920 gstevent.c:679:gst_event_new_caps creating caps event audio/x-raw, format=(string)S16LE, layout=(string)interleaved, rate=(int)44100, channels=(int)2, channel-mask=(bitmask)0x0000000000000003

I/GStreamer(25504): 0:00:37.128021242 0x503be920 gstbasetransform.c:1367:gst_base_transform_setcaps:<volume> reuse caps

I/GStreamer(25504): 0:00:37.129486085 0x503be920 gstevent.c:679:gst_event_new_caps creating caps event audio/x-raw, format=(string)S16LE, layout=(string)interleaved, rate=(int)44100, channels=(int)2, channel-mask=(bitmask)0x0000000000000003

I/GStreamer(25504): 0:00:37.143646241 0x50432200 gstevent.c:679:gst_event_new_caps creating caps event video/x-raw(memory:GLMemory), width=(int)1280, height=(int)720, interlace-mode=(string)progressive, multiview-mode=(string)side-by-side, multiview-flags=(GstVideoMultiviewFlagsSet)1:ffffffff:+right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono, pixel-aspect-ratio=(fraction)2/1, framerate=(fraction)30/1, format=(string)RGBA, texture-target=(string)2D, colorimetry=(string)sRGB

I/GStreamer(25504): 0:00:37.146331787 0x50432200 gstbasetransform.c:1367:gst_base_transform_setcaps:<glcolorconvertelement0> reuse caps

I/GStreamer(25504): 0:00:37.148956299 0x50432200 gstevent.c:679:gst_event_new_caps creating caps event video/x-raw(memory:GLMemory), width=(int)1280, height=(int)720, interlace-mode=(string)progressive, multiview-mode=(string)side-by-side, multiview-flags=(GstVideoMultiviewFlagsSet)1:ffffffff:+right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono, pixel-aspect-ratio=(fraction)2/1, framerate=(fraction)30/1, format=(string)RGBA, texture-target=(string)2D, colorimetry=(string)sRGB

I/GStreamer(25504): 0:00:37.172912598 0x503be920 gstaudioringbuffer.c:581:gst_audio_ring_buffer_acquire:<openslesringbuffer0> Allocating an array for 10 timestamps

I/GStreamer(25504): 0:00:37.184082032 0x50432200 gstbasetransform.c:1367:gst_base_transform_setcaps:<glcolorbalance0> reuse caps

I/GStreamer(25504): 0:00:37.184509278 0x50432200 gstevent.c:679:gst_event_new_caps creating caps event video/x-raw(memory:GLMemory), width=(int)1280, height=(int)720, interlace-mode=(string)progressive, multiview-mode=(string)side-by-side, multiview-flags=(GstVideoMultiviewFlagsSet)1:ffffffff:+right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono, pixel-aspect-ratio=(fraction)2/1, framerate=(fraction)30/1, format=(string)RGBA, texture-target=(string)2D, colorimetry=(string)sRGB

I/GStreamer(25504): 0:00:37.202880860 0x50432200 gstglviewconvert.c:423:gst_gl_view_convert_set_caps:<glviewconvert0> Configuring multiview conversion from caps video/x-raw(memory:GLMemory), width=(int)1280, height=(int)720, interlace-mode=(string)progressive, multiview-mode=(string)side-by-side, multiview-flags=(GstVideoMultiviewFlagsSet)1:ffffffff:+right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono, pixel-aspect-ratio=(fraction)2/1, framerate=(fraction)30/1, format=(string)RGBA, texture-target=(string)2D, colorimetry=(string)sRGB to video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)1, height=(int)1, interlace-mode=(string)progressive, multiview-mode=(string)mono, multiview-flags=(GstVideoMultiviewFlagsSet)0:ffffffff:/right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono, pixel-aspect-ratio=(fraction)2/1, colorimetry=(string)sRGB, views=(int)2, framerate=(fraction)30/1, texture-target=(string)2D

W/GStreamer(25504): 0:00:37.205169678 0x50432200 gststructure.c:1935:priv_gst_structure_append_to_gstring No value transform to serialize field 'params' of type 'GstAllocationParams'

I/GStreamer(25504): 0:00:37.206115723 0x50432200 gststructure.c:3049:gst_structure_get_valist Expected field 'gl-allocation-params' in structure: GstBufferPoolConfig, caps=(GstCaps)"video/x-raw\(memory:GLMemory\)\,\ width\=\(int\)1280\,\ height\=\(int\)720\,\ interlace-mode\=\(string\)progressive\,\ multiview-mode\=\(string\)side-by-side\,\ multiview-flags\=\(GstVideoMultiviewFlagsSet\)1:ffffffff:+right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono\,\ pixel-aspect-ratio\=\(fraction\)2/1\,\ framerate\=\(fraction\)30/1\,\ format\=\(string\)RGBA\,\ texture-target\=\(string\)2D\,\ colorimetry\=\(string\)sRGB", size=(uint)3686400, min-buffers=(uint)0, max-buffers=(uint)0, allocator=(GstAllocator)"NULL", params=(GstAllocationParams)NULL, options=(string)< GstBufferPoolOptionGLSyncMeta >;

I/GStreamer(25504): 0:00:37.207489014 0x503be920 gstbin.c:3238:bin_handle_async_done:<abin> committing state from READY to PAUSED, old pending PAUSED

I/GStreamer(25504): 0:00:37.208709717 0x503be920 gstbin.c:3258:bin_handle_async_done:<abin> completed state change, pending VOID

I/GStreamer(25504): 0:00:37.208984375 0x503be920 gstelement.c:2277:_priv_gst_element_state_changed:<abin> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

W/GStreamer(25504): 0:00:37.210296631 0x50432200 gststructure.c:1935:priv_gst_structure_append_to_gstring No value transform to serialize field 'params' of type 'GstAllocationParams'

I/GStreamer(25504): 0:00:37.210571289 0x50432200 gststructure.c:3049:gst_structure_get_valist Expected field 'gl-allocation-params' in structure: GstBufferPoolConfig, caps=(GstCaps)"video/x-raw\(memory:GLMemory\)\,\ width\=\(int\)1280\,\ height\=\(int\)720\,\ interlace-mode\=\(string\)progressive\,\ multiview-mode\=\(string\)side-by-side\,\ multiview-flags\=\(GstVideoMultiviewFlagsSet\)1:ffffffff:+right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono\,\ pixel-aspect-ratio\=\(fraction\)2/1\,\ framerate\=\(fraction\)30/1\,\ format\=\(string\)RGBA\,\ texture-target\=\(string\)2D\,\ colorimetry\=\(string\)sRGB", size=(uint)3686400, min-buffers=(uint)2, max-buffers=(uint)0, allocator=(GstAllocator)"NULL", params=(GstAllocationParams)NULL, options=(string)< GstBufferPoolOptionGLSyncMeta >;

I/GStreamer(25504): 0:00:37.214172363 0x50432200 gstbin.c:3238:bin_handle_async_done:<glimagesinkbin0> committing state from READY to PAUSED, old pending PAUSED

I/GStreamer(25504): 0:00:37.214874268 0x50432200 gstbin.c:3258:bin_handle_async_done:<glimagesinkbin0> completed state change, pending VOID

I/GStreamer(25504): 0:00:37.215209961 0x50432200 gstelement.c:2277:_priv_gst_element_state_changed:<glimagesinkbin0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:37.215667725 0x50432200 gstbin.c:3238:bin_handle_async_done:<vbin> committing state from READY to PAUSED, old pending PAUSED

I/GStreamer(25504): 0:00:37.217956543 0x50432200 gstbin.c:3258:bin_handle_async_done:<vbin> completed state change, pending VOID

I/GStreamer(25504): 0:00:37.218475342 0x50432200 gstelement.c:2277:_priv_gst_element_state_changed:<vbin> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

I/GStreamer(25504): 0:00:37.220031738 0x50432200 gstbin.c:3238:bin_handle_async_done:<playsink> committing state from READY to PAUSED, old pending PAUSED

I/GStreamer(25504): 0:00:37.224578857 0x50432200 gstbin.c:3258:bin_handle_async_done:<playsink> completed state change, pending VOID

I/GStreamer(25504): 0:00:37.224914550 0x50432200 gstelement.c:2277:_priv_gst_element_state_changed:<playsink> notifying about state-changed READY to PAUSED (VOID_PENDING pending)

