Zero-copy "decodebin ! glimagesink" equivalent to playbin on Android
Grégoire Gentil
gregoire at gentil.com
Tue Feb 9 06:35:15 UTC 2016
On 02/08/2016 04:31 PM, Matthew Waters wrote:
> If everything is working, then it will be picked automatically. The
> fact that it isn't, indicates a problem with your code. Look at
> playbin's implementation as well as the debug logs to determine what
> exactly is happening (It's all there somewhere ;)).
Are you absolutely sure it's only bug 742924 and there is no problem
elsewhere?
I have implemented an horrible but working autoplug_query function:
static gboolean autoplug_query_cb(GstElement *bin, GstPad *pad,
GstElement *element, GstQuery *query, CustomData *data) {
if (strstr(gst_element_get_name(element), "amcvideodec") == NULL)
return FALSE;
if (GST_QUERY_TYPE(query) == GST_QUERY_CONTEXT) {
GstPad *sinkpad = gst_element_get_static_pad(data->scd[0].video_sink,
"sink");
if (sinkpad) {
gboolean res = gst_pad_query(sinkpad, query);
gst_object_unref(sinkpad);
return res;
}
}
if (GST_QUERY_TYPE(query) == GST_QUERY_CAPS) {
GstCaps *filter;
gst_query_parse_caps(query, &filter);
char *a = gst_caps_to_string(filter);
char *b = strstr(a, ";");//The first hunk is always the gl one
//structure from caps is confused by memory:GLMemory
//so we go down to string
if (b) *b = '\0';
if (strstr(a, "texture-target=(string)external-oes") != NULL) {
GstCaps *cc = gst_caps_from_string(a);
GST_DEBUG("WE FOUND IT %s\n", gst_caps_to_string(cc));
gst_query_set_caps_result(query, cc);
return TRUE;
}
}
return FALSE;
}
I'm pretty sure it's working because I get the following in the log:
aigstreamer.c:625:autoplug_query_cb WE FOUND IT
video/x-raw(memory:GLMemory), format=(string)RGBA,
texture-target=(string)external-oes
02-08 21:57:54.140 20973 21058 I GStreamer+GST_CONTEXT:
0:00:08.614410400 0x9c73fe30
gstglutils.c:573:_gst_context_query:<amcvideodec-omxqcomvideodecoderavc0> found
context (0x96c68120) in downstream query
02-08 21:57:54.142 20973 21058 I GStreamer+amcvideodec:
0:00:08.616394043 0x9c73fe30
gstamcvideodec.c:1953:gst_amc_video_dec_set_format:<amcvideodec-omxqcomvideodecoderavc0>
GL output: enabled
so hardware goes directly to GL and context is properly passed.
But decodebin ! glimagesink is still at 30fps while playbin is at 50fps.
I *really* suspect that there is another problem somewhere else,
Grégoire
-------------- next part --------------
02-08 21:57:47.874 20973 20973 D BluetoothLeService: Trying to create a new connection for 1
02-08 21:57:53.867 20973 20973 E aiTennis3D: filesrc location=/storage/emulated/0/aiTennis3D/20160118_112256/videov.mp4 ! decodebin name=decodebin_0 ! glimagesink force-aspect-ratio=false
02-08 21:57:53.868 20973 20973 D GStreamer+aiTennis3D: 0:00:08.342285154 0xaebeae00 gstreamer/aigstreamer.c:1082:gst_native_init Created CustomData at 0x9d30e480
02-08 21:57:53.868 20973 20973 D GStreamer+aiTennis3D: 0:00:08.342376707 0xaebeae00 gstreamer/aigstreamer.c:1084:gst_native_init Created GlobalRef for app object at 0x5fe
02-08 21:57:53.869 20973 21038 D GStreamer+aiTennis3D: 0:00:08.343109129 0x9d319f20 gstreamer/aigstreamer.c:917:app_function Gstreamer Version is GStreamer 1.7.1 (GIT)
02-08 21:57:53.869 20973 21038 D GStreamer+aiTennis3D: 0:00:08.343231199 0x9d319f20 gstreamer/aigstreamer.c:919:app_function Creating for id 0 pipeline in CustomData at 0x9d30e480
02-08 21:57:53.869 20973 21038 D GStreamer+aiTennis3D: 0:00:08.343627928 0x9d319f20 gstreamer/aigstreamer.c:926:app_function PIPELINE[0] filesrc location=/storage/emulated/0/aiTennis3D/20160118_112256/videov.mp4 ! decodebin name=decodebin_0 ! glimagesink force-aspect-ratio=false
02-08 21:57:53.869 20973 21038 I GStreamer+GST_PIPELINE: 0:00:08.343749998 0x9d319f20 gstparse.c:323:gst_parse_launch_full parsing pipeline description 'filesrc location=/storage/emulated/0/aiTennis3D/20160118_112256/videov.mp4 ! decodebin name=decodebin_0 ! glimagesink force-aspect-ratio=false'
02-08 21:57:53.869 20973 21038 I GStreamer+GST_ELEMENT_FACTORY: 0:00:08.343933104 0x9d319f20 gstelementfactory.c:364:gst_element_factory_create creating element "filesrc"
02-08 21:57:53.870 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.344299314 0x9d319f20 gstelement.c:659:gst_element_add_pad:<GstBaseSrc at 0x9c602168> adding pad 'src'
02-08 21:57:53.870 20973 21038 I GStreamer+filesrc: 0:00:08.344573973 0x9d319f20 gstfilesrc.c:263:gst_file_src_set_location filename : /storage/emulated/0/aiTennis3D/20160118_112256/videov.mp4
02-08 21:57:53.870 20973 21038 I GStreamer+filesrc: 0:00:08.344665525 0x9d319f20 gstfilesrc.c:264:gst_file_src_set_location uri : file:///storage/emulated/0/aiTennis3D/20160118_112256/videov.mp4
02-08 21:57:53.870 20973 21038 I GStreamer+GST_ELEMENT_FACTORY: 0:00:08.344818113 0x9d319f20 gstelementfactory.c:364:gst_element_factory_create creating element "decodebin"
02-08 21:57:53.871 20973 21038 I GStreamer+GST_ELEMENT_FACTORY: 0:00:08.345031736 0x9d319f20 gstelementfactory.c:362:gst_element_factory_create creating element "typefind" named "typefind"
02-08 21:57:53.871 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.345825194 0x9d319f20 gstelement.c:659:gst_element_add_pad:<GstTypeFindElement at 0x9c60a0b0> adding pad 'sink'
02-08 21:57:53.872 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.346130370 0x9d319f20 gstelement.c:659:gst_element_add_pad:<GstTypeFindElement at 0x9c60a0b0> adding pad 'src'
02-08 21:57:53.872 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.346527098 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad typefind:sink
02-08 21:57:53.875 20973 21038 I GStreamer+GST_PADS: 0:00:08.349121092 0x9d319f20 gstpad.c:2292:gst_pad_link_prepare trying to link sink:proxypad0 and typefind:sink
02-08 21:57:53.875 20973 21038 I GStreamer+GST_PADS: 0:00:08.349243163 0x9d319f20 gstpad.c:2498:gst_pad_link_full linked sink:proxypad0 and typefind:sink, successful
02-08 21:57:53.875 20973 21038 I GStreamer+GST_EVENT: 0:00:08.349365233 0x9d319f20 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event
02-08 21:57:53.875 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.349548339 0x9d319f20 gstelement.c:659:gst_element_add_pad:<GstDecodeBin at 0x9c606048> adding pad 'sink'
02-08 21:57:53.875 20973 21038 I GStreamer+GST_ELEMENT_FACTORY: 0:00:08.349884032 0x9d319f20 gstelementfactory.c:364:gst_element_factory_create creating element "glimagesink"
02-08 21:57:53.876 20973 21038 I GStreamer+GST_ELEMENT_FACTORY: 0:00:08.350128172 0x9d319f20 gstelementfactory.c:364:gst_element_factory_create creating element "glupload"
02-08 21:57:53.876 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.350402831 0x9d319f20 gstelement.c:659:gst_element_add_pad:<GstBaseTransform at 0x9c626160> adding pad 'sink'
02-08 21:57:53.876 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.350616454 0x9d319f20 gstelement.c:659:gst_element_add_pad:<GstBaseTransform at 0x9c626160> adding pad 'src'
02-08 21:57:53.876 20973 21038 I GStreamer+GST_ELEMENT_FACTORY: 0:00:08.350799559 0x9d319f20 gstelementfactory.c:364:gst_element_factory_create creating element "glcolorconvert"
02-08 21:57:53.877 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.350982665 0x9d319f20 gstelement.c:659:gst_element_add_pad:<GstBaseTransform at 0x9c6263c0> adding pad 'sink'
02-08 21:57:53.877 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.351196288 0x9d319f20 gstelement.c:659:gst_element_add_pad:<GstBaseTransform at 0x9c6263c0> adding pad 'src'
02-08 21:57:53.877 20973 21038 I GStreamer+GST_ELEMENT_FACTORY: 0:00:08.351348876 0x9d319f20 gstelementfactory.c:364:gst_element_factory_create creating element "glcolorbalance"
02-08 21:57:53.877 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.351593016 0x9d319f20 gstelement.c:659:gst_element_add_pad:<GstBaseTransform at 0x9c62e0a8> adding pad 'sink'
02-08 21:57:53.878 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.352142333 0x9d319f20 gstelement.c:659:gst_element_add_pad:<GstBaseTransform at 0x9c62e0a8> adding pad 'src'
02-08 21:57:53.878 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.352539061 0x9d319f20 gstutils.c:1571:gst_element_link_pads_full trying to link element gluploadelement0:src to element glcolorconvertelement0:sink
02-08 21:57:53.878 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.352661131 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad gluploadelement0:src
02-08 21:57:53.878 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.352752684 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad glcolorconvertelement0:sink
02-08 21:57:53.879 20973 21038 I GStreamer+GST_PADS: 0:00:08.352874754 0x9d319f20 gstutils.c:1444:prepare_link_maybe_ghosting gluploadelement0 and glcolorconvertelement0 in same bin, no need for ghost pads
02-08 21:57:53.879 20973 21038 I GStreamer+GST_PADS: 0:00:08.353210448 0x9d319f20 gstpad.c:2292:gst_pad_link_prepare trying to link gluploadelement0:src and glcolorconvertelement0:sink
02-08 21:57:53.879 20973 21038 I GStreamer+GST_PADS: 0:00:08.353393553 0x9d319f20 gstpad.c:4065:gst_pad_peer_query:<gluploadelement0:sink> pad has no peer
02-08 21:57:53.914 20973 21038 I GStreamer+GST_PADS: 0:00:08.388885498 0x9d319f20 gstpad.c:4065:gst_pad_peer_query:<glcolorconvertelement0:src> pad has no peer
02-08 21:57:53.915 20973 21038 I GStreamer+GST_PADS: 0:00:08.389831543 0x9d319f20 gstpad.c:2498:gst_pad_link_full linked gluploadelement0:src and glcolorconvertelement0:sink, successful
02-08 21:57:53.915 20973 21038 I GStreamer+GST_EVENT: 0:00:08.389923096 0x9d319f20 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event
02-08 21:57:53.916 20973 21038 I GStreamer+GST_EVENT: 0:00:08.390014649 0x9d319f20 gstpad.c:5601:gst_pad_send_event_unchecked:<gluploadelement0:src> Received event on flushing pad. Discarding
02-08 21:57:53.917 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.391540527 0x9d319f20 gstutils.c:1571:gst_element_link_pads_full trying to link element glcolorconvertelement0:src to element glcolorbalance0:sink
02-08 21:57:53.918 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.392059326 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad glcolorconvertelement0:src
02-08 21:57:53.918 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.392150879 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad glcolorbalance0:sink
02-08 21:57:53.918 20973 21038 I GStreamer+GST_PADS: 0:00:08.392456055 0x9d319f20 gstutils.c:1444:prepare_link_maybe_ghosting glcolorconvertelement0 and glcolorbalance0 in same bin, no need for ghost pads
02-08 21:57:53.918 20973 21038 I GStreamer+GST_PADS: 0:00:08.392608643 0x9d319f20 gstpad.c:2292:gst_pad_link_prepare trying to link glcolorconvertelement0:src and glcolorbalance0:sink
02-08 21:57:53.918 20973 21038 I GStreamer+GST_PADS: 0:00:08.392761230 0x9d319f20 gstpad.c:4065:gst_pad_peer_query:<gluploadelement0:sink> pad has no peer
02-08 21:57:53.960 20973 21038 I GStreamer+GST_PADS: 0:00:08.434234618 0x9d319f20 gstpad.c:4065:gst_pad_peer_query:<glcolorbalance0:src> pad has no peer
02-08 21:57:53.960 20973 21038 I GStreamer+GST_PADS: 0:00:08.434570311 0x9d319f20 gstpad.c:2498:gst_pad_link_full linked glcolorconvertelement0:src and glcolorbalance0:sink, successful
02-08 21:57:53.960 20973 21038 I GStreamer+GST_EVENT: 0:00:08.434631346 0x9d319f20 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event
02-08 21:57:53.960 20973 21038 I GStreamer+GST_EVENT: 0:00:08.434722899 0x9d319f20 gstpad.c:5601:gst_pad_send_event_unchecked:<glcolorconvertelement0:src> Received event on flushing pad. Discarding
02-08 21:57:53.960 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.434875487 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad gluploadelement0:sink
02-08 21:57:53.961 20973 21038 I GStreamer+GST_PADS: 0:00:08.435089110 0x9d319f20 gstpad.c:2292:gst_pad_link_prepare trying to link sink:proxypad1 and gluploadelement0:sink
02-08 21:57:53.961 20973 21038 I GStreamer+GST_PADS: 0:00:08.435180662 0x9d319f20 gstpad.c:2498:gst_pad_link_full linked sink:proxypad1 and gluploadelement0:sink, successful
02-08 21:57:53.963 20973 21038 I GStreamer+GST_EVENT: 0:00:08.437286375 0x9d319f20 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event
02-08 21:57:53.963 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.437622068 0x9d319f20 gstelement.c:659:gst_element_add_pad:<GstGLSinkBin at 0x9c621090> adding pad 'sink'
02-08 21:57:53.963 20973 21038 I GStreamer+gstcontrolbinding: 0:00:08.437866209 0x9d319f20 gstcontrolbinding.c:125:gst_control_binding_constructor:<glcolorbalance0> trying to put property 'contrast' under control
02-08 21:57:53.964 20973 21038 I GStreamer+gstcontrolbinding: 0:00:08.438018797 0x9d319f20 gstcontrolbinding.c:125:gst_control_binding_constructor:<glcolorbalance0> trying to put property 'brightness' under control
02-08 21:57:53.964 20973 21038 I GStreamer+gstcontrolbinding: 0:00:08.438201902 0x9d319f20 gstcontrolbinding.c:125:gst_control_binding_constructor:<glcolorbalance0> trying to put property 'hue' under control
02-08 21:57:53.964 20973 21038 I GStreamer+gstcontrolbinding: 0:00:08.438354490 0x9d319f20 gstcontrolbinding.c:125:gst_control_binding_constructor:<glcolorbalance0> trying to put property 'saturation' under control
02-08 21:57:53.964 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.438537596 0x9d319f20 gstelement.c:659:gst_element_add_pad:<GstBaseSink at 0x9d2fe3f8> adding pad 'sink'
02-08 21:57:53.964 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.438781736 0x9d319f20 gstutils.c:1571:gst_element_link_pads_full trying to link element glcolorbalance0:src to element sink:sink
02-08 21:57:53.964 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.438873289 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad glcolorbalance0:src
02-08 21:57:53.964 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.438934324 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad sink:sink
02-08 21:57:53.965 20973 21038 I GStreamer+GST_PADS: 0:00:08.439025877 0x9d319f20 gstutils.c:1444:prepare_link_maybe_ghosting glcolorbalance0 and sink in same bin, no need for ghost pads
02-08 21:57:53.965 20973 21038 I GStreamer+GST_PADS: 0:00:08.439117430 0x9d319f20 gstpad.c:2292:gst_pad_link_prepare trying to link glcolorbalance0:src and sink:sink
02-08 21:57:53.965 20973 21038 I GStreamer+GST_PADS: 0:00:08.439270018 0x9d319f20 gstpad.c:4065:gst_pad_peer_query:<(NULL):sink> pad has no peer
02-08 21:57:53.987 20973 20973 D GStreamer+aiTennis3D: 0:00:08.461914061 0xaebeae00 gstreamer/aigstreamer.c:1308:gst_native_surface_init Received surface 0xbeb2b050 (native window 0xaee7f208) for id 0
02-08 21:57:53.988 20973 20973 D GStreamer+aiTennis3D: 0:00:08.462036131 0xaebeae00 gstreamer/aigstreamer.c:157:attach_current_thread Attaching thread 0xaebeae00
02-08 21:57:53.989 20973 21038 I GStreamer+GST_PADS: 0:00:08.463714598 0x9d319f20 gstpad.c:2498:gst_pad_link_full linked glcolorbalance0:src and sink:sink, successful
02-08 21:57:53.989 20973 21038 I GStreamer+GST_EVENT: 0:00:08.463775633 0x9d319f20 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event
02-08 21:57:53.989 20973 21038 I GStreamer+GST_EVENT: 0:00:08.463867186 0x9d319f20 gstpad.c:5601:gst_pad_send_event_unchecked:<glcolorbalance0:src> Received event on flushing pad. Discarding
02-08 21:57:53.990 20973 21038 I GStreamer+GST_ELEMENT_FACTORY: 0:00:08.464202879 0x9d319f20 gstelementfactory.c:364:gst_element_factory_create creating element "pipeline"
02-08 21:57:53.990 20973 21038 I GStreamer+GST_PIPELINE: 0:00:08.464813231 0x9d319f20 ./grammar.y:596:gst_parse_perform_link linking some pad of GstFileSrc named filesrc0 to some pad of GstDecodeBin named decodebin_0 (0/0) with caps "(NULL)"
02-08 21:57:53.990 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.464904784 0x9d319f20 gstutils.c:1571:gst_element_link_pads_full trying to link element filesrc0:(any) to element decodebin_0:(any)
02-08 21:57:53.990 20973 21038 I GStreamer+GST_PADS: 0:00:08.464996336 0x9d319f20 gstutils.c:932:gst_pad_check_link trying to link filesrc0:src and decodebin_0:sink
02-08 21:57:53.991 20973 21038 I GStreamer+GST_PADS: 0:00:08.465179442 0x9d319f20 gstutils.c:1444:prepare_link_maybe_ghosting filesrc0 and decodebin_0 in same bin, no need for ghost pads
02-08 21:57:53.991 20973 21038 I GStreamer+GST_PADS: 0:00:08.465332031 0x9d319f20 gstpad.c:2292:gst_pad_link_prepare trying to link filesrc0:src and decodebin_0:sink
02-08 21:57:53.991 20973 21038 I GStreamer+GST_PADS: 0:00:08.465484619 0x9d319f20 gstpad.c:2498:gst_pad_link_full linked filesrc0:src and decodebin_0:sink, successful
02-08 21:57:53.991 20973 21038 I GStreamer+GST_EVENT: 0:00:08.465545654 0x9d319f20 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event
02-08 21:57:53.991 20973 21038 I GStreamer+GST_EVENT: 0:00:08.465637206 0x9d319f20 gstpad.c:5601:gst_pad_send_event_unchecked:<filesrc0:src> Received event on flushing pad. Discarding
02-08 21:57:53.991 20973 21038 I GStreamer+GST_PIPELINE: 0:00:08.465789794 0x9d319f20 ./grammar.y:596:gst_parse_perform_link linking some pad of GstDecodeBin named decodebin_0 to some pad of GstGLImageSinkBin named glimagesinkbin0 (0/0) with caps "(NULL)"
02-08 21:57:53.991 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.465881347 0x9d319f20 gstutils.c:1571:gst_element_link_pads_full trying to link element decodebin_0:(any) to element glimagesinkbin0:(any)
02-08 21:57:53.994 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.468139648 0x9d319f20 gstelement.c:904:gst_element_get_static_pad no such pad 'src_%u' in element "decodebin_0"
02-08 21:57:53.994 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.468353271 0x9d319f20 gstutils.c:1124:gst_element_get_compatible_pad:<decodebin_0> Could not find a compatible pad to link to glimagesinkbin0:sink
02-08 21:57:53.994 20973 21038 I GStreamer+default: 0:00:08.468444823 0x9d319f20 gstutils.c:1960:gst_element_link_pads_filtered Could not link pads: decodebin_0:(null) - glimagesinkbin0:(null)
02-08 21:57:53.994 20973 21038 I GStreamer+GST_STATES: 0:00:08.468627929 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<glimagesinkbin0> current NULL pending VOID_PENDING, desired next READY
02-08 21:57:53.994 20973 21038 I GStreamer+GST_STATES: 0:00:08.468780517 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<sink> current NULL pending VOID_PENDING, desired next READY
02-08 21:57:53.995 20973 21038 I GStreamer+GST_PADS: 0:00:08.469207763 0x9d319f20 gstpad.c:4065:gst_pad_peer_query:<glimagesinkbin0:sink> pad has no peer
02-08 21:57:53.995 20973 21038 I GStreamer+GST_CONTEXT: 0:00:08.469329833 0x9d319f20 gstglutils.c:528:pad_query:<sink:sink> pad peer query failed
02-08 21:57:53.995 20973 21038 I GStreamer+GST_CONTEXT: 0:00:08.469482421 0x9d319f20 gstglutils.c:590:_gst_context_query:<sink> posting need context message
02-08 21:57:53.995 20973 21038 I GStreamer+gldisplay: 0:00:08.469787597 0x9d319f20 gstgldisplay.c:191:gst_gl_display_new creating a display, user choice:(NULL) (platform: (NULL))
02-08 21:57:53.997 20973 21038 I GStreamer+GST_CONTEXT: 0:00:08.471008300 0x9d319f20 gstglutils.c:653:gst_gl_display_context_propagate:<sink> posting have context (0x9d2ec6c0) message with display (0x9c60e120)
02-08 21:57:53.997 20973 21038 I GStreamer+GST_PADS: 0:00:08.471405028 0x9d319f20 gstpad.c:4065:gst_pad_peer_query:<glimagesinkbin0:sink> pad has no peer
02-08 21:57:53.997 20973 21038 I GStreamer+GST_CONTEXT: 0:00:08.471557616 0x9d319f20 gstglutils.c:528:pad_query:<sink:sink> pad peer query failed
02-08 21:57:53.997 20973 21038 I GStreamer+GST_CONTEXT: 0:00:08.471710204 0x9d319f20 gstglutils.c:590:_gst_context_query:<sink> posting need context message
02-08 21:57:53.998 20973 21038 I GStreamer+glcontext: 0:00:08.472106933 0x9d319f20 gstglcontext.c:330:gst_gl_context_new creating a context for display <gldisplayegl0>, user choice:(null)
02-08 21:57:53.998 20973 21038 I GStreamer+glwindow: 0:00:08.472412108 0x9d319f20 gstglwindow.c:275:gst_gl_window_new creating a window, user choice:(null)
02-08 21:57:54.002 20973 21052 I GStreamer+glcontext: 0:00:08.476043701 0x9d2ec6f0 gstglcontext.c:1159: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)
02-08 21:57:54.002 20973 21052 I GStreamer+glcontext: 0:00:08.476196289 0x9d2ec6f0 gstglcontext_egl.c:366:gst_gl_context_egl_create_context egl initialized, version: 1.4
02-08 21:57:54.002 20973 21052 I GStreamer+glcontext: 0:00:08.476287842 0x9d2ec6f0 gstglcontext_egl.c:478:gst_gl_context_egl_create_context Bound OpenGL|ES
02-08 21:57:54.002 20973 21052 I GStreamer+glcontext: 0:00:08.476440429 0x9d2ec6f0 gstglcontext_egl.c:225:gst_gl_context_egl_choose_config config set: 2, 1
02-08 21:57:54.008 20973 21052 I GStreamer+glcontext: 0:00:08.482482910 0x9d2ec6f0 gstglcontext_egl.c:517:gst_gl_context_egl_create_context gl context created: 2624279872
02-08 21:57:54.008 20973 21052 I GStreamer+glcontext: 0:00:08.482727050 0x9d2ec6f0 gstglcontext_egl.c:591:gst_gl_context_egl_create_context surface created
02-08 21:57:54.014 20973 21052 I GStreamer+glcontext: 0:00:08.488525391 0x9d2ec6f0 gstglcontext.c:1170:gst_gl_context_create_thread:<glcontextegl0> created context
02-08 21:57:54.015 20973 21052 I GStreamer+glcontext: 0:00:08.489654541 0x9d2ec6f0 gstglcontext.c:1186:gst_gl_context_create_thread:<glcontextegl0> available GL APIs: gles2
02-08 21:57:54.016 20973 21052 I GStreamer+glcontext: 0:00:08.489868164 0x9d2ec6f0 gstglcontext.c:997:_create_context_info:<glcontextegl0> GL_VERSION: OpenGL ES 3.0 V at 127.0 AU@ (GIT at I96aee987eb)
02-08 21:57:54.016 20973 21052 I GStreamer+glcontext: 0:00:08.490386963 0x9d2ec6f0 gstglcontext.c:1000:_create_context_info:<glcontextegl0> GL_SHADING_LANGUAGE_VERSION: OpenGL ES GLSL ES 3.00
02-08 21:57:54.016 20973 21052 I GStreamer+glcontext: 0:00:08.490478515 0x9d2ec6f0 gstglcontext.c:1002:_create_context_info:<glcontextegl0> GL_VENDOR: Qualcomm
02-08 21:57:54.016 20973 21052 I GStreamer+glcontext: 0:00:08.490570068 0x9d2ec6f0 gstglcontext.c:1004:_create_context_info:<glcontextegl0> GL_RENDERER: Adreno (TM) 320
02-08 21:57:54.018 20973 21052 I GStreamer+glcontext: 0:00:08.492828369 0x9d2ec6f0 gstglcontext.c:1057:_unlock_create_thread:<glcontextegl0> gl thread running
02-08 21:57:54.019 20973 21038 I GStreamer+glcontext: 0:00:08.493469238 0x9d319f20 gstglcontext.c:963:gst_gl_context_create:<glcontextegl0> gl thread created
02-08 21:57:54.019 20973 21038 I GStreamer+GST_STATES: 0:00:08.493682861 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<sink> completed state change to READY
02-08 21:57:54.019 20973 21038 I GStreamer+GST_STATES: 0:00:08.493804931 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<sink> notifying about state-changed NULL to READY (VOID_PENDING pending)
02-08 21:57:54.019 20973 21038 I GStreamer+GST_STATES: 0:00:08.493927002 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'sink' changed state to 2(READY) successfully
02-08 21:57:54.020 20973 21038 I GStreamer+GST_STATES: 0:00:08.494049072 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<glcolorbalance0> current NULL pending VOID_PENDING, desired next READY
02-08 21:57:54.020 20973 21038 I GStreamer+GST_CONTEXT: 0:00:08.494201660 0x9d319f20 gstglutils.c:573:_gst_context_query:<glcolorbalance0> found context (0x9d2ec8f0) in downstream query
02-08 21:57:54.020 20973 21038 I GStreamer+GST_CONTEXT: 0:00:08.494354248 0x9d319f20 gstglutils.c:528:pad_query:<glcolorbalance0:src> pad peer query failed
02-08 21:57:54.020 20973 21038 I GStreamer+GST_PADS: 0:00:08.494537353 0x9d319f20 gstpad.c:4065:gst_pad_peer_query:<glimagesinkbin0:sink> pad has no peer
02-08 21:57:54.020 20973 21038 I GStreamer+GST_CONTEXT: 0:00:08.494628906 0x9d319f20 gstglutils.c:528:pad_query:<glcolorbalance0:sink> pad peer query failed
02-08 21:57:54.020 20973 21038 I GStreamer+GST_CONTEXT: 0:00:08.494720459 0x9d319f20 gstglutils.c:590:_gst_context_query:<glcolorbalance0> posting need context message
02-08 21:57:54.020 20973 21038 I GStreamer+GST_STATES: 0:00:08.494842529 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<glcolorbalance0> completed state change to READY
02-08 21:57:54.020 20973 21038 I GStreamer+GST_STATES: 0:00:08.494964599 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<glcolorbalance0> notifying about state-changed NULL to READY (VOID_PENDING pending)
02-08 21:57:54.021 20973 21038 I GStreamer+GST_STATES: 0:00:08.495056152 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'glcolorbalance0' changed state to 2(READY) successfully
02-08 21:57:54.021 20973 21038 I GStreamer+GST_STATES: 0:00:08.495178222 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<glcolorconvertelement0> current NULL pending VOID_PENDING, desired next READY
02-08 21:57:54.021 20973 21038 I GStreamer+GST_CONTEXT: 0:00:08.495391846 0x9d319f20 gstglutils.c:573:_gst_context_query:<glcolorconvertelement0> found context (0x9d2ec720) in downstream query
02-08 21:57:54.021 20973 21038 I GStreamer+GST_CONTEXT: 0:00:08.495574952 0x9d319f20 gstglutils.c:528:pad_query:<glcolorconvertelement0:src> pad peer query failed
02-08 21:57:54.021 20973 21038 I GStreamer+GST_PADS: 0:00:08.495727540 0x9d319f20 gstpad.c:4065:gst_pad_peer_query:<glimagesinkbin0:sink> pad has no peer
02-08 21:57:54.021 20973 21038 I GStreamer+GST_CONTEXT: 0:00:08.495819092 0x9d319f20 gstglutils.c:528:pad_query:<glcolorconvertelement0:sink> pad peer query failed
02-08 21:57:54.021 20973 21038 I GStreamer+GST_CONTEXT: 0:00:08.495910645 0x9d319f20 gstglutils.c:590:_gst_context_query:<glcolorconvertelement0> posting need context message
02-08 21:57:54.022 20973 21038 I GStreamer+GST_STATES: 0:00:08.496032715 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<glcolorconvertelement0> completed state change to READY
02-08 21:57:54.022 20973 21038 I GStreamer+GST_STATES: 0:00:08.496154786 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<glcolorconvertelement0> notifying about state-changed NULL to READY (VOID_PENDING pending)
02-08 21:57:54.022 20973 21038 I GStreamer+GST_STATES: 0:00:08.496276856 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'glcolorconvertelement0' changed state to 2(READY) successfully
02-08 21:57:54.022 20973 21038 I GStreamer+GST_STATES: 0:00:08.496368409 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<gluploadelement0> current NULL pending VOID_PENDING, desired next READY
02-08 21:57:54.022 20973 21038 I GStreamer+GST_CONTEXT: 0:00:08.496520997 0x9d319f20 gstglutils.c:573:_gst_context_query:<gluploadelement0> found context (0x9d2ec8c0) in downstream query
02-08 21:57:54.022 20973 21038 I GStreamer+GST_CONTEXT: 0:00:08.496765137 0x9d319f20 gstglutils.c:528:pad_query:<gluploadelement0:src> pad peer query failed
02-08 21:57:54.022 20973 21038 I GStreamer+GST_PADS: 0:00:08.496856690 0x9d319f20 gstpad.c:4065:gst_pad_peer_query:<glimagesinkbin0:sink> pad has no peer
02-08 21:57:54.022 20973 21038 I GStreamer+GST_CONTEXT: 0:00:08.496978760 0x9d319f20 gstglutils.c:528:pad_query:<gluploadelement0:sink> pad peer query failed
02-08 21:57:54.023 20973 21038 I GStreamer+GST_CONTEXT: 0:00:08.497070313 0x9d319f20 gstglutils.c:590:_gst_context_query:<gluploadelement0> posting need context message
02-08 21:57:54.023 20973 21038 I GStreamer+GST_STATES: 0:00:08.497192383 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<gluploadelement0> completed state change to READY
02-08 21:57:54.023 20973 21038 I GStreamer+GST_STATES: 0:00:08.497314454 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<gluploadelement0> notifying about state-changed NULL to READY (VOID_PENDING pending)
02-08 21:57:54.023 20973 21038 I GStreamer+GST_STATES: 0:00:08.497497559 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'gluploadelement0' changed state to 2(READY) successfully
02-08 21:57:54.023 20973 21038 I GStreamer+GST_STATES: 0:00:08.497741700 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<glimagesinkbin0> completed state change to READY
02-08 21:57:54.023 20973 21038 I GStreamer+GST_STATES: 0:00:08.497863770 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<glimagesinkbin0> notifying about state-changed NULL to READY (VOID_PENDING pending)
02-08 21:57:54.024 20973 21038 I GStreamer+GST_STATES: 0:00:08.498046875 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<pipeline0> child 'glimagesinkbin0' changed state to 2(READY) successfully
02-08 21:57:54.024 20973 21038 I GStreamer+GST_STATES: 0:00:08.498260498 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<decodebin_0> current NULL pending VOID_PENDING, desired next READY
02-08 21:57:54.024 20973 21038 I GStreamer+GST_STATES: 0:00:08.498535157 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<typefind> current NULL pending VOID_PENDING, desired next READY
02-08 21:57:54.024 20973 21038 I GStreamer+GST_STATES: 0:00:08.498718262 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<typefind> completed state change to READY
02-08 21:57:54.024 20973 21038 I GStreamer+GST_STATES: 0:00:08.498901368 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<typefind> notifying about state-changed NULL to READY (VOID_PENDING pending)
02-08 21:57:54.025 20973 21038 I GStreamer+GST_STATES: 0:00:08.499145508 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'typefind' changed state to 2(READY) successfully
02-08 21:57:54.025 20973 21038 I GStreamer+GST_STATES: 0:00:08.499328614 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<decodebin_0> completed state change to READY
02-08 21:57:54.025 20973 21038 I GStreamer+GST_STATES: 0:00:08.499511719 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<decodebin_0> notifying about state-changed NULL to READY (VOID_PENDING pending)
02-08 21:57:54.025 20973 21038 I GStreamer+GST_STATES: 0:00:08.499725342 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<pipeline0> child 'decodebin_0' changed state to 2(READY) successfully
02-08 21:57:54.025 20973 21038 I GStreamer+GST_STATES: 0:00:08.499908448 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<filesrc0> current NULL pending VOID_PENDING, desired next READY
02-08 21:57:54.026 20973 21038 I GStreamer+GST_STATES: 0:00:08.500061035 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<filesrc0> completed state change to READY
02-08 21:57:54.026 20973 21038 I GStreamer+GST_STATES: 0:00:08.500213623 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<filesrc0> notifying about state-changed NULL to READY (VOID_PENDING pending)
02-08 21:57:54.026 20973 21038 I GStreamer+GST_STATES: 0:00:08.500366211 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<pipeline0> child 'filesrc0' changed state to 2(READY) successfully
02-08 21:57:54.026 20973 21038 I GStreamer+GST_STATES: 0:00:08.500457764 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<pipeline0> completed state change to READY
02-08 21:57:54.026 20973 21038 I GStreamer+GST_STATES: 0:00:08.500579834 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<pipeline0> notifying about state-changed NULL to READY (VOID_PENDING pending)
02-08 21:57:54.026 20973 21038 I GStreamer+GST_PARENTAGE: 0:00:08.500701905 0x9d319f20 gstbin.c:4269:gst_bin_get_by_name [pipeline0]: looking up child element videocrop0
02-08 21:57:54.026 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:08.500885010 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad glimagesinkbin0:sink
02-08 21:57:54.026 20973 21038 I GStreamer+GST_PARENTAGE: 0:00:08.501007080 0x9d319f20 gstbin.c:4269:gst_bin_get_by_name [pipeline0]: looking up child element decodebin_0
02-08 21:57:54.027 20973 21038 I GStreamer+GST_PARENTAGE: 0:00:08.501159668 0x9d319f20 gstbin.c:4269:gst_bin_get_by_name [pipeline0]: looking up child element dropaudio
02-08 21:57:54.027 20973 21038 I GStreamer+GST_PARENTAGE: 0:00:08.501342774 0x9d319f20 gstbin.c:4269:gst_bin_get_by_name [pipeline0]: looking up child element dropvideo
02-08 21:57:54.028 20973 21038 D GStreamer+aiTennis3D: 0:00:08.502105713 0x9d319f20 gstreamer/aigstreamer.c:1048:app_function Entering main loop id=0... (CustomData:0x9d30e480)
02-08 21:57:54.028 20973 21038 D GStreamer+aiTennis3D: 0:00:08.502197266 0x9d319f20 gstreamer/aigstreamer.c:157:attach_current_thread Attaching thread 0x9d319f20
02-08 21:57:54.029 20973 21038 D GStreamer+aiTennis3D: 0:00:08.503479004 0x9d319f20 gstreamer/aigstreamer.c:895:check_initialization_complete Initialization complete for 0, notifying application. native_window:0xaee7f208 main_loop:0x9c634900
02-08 21:57:54.029 20973 21038 I System.out: Gst initialized for 0
02-08 21:57:54.030 20973 21038 I System.out: Gst setMessage (0) State changed (0) to READY
02-08 21:57:54.046 20973 20973 I GStreamer+GST_STATES: 0:00:08.520904541 0xaebeae00 gstbin.c:2316:gst_bin_element_set_state:<glimagesinkbin0> current READY pending VOID_PENDING, desired next PAUSED
02-08 21:57:54.047 20973 20973 I GStreamer+GST_STATES: 0:00:08.521148682 0xaebeae00 gstbin.c:2316:gst_bin_element_set_state:<sink> current READY pending VOID_PENDING, desired next PAUSED
02-08 21:57:54.047 20973 20973 I GStreamer+GST_STATES: 0:00:08.521575928 0xaebeae00 gstbin.c:2770:gst_bin_change_state_func:<glimagesinkbin0> child 'sink' is changing state asynchronously to PAUSED
02-08 21:57:54.047 20973 20973 I GStreamer+GST_STATES: 0:00:08.521728516 0xaebeae00 gstbin.c:2316:gst_bin_element_set_state:<glcolorbalance0> current READY pending VOID_PENDING, desired next PAUSED
02-08 21:57:54.047 20973 20973 I GStreamer+GST_STATES: 0:00:08.521942139 0xaebeae00 gstelement.c:2350:gst_element_continue_state:<glcolorbalance0> completed state change to PAUSED
02-08 21:57:54.048 20973 20973 I GStreamer+GST_STATES: 0:00:08.522094726 0xaebeae00 gstelement.c:2255:_priv_gst_element_state_changed:<glcolorbalance0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
02-08 21:57:54.048 20973 20973 I GStreamer+GST_STATES: 0:00:08.522277832 0xaebeae00 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'glcolorbalance0' changed state to 3(PAUSED) successfully
02-08 21:57:54.048 20973 20973 I GStreamer+GST_STATES: 0:00:08.522430420 0xaebeae00 gstbin.c:2316:gst_bin_element_set_state:<glcolorconvertelement0> current READY pending VOID_PENDING, desired next PAUSED
02-08 21:57:54.048 20973 20973 I GStreamer+GST_STATES: 0:00:08.522583008 0xaebeae00 gstelement.c:2350:gst_element_continue_state:<glcolorconvertelement0> completed state change to PAUSED
02-08 21:57:54.048 20973 20973 I GStreamer+GST_STATES: 0:00:08.522705078 0xaebeae00 gstelement.c:2255:_priv_gst_element_state_changed:<glcolorconvertelement0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
02-08 21:57:54.048 20973 20973 I GStreamer+GST_STATES: 0:00:08.522888183 0xaebeae00 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'glcolorconvertelement0' changed state to 3(PAUSED) successfully
02-08 21:57:54.049 20973 20973 I GStreamer+GST_STATES: 0:00:08.523040771 0xaebeae00 gstbin.c:2316:gst_bin_element_set_state:<gluploadelement0> current READY pending VOID_PENDING, desired next PAUSED
02-08 21:57:54.049 20973 20973 I GStreamer+GST_STATES: 0:00:08.523193359 0xaebeae00 gstelement.c:2350:gst_element_continue_state:<gluploadelement0> completed state change to PAUSED
02-08 21:57:54.049 20973 20973 I GStreamer+GST_STATES: 0:00:08.523315429 0xaebeae00 gstelement.c:2255:_priv_gst_element_state_changed:<gluploadelement0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
02-08 21:57:54.049 20973 20973 I GStreamer+GST_STATES: 0:00:08.523468017 0xaebeae00 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'gluploadelement0' changed state to 3(PAUSED) successfully
02-08 21:57:54.049 20973 20973 I GStreamer+GST_STATES: 0:00:08.523651123 0xaebeae00 gstbin.c:2770:gst_bin_change_state_func:<pipeline0> child 'glimagesinkbin0' is changing state asynchronously to PAUSED
02-08 21:57:54.049 20973 20973 I GStreamer+GST_STATES: 0:00:08.523803711 0xaebeae00 gstbin.c:2316:gst_bin_element_set_state:<decodebin_0> current READY pending VOID_PENDING, desired next PAUSED
02-08 21:57:54.050 20973 20973 I GStreamer+GST_STATES: 0:00:08.524017334 0xaebeae00 gstbin.c:2316:gst_bin_element_set_state:<typefind> current READY pending VOID_PENDING, desired next PAUSED
02-08 21:57:54.050 20973 20973 I GStreamer+filesrc: 0:00:08.524200439 0xaebeae00 gstfilesrc.c:460:gst_file_src_start:<filesrc0> opening file /storage/emulated/0/aiTennis3D/20160118_112256/videov.mp4
02-08 21:57:54.051 20973 20973 W GStreamer+basesrc: 0:00:08.525054931 0xaebeae00 gstbasesrc.c:3486:gst_base_src_start_complete:<filesrc0> pad not activated yet
02-08 21:57:54.051 20973 20973 I GStreamer+filesrc: 0:00:08.525390625 0xaebeae00 gstfilesrc.c:460:gst_file_src_start:<filesrc0> opening file /storage/emulated/0/aiTennis3D/20160118_112256/videov.mp4
02-08 21:57:54.051 20973 20973 I GStreamer+task: 0:00:08.525756836 0xaebeae00 gsttask.c:450:gst_task_set_lock setting stream lock 0x9d2ef1d4 on task 0x9d2d5828
02-08 21:57:54.051 20973 20973 I GStreamer+GST_PADS: 0:00:08.525878906 0xaebeae00 gstpad.c:5947:gst_pad_start_task:<typefind:sink> created task 0x9d2d5828
02-08 21:57:54.052 20973 20973 I GStreamer+GST_STATES: 0:00:08.526458740 0xaebeae00 gstelement.c:2350:gst_element_continue_state:<typefind> completed state change to PAUSED
02-08 21:57:54.053 20973 20973 I GStreamer+GST_STATES: 0:00:08.526611328 0xaebeae00 gstelement.c:2255:_priv_gst_element_state_changed:<typefind> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
02-08 21:57:54.053 20973 20973 I GStreamer+GST_STATES: 0:00:08.527648925 0xaebeae00 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'typefind' changed state to 3(PAUSED) successfully
02-08 21:57:54.053 20973 21056 I GStreamer+GST_ELEMENT_PADS: 0:00:08.527740478 0x9d319630 gstelement.c:907:gst_element_get_static_pad found pad typefind:sink
02-08 21:57:54.053 20973 20973 I GStreamer+GST_STATES: 0:00:08.527832031 0xaebeae00 gstbin.c:2770:gst_bin_change_state_func:<pipeline0> child 'decodebin_0' is changing state asynchronously to PAUSED
02-08 21:57:54.053 20973 20973 I GStreamer+GST_STATES: 0:00:08.527984619 0xaebeae00 gstbin.c:2316:gst_bin_element_set_state:<filesrc0> current READY pending VOID_PENDING, desired next PAUSED
02-08 21:57:54.054 20973 20973 I GStreamer+GST_STATES: 0:00:08.528137207 0xaebeae00 gstelement.c:2350:gst_element_continue_state:<filesrc0> completed state change to PAUSED
02-08 21:57:54.054 20973 20973 I GStreamer+GST_STATES: 0:00:08.528289794 0xaebeae00 gstelement.c:2255:_priv_gst_element_state_changed:<filesrc0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
02-08 21:57:54.054 20973 20973 I GStreamer+GST_STATES: 0:00:08.528472900 0xaebeae00 gstbin.c:2764:gst_bin_change_state_func:<pipeline0> child 'filesrc0' changed state to 3(PAUSED) successfully
02-08 21:57:54.055 20973 21056 I GStreamer+GST_ELEMENT_PADS: 0:00:08.529235839 0x9d319630 gstelement.c:907:gst_element_get_static_pad found pad typefind:src
02-08 21:57:54.055 20973 21056 I GStreamer+GST_ELEMENT_PADS: 0:00:08.529357910 0x9d319630 gstelement.c:907:gst_element_get_static_pad found pad typefind:sink
02-08 21:57:54.056 20973 21056 I GStreamer+GST_PADS: 0:00:08.530822753 0x9d319630 gstpad.c:2292:gst_pad_link_prepare trying to link typefind:src and decodepad0:proxypad2
02-08 21:57:54.056 20973 21056 I GStreamer+GST_PADS: 0:00:08.530944824 0x9d319630 gstpad.c:2498:gst_pad_link_full linked typefind:src and decodepad0:proxypad2, successful
02-08 21:57:54.056 20973 21056 I GStreamer+GST_EVENT: 0:00:08.531036376 0x9d319630 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event
02-08 21:57:54.057 20973 21056 I GStreamer+typefind: 0:00:08.531280517 0x9d319630 gsttypefindelement.c:178:gst_type_find_element_have_type:<typefind> found caps video/quicktime, variant=(string)iso, probability=100
02-08 21:57:54.057 20973 21056 I GStreamer+GST_EVENT: 0:00:08.531402587 0x9d319630 gstevent.c:679:gst_event_new_caps creating caps event video/quicktime, variant=(string)iso
02-08 21:57:54.057 20973 21056 I GStreamer+GST_ELEMENT_PADS: 0:00:08.531524657 0x9d319630 gstpad.c:2048:gst_pad_unlink unlinking typefind:src(0x9d2ef2d8) and decodepad0:proxypad2(0x9c61d658)
02-08 21:57:54.057 20973 21056 I GStreamer+GST_ELEMENT_PADS: 0:00:08.531646728 0x9d319630 gstpad.c:2102:gst_pad_unlink unlinked typefind:src and decodepad0:proxypad2
02-08 21:57:54.057 20973 21056 I GStreamer+GST_PADS: 0:00:08.531799316 0x9d319630 gstpad.c:2292:gst_pad_link_prepare trying to link typefind:src and decodepad0:proxypad2
02-08 21:57:54.057 20973 21056 I GStreamer+GST_PADS: 0:00:08.531890868 0x9d319630 gstpad.c:2498:gst_pad_link_full linked typefind:src and decodepad0:proxypad2, successful
02-08 21:57:54.057 20973 21056 I GStreamer+GST_EVENT: 0:00:08.531951904 0x9d319630 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event
02-08 21:57:54.064 20973 21056 I GStreamer+GST_ELEMENT_PADS: 0:00:08.538085936 0x9d319630 gstpad.c:2048:gst_pad_unlink unlinking typefind:src(0x9d2ef2d8) and decodepad0:proxypad2(0x9c61d658)
02-08 21:57:54.064 20973 21056 I GStreamer+GST_ELEMENT_PADS: 0:00:08.538208007 0x9d319630 gstpad.c:2102:gst_pad_unlink unlinked typefind:src and decodepad0:proxypad2
02-08 21:57:54.064 20973 21056 I GStreamer+GST_PADS: 0:00:08.538360595 0x9d319630 gstpad.c:2292:gst_pad_link_prepare trying to link typefind:src and decodepad0:proxypad2
02-08 21:57:54.064 20973 21056 I GStreamer+GST_PADS: 0:00:08.538452147 0x9d319630 gstpad.c:2498:gst_pad_link_full linked typefind:src and decodepad0:proxypad2, successful
02-08 21:57:54.064 20973 21056 I GStreamer+GST_EVENT: 0:00:08.538513182 0x9d319630 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event
02-08 21:57:54.064 20973 21056 I GStreamer+GST_ELEMENT_PADS: 0:00:08.538665770 0x9d319630 gstpad.c:2048:gst_pad_unlink unlinking typefind:src(0x9d2ef2d8) and decodepad0:proxypad2(0x9c61d658)
02-08 21:57:54.064 20973 21056 I GStreamer+GST_ELEMENT_PADS: 0:00:08.538787841 0x9d319630 gstpad.c:2102:gst_pad_unlink unlinked typefind:src and decodepad0:proxypad2
02-08 21:57:54.064 20973 21056 I GStreamer+GST_ELEMENT_FACTORY: 0:00:08.538909911 0x9d319630 gstelementfactory.c:364:gst_element_factory_create creating element "qtdemux"
02-08 21:57:54.066 20973 21056 I GStreamer+GST_ELEMENT_PADS: 0:00:08.540435790 0x9d319630 gstelement.c:659:gst_element_add_pad:<GstQTDemux at 0x99014050> adding pad 'sink'
02-08 21:57:54.066 20973 21056 I GStreamer+GST_PADS: 0:00:08.540710448 0x9d319630 gstpad.c:2292:gst_pad_link_prepare trying to link typefind:src and qtdemux0:sink
02-08 21:57:54.066 20973 21056 I GStreamer+GST_PADS: 0:00:08.540802001 0x9d319630 gstpad.c:2498:gst_pad_link_full linked typefind:src and qtdemux0:sink, successful
02-08 21:57:54.066 20973 21056 I GStreamer+GST_EVENT: 0:00:08.540863036 0x9d319630 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event
02-08 21:57:54.067 20973 21056 I GStreamer+GST_STATES: 0:00:08.541046141 0x9d319630 gstelement.c:2350:gst_element_continue_state:<qtdemux0> completed state change to READY
02-08 21:57:54.067 20973 21056 I GStreamer+GST_STATES: 0:00:08.541137694 0x9d319630 gstelement.c:2255:_priv_gst_element_state_changed:<qtdemux0> notifying about state-changed NULL to READY (VOID_PENDING pending)
02-08 21:57:54.067 20973 21056 I GStreamer+GST_ELEMENT_PADS: 0:00:08.541381835 0x9d319630 gstelement.c:904:gst_element_get_static_pad no such pad 'video_%u' in element "qtdemux0"
02-08 21:57:54.067 20973 21056 I GStreamer+GST_ELEMENT_PADS: 0:00:08.541442870 0x9d319630 gstelement.c:904:gst_element_get_static_pad no such pad 'audio_%u' in element "qtdemux0"
02-08 21:57:54.067 20973 21056 I GStreamer+GST_ELEMENT_PADS: 0:00:08.541534422 0x9d319630 gstelement.c:904:gst_element_get_static_pad no such pad 'subtitle_%u' in element "qtdemux0"
02-08 21:57:54.067 20973 21056 I GStreamer+task: 0:00:08.541809081 0x9d319630 gsttask.c:450:gst_task_set_lock setting stream lock 0x99018074 on task 0x9d2d5e10
02-08 21:57:54.067 20973 21056 I GStreamer+GST_PADS: 0:00:08.541900633 0x9d319630 gstpad.c:5947:gst_pad_start_task:<qtdemux0:sink> created task 0x9d2d5e10
02-08 21:57:54.068 20973 21056 I GStreamer+GST_STATES: 0:00:08.542633055 0x9d319630 gstelement.c:2350:gst_element_continue_state:<qtdemux0> completed state change to PAUSED
02-08 21:57:54.068 20973 21056 I GStreamer+GST_STATES: 0:00:08.542785643 0x9d319630 gstelement.c:2255:_priv_gst_element_state_changed:<qtdemux0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
02-08 21:57:54.069 20973 21056 I GStreamer+task: 0:00:08.543029784 0x9d319630 gsttask.c:315:gst_task_func:<typefind:sink> Task going to paused
02-08 21:57:54.072 20973 21057 I GStreamer+qtdemux: 0:00:08.545989989 0x96bfef50 qtdemux.c:11865:qtdemux_parse_tree:<qtdemux0> timescale: 1000
02-08 21:57:54.072 20973 21057 I GStreamer+qtdemux: 0:00:08.546112059 0x96bfef50 qtdemux.c:11866:qtdemux_parse_tree:<qtdemux0> duration: 41982
02-08 21:57:54.072 20973 21057 W GStreamer+qtdemux: 0:00:08.546478270 0x96bfef50 qtdemux.c:2637:qtdemux_parse_trex:<qtdemux0> failed to find fragment defaults for stream 1
02-08 21:57:54.073 20973 21057 I GStreamer+qtdemux: 0:00:08.547119139 0x96bfef50 qtdemux.c:9576:qtdemux_parse_trak:<qtdemux0> type avc1 caps video/x-h264, stream-format=(string)avc, alignment=(string)au, level=(string)4, profile=(string)baseline, codec_data=(buffer)01428028ffe1000a27428028e54028016b2001000528de01ae20
02-08 21:57:54.073 20973 21057 W GStreamer+basesrc: 0:00:08.547790526 0x96bfef50 gstbasesrc.c:2396:gst_base_src_update_length:<filesrc0> processing at or past EOS
02-08 21:57:54.073 20973 21057 I GStreamer+GST_SCHEDULING: 0:00:08.547912596 0x96bfef50 gstpad.c:4724:gst_pad_get_range_unchecked:<filesrc0:src> getrange failed, flow: eos
02-08 21:57:54.073 20973 21057 I GStreamer+GST_SCHEDULING: 0:00:08.548004149 0x96bfef50 gstpad.c:4939:gst_pad_pull_range:<decodebin_0:sink> pullrange failed, flow: eos
02-08 21:57:54.074 20973 21057 I GStreamer+GST_SCHEDULING: 0:00:08.548095701 0x96bfef50 gstpad.c:4724:gst_pad_get_range_unchecked:<sink:proxypad0> getrange failed, flow: eos
02-08 21:57:54.074 20973 21057 I GStreamer+GST_SCHEDULING: 0:00:08.548187254 0x96bfef50 gstpad.c:4939:gst_pad_pull_range:<typefind:sink> pullrange failed, flow: eos
02-08 21:57:54.074 20973 21057 I GStreamer+GST_SCHEDULING: 0:00:08.548278807 0x96bfef50 gstpad.c:4724:gst_pad_get_range_unchecked:<typefind:src> getrange failed, flow: eos
02-08 21:57:54.074 20973 21057 I GStreamer+GST_SCHEDULING: 0:00:08.548370360 0x96bfef50 gstpad.c:4939:gst_pad_pull_range:<qtdemux0:sink> pullrange failed, flow: eos
02-08 21:57:54.075 20973 21057 I GStreamer+GST_ELEMENT_PADS: 0:00:08.549682615 0x96bfef50 gstelement.c:907:gst_element_get_static_pad found pad qtdemux0:sink
02-08 21:57:54.076 20973 21057 I GStreamer+GST_EVENT: 0:00:08.550048826 0x96bfef50 gstevent.c:679:gst_event_new_caps creating caps event video/x-h264, stream-format=(string)avc, alignment=(string)au, level=(string)4, profile=(string)baseline, codec_data=(buffer)01428028ffe1000a27428028e54028016b2001000528de01ae20, width=(int)1280, height=(int)1440, framerate=(fraction)154/3, pixel-aspect-ratio=(fraction)1/1
02-08 21:57:54.076 20973 21057 I GStreamer+GST_ELEMENT_PADS: 0:00:08.550140379 0x96bfef50 gstelement.c:659:gst_element_add_pad:<qtdemux0> adding pad 'video_0'
02-08 21:57:54.076 20973 21057 I GStreamer+GST_ELEMENT_FACTORY: 0:00:08.550323484 0x96bfef50 gstelementfactory.c:364:gst_element_factory_create creating element "multiqueue"
02-08 21:57:54.076 20973 21057 I GStreamer+GST_ELEMENT_PADS: 0:00:08.550445555 0x96bfef50 gstelement.c:907:gst_element_get_static_pad found pad qtdemux0:sink
02-08 21:57:54.076 20973 21057 I GStreamer+GST_STATES: 0:00:08.550689695 0x96bfef50 gstelement.c:2325:gst_element_continue_state:<multiqueue0> committing state from NULL to READY, pending PAUSED, next PAUSED
02-08 21:57:54.076 20973 21057 I GStreamer+GST_STATES: 0:00:08.550781248 0x96bfef50 gstelement.c:2255:_priv_gst_element_state_changed:<multiqueue0> notifying about state-changed NULL to READY (PAUSED pending)
02-08 21:57:54.076 20973 21057 I GStreamer+GST_STATES: 0:00:08.550872801 0x96bfef50 gstelement.c:2332:gst_element_continue_state:<multiqueue0> continue state change READY to PAUSED, final PAUSED
02-08 21:57:54.076 20973 21057 I GStreamer+GST_STATES: 0:00:08.550994871 0x96bfef50 gstelement.c:2350:gst_element_continue_state:<multiqueue0> completed state change to PAUSED
02-08 21:57:54.077 20973 21057 I GStreamer+GST_STATES: 0:00:08.551086424 0x96bfef50 gstelement.c:2255:_priv_gst_element_state_changed:<multiqueue0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
02-08 21:57:54.077 20973 21057 I GStreamer+GST_PADS: 0:00:08.551483152 0x96bfef50 gstpad.c:2292:gst_pad_link_prepare trying to link qtdemux0:video_0 and decodepad1:proxypad3
02-08 21:57:54.077 20973 21057 I GStreamer+GST_PADS: 0:00:08.551574705 0x96bfef50 gstpad.c:2498:gst_pad_link_full linked qtdemux0:video_0 and decodepad1:proxypad3, successful
02-08 21:57:54.077 20973 21057 I GStreamer+GST_EVENT: 0:00:08.551635740 0x96bfef50 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event
02-08 21:57:54.078 20973 21057 I GStreamer+GST_ELEMENT_PADS: 0:00:08.552215574 0x96bfef50 gstpad.c:2048:gst_pad_unlink unlinking qtdemux0:video_0(0x99018568) and decodepad1:proxypad3(0x9c61d658)
02-08 21:57:54.078 20973 21057 I GStreamer+GST_ELEMENT_PADS: 0:00:08.552337644 0x96bfef50 gstpad.c:2102:gst_pad_unlink unlinked qtdemux0:video_0 and decodepad1:proxypad3
02-08 21:57:54.079 20973 21057 I GStreamer+task: 0:00:08.553039549 0x96bfef50 gsttask.c:450:gst_task_set_lock setting stream lock 0x99018854 on task 0x9907cc18
02-08 21:57:54.079 20973 21057 I GStreamer+GST_PADS: 0:00:08.553131101 0x96bfef50 gstpad.c:5947:gst_pad_start_task:<'':src_0> created task 0x9907cc18
02-08 21:57:54.079 20973 21057 I GStreamer+GST_ELEMENT_PADS: 0:00:08.553375242 0x96bfef50 gstelement.c:659:gst_element_add_pad:<multiqueue0> adding pad 'src_0'
02-08 21:57:54.079 20973 21057 I GStreamer+GST_ELEMENT_PADS: 0:00:08.553497312 0x96bfef50 gstelement.c:659:gst_element_add_pad:<multiqueue0> adding pad 'sink_0'
02-08 21:57:54.079 20973 21057 I GStreamer+GST_PADS: 0:00:08.553619383 0x96bfef50 gstpad.c:2292:gst_pad_link_prepare trying to link qtdemux0:video_0 and multiqueue0:sink_0
02-08 21:57:54.079 20973 21057 I GStreamer+GST_PADS: 0:00:08.553680418 0x96bfef50 gstpad.c:2498:gst_pad_link_full linked qtdemux0:video_0 and multiqueue0:sink_0, successful
02-08 21:57:54.079 20973 21057 I GStreamer+GST_EVENT: 0:00:08.553771971 0x96bfef50 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event
02-08 21:57:54.079 20973 21057 I GStreamer+GST_PADS: 0:00:08.553955076 0x96bfef50 gstpad.c:2292:gst_pad_link_prepare trying to link multiqueue0:src_0 and decodepad1:proxypad3
02-08 21:57:54.080 20973 21057 I GStreamer+GST_PADS: 0:00:08.554046629 0x96bfef50 gstpad.c:2498:gst_pad_link_full linked multiqueue0:src_0 and decodepad1:proxypad3, successful
02-08 21:57:54.080 20973 21057 I GStreamer+GST_EVENT: 0:00:08.554107664 0x96bfef50 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event
02-08 21:57:54.080 20973 21057 I GStreamer+GST_ELEMENT_PADS: 0:00:08.554321287 0x96bfef50 gstpad.c:2048:gst_pad_unlink unlinking multiqueue0:src_0(0x99018808) and decodepad1:proxypad3(0x9c61d658)
02-08 21:57:54.080 20973 21057 I GStreamer+GST_ELEMENT_PADS: 0:00:08.554412840 0x96bfef50 gstpad.c:2102:gst_pad_unlink unlinked multiqueue0:src_0 and decodepad1:proxypad3
02-08 21:57:54.080 20973 21057 I GStreamer+GST_PADS: 0:00:08.554565428 0x96bfef50 gstpad.c:2292:gst_pad_link_prepare trying to link multiqueue0:src_0 and decodepad1:proxypad3
02-08 21:57:54.080 20973 21057 I GStreamer+GST_PADS: 0:00:08.554656980 0x96bfef50 gstpad.c:2498:gst_pad_link_full linked multiqueue0:src_0 and decodepad1:proxypad3, successful
02-08 21:57:54.080 20973 21057 I GStreamer+GST_EVENT: 0:00:08.554718015 0x96bfef50 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event
02-08 21:57:54.080 20973 21057 I GStreamer+GST_ELEMENT_PADS: 0:00:08.554931638 0x96bfef50 gstpad.c:2048:gst_pad_unlink unlinking multiqueue0:src_0(0x99018808) and decodepad1:proxypad3(0x9c61d658)
02-08 21:57:54.081 20973 21057 I GStreamer+GST_ELEMENT_PADS: 0:00:08.555053709 0x96bfef50 gstpad.c:2102:gst_pad_unlink unlinked multiqueue0:src_0 and decodepad1:proxypad3
02-08 21:57:54.081 20973 21057 I GStreamer+GST_ELEMENT_FACTORY: 0:00:08.555145262 0x96bfef50 gstelementfactory.c:364:gst_element_factory_create creating element "h264parse"
02-08 21:57:54.081 20973 21057 I GStreamer+GST_ELEMENT_PADS: 0:00:08.555358886 0x96bfef50 gstelement.c:659:gst_element_add_pad:<GstBaseParse at 0xaee4da08> adding pad 'sink'
02-08 21:57:54.081 20973 21057 I GStreamer+GST_ELEMENT_PADS: 0:00:08.555541991 0x96bfef50 gstelement.c:659:gst_element_add_pad:<GstBaseParse at 0xaee4da08> adding pad 'src'
02-08 21:57:54.081 20973 21057 I GStreamer+baseparse: 0:00:08.555694579 0x96bfef50 gstbaseparse.c:3900:gst_base_parse_set_pts_interpolation:<GstH264Parse at 0xaee4da08> PTS interpolation: no
02-08 21:57:54.081 20973 21057 I GStreamer+GST_PADS: 0:00:08.555908202 0x96bfef50 gstpad.c:2292:gst_pad_link_prepare trying to link multiqueue0:src_0 and h264parse0:sink
02-08 21:57:54.081 20973 21057 I GStreamer+GST_PADS: 0:00:08.555999755 0x96bfef50 gstpad.c:2498:gst_pad_link_full linked multiqueue0:src_0 and h264parse0:sink, successful
02-08 21:57:54.082 20973 21057 I GStreamer+GST_EVENT: 0:00:08.556060790 0x96bfef50 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event
02-08 21:57:54.082 20973 21057 I GStreamer+GST_STATES: 0:00:08.556274413 0x96bfef50 gstelement.c:2350:gst_element_continue_state:<h264parse0> completed state change to READY
02-08 21:57:54.082 20973 21057 I GStreamer+GST_STATES: 0:00:08.556365966 0x96bfef50 gstelement.c:2255:_priv_gst_element_state_changed:<h264parse0> notifying about state-changed NULL to READY (VOID_PENDING pending)
02-08 21:57:54.082 20973 21057 I GStreamer+GST_ELEMENT_PADS: 0:00:08.556671141 0x96bfef50 gstelement.c:907:gst_element_get_static_pad found pad h264parse0:src
02-08 21:57:54.082 20973 21057 I GStreamer+GST_PADS: 0:00:08.556793212 0x96bfef50 gstpad.c:2292:gst_pad_link_prepare trying to link h264parse0:src and decodepad1:proxypad3
02-08 21:57:54.082 20973 21057 I GStreamer+GST_PADS: 0:00:08.556884764 0x96bfef50 gstpad.c:2498:gst_pad_link_full linked h264parse0:src and decodepad1:proxypad3, successful
02-08 21:57:54.082 20973 21057 I GStreamer+GST_EVENT: 0:00:08.556976317 0x96bfef50 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event
02-08 21:57:54.083 20973 21057 I GStreamer+GST_EVENT: 0:00:08.557037352 0x96bfef50 gstpad.c:5601:gst_pad_send_event_unchecked:<h264parse0:src> Received event on flushing pad. Discarding
02-08 21:57:54.083 20973 21057 I GStreamer+GST_ELEMENT_FACTORY: 0:00:08.557800292 0x96bfef50 gstelementfactory.c:364:gst_element_factory_create creating element "capsfilter"
02-08 21:57:54.084 20973 21057 I GStreamer+GST_ELEMENT_PADS: 0:00:08.558013915 0x96bfef50 gstelement.c:659:gst_element_add_pad:<GstBaseTransform at 0x9c828188> adding pad 'sink'
02-08 21:57:54.084 20973 21057 I GStreamer+GST_ELEMENT_PADS: 0:00:08.558166503 0x96bfef50 gstelement.c:659:gst_element_add_pad:<GstBaseTransform at 0x9c828188> adding pad 'src'
02-08 21:57:54.084 20973 21057 I GStreamer+GST_EVENT: 0:00:08.558319090 0x96bfef50 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event
02-08 21:57:54.084 20973 21057 I GStreamer+GST_STATES: 0:00:08.558410643 0x96bfef50 gstelement.c:2325:gst_element_continue_state:<capsfilter0> committing state from NULL to READY, pending PAUSED, next PAUSED
02-08 21:57:54.084 20973 21057 I GStreamer+GST_STATES: 0:00:08.558502196 0x96bfef50 gstelement.c:2255:_priv_gst_element_state_changed:<capsfilter0> notifying about state-changed NULL to READY (PAUSED pending)
02-08 21:57:54.084 20973 21057 I GStreamer+GST_STATES: 0:00:08.558624266 0x96bfef50 gstelement.c:2332:gst_element_continue_state:<capsfilter0> continue state change READY to PAUSED, final PAUSED
02-08 21:57:54.084 20973 21057 I GStreamer+GST_STATES: 0:00:08.558746337 0x96bfef50 gstelement.c:2350:gst_element_continue_state:<capsfilter0> completed state change to PAUSED
02-08 21:57:54.084 20973 21057 I GStreamer+GST_STATES: 0:00:08.558837889 0x96bfef50 gstelement.c:2255:_priv_gst_element_state_changed:<capsfilter0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
02-08 21:57:54.084 20973 21057 I GStreamer+GST_ELEMENT_PADS: 0:00:08.558990477 0x96bfef50 gstpad.c:2048:gst_pad_unlink unlinking h264parse0:src(0x99018aa8) and decodepad1:proxypad3(0x9c61d658)
02-08 21:57:54.085 20973 21057 I GStreamer+GST_ELEMENT_PADS: 0:00:08.559082030 0x96bfef50 gstpad.c:2102:gst_pad_unlink unlinked h264parse0:src and decodepad1:proxypad3
02-08 21:57:54.085 20973 21057 I GStreamer+GST_ELEMENT_PADS: 0:00:08.559204100 0x96bfef50 gstelement.c:907:gst_element_get_static_pad found pad capsfilter0:sink
02-08 21:57:54.085 20973 21057 I GStreamer+GST_PADS: 0:00:08.559753417 0x96bfef50 gstpad.c:2292:gst_pad_link_prepare trying to link h264parse0:src and capsfilter0:sink
02-08 21:57:54.085 20973 21057 I GStreamer+GST_PADS: 0:00:08.559844969 0x96bfef50 gstpad.c:2498:gst_pad_link_full linked h264parse0:src and capsfilter0:sink, successful
02-08 21:57:54.085 20973 21057 I GStreamer+GST_EVENT: 0:00:08.559967040 0x96bfef50 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event
02-08 21:57:54.086 20973 21057 I GStreamer+GST_EVENT: 0:00:08.560058592 0x96bfef50 gstpad.c:5601:gst_pad_send_event_unchecked:<h264parse0:src> Received event on flushing pad. Discarding
02-08 21:57:54.086 20973 21057 I GStreamer+GST_ELEMENT_PADS: 0:00:08.560180663 0x96bfef50 gstelement.c:907:gst_element_get_static_pad found pad capsfilter0:src
02-08 21:57:54.086 20973 21057 I GStreamer+GST_PADS: 0:00:08.560302733 0x96bfef50 gstpad.c:2292:gst_pad_link_prepare trying to link capsfilter0:src and decodepad1:proxypad3
02-08 21:57:54.086 20973 21057 I GStreamer+GST_PADS: 0:00:08.560394286 0x96bfef50 gstpad.c:2498:gst_pad_link_full linked capsfilter0:src and decodepad1:proxypad3, successful
02-08 21:57:54.086 20973 21057 I GStreamer+GST_EVENT: 0:00:08.560455321 0x96bfef50 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event
02-08 21:57:54.086 20973 21057 I GStreamer+GST_EVENT: 0:00:08.560546874 0x96bfef50 gstpad.c:5601:gst_pad_send_event_unchecked:<h264parse0:src> Received event on flushing pad. Discarding
02-08 21:57:54.087 20973 21057 I GStreamer+GST_STATES: 0:00:08.561828612 0x96bfef50 gstelement.c:2350:gst_element_continue_state:<h264parse0> completed state change to PAUSED
02-08 21:57:54.087 20973 21057 I GStreamer+GST_STATES: 0:00:08.561920164 0x96bfef50 gstelement.c:2255:_priv_gst_element_state_changed:<h264parse0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
02-08 21:57:54.088 20973 21057 I GStreamer+GST_EVENT: 0:00:08.562469481 0x96bfef50 gstevent.c:760:gst_event_new_segment creating segment event time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=0:00:41.982000000, 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
02-08 21:57:54.089 20973 21058 D GStreamer+aiTennis3D: 0:00:08.563690184 0x9c73fe30 gstreamer/aigstreamer.c:592:autoplug_query_cb Gg Element:h264parse0 Pad:decodepad1 Type-Query:caps
02-08 21:57:54.089 20973 21058 D GStreamer+aiTennis3D:
02-08 21:57:54.090 20973 21058 D GStreamer+aiTennis3D: 0:00:08.564453123 0x9c73fe30 gstreamer/aigstreamer.c:601:autoplug_query_cb Gg caps: video/x-h264, width=(int)[ 16, 4096 ], height=(int)[ 16, 4096 ], framerate=(fraction)[ 0/1, 2147483647/1 ], parsed=(boolean)true, stream-format=(string)byte-stream, alignment=(string)au, profile=(string)high; video/x-h264, width=(int)[ 16, 4096 ], height=(int)[ 16, 4096 ], framerate=(fraction)[ 0/1, 2147483647/1 ], parsed=(boolean)true, stream-format=(string)byte-stream, alignment=(string)au, profile=(string)main; video/x-h264, width=(int)[ 16, 4096 ], height=(int)[ 16, 4096 ], framerate=(fraction)[ 0/1, 2147483647/1 ], parsed=(boolean)true, stream-format=(string)byte-stream, alignment=(string)au, profile=(string)baseline; video/x-h264, width=(int)[ 16, 4096 ], height=(int)[ 16, 4096 ], framerate=(fraction)[ 0/1, 2147483647/1 ], parsed=(boolean)true, stream-format=(string)byte-stream, alignment=(string)au, profile=(string)constrained-baseline; video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, profile=(str
02-08 21:57:54.090 20973 21058 I GStreamer+GST_PADS: 0:00:08.564727781 0x9c73fe30 gstpad.c:4065:gst_pad_peer_query:<'':decodepad1> pad has no peer
02-08 21:57:54.093 20973 21058 I GStreamer+baseparse: 0:00:08.566986083 0x9c73fe30 gstbaseparse.c:4716:gst_base_parse_set_upstream_tags:<h264parse0> upstream tags: taglist, video-codec=(string)"H.264\ /\ AVC", bitrate=(uint)59910406, language-code=(string)en;
02-08 21:57:54.093 20973 21058 I GStreamer+h264parse: 0:00:08.567687987 0x9c73fe30 gsth264parse.c:777:gst_h264_parse_process_nal:<h264parse0> have SPS/PPS in stream
02-08 21:57:54.094 20973 21058 I GStreamer+baseparse: 0:00:08.568847655 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:54.095 20973 21058 D GStreamer+aiTennis3D: 0:00:08.569396972 0x9c73fe30 gstreamer/aigstreamer.c:592:autoplug_query_cb Gg Element:h264parse0 Pad:decodepad1 Type-Query:caps
02-08 21:57:54.095 20973 21058 D GStreamer+aiTennis3D:
02-08 21:57:54.096 20973 21058 D GStreamer+aiTennis3D: 0:00:08.570068358 0x9c73fe30 gstreamer/aigstreamer.c:601:autoplug_query_cb Gg caps: video/x-h264, width=(int)[ 16, 4096 ], height=(int)[ 16, 4096 ], framerate=(fraction)[ 0/1, 2147483647/1 ], parsed=(boolean)true, stream-format=(string)byte-stream, alignment=(string)au, profile=(string)high; video/x-h264, width=(int)[ 16, 4096 ], height=(int)[ 16, 4096 ], framerate=(fraction)[ 0/1, 2147483647/1 ], parsed=(boolean)true, stream-format=(string)byte-stream, alignment=(string)au, profile=(string)main; video/x-h264, width=(int)[ 16, 4096 ], height=(int)[ 16, 4096 ], framerate=(fraction)[ 0/1, 2147483647/1 ], parsed=(boolean)true, stream-format=(string)byte-stream, alignment=(string)au, profile=(string)baseline; video/x-h264, width=(int)[ 16, 4096 ], height=(int)[ 16, 4096 ], framerate=(fraction)[ 0/1, 2147483647/1 ], parsed=(boolean)true, stream-format=(string)byte-stream, alignment=(string)au, profile=(string)constrained-baseline; video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, profile=(str
02-08 21:57:54.096 20973 21058 I GStreamer+GST_PADS: 0:00:08.570251464 0x9c73fe30 gstpad.c:4065:gst_pad_peer_query:<'':decodepad1> pad has no peer
02-08 21:57:54.098 20973 21058 I GStreamer+GST_EVENT: 0:00:08.572387694 0x9c73fe30 gstevent.c:679:gst_event_new_caps creating caps event video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, level=(string)4, profile=(string)baseline, width=(int)1280, height=(int)1440, framerate=(fraction)154/3, pixel-aspect-ratio=(fraction)1/1, parsed=(boolean)true
02-08 21:57:54.098 20973 21058 D GStreamer+aiTennis3D: 0:00:08.572937010 0x9c73fe30 gstreamer/aigstreamer.c:592:autoplug_query_cb Gg Element:h264parse0 Pad:decodepad1 Type-Query:accept-caps
02-08 21:57:54.098 20973 21058 D GStreamer+aiTennis3D:
02-08 21:57:54.099 20973 21058 D GStreamer+aiTennis3D: 0:00:08.573150633 0x9c73fe30 gstreamer/aigstreamer.c:596:autoplug_query_cb Gg accept-caps: video/x-h264, width=(int)1280, height=(int)1440, framerate=(fraction)154/3, parsed=(boolean)true, stream-format=(string)byte-stream, alignment=(string)au, profile=(string)baseline, level=(string)4, pixel-aspect-ratio=(fraction)1/1
02-08 21:57:54.099 20973 21058 D GStreamer+aiTennis3D:
02-08 21:57:54.099 20973 21058 I GStreamer+GST_PADS: 0:00:08.573333739 0x9c73fe30 gstpad.c:4065:gst_pad_peer_query:<'':decodepad1> pad has no peer
02-08 21:57:54.099 20973 21058 I GStreamer+basetransform: 0:00:08.573516844 0x9c73fe30 gstbasetransform.c:1367:gst_base_transform_setcaps:<capsfilter0> reuse caps
02-08 21:57:54.099 20973 21058 I GStreamer+GST_EVENT: 0:00:08.573760985 0x9c73fe30 gstevent.c:679:gst_event_new_caps creating caps event video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, level=(string)4, profile=(string)baseline, width=(int)1280, height=(int)1440, framerate=(fraction)154/3, pixel-aspect-ratio=(fraction)1/1, parsed=(boolean)true
02-08 21:57:54.099 20973 21058 I GStreamer+GST_ELEMENT_PADS: 0:00:08.574005126 0x9c73fe30 gstpad.c:2048:gst_pad_unlink unlinking capsfilter0:src(0x99018d48) and decodepad1:proxypad3(0x9c61d658)
02-08 21:57:54.100 20973 21058 I GStreamer+GST_ELEMENT_PADS: 0:00:08.574157713 0x9c73fe30 gstpad.c:2102:gst_pad_unlink unlinked capsfilter0:src and decodepad1:proxypad3
02-08 21:57:54.100 20973 21058 I GStreamer+GST_PADS: 0:00:08.574340819 0x9c73fe30 gstpad.c:2292:gst_pad_link_prepare trying to link capsfilter0:src and decodepad1:proxypad3
02-08 21:57:54.100 20973 21058 I GStreamer+GST_PADS: 0:00:08.574523924 0x9c73fe30 gstpad.c:2498:gst_pad_link_full linked capsfilter0:src and decodepad1:proxypad3, successful
02-08 21:57:54.100 20973 21058 I GStreamer+GST_EVENT: 0:00:08.574615477 0x9c73fe30 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event
02-08 21:57:54.101 20973 21058 I GStreamer+GST_ELEMENT_PADS: 0:00:08.575531005 0x9c73fe30 gstpad.c:2048:gst_pad_unlink unlinking capsfilter0:src(0x99018d48) and decodepad1:proxypad3(0x9c61d658)
02-08 21:57:54.101 20973 21058 I GStreamer+GST_ELEMENT_PADS: 0:00:08.575683593 0x9c73fe30 gstpad.c:2102:gst_pad_unlink unlinked capsfilter0:src and decodepad1:proxypad3
02-08 21:57:54.101 20973 21058 I GStreamer+GST_PADS: 0:00:08.575866699 0x9c73fe30 gstpad.c:2292:gst_pad_link_prepare trying to link capsfilter0:src and decodepad1:proxypad3
02-08 21:57:54.101 20973 21058 I GStreamer+GST_PADS: 0:00:08.575988769 0x9c73fe30 gstpad.c:2498:gst_pad_link_full linked capsfilter0:src and decodepad1:proxypad3, successful
02-08 21:57:54.102 20973 21058 I GStreamer+GST_EVENT: 0:00:08.576080322 0x9c73fe30 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event
02-08 21:57:54.102 20973 21058 I GStreamer+GST_ELEMENT_PADS: 0:00:08.576538085 0x9c73fe30 gstpad.c:2048:gst_pad_unlink unlinking capsfilter0:src(0x99018d48) and decodepad1:proxypad3(0x9c61d658)
02-08 21:57:54.102 20973 21058 I GStreamer+GST_ELEMENT_PADS: 0:00:08.576690673 0x9c73fe30 gstpad.c:2102:gst_pad_unlink unlinked capsfilter0:src and decodepad1:proxypad3
02-08 21:57:54.102 20973 21058 I GStreamer+GST_PADS: 0:00:08.576873779 0x9c73fe30 gstpad.c:2292:gst_pad_link_prepare trying to link capsfilter0:src and decodepad1:proxypad3
02-08 21:57:54.102 20973 21058 I GStreamer+GST_PADS: 0:00:08.576965331 0x9c73fe30 gstpad.c:2498:gst_pad_link_full linked capsfilter0:src and decodepad1:proxypad3, successful
02-08 21:57:54.103 20973 21058 I GStreamer+GST_EVENT: 0:00:08.577056884 0x9c73fe30 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event
02-08 21:57:54.103 20973 21058 I GStreamer+GST_ELEMENT_PADS: 0:00:08.577392578 0x9c73fe30 gstpad.c:2048:gst_pad_unlink unlinking capsfilter0:src(0x99018d48) and decodepad1:proxypad3(0x9c61d658)
02-08 21:57:54.103 20973 21058 I GStreamer+GST_ELEMENT_PADS: 0:00:08.577514648 0x9c73fe30 gstpad.c:2102:gst_pad_unlink unlinked capsfilter0:src and decodepad1:proxypad3
02-08 21:57:54.103 20973 21058 I GStreamer+GST_ELEMENT_FACTORY: 0:00:08.577667236 0x9c73fe30 gstelementfactory.c:364:gst_element_factory_create creating element "amcviddec-omxqcomvideodecoderavc"
02-08 21:57:54.103 20973 21058 I GStreamer+GST_ELEMENT_PADS: 0:00:08.577972412 0x9c73fe30 gstelement.c:659:gst_element_add_pad:<GstVideoDecoder at 0x96bdc148> adding pad 'sink'
02-08 21:57:54.104 20973 21058 I GStreamer+GST_ELEMENT_PADS: 0:00:08.578186035 0x9c73fe30 gstelement.c:659:gst_element_add_pad:<GstVideoDecoder at 0x96bdc148> adding pad 'src'
02-08 21:57:54.104 20973 21058 I GStreamer+GST_PADS: 0:00:08.578491210 0x9c73fe30 gstpad.c:2292:gst_pad_link_prepare trying to link capsfilter0:src and amcvideodec-omxqcomvideodecoderavc0:sink
02-08 21:57:54.104 20973 21058 I GStreamer+GST_PADS: 0:00:08.578613281 0x9c73fe30 gstpad.c:2498:gst_pad_link_full linked capsfilter0:src and amcvideodec-omxqcomvideodecoderavc0:sink, successful
02-08 21:57:54.104 20973 21058 I GStreamer+GST_EVENT: 0:00:08.578704833 0x9c73fe30 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event
02-08 21:57:54.108 20973 21062 I OMXClient: Using client-side OMX mux.
02-08 21:57:54.132 20973 21058 I GStreamer+GST_STATES: 0:00:08.606964112 0x9c73fe30 gstelement.c:2350:gst_element_continue_state:<amcvideodec-omxqcomvideodecoderavc0> completed state change to READY
02-08 21:57:54.133 20973 21058 I GStreamer+GST_STATES: 0:00:08.607116700 0x9c73fe30 gstelement.c:2255:_priv_gst_element_state_changed:<amcvideodec-omxqcomvideodecoderavc0> notifying about state-changed NULL to READY (VOID_PENDING pending)
02-08 21:57:54.133 20973 21058 I GStreamer+GST_PADS: 0:00:08.607513428 0x9c73fe30 gstpad.c:4065:gst_pad_peer_query:<amcvideodec-omxqcomvideodecoderavc0:src> pad has no peer
02-08 21:57:54.134 20973 21058 I GStreamer+GST_ELEMENT_PADS: 0:00:08.608428956 0x9c73fe30 gstelement.c:907:gst_element_get_static_pad found pad amcvideodec-omxqcomvideodecoderavc0:src
02-08 21:57:54.134 20973 21058 I GStreamer+GST_PADS: 0:00:08.608612061 0x9c73fe30 gstpad.c:2292:gst_pad_link_prepare trying to link amcvideodec-omxqcomvideodecoderavc0:src and decodepad1:proxypad3
02-08 21:57:54.134 20973 21058 I GStreamer+GST_PADS: 0:00:08.608734131 0x9c73fe30 gstpad.c:2498:gst_pad_link_full linked amcvideodec-omxqcomvideodecoderavc0:src and decodepad1:proxypad3, successful
02-08 21:57:54.134 20973 21058 I GStreamer+GST_EVENT: 0:00:08.608825684 0x9c73fe30 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event
02-08 21:57:54.134 20973 21058 I GStreamer+GST_EVENT: 0:00:08.608947754 0x9c73fe30 gstpad.c:5601:gst_pad_send_event_unchecked:<amcvideodec-omxqcomvideodecoderavc0:src> Received event on flushing pad. Discarding
02-08 21:57:54.135 20973 21058 I GStreamer+GST_STATES: 0:00:08.609191895 0x9c73fe30 gstelement.c:2350:gst_element_continue_state:<amcvideodec-omxqcomvideodecoderavc0> completed state change to PAUSED
02-08 21:57:54.135 20973 21058 I GStreamer+GST_STATES: 0:00:08.609313965 0x9c73fe30 gstelement.c:2255:_priv_gst_element_state_changed:<amcvideodec-omxqcomvideodecoderavc0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
02-08 21:57:54.135 20973 21058 V GStreamer+videodecoder: 0:00:08.609527588 0x9c73fe30 gstvideodecoder.c:1057:gst_video_decoder_drain_out:<amcvideodec-omxqcomvideodecoderavc0> Sub-class should implement drain()
02-08 21:57:54.135 20973 21058 D GStreamer+aiTennis3D: 0:00:08.609863282 0x9c73fe30 gstreamer/aigstreamer.c:592:autoplug_query_cb Gg Element:amcvideodec-omxqcomvideodecoderavc0 Pad:decodepad1 Type-Query:caps
02-08 21:57:54.135 20973 21058 D GStreamer+aiTennis3D:
02-08 21:57:54.136 20973 21058 D GStreamer+aiTennis3D: 0:00:08.610107422 0x9c73fe30 gstreamer/aigstreamer.c:601:autoplug_query_cb Gg caps: video/x-raw(memory:GLMemory), width=(int)1280, height=(int)1440, framerate=(fraction)154/3, pixel-aspect-ratio=(fraction)1/1; video/x-raw, width=(int)1280, height=(int)1440, framerate=(fraction)154/3, pixel-aspect-ratio=(fraction)1/1
02-08 21:57:54.136 20973 21058 D GStreamer+aiTennis3D:
02-08 21:57:54.136 20973 21058 I GStreamer+GST_PADS: 0:00:08.610351563 0x9c73fe30 gstpad.c:4065:gst_pad_peer_query:<'':decodepad1> pad has no peer
02-08 21:57:54.137 20973 21058 D GStreamer+aiTennis3D: 0:00:08.611511231 0x9c73fe30 gstreamer/aigstreamer.c:592:autoplug_query_cb Gg Element:amcvideodec-omxqcomvideodecoderavc0 Pad:decodepad1 Type-Query:caps
02-08 21:57:54.137 20973 21058 D GStreamer+aiTennis3D:
02-08 21:57:54.137 20973 21058 D GStreamer+aiTennis3D: 0:00:08.611785889 0x9c73fe30 gstreamer/aigstreamer.c:601:autoplug_query_cb Gg caps: video/x-raw(memory:GLMemory), format=(string)RGBA, texture-target=(string)external-oes; video/x-raw, format=(string)NV12, width=(int)[ 1, 2147483647 ], height=(int)[ 1, 2147483647 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-raw, format=(string)I420, width=(int)[ 1, 2147483647 ], height=(int)[ 1, 2147483647 ], framerate=(fraction)[ 0/1, 2147483647/1 ]
02-08 21:57:54.137 20973 21058 D GStreamer+aiTennis3D:
02-08 21:57:54.138 20973 21058 D GStreamer+aiTennis3D: 0:00:08.612121582 0x9c73fe30 gstreamer/aigstreamer.c:625:autoplug_query_cb WE FOUND IT video/x-raw(memory:GLMemory), format=(string)RGBA, texture-target=(string)external-oes
02-08 21:57:54.138 20973 21058 D GStreamer+aiTennis3D:
02-08 21:57:54.138 20973 21058 I GStreamer+GST_EVENT: 0:00:08.612518311 0x9c73fe30 gstevent.c:679:gst_event_new_caps creating caps event video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)1280, height=(int)1440, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, colorimetry=(string)sRGB, framerate=(fraction)154/3
02-08 21:57:54.138 20973 21058 D GStreamer+aiTennis3D: 0:00:08.612701416 0x9c73fe30 gstreamer/aigstreamer.c:592:autoplug_query_cb Gg Element:amcvideodec-omxqcomvideodecoderavc0 Pad:decodepad1 Type-Query:accept-caps
02-08 21:57:54.138 20973 21058 D GStreamer+aiTennis3D:
02-08 21:57:54.138 20973 21058 D GStreamer+aiTennis3D: 0:00:08.612884522 0x9c73fe30 gstreamer/aigstreamer.c:596:autoplug_query_cb Gg accept-caps: video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)1280, height=(int)1440, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, colorimetry=(string)sRGB, framerate=(fraction)154/3
02-08 21:57:54.138 20973 21058 D GStreamer+aiTennis3D:
02-08 21:57:54.139 20973 21058 I GStreamer+GST_PADS: 0:00:08.613037110 0x9c73fe30 gstpad.c:4065:gst_pad_peer_query:<'':decodepad1> pad has no peer
02-08 21:57:54.139 20973 21058 D GStreamer+aiTennis3D: 0:00:08.613250733 0x9c73fe30 gstreamer/aigstreamer.c:592:autoplug_query_cb Gg Element:amcvideodec-omxqcomvideodecoderavc0 Pad:decodepad1 Type-Query:allocation
02-08 21:57:54.139 20973 21058 D GStreamer+aiTennis3D:
02-08 21:57:54.139 20973 21058 I GStreamer+GST_PADS: 0:00:08.613403320 0x9c73fe30 gstpad.c:4065:gst_pad_peer_query:<'':decodepad1> pad has no peer
02-08 21:57:54.140 20973 21058 D GStreamer+aiTennis3D: 0:00:08.614105225 0x9c73fe30 gstreamer/aigstreamer.c:592:autoplug_query_cb Gg Element:amcvideodec-omxqcomvideodecoderavc0 Pad:decodepad1 Type-Query:context
02-08 21:57:54.140 20973 21058 D GStreamer+aiTennis3D:
02-08 21:57:54.140 20973 21058 I GStreamer+GST_ELEMENT_PADS: 0:00:08.614196777 0x9c73fe30 gstelement.c:907:gst_element_get_static_pad found pad glimagesinkbin0:sink
02-08 21:57:54.140 20973 21058 I GStreamer+GST_CONTEXT: 0:00:08.614410400 0x9c73fe30 gstglutils.c:573:_gst_context_query:<amcvideodec-omxqcomvideodecoderavc0> found context (0x96c68120) in downstream query
02-08 21:57:54.140 20973 21058 D GStreamer+aiTennis3D: 0:00:08.614562988 0x9c73fe30 gstreamer/aigstreamer.c:592:autoplug_query_cb Gg Element:amcvideodec-omxqcomvideodecoderavc0 Pad:decodepad1 Type-Query:context
02-08 21:57:54.140 20973 21058 D GStreamer+aiTennis3D:
02-08 21:57:54.140 20973 21058 I GStreamer+GST_ELEMENT_PADS: 0:00:08.614685059 0x9c73fe30 gstelement.c:907:gst_element_get_static_pad found pad glimagesinkbin0:sink
02-08 21:57:54.141 20973 21058 I GStreamer+GST_PADS: 0:00:08.615020752 0x9c73fe30 gstpad.c:4065:gst_pad_peer_query:<'':decodepad1> pad has no peer
02-08 21:57:54.141 20973 21058 I GStreamer+GST_CONTEXT: 0:00:08.615142822 0x9c73fe30 gstglutils.c:528:pad_query:<amcvideodec-omxqcomvideodecoderavc0:src> pad peer query failed
02-08 21:57:54.141 20973 21058 I GStreamer+GST_CONTEXT: 0:00:08.615447998 0x9c73fe30 gstglutils.c:528:pad_query:<amcvideodec-omxqcomvideodecoderavc0:sink> pad peer query failed
02-08 21:57:54.141 20973 21058 I GStreamer+GST_CONTEXT: 0:00:08.615570068 0x9c73fe30 gstglutils.c:590:_gst_context_query:<amcvideodec-omxqcomvideodecoderavc0> posting need context message
02-08 21:57:54.141 20973 21058 D GStreamer+aiTennis3D: 0:00:08.615936279 0x9c73fe30 gstreamer/aigstreamer.c:592:autoplug_query_cb Gg Element:amcvideodec-omxqcomvideodecoderavc0 Pad:decodepad1 Type-Query:context
02-08 21:57:54.141 20973 21058 D GStreamer+aiTennis3D:
02-08 21:57:54.142 20973 21058 I GStreamer+GST_ELEMENT_PADS: 0:00:08.616027832 0x9c73fe30 gstelement.c:907:gst_element_get_static_pad found pad glimagesinkbin0:sink
02-08 21:57:54.142 20973 21058 I GStreamer+amcvideodec: 0:00:08.616394043 0x9c73fe30 gstamcvideodec.c:1953:gst_amc_video_dec_set_format:<amcvideodec-omxqcomvideodecoderavc0> GL output: enabled
02-08 21:57:54.144 20973 21059 I MediaCodec: [OMX.qcom.video.decoder.avc] setting surface generation to 21476353
02-08 21:57:54.145 195 19231 E OMX-VDEC-1080P: Extension: OMX.google.android.index.storeANWBufferInMetadata not implemented
02-08 21:57:54.145 195 19231 E OMX-VDEC-1080P: Extension: OMX.google.android.index.storeMetaDataInBuffers not implemented
02-08 21:57:54.145 195 19231 E OMXNodeInstance: getExtensionIndex(106:qcom.decoder.avc, OMX.google.android.index.storeMetaDataInBuffers) ERROR: NotImplemented(0x80001006)
02-08 21:57:54.145 20973 21062 E ACodec : [OMX.qcom.video.decoder.avc] storeMetaDataInBuffers failed w/ err -2147483648
02-08 21:57:54.146 195 19232 E OMX-VDEC-1080P:
02-08 21:57:54.146 195 19232 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.146 195 4389 E OMX-VDEC-1080P:
02-08 21:57:54.146 195 4389 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.146 195 19231 E OMX-VDEC-1080P:
02-08 21:57:54.146 195 19231 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.146 195 19231 E OMX-VDEC-1080P:
02-08 21:57:54.146 195 19231 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.147 195 3995 E OMX-VDEC-1080P:
02-08 21:57:54.147 195 3995 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.151 195 32643 E OMX-VDEC-1080P:
02-08 21:57:54.151 195 32643 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.151 195 195 E OMX-VDEC-1080P:
02-08 21:57:54.151 195 195 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.151 20973 21062 D SurfaceUtils: set up nativeWindow 0xaedd1608 for 1280x1440, color 0x7fa30c03, rotation 0, usage 0x42002900
02-08 21:57:54.151 195 19232 E OMX-VDEC-1080P:
02-08 21:57:54.151 195 19232 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.151 195 19232 E OMX-VDEC-1080P:
02-08 21:57:54.151 195 19232 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.156 195 687 E OMX-VDEC-1080P:
02-08 21:57:54.156 195 687 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.156 195 687 E OMX-VDEC-1080P: GET_MV_BUFFER_SIZE returned: Size: 491520 and alignment: 8192
02-08 21:57:54.162 195 655 E OMX-VDEC-1080P:
02-08 21:57:54.162 195 655 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.166 195 4389 E OMX-VDEC-1080P:
02-08 21:57:54.166 195 4389 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.170 195 19231 E OMX-VDEC-1080P:
02-08 21:57:54.170 195 19231 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.174 195 32643 E OMX-VDEC-1080P:
02-08 21:57:54.174 195 32643 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.178 195 195 E OMX-VDEC-1080P:
02-08 21:57:54.178 195 195 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.182 195 3995 E OMX-VDEC-1080P:
02-08 21:57:54.182 195 3995 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.186 195 19232 E OMX-VDEC-1080P:
02-08 21:57:54.186 195 19232 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.190 195 687 E OMX-VDEC-1080P:
02-08 21:57:54.190 195 687 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.194 195 655 E OMX-VDEC-1080P:
02-08 21:57:54.194 195 655 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.198 195 4389 E OMX-VDEC-1080P:
02-08 21:57:54.198 195 4389 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.200 20973 21058 I GStreamer+task: 0:00:08.674530028 0x9c73fe30 gsttask.c:450:gst_task_set_lock setting stream lock 0xaedf11dc on task 0x9d2e9220
02-08 21:57:54.200 20973 21058 I GStreamer+GST_PADS: 0:00:08.674682616 0x9c73fe30 gstpad.c:5947:gst_pad_start_task:<amcvideodec-omxqcomvideodecoderavc0:src> created task 0x9d2e9220
02-08 21:57:54.201 20973 21058 V GStreamer+videodecoder: 0:00:08.675140379 0x9c73fe30 gstvideodecoder.c:1057:gst_video_decoder_drain_out:<amcvideodec-omxqcomvideodecoderavc0> Sub-class should implement drain()
02-08 21:57:54.201 20973 21058 D GStreamer+aiTennis3D: 0:00:08.675537109 0x9c73fe30 gstreamer/aigstreamer.c:592:autoplug_query_cb Gg Element:amcvideodec-omxqcomvideodecoderavc0 Pad:decodepad1 Type-Query:caps
02-08 21:57:54.201 20973 21058 D GStreamer+aiTennis3D:
02-08 21:57:54.201 20973 21058 D GStreamer+aiTennis3D: 0:00:08.675781249 0x9c73fe30 gstreamer/aigstreamer.c:601:autoplug_query_cb Gg caps: video/x-raw(memory:GLMemory), width=(int)1280, height=(int)1440, framerate=(fraction)154/3, pixel-aspect-ratio=(fraction)1/1; video/x-raw, width=(int)1280, height=(int)1440, framerate=(fraction)154/3, pixel-aspect-ratio=(fraction)1/1
02-08 21:57:54.201 20973 21058 D GStreamer+aiTennis3D:
02-08 21:57:54.202 20973 21058 I GStreamer+GST_PADS: 0:00:08.676086425 0x9c73fe30 gstpad.c:4065:gst_pad_peer_query:<'':decodepad1> pad has no peer
02-08 21:57:54.204 20973 21058 I GStreamer+videodecoder: 0:00:08.678558349 0x9c73fe30 gstvideodecoder.c:1432:gst_video_decoder_sink_event_default:<amcvideodec-omxqcomvideodecoderavc0> upstream tags: taglist, video-codec=(string)"H.264\ /\ AVC", bitrate=(uint)59910406, language-code=(string)en;
02-08 21:57:54.205 20973 21058 D GStreamer+aiTennis3D: 0:00:08.679168700 0x9c73fe30 gstreamer/aigstreamer.c:592:autoplug_query_cb Gg Element:amcvideodec-omxqcomvideodecoderavc0 Pad:decodepad1 Type-Query:caps
02-08 21:57:54.205 20973 21058 D GStreamer+aiTennis3D:
02-08 21:57:54.205 20973 21058 D GStreamer+aiTennis3D: 0:00:08.679351806 0x9c73fe30 gstreamer/aigstreamer.c:601:autoplug_query_cb Gg caps: video/x-raw(memory:GLMemory), width=(int)1280, height=(int)1440, framerate=(fraction)154/3, pixel-aspect-ratio=(fraction)1/1; video/x-raw, width=(int)1280, height=(int)1440, framerate=(fraction)154/3, pixel-aspect-ratio=(fraction)1/1
02-08 21:57:54.205 20973 21058 D GStreamer+aiTennis3D:
02-08 21:57:54.205 20973 21058 I GStreamer+GST_PADS: 0:00:08.679565429 0x9c73fe30 gstpad.c:4065:gst_pad_peer_query:<'':decodepad1> pad has no peer
02-08 21:57:54.206 20973 21058 I GStreamer+basetransform: 0:00:08.680450438 0x9c73fe30 gstbasetransform.c:1367:gst_base_transform_setcaps:<capsfilter0> reuse caps
02-08 21:57:54.385 20973 21058 I GStreamer+baseparse: 0:00:08.859191893 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:54.385 20973 21058 I GStreamer+videodecoder: 0:00:08.859832762 0x9c73fe30 gstvideodecoder.c:1432:gst_video_decoder_sink_event_default:<amcvideodec-omxqcomvideodecoderavc0> upstream tags: taglist, video-codec=(string)"H.264\ \(Baseline\ Profile\)", bitrate=(uint)59910406, language-code=(string)en;
02-08 21:57:54.393 20973 21058 I GStreamer+baseparse: 0:00:08.867156981 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:54.394 195 21063 E OMX-VDEC-1080P:
02-08 21:57:54.394 195 21063 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.396 195 32643 E OMX-VDEC-1080P:
02-08 21:57:54.396 195 32643 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.396 195 32643 E OMX-VDEC-1080P:
02-08 21:57:54.396 195 32643 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.396 20973 21062 D SurfaceUtils: set up nativeWindow 0xaedd1608 for 1280x1440, color 0x7fa30c03, rotation 0, usage 0x42002900
02-08 21:57:54.396 195 687 E OMX-VDEC-1080P:
02-08 21:57:54.396 195 687 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.397 195 687 E OMX-VDEC-1080P:
02-08 21:57:54.397 195 687 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.407 195 19232 E OMX-VDEC-1080P:
02-08 21:57:54.407 195 19232 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.407 195 19232 E OMX-VDEC-1080P: GET_MV_BUFFER_SIZE returned: Size: 491520 and alignment: 8192
02-08 21:57:54.416 195 3995 E OMX-VDEC-1080P:
02-08 21:57:54.416 195 3995 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.422 195 655 E OMX-VDEC-1080P:
02-08 21:57:54.422 195 655 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.428 195 4389 E OMX-VDEC-1080P:
02-08 21:57:54.428 195 4389 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.434 195 19231 E OMX-VDEC-1080P:
02-08 21:57:54.434 195 19231 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.441 195 32643 E OMX-VDEC-1080P:
02-08 21:57:54.441 195 32643 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.447 195 195 E OMX-VDEC-1080P:
02-08 21:57:54.447 195 195 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.453 195 687 E OMX-VDEC-1080P:
02-08 21:57:54.453 195 687 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.459 195 19232 E OMX-VDEC-1080P:
02-08 21:57:54.459 195 19232 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.465 195 3995 E OMX-VDEC-1080P:
02-08 21:57:54.465 195 3995 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.472 195 655 E OMX-VDEC-1080P:
02-08 21:57:54.472 195 655 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.510 20973 21058 I GStreamer+baseparse: 0:00:08.984069822 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:54.528 195 655 E OMX-VDEC-1080P:
02-08 21:57:54.528 195 655 E OMX-VDEC-1080P: No color conversion required
02-08 21:57:54.530 20973 21066 I GStreamer+GST_EVENT: 0:00:09.004913329 0x96c68380 gstevent.c:679:gst_event_new_caps creating caps event video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)1280, height=(int)1440, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, colorimetry=(string)sRGB, framerate=(fraction)154/3, texture-target=(string)external-oes
02-08 21:57:54.531 20973 21066 I GStreamer+GST_ELEMENT_PADS: 0:00:09.005401611 0x96c68380 gstelement.c:659:gst_element_add_pad:<decodebin_0> adding pad 'src_0'
02-08 21:57:54.531 20973 21066 I GStreamer+GST_PIPELINE: 0:00:09.005615234 0x96c68380 ./grammar.y:518:gst_parse_found_pad trying delayed linking some pad of GstDecodeBin named decodebin_0 to some pad of GstGLImageSinkBin named glimagesinkbin0
02-08 21:57:54.531 20973 21066 I GStreamer+GST_ELEMENT_PADS: 0:00:09.005737304 0x96c68380 gstutils.c:1571:gst_element_link_pads_full trying to link element decodebin_0:(any) to element glimagesinkbin0:(any)
02-08 21:57:54.531 20973 21066 I GStreamer+GST_PADS: 0:00:09.005920410 0x96c68380 gstutils.c:932:gst_pad_check_link trying to link decodebin_0:src_0 and glimagesinkbin0:sink
02-08 21:57:54.535 20973 21066 I GStreamer+GST_PADS: 0:00:09.009429931 0x96c68380 gstutils.c:1444:prepare_link_maybe_ghosting decodebin_0 and glimagesinkbin0 in same bin, no need for ghost pads
02-08 21:57:54.535 20973 21066 I GStreamer+GST_PADS: 0:00:09.009613037 0x96c68380 gstpad.c:2292:gst_pad_link_prepare trying to link decodebin_0:src_0 and glimagesinkbin0:sink
02-08 21:57:54.538 20973 21066 I GStreamer+GST_PADS: 0:00:09.012939452 0x96c68380 gstpad.c:2498:gst_pad_link_full linked decodebin_0:src_0 and glimagesinkbin0:sink, successful
02-08 21:57:54.539 20973 21066 I GStreamer+GST_EVENT: 0:00:09.013061523 0x96c68380 gstevent.c:1382:gst_event_new_reconfigure creating reconfigure event
02-08 21:57:54.539 20973 21066 I GStreamer+decodebin: 0:00:09.013519286 0x96c68380 gstdecodebin2.c:4684:gst_decode_bin_expose:<decodebin_0:src_0> added new decoded pad
02-08 21:57:54.539 20973 21066 I GStreamer+GST_STATES: 0:00:09.013702392 0x96c68380 gstbin.c:3238:bin_handle_async_done:<decodebin_0> committing state from READY to PAUSED, old pending PAUSED
02-08 21:57:54.539 20973 21066 I GStreamer+GST_STATES: 0:00:09.013854980 0x96c68380 gstbin.c:3258:bin_handle_async_done:<decodebin_0> completed state change, pending VOID
02-08 21:57:54.539 20973 21066 I GStreamer+GST_STATES: 0:00:09.013977050 0x96c68380 gstelement.c:2255:_priv_gst_element_state_changed:<decodebin_0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
02-08 21:57:54.544 20973 21066 I GStreamer+basetransform: 0:00:09.018218994 0x96c68380 gstbasetransform.c:1367:gst_base_transform_setcaps:<gluploadelement0> reuse caps
02-08 21:57:54.544 20973 21066 I GStreamer+GST_EVENT: 0:00:09.018493652 0x96c68380 gstevent.c:679:gst_event_new_caps creating caps event video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)1280, height=(int)1440, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, colorimetry=(string)sRGB, framerate=(fraction)154/3, texture-target=(string)external-oes
02-08 21:57:54.546 20973 21066 I GStreamer+GST_EVENT: 0:00:09.020324707 0x96c68380 gstevent.c:679:gst_event_new_caps creating caps event video/x-raw(memory:GLMemory), width=(int)1280, height=(int)1440, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)154/3, format=(string)RGBA, texture-target=(string)2D
02-08 21:57:54.547 20973 21066 I GStreamer+basetransform: 0:00:09.021331787 0x96c68380 gstbasetransform.c:1367:gst_base_transform_setcaps:<glcolorbalance0> reuse caps
02-08 21:57:54.547 20973 21066 I GStreamer+GST_EVENT: 0:00:09.021636963 0x96c68380 gstevent.c:679:gst_event_new_caps creating caps event video/x-raw(memory:GLMemory), width=(int)1280, height=(int)1440, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)154/3, format=(string)RGBA, texture-target=(string)2D
02-08 21:57:54.550 20973 21066 W GStreamer+structure: 0:00:09.024688720 0x96c68380 gststructure.c:1935:priv_gst_structure_append_to_gstring No value transform to serialize field 'params' of type 'GstAllocationParams'
02-08 21:57:54.550 20973 21066 I GStreamer+structure: 0:00:09.024841308 0x96c68380 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\)1440\,\ interlace-mode\=\(string\)progressive\,\ pixel-aspect-ratio\=\(fraction\)1/1\,\ framerate\=\(fraction\)154/3\,\ format\=\(string\)RGBA\,\ texture-target\=\(string\)2D", size=(uint)7372800, min-buffers=(uint)0, max-buffers=(uint)0, allocator=(GstAllocator)"NULL", params=(GstAllocationParams)NULL, options=(string)< GstBufferPoolOptionGLSyncMeta >;
02-08 21:57:54.552 20973 21066 W GStreamer+structure: 0:00:09.026245118 0x96c68380 gststructure.c:1935:priv_gst_structure_append_to_gstring No value transform to serialize field 'params' of type 'GstAllocationParams'
02-08 21:57:54.552 20973 21066 I GStreamer+structure: 0:00:09.026397705 0x96c68380 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\)1440\,\ interlace-mode\=\(string\)progressive\,\ pixel-aspect-ratio\=\(fraction\)1/1\,\ framerate\=\(fraction\)154/3\,\ format\=\(string\)RGBA\,\ texture-target\=\(string\)2D", size=(uint)7372800, min-buffers=(uint)2, max-buffers=(uint)0, allocator=(GstAllocator)"NULL", params=(GstAllocationParams)NULL, options=(string)< GstBufferPoolOptionGLSyncMeta >;
02-08 21:57:54.561 20973 21058 I GStreamer+baseparse: 0:00:09.035461427 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:54.565 20973 21066 D GStreamer+aiTennis3D: 0:00:09.039428712 0x96c68380 gstreamer/aigstreamer.c:157:attach_current_thread Attaching thread 0x96c68380
02-08 21:57:54.566 20973 21052 I GStreamer+glconvert: 0:00:09.040039063 0x9d2ec6f0 gstglcolorconvert.c:2029:_init_convert Initializing color conversion from RGBA to RGBA
02-08 21:57:54.582 20973 21058 I GStreamer+basetransform: 0:00:09.055999755 0x9c73fe30 gstbasetransform.c:1367:gst_base_transform_setcaps:<capsfilter0> reuse caps
02-08 21:57:54.591 20973 21052 V GStreamer+glshader: 0:00:09.065551757 0x9d2ec6f0 gstglshader.c:642:gst_gl_shader_link shader link log:
02-08 21:57:54.591 20973 21052 V GStreamer+glshader: --From Vertex Shader:
02-08 21:57:54.591 20973 21052 V GStreamer+glshader: --From Fragment Shader:
02-08 21:57:54.591 20973 21052 V GStreamer+glshader: Link was successful.
02-08 21:57:54.591 20973 21052 V GStreamer+glshader:
02-08 21:57:54.591 20973 21052 V GStreamer+glshader:
02-08 21:57:54.591 20973 21052 I GStreamer+glconvert: 0:00:09.065826415 0x9d2ec6f0 gstglcolorconvert.c:2213:_init_convert_fbo Context, EXT_framebuffer_object supported: yes
02-08 21:57:54.654 20973 21052 V GStreamer+glshader: 0:00:09.128509522 0x9d2ec6f0 gstglshader.c:642:gst_gl_shader_link shader link log:
02-08 21:57:54.654 20973 21052 V GStreamer+glshader: --From Vertex Shader:
02-08 21:57:54.654 20973 21052 V GStreamer+glshader: --From Fragment Shader:
02-08 21:57:54.654 20973 21052 V GStreamer+glshader: Link was successful.
02-08 21:57:54.654 20973 21052 V GStreamer+glshader:
02-08 21:57:54.654 20973 21052 V GStreamer+glshader:
02-08 21:57:54.664 20973 21052 V GStreamer+glshader: 0:00:09.138061524 0x9d2ec6f0 gstglshader.c:642:gst_gl_shader_link shader link log:
02-08 21:57:54.664 20973 21052 V GStreamer+glshader: --From Vertex Shader:
02-08 21:57:54.664 20973 21052 V GStreamer+glshader: --From Fragment Shader:
02-08 21:57:54.664 20973 21052 V GStreamer+glshader: Link was successful.
02-08 21:57:54.664 20973 21052 V GStreamer+glshader:
02-08 21:57:54.664 20973 21052 V GStreamer+glshader:
02-08 21:57:54.669 20973 21066 I GStreamer+GST_STATES: 0:00:09.143096924 0x96c68380 gstbin.c:3238:bin_handle_async_done:<glimagesinkbin0> committing state from READY to PAUSED, old pending PAUSED
02-08 21:57:54.669 20973 21066 I GStreamer+GST_STATES: 0:00:09.143218994 0x96c68380 gstbin.c:3258:bin_handle_async_done:<glimagesinkbin0> completed state change, pending VOID
02-08 21:57:54.669 20973 21066 I GStreamer+GST_STATES: 0:00:09.143310547 0x96c68380 gstelement.c:2255:_priv_gst_element_state_changed:<glimagesinkbin0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
02-08 21:57:54.669 20973 21066 I GStreamer+GST_STATES: 0:00:09.143463135 0x96c68380 gstbin.c:3238:bin_handle_async_done:<pipeline0> committing state from READY to PAUSED, old pending PLAYING
02-08 21:57:54.669 20973 21066 I GStreamer+GST_STATES: 0:00:09.143524170 0x96c68380 gstbin.c:3267:bin_handle_async_done:<pipeline0> continue state change, pending PLAYING
02-08 21:57:54.669 20973 21066 I GStreamer+GST_STATES: 0:00:09.143615723 0x96c68380 gstelement.c:2255:_priv_gst_element_state_changed:<pipeline0> notifying about state-changed READY to PAUSED (PLAYING pending)
02-08 21:57:54.669 20973 21038 I System.out: Gst setMessage (0) State changed (0) to PAUSED
02-08 21:57:54.670 20973 21072 I GStreamer+GST_STATES: 0:00:09.144073486 0x9caeeec0 gstbin.c:3061:gst_bin_continue_func:<pipeline0> continue state change PAUSED to PLAYING, final PLAYING
02-08 21:57:54.671 20973 21072 I GStreamer+GST_EVENT: 0:00:09.145172119 0x9caeeec0 gstevent.c:1253:gst_event_new_latency creating latency event 0:00:00.000000000
02-08 21:57:54.671 20973 21072 I GStreamer+bin: 0:00:09.145538331 0x9caeeec0 gstbin.c:2593:gst_bin_do_latency_func:<pipeline0> configured latency of 0:00:00.000000000
02-08 21:57:54.671 20973 21072 I GStreamer+GST_STATES: 0:00:09.145721436 0x9caeeec0 gstbin.c:2316:gst_bin_element_set_state:<glimagesinkbin0> current PAUSED pending VOID_PENDING, desired next PLAYING
02-08 21:57:54.671 20973 21072 I GStreamer+GST_STATES: 0:00:09.145874024 0x9caeeec0 gstbin.c:2316:gst_bin_element_set_state:<sink> current PAUSED pending VOID_PENDING, desired next PLAYING
02-08 21:57:54.672 20973 21072 I GStreamer+GST_STATES: 0:00:09.146972657 0x9caeeec0 gstelement.c:2350:gst_element_continue_state:<sink> completed state change to PLAYING
02-08 21:57:54.673 20973 21072 I GStreamer+GST_STATES: 0:00:09.147094727 0x9caeeec0 gstelement.c:2255:_priv_gst_element_state_changed:<sink> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
02-08 21:57:54.673 20973 21072 I GStreamer+GST_STATES: 0:00:09.147644044 0x9caeeec0 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'sink' changed state to 4(PLAYING) successfully
02-08 21:57:54.673 20973 21072 I GStreamer+GST_STATES: 0:00:09.147857667 0x9caeeec0 gstbin.c:2316:gst_bin_element_set_state:<glcolorbalance0> current PAUSED pending VOID_PENDING, desired next PLAYING
02-08 21:57:54.674 20973 21072 I GStreamer+GST_STATES: 0:00:09.148040772 0x9caeeec0 gstelement.c:2350:gst_element_continue_state:<glcolorbalance0> completed state change to PLAYING
02-08 21:57:54.674 20973 21072 I GStreamer+GST_STATES: 0:00:09.148193360 0x9caeeec0 gstelement.c:2255:_priv_gst_element_state_changed:<glcolorbalance0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
02-08 21:57:54.674 20973 21072 I GStreamer+GST_STATES: 0:00:09.148803712 0x9caeeec0 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'glcolorbalance0' changed state to 4(PLAYING) successfully
02-08 21:57:54.675 20973 21072 I GStreamer+GST_STATES: 0:00:09.149017335 0x9caeeec0 gstbin.c:2316:gst_bin_element_set_state:<glcolorconvertelement0> current PAUSED pending VOID_PENDING, desired next PLAYING
02-08 21:57:54.675 20973 21072 I GStreamer+GST_STATES: 0:00:09.149169923 0x9caeeec0 gstelement.c:2350:gst_element_continue_state:<glcolorconvertelement0> completed state change to PLAYING
02-08 21:57:54.675 20973 21072 I GStreamer+GST_STATES: 0:00:09.149322510 0x9caeeec0 gstelement.c:2255:_priv_gst_element_state_changed:<glcolorconvertelement0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
02-08 21:57:54.675 20973 21072 I GStreamer+GST_STATES: 0:00:09.149688721 0x9caeeec0 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'glcolorconvertelement0' changed state to 4(PLAYING) successfully
02-08 21:57:54.680 20973 21058 I GStreamer+baseparse: 0:00:09.154022217 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:54.691 20973 21072 I GStreamer+GST_STATES: 0:00:09.165771484 0x9caeeec0 gstbin.c:2316:gst_bin_element_set_state:<gluploadelement0> current PAUSED pending VOID_PENDING, desired next PLAYING
02-08 21:57:54.691 20973 21072 I GStreamer+GST_STATES: 0:00:09.165924072 0x9caeeec0 gstelement.c:2350:gst_element_continue_state:<gluploadelement0> completed state change to PLAYING
02-08 21:57:54.692 20973 21072 I GStreamer+GST_STATES: 0:00:09.166015625 0x9caeeec0 gstelement.c:2255:_priv_gst_element_state_changed:<gluploadelement0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
02-08 21:57:54.692 20973 21072 I GStreamer+GST_STATES: 0:00:09.166320800 0x9caeeec0 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'gluploadelement0' changed state to 4(PLAYING) successfully
02-08 21:57:54.692 20973 21072 I GStreamer+GST_STATES: 0:00:09.166412353 0x9caeeec0 gstelement.c:2350:gst_element_continue_state:<glimagesinkbin0> completed state change to PLAYING
02-08 21:57:54.692 20973 21072 I GStreamer+GST_STATES: 0:00:09.166534423 0x9caeeec0 gstelement.c:2255:_priv_gst_element_state_changed:<glimagesinkbin0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
02-08 21:57:54.692 20973 21072 I GStreamer+GST_STATES: 0:00:09.166748046 0x9caeeec0 gstbin.c:2764:gst_bin_change_state_func:<pipeline0> child 'glimagesinkbin0' changed state to 4(PLAYING) successfully
02-08 21:57:54.693 20973 21072 I GStreamer+GST_STATES: 0:00:09.167999267 0x9caeeec0 gstbin.c:2316:gst_bin_element_set_state:<decodebin_0> current PAUSED pending VOID_PENDING, desired next PLAYING
02-08 21:57:54.694 20973 21072 I GStreamer+GST_STATES: 0:00:09.168182373 0x9caeeec0 gstbin.c:2316:gst_bin_element_set_state:<amcvideodec-omxqcomvideodecoderavc0> current PAUSED pending VOID_PENDING, desired next PLAYING
02-08 21:57:54.694 20973 21072 I GStreamer+GST_STATES: 0:00:09.168273925 0x9caeeec0 gstelement.c:2350:gst_element_continue_state:<amcvideodec-omxqcomvideodecoderavc0> completed state change to PLAYING
02-08 21:57:54.694 20973 21072 I GStreamer+GST_STATES: 0:00:09.168395996 0x9caeeec0 gstelement.c:2255:_priv_gst_element_state_changed:<amcvideodec-omxqcomvideodecoderavc0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
02-08 21:57:54.694 20973 21072 I GStreamer+GST_STATES: 0:00:09.168640136 0x9caeeec0 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'amcvideodec-omxqcomvideodecoderavc0' changed state to 4(PLAYING) successfully
02-08 21:57:54.694 20973 21072 I GStreamer+GST_STATES: 0:00:09.168731689 0x9caeeec0 gstbin.c:2316:gst_bin_element_set_state:<capsfilter0> current PAUSED pending VOID_PENDING, desired next PLAYING
02-08 21:57:54.694 20973 21072 I GStreamer+GST_STATES: 0:00:09.168853759 0x9caeeec0 gstelement.c:2350:gst_element_continue_state:<capsfilter0> completed state change to PLAYING
02-08 21:57:54.694 20973 21072 I GStreamer+GST_STATES: 0:00:09.168945312 0x9caeeec0 gstelement.c:2255:_priv_gst_element_state_changed:<capsfilter0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
02-08 21:57:54.695 20973 21072 I GStreamer+GST_STATES: 0:00:09.169189453 0x9caeeec0 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'capsfilter0' changed state to 4(PLAYING) successfully
02-08 21:57:54.695 20973 21072 I GStreamer+GST_STATES: 0:00:09.169281005 0x9caeeec0 gstbin.c:2316:gst_bin_element_set_state:<h264parse0> current PAUSED pending VOID_PENDING, desired next PLAYING
02-08 21:57:54.695 20973 21072 I GStreamer+GST_STATES: 0:00:09.169372558 0x9caeeec0 gstelement.c:2350:gst_element_continue_state:<h264parse0> completed state change to PLAYING
02-08 21:57:54.695 20973 21072 I GStreamer+GST_STATES: 0:00:09.169464111 0x9caeeec0 gstelement.c:2255:_priv_gst_element_state_changed:<h264parse0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
02-08 21:57:54.695 20973 21072 I GStreamer+GST_STATES: 0:00:09.169708251 0x9caeeec0 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'h264parse0' changed state to 4(PLAYING) successfully
02-08 21:57:54.695 20973 21072 I GStreamer+GST_STATES: 0:00:09.169830322 0x9caeeec0 gstbin.c:2316:gst_bin_element_set_state:<multiqueue0> current PAUSED pending VOID_PENDING, desired next PLAYING
02-08 21:57:54.695 20973 21072 I GStreamer+GST_STATES: 0:00:09.169921874 0x9caeeec0 gstelement.c:2350:gst_element_continue_state:<multiqueue0> completed state change to PLAYING
02-08 21:57:54.696 20973 21072 I GStreamer+GST_STATES: 0:00:09.170013427 0x9caeeec0 gstelement.c:2255:_priv_gst_element_state_changed:<multiqueue0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
02-08 21:57:54.696 20973 21072 I GStreamer+GST_STATES: 0:00:09.170257568 0x9caeeec0 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'multiqueue0' changed state to 4(PLAYING) successfully
02-08 21:57:54.696 20973 21072 I GStreamer+GST_STATES: 0:00:09.170379638 0x9caeeec0 gstbin.c:2316:gst_bin_element_set_state:<qtdemux0> current PAUSED pending VOID_PENDING, desired next PLAYING
02-08 21:57:54.696 20973 21072 I GStreamer+GST_STATES: 0:00:09.170471191 0x9caeeec0 gstelement.c:2350:gst_element_continue_state:<qtdemux0> completed state change to PLAYING
02-08 21:57:54.696 20973 21072 I GStreamer+GST_STATES: 0:00:09.170562744 0x9caeeec0 gstelement.c:2255:_priv_gst_element_state_changed:<qtdemux0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
02-08 21:57:54.696 20973 21072 I GStreamer+GST_STATES: 0:00:09.170806884 0x9caeeec0 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'qtdemux0' changed state to 4(PLAYING) successfully
02-08 21:57:54.696 20973 21072 I GStreamer+GST_STATES: 0:00:09.170898437 0x9caeeec0 gstbin.c:2316:gst_bin_element_set_state:<typefind> current PAUSED pending VOID_PENDING, desired next PLAYING
02-08 21:57:54.696 20973 21072 I GStreamer+GST_STATES: 0:00:09.171020507 0x9caeeec0 gstelement.c:2350:gst_element_continue_state:<typefind> completed state change to PLAYING
02-08 21:57:54.697 20973 21072 I GStreamer+GST_STATES: 0:00:09.171112060 0x9caeeec0 gstelement.c:2255:_priv_gst_element_state_changed:<typefind> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
02-08 21:57:54.697 20973 21072 I GStreamer+GST_STATES: 0:00:09.171325683 0x9caeeec0 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'typefind' changed state to 4(PLAYING) successfully
02-08 21:57:54.697 20973 21072 I GStreamer+GST_STATES: 0:00:09.171508788 0x9caeeec0 gstelement.c:2350:gst_element_continue_state:<decodebin_0> completed state change to PLAYING
02-08 21:57:54.697 20973 21072 I GStreamer+GST_STATES: 0:00:09.171600341 0x9caeeec0 gstelement.c:2255:_priv_gst_element_state_changed:<decodebin_0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
02-08 21:57:54.697 20973 21072 I GStreamer+GST_STATES: 0:00:09.171844482 0x9caeeec0 gstbin.c:2764:gst_bin_change_state_func:<pipeline0> child 'decodebin_0' changed state to 4(PLAYING) successfully
02-08 21:57:54.697 20973 21072 I GStreamer+GST_STATES: 0:00:09.171936034 0x9caeeec0 gstbin.c:2316:gst_bin_element_set_state:<filesrc0> current PAUSED pending VOID_PENDING, desired next PLAYING
02-08 21:57:54.698 20973 21072 I GStreamer+GST_STATES: 0:00:09.172027587 0x9caeeec0 gstelement.c:2350:gst_element_continue_state:<filesrc0> completed state change to PLAYING
02-08 21:57:54.698 20973 21072 I GStreamer+GST_STATES: 0:00:09.172119140 0x9caeeec0 gstelement.c:2255:_priv_gst_element_state_changed:<filesrc0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
02-08 21:57:54.698 20973 21072 I GStreamer+GST_STATES: 0:00:09.172363281 0x9caeeec0 gstbin.c:2764:gst_bin_change_state_func:<pipeline0> child 'filesrc0' changed state to 4(PLAYING) successfully
02-08 21:57:54.698 20973 21072 I GStreamer+GST_STATES: 0:00:09.172454833 0x9caeeec0 gstelement.c:2350:gst_element_continue_state:<pipeline0> completed state change to PLAYING
02-08 21:57:54.698 20973 21072 I GStreamer+GST_STATES: 0:00:09.172546386 0x9caeeec0 gstelement.c:2255:_priv_gst_element_state_changed:<pipeline0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
02-08 21:57:54.698 20973 21038 I System.out: Gst setMessage (0) State changed (0) to PLAYING
02-08 21:57:54.698 20973 21038 D GStreamer+aiTennis3D: 0:00:09.172821044 0x9d319f20 gstreamer/aigstreamer.c:126:dumpBin_ DUMP Element: glimagesinkbin0
02-08 21:57:54.698 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.172912597 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad glimagesinkbin0:sink
02-08 21:57:54.699 20973 21038 D GStreamer+aiTennis3D: 0:00:09.173095702 0x9d319f20 gstreamer/aigstreamer.c:132:dumpBin_ DUMP Sink: video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)1280, height=(int)1440, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, colorimetry=(string)sRGB, framerate=(fraction)154/3, texture-target=(string)external-oes
02-08 21:57:54.699 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.173156738 0x9d319f20 gstelement.c:904:gst_element_get_static_pad no such pad 'src' in element "glimagesinkbin0"
02-08 21:57:54.699 20973 21038 D GStreamer+aiTennis3D: 0:00:09.173248290 0x9d319f20 gstreamer/aigstreamer.c:126:dumpBin_ DUMP Element: sink
02-08 21:57:54.699 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.173309325 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad sink:sink
02-08 21:57:54.699 20973 21038 D GStreamer+aiTennis3D: 0:00:09.173461913 0x9d319f20 gstreamer/aigstreamer.c:132:dumpBin_ DUMP Sink: video/x-raw(memory:GLMemory), width=(int)1280, height=(int)1440, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)154/3, format=(string)RGBA, texture-target=(string)2D
02-08 21:57:54.699 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.173522948 0x9d319f20 gstelement.c:904:gst_element_get_static_pad no such pad 'src' in element "sink"
02-08 21:57:54.699 20973 21038 D GStreamer+aiTennis3D: 0:00:09.173736571 0x9d319f20 gstreamer/aigstreamer.c:126:dumpBin_ DUMP Element: glcolorbalance0
02-08 21:57:54.699 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.173797607 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad glcolorbalance0:sink
02-08 21:57:54.699 20973 21038 D GStreamer+aiTennis3D: 0:00:09.173950194 0x9d319f20 gstreamer/aigstreamer.c:132:dumpBin_ DUMP Sink: video/x-raw(memory:GLMemory), width=(int)1280, height=(int)1440, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)154/3, format=(string)RGBA, texture-target=(string)2D
02-08 21:57:54.700 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.174011230 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad glcolorbalance0:src
02-08 21:57:54.700 20973 21038 D GStreamer+aiTennis3D: 0:00:09.174163818 0x9d319f20 gstreamer/aigstreamer.c:139:dumpBin_ DUMP Src: video/x-raw(memory:GLMemory), width=(int)1280, height=(int)1440, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)154/3, format=(string)RGBA, texture-target=(string)2D
02-08 21:57:54.700 20973 21038 D GStreamer+aiTennis3D: 0:00:09.174346923 0x9d319f20 gstreamer/aigstreamer.c:126:dumpBin_ DUMP Element: glcolorconvertelement0
02-08 21:57:54.700 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.174407958 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad glcolorconvertelement0:sink
02-08 21:57:54.700 20973 21038 D GStreamer+aiTennis3D: 0:00:09.174560546 0x9d319f20 gstreamer/aigstreamer.c:132:dumpBin_ DUMP Sink: video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)1280, height=(int)1440, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, colorimetry=(string)sRGB, framerate=(fraction)154/3, texture-target=(string)external-oes
02-08 21:57:54.700 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.174621581 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad glcolorconvertelement0:src
02-08 21:57:54.700 20973 21038 D GStreamer+aiTennis3D: 0:00:09.174774169 0x9d319f20 gstreamer/aigstreamer.c:139:dumpBin_ DUMP Src: video/x-raw(memory:GLMemory), width=(int)1280, height=(int)1440, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)154/3, format=(string)RGBA, texture-target=(string)2D
02-08 21:57:54.700 20973 21038 D GStreamer+aiTennis3D: 0:00:09.174957274 0x9d319f20 gstreamer/aigstreamer.c:126:dumpBin_ DUMP Element: gluploadelement0
02-08 21:57:54.701 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.175018310 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad gluploadelement0:sink
02-08 21:57:54.701 20973 21038 D GStreamer+aiTennis3D: 0:00:09.175170898 0x9d319f20 gstreamer/aigstreamer.c:132:dumpBin_ DUMP Sink: video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)1280, height=(int)1440, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, colorimetry=(string)sRGB, framerate=(fraction)154/3, texture-target=(string)external-oes
02-08 21:57:54.701 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.175231933 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad gluploadelement0:src
02-08 21:57:54.701 20973 21038 D GStreamer+aiTennis3D: 0:00:09.175415038 0x9d319f20 gstreamer/aigstreamer.c:139:dumpBin_ DUMP Src: video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)1280, height=(int)1440, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, colorimetry=(string)sRGB, framerate=(fraction)154/3, texture-target=(string)external-oes
02-08 21:57:54.701 20973 21038 D GStreamer+aiTennis3D: 0:00:09.175598144 0x9d319f20 gstreamer/aigstreamer.c:126:dumpBin_ DUMP Element: decodebin_0
02-08 21:57:54.701 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.175659179 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad decodebin_0:sink
02-08 21:57:54.701 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.175750731 0x9d319f20 gstelement.c:904:gst_element_get_static_pad no such pad 'src' in element "decodebin_0"
02-08 21:57:54.701 20973 21038 D GStreamer+aiTennis3D: 0:00:09.175842284 0x9d319f20 gstreamer/aigstreamer.c:126:dumpBin_ DUMP Element: amcvideodec-omxqcomvideodecoderavc0
02-08 21:57:54.701 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.175903319 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad amcvideodec-omxqcomvideodecoderavc0:sink
02-08 21:57:54.702 20973 21038 D GStreamer+aiTennis3D: 0:00:09.176055907 0x9d319f20 gstreamer/aigstreamer.c:132:dumpBin_ DUMP Sink: video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, level=(string)4, profile=(string)baseline, width=(int)1280, height=(int)1440, framerate=(fraction)154/3, pixel-aspect-ratio=(fraction)1/1, parsed=(boolean)true
02-08 21:57:54.702 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.176116942 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad amcvideodec-omxqcomvideodecoderavc0:src
02-08 21:57:54.702 20973 21038 D GStreamer+aiTennis3D: 0:00:09.176269530 0x9d319f20 gstreamer/aigstreamer.c:139:dumpBin_ DUMP Src: video/x-raw(memory:GLMemory), format=(string)RGBA, width=(int)1280, height=(int)1440, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, colorimetry=(string)sRGB, framerate=(fraction)154/3, texture-target=(string)external-oes
02-08 21:57:54.702 20973 21038 D GStreamer+aiTennis3D: 0:00:09.176452636 0x9d319f20 gstreamer/aigstreamer.c:126:dumpBin_ DUMP Element: capsfilter0
02-08 21:57:54.702 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.176513671 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad capsfilter0:sink
02-08 21:57:54.702 20973 21038 D GStreamer+aiTennis3D: 0:00:09.176666259 0x9d319f20 gstreamer/aigstreamer.c:132:dumpBin_ DUMP Sink: video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, level=(string)4, profile=(string)baseline, width=(int)1280, height=(int)1440, framerate=(fraction)154/3, pixel-aspect-ratio=(fraction)1/1, parsed=(boolean)true
02-08 21:57:54.702 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.176757812 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad capsfilter0:src
02-08 21:57:54.702 20973 21038 D GStreamer+aiTennis3D: 0:00:09.176879882 0x9d319f20 gstreamer/aigstreamer.c:139:dumpBin_ DUMP Src: video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, level=(string)4, profile=(string)baseline, width=(int)1280, height=(int)1440, framerate=(fraction)154/3, pixel-aspect-ratio=(fraction)1/1, parsed=(boolean)true
02-08 21:57:54.703 20973 21038 D GStreamer+aiTennis3D: 0:00:09.177093505 0x9d319f20 gstreamer/aigstreamer.c:126:dumpBin_ DUMP Element: h264parse0
02-08 21:57:54.703 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.177154540 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad h264parse0:sink
02-08 21:57:54.703 20973 21038 D GStreamer+aiTennis3D: 0:00:09.177337645 0x9d319f20 gstreamer/aigstreamer.c:132:dumpBin_ DUMP Sink: video/x-h264, stream-format=(string)avc, alignment=(string)au, level=(string)4, profile=(string)baseline, codec_data=(buffer)01428028ffe1000a27428028e54028016b2001000528de01ae20, width=(int)1280, height=(int)1440, framerate=(fraction)154/3, pixel-aspect-ratio=(fraction)1/1
02-08 21:57:54.703 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.177398681 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad h264parse0:src
02-08 21:57:54.703 20973 21038 D GStreamer+aiTennis3D: 0:00:09.177551268 0x9d319f20 gstreamer/aigstreamer.c:139:dumpBin_ DUMP Src: video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, level=(string)4, profile=(string)baseline, width=(int)1280, height=(int)1440, framerate=(fraction)154/3, pixel-aspect-ratio=(fraction)1/1, parsed=(boolean)true
02-08 21:57:54.703 20973 21038 D GStreamer+aiTennis3D: 0:00:09.177734374 0x9d319f20 gstreamer/aigstreamer.c:126:dumpBin_ DUMP Element: multiqueue0
02-08 21:57:54.703 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.177795409 0x9d319f20 gstelement.c:904:gst_element_get_static_pad no such pad 'sink' in element "multiqueue0"
02-08 21:57:54.703 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.177886962 0x9d319f20 gstelement.c:904:gst_element_get_static_pad no such pad 'src' in element "multiqueue0"
02-08 21:57:54.704 20973 21038 D GStreamer+aiTennis3D: 0:00:09.178070067 0x9d319f20 gstreamer/aigstreamer.c:126:dumpBin_ DUMP Element: qtdemux0
02-08 21:57:54.704 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.178131102 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad qtdemux0:sink
02-08 21:57:54.704 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.178222655 0x9d319f20 gstelement.c:904:gst_element_get_static_pad no such pad 'src' in element "qtdemux0"
02-08 21:57:54.704 20973 21038 D GStreamer+aiTennis3D: 0:00:09.178405761 0x9d319f20 gstreamer/aigstreamer.c:126:dumpBin_ DUMP Element: typefind
02-08 21:57:54.704 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.178466796 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad typefind:sink
02-08 21:57:54.704 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.178558348 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad typefind:src
02-08 21:57:54.704 20973 21038 D GStreamer+aiTennis3D: 0:00:09.178741454 0x9d319f20 gstreamer/aigstreamer.c:126:dumpBin_ DUMP Element: filesrc0
02-08 21:57:54.704 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.178833007 0x9d319f20 gstelement.c:904:gst_element_get_static_pad no such pad 'sink' in element "filesrc0"
02-08 21:57:54.704 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:09.178894042 0x9d319f20 gstelement.c:907:gst_element_get_static_pad found pad filesrc0:src
02-08 21:57:54.705 20973 21038 D GStreamer+aiTennis3D: 0:00:09.179077147 0x9d319f20 gstreamer/aigstreamer.c:150:dumpBin [0] DUMPELEMENTS: | glimagesinkbin0 | sink | glcolorbalance0 | glcolorconvertelement0 | gluploadelement0 | decodebin_0 | amcvideodec-omxqcomvideodecoderavc0 | capsfilter0 | h264parse0 | multiqueue0 | qtdemux0 | typefind | filesrc0
02-08 21:57:54.731 20973 21058 I GStreamer+baseparse: 0:00:09.205474853 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:54.763 621 665 I InputReader: Reconfiguring input devices. changes=0x00000010
02-08 21:57:54.768 20973 21058 I GStreamer+baseparse: 0:00:09.242218017 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:54.779 20973 21038 V GStreamer+bin: 0:00:09.253387451 0x9d319f20 gstbin.c:4144:gst_bin_query implement duration caching in GstBin again
02-08 21:57:54.779 20973 21038 V GStreamer+bin: 0:00:09.253540039 0x9d319f20 gstbin.c:4144:gst_bin_query implement duration caching in GstBin again
02-08 21:57:54.792 20973 21058 I GStreamer+baseparse: 0:00:09.266632079 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:54.810 20259 21079 I UpdateIcingCorporaServi: Updating corpora: APPS=com.google.android.gms, CONTACTS=MAYBE
02-08 21:57:54.818 20973 21058 I GStreamer+baseparse: 0:00:09.292205808 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:54.850 20973 21058 I GStreamer+baseparse: 0:00:09.324371337 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:54.860 1107 21084 D PackageBroadcastService: Received broadcast action=android.intent.action.PACKAGE_CHANGED and uri=com.google.android.gms
02-08 21:57:54.870 20973 21058 I GStreamer+videodecoder: 0:00:09.344787597 0x9c73fe30 gstvideodecoder.c:1432:gst_video_decoder_sink_event_default:<amcvideodec-omxqcomvideodecoderavc0> upstream tags: taglist, video-codec=(string)"H.264\ \(Baseline\ Profile\)", bitrate=(uint)59910406, language-code=(string)en, minimum-bitrate=(uint)59082614, maximum-bitrate=(uint)59082614;
02-08 21:57:54.871 1107 21084 I PackageBroadcastService: Null package name or gms related package. Ignoreing.
02-08 21:57:54.871 20973 21066 W GStreamer+amcvideodec: 0:00:09.345672608 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.003414503)
02-08 21:57:54.874 20973 21058 I GStreamer+baseparse: 0:00:09.348327637 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:54.875 20973 21058 I GStreamer+videodecoder: 0:00:09.349243164 0x9c73fe30 gstvideodecoder.c:1432:gst_video_decoder_sink_event_default:<amcvideodec-omxqcomvideodecoderavc0> upstream tags: taglist, video-codec=(string)"H.264\ \(Baseline\ Profile\)", bitrate=(uint)59910406, language-code=(string)en, minimum-bitrate=(uint)59036209, maximum-bitrate=(uint)59082614;
02-08 21:57:54.886 20259 21079 I UpdateIcingCorporaServi: UpdateCorporaTask done [took 76 ms] updated apps [took 76 ms]
02-08 21:57:54.904 1107 2514 I Icing : updateResources: need to parse f{com.google.android.gms}
02-08 21:57:54.908 20973 21058 I GStreamer+baseparse: 0:00:09.382904052 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:54.909 20973 21058 I GStreamer+videodecoder: 0:00:09.383697509 0x9c73fe30 gstvideodecoder.c:1432:gst_video_decoder_sink_event_default:<amcvideodec-omxqcomvideodecoderavc0> upstream tags: taglist, video-codec=(string)"H.264\ \(Baseline\ Profile\)", bitrate=(uint)59910406, language-code=(string)en, minimum-bitrate=(uint)59004998, maximum-bitrate=(uint)59082614;
02-08 21:57:54.933 20973 21066 W GStreamer+amcvideodec: 0:00:09.407501219 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.013828659)
02-08 21:57:54.934 20973 21058 I GStreamer+baseparse: 0:00:09.408172606 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:54.937 20973 21058 I GStreamer+videodecoder: 0:00:09.411224364 0x9c73fe30 gstvideodecoder.c:1432:gst_video_decoder_sink_event_default:<amcvideodec-omxqcomvideodecoderavc0> upstream tags: taglist, video-codec=(string)"H.264\ \(Baseline\ Profile\)", bitrate=(uint)59910406, language-code=(string)en, minimum-bitrate=(uint)59004998, maximum-bitrate=(uint)59153660;
02-08 21:57:54.938 20973 21058 I GStreamer+baseparse: 0:00:09.412719725 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:54.939 20973 21058 I GStreamer+videodecoder: 0:00:09.413513182 0x9c73fe30 gstvideodecoder.c:1432:gst_video_decoder_sink_event_default:<amcvideodec-omxqcomvideodecoderavc0> upstream tags: taglist, video-codec=(string)"H.264\ \(Baseline\ Profile\)", bitrate=(uint)59910406, language-code=(string)en, minimum-bitrate=(uint)59004998, maximum-bitrate=(uint)59209510;
02-08 21:57:54.943 1183 1183 I GCoreNlp: !shouldConfirmNlp, ensuring user opted into NLP
02-08 21:57:54.954 20973 21058 I GStreamer+baseparse: 0:00:09.428131103 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:54.972 20973 21066 W GStreamer+amcvideodec: 0:00:09.446563721 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.005164599)
02-08 21:57:54.973 20973 21058 I GStreamer+baseparse: 0:00:09.447082520 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:54.973 20973 21058 I GStreamer+videodecoder: 0:00:09.447937012 0x9c73fe30 gstvideodecoder.c:1432:gst_video_decoder_sink_event_default:<amcvideodec-omxqcomvideodecoderavc0> upstream tags: taglist, video-codec=(string)"H.264\ \(Baseline\ Profile\)", bitrate=(uint)59910406, language-code=(string)en, minimum-bitrate=(uint)58982412, maximum-bitrate=(uint)59209510;
02-08 21:57:54.992 20973 21058 I GStreamer+baseparse: 0:00:09.466491700 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.010 20973 21066 W GStreamer+amcvideodec: 0:00:09.484069824 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.010511377)
02-08 21:57:55.011 20973 21058 I GStreamer+baseparse: 0:00:09.485900879 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
--------- beginning of system
02-08 21:57:55.026 621 754 D WifiService: acquireWifiLockLocked: WifiLock{NlpWifiLock type=2 binder=android.os.BinderProxy at 6f59fee}
02-08 21:57:55.043 20973 21058 I GStreamer+baseparse: 0:00:09.517303466 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.045 621 3541 D WifiService: releaseWifiLockLocked: WifiLock{NlpWifiLock type=2 binder=android.os.BinderProxy at 6f59fee}
02-08 21:57:55.047 1454 1454 I Launcher: Deferring update until onResume
02-08 21:57:55.049 20973 21058 I GStreamer+baseparse: 0:00:09.523071287 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.067 20973 21066 W GStreamer+amcvideodec: 0:00:09.541931152 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.011631299)
02-08 21:57:55.068 20973 21058 I GStreamer+baseparse: 0:00:09.542572021 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.086 20973 21058 I GStreamer+baseparse: 0:00:09.560699463 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.106 20973 21058 I GStreamer+baseparse: 0:00:09.580322265 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.125 20973 21058 I GStreamer+baseparse: 0:00:09.599243163 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.143 20973 21058 I GStreamer+baseparse: 0:00:09.617980956 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.162 20973 21058 I GStreamer+baseparse: 0:00:09.636993407 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.182 20973 21058 I GStreamer+baseparse: 0:00:09.656188965 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.201 20973 21066 W GStreamer+amcvideodec: 0:00:09.675537110 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.006643213)
02-08 21:57:55.201 20973 21058 I GStreamer+baseparse: 0:00:09.675781251 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.219 20973 21058 I GStreamer+baseparse: 0:00:09.693817138 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.239 20973 21058 I GStreamer+baseparse: 0:00:09.713043211 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.257 20973 21058 I GStreamer+baseparse: 0:00:09.731933592 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.278 20973 21058 I GStreamer+videodecoder: 0:00:09.752410888 0x9c73fe30 gstvideodecoder.c:1432:gst_video_decoder_sink_event_default:<amcvideodec-omxqcomvideodecoderavc0> upstream tags: taglist, video-codec=(string)"H.264\ \(Baseline\ Profile\)", bitrate=(uint)59910406, language-code=(string)en, minimum-bitrate=(uint)58982412, maximum-bitrate=(uint)59316284;
02-08 21:57:55.316 20973 21058 I GStreamer+baseparse: 0:00:09.790100098 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.319 20973 21058 I GStreamer+baseparse: 0:00:09.793609620 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.339 20973 21058 I GStreamer+baseparse: 0:00:09.813507079 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.357 20973 21066 W GStreamer+amcvideodec: 0:00:09.831787107 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.038337253)
02-08 21:57:55.359 20973 21058 I GStreamer+baseparse: 0:00:09.833221434 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.372 20973 21066 W GStreamer+amcvideodec: 0:00:09.846679687 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.018937253)
02-08 21:57:55.374 20973 21058 I GStreamer+baseparse: 0:00:09.848846435 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.407 20973 21058 I GStreamer+baseparse: 0:00:09.881225586 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.409 20973 21066 W GStreamer+amcvideodec: 0:00:09.883605957 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.023099757)
02-08 21:57:55.412 20973 21058 I GStreamer+baseparse: 0:00:09.886505128 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.427 20973 21066 W GStreamer+amcvideodec: 0:00:09.901824951 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.003699757)
02-08 21:57:55.429 20973 21058 I GStreamer+baseparse: 0:00:09.903900146 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.460 20973 21058 I GStreamer+baseparse: 0:00:09.934539793 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.463 20973 21058 I GStreamer+videodecoder: 0:00:09.937072752 0x9c73fe30 gstvideodecoder.c:1432:gst_video_decoder_sink_event_default:<amcvideodec-omxqcomvideodecoderavc0> upstream tags: taglist, video-codec=(string)"H.264\ \(Baseline\ Profile\)", bitrate=(uint)59910406, language-code=(string)en, minimum-bitrate=(uint)58952844, maximum-bitrate=(uint)59316284;
02-08 21:57:55.465 20973 21066 W GStreamer+amcvideodec: 0:00:09.939086912 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.017034421)
02-08 21:57:55.469 20973 21058 I GStreamer+baseparse: 0:00:09.943176268 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.500 20973 21058 I GStreamer+baseparse: 0:00:09.973663329 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.501 20973 21066 W GStreamer+amcvideodec: 0:00:09.975097655 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.014124561)
02-08 21:57:55.508 20973 21058 I GStreamer+baseparse: 0:00:09.980072021 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.520 20973 21058 I GStreamer+baseparse: 0:00:09.994384766 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.539 20973 21058 I GStreamer+baseparse: 0:00:10.013610840 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.540 20973 21066 W GStreamer+amcvideodec: 0:00:10.014587402 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.017090967)
02-08 21:57:55.544 20973 21058 I GStreamer+videodecoder: 0:00:10.018035888 0x9c73fe30 gstvideodecoder.c:1432:gst_video_decoder_sink_event_default:<amcvideodec-omxqcomvideodecoderavc0> upstream tags: taglist, video-codec=(string)"H.264\ \(Baseline\ Profile\)", bitrate=(uint)59910406, language-code=(string)en, minimum-bitrate=(uint)58940524, maximum-bitrate=(uint)59316284;
02-08 21:57:55.579 20973 21066 W GStreamer+amcvideodec: 0:00:10.053863524 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.015262839)
02-08 21:57:55.582 20973 21058 I GStreamer+baseparse: 0:00:10.056976317 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.586 20973 21058 I GStreamer+baseparse: 0:00:10.060028075 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.615 20973 21058 I GStreamer+baseparse: 0:00:10.089660645 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.620 20973 21066 W GStreamer+amcvideodec: 0:00:10.094482422 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.014078857)
02-08 21:57:55.624 20973 21058 I GStreamer+baseparse: 0:00:10.098693848 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.652 20973 21058 I GStreamer+baseparse: 0:00:10.126739502 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.655 20973 21058 I GStreamer+videodecoder: 0:00:10.129272460 0x9c73fe30 gstvideodecoder.c:1432:gst_video_decoder_sink_event_default:<amcvideodec-omxqcomvideodecoderavc0> upstream tags: taglist, video-codec=(string)"H.264\ \(Baseline\ Profile\)", bitrate=(uint)59910406, language-code=(string)en, minimum-bitrate=(uint)58910134, maximum-bitrate=(uint)59316284;
02-08 21:57:55.655 20973 21066 W GStreamer+amcvideodec: 0:00:10.129791259 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.010663819)
02-08 21:57:55.660 20973 21058 I GStreamer+baseparse: 0:00:10.134735107 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.688 20973 21058 I GStreamer+baseparse: 0:00:10.162719725 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.692 20973 21066 W GStreamer+amcvideodec: 0:00:10.166290282 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.008259129)
02-08 21:57:55.695 20973 21058 I GStreamer+baseparse: 0:00:10.169067382 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.696 20973 21058 I GStreamer+videodecoder: 0:00:10.170043944 0x9c73fe30 gstvideodecoder.c:1432:gst_video_decoder_sink_event_default:<amcvideodec-omxqcomvideodecoderavc0> upstream tags: taglist, video-codec=(string)"H.264\ \(Baseline\ Profile\)", bitrate=(uint)59910406, language-code=(string)en, minimum-bitrate=(uint)58910134, maximum-bitrate=(uint)59316694;
02-08 21:57:55.711 20973 21058 I GStreamer+baseparse: 0:00:10.185272217 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.729 20973 21066 W GStreamer+amcvideodec: 0:00:10.203430176 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.004128565)
02-08 21:57:55.731 20973 21058 I GStreamer+baseparse: 0:00:10.205535889 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.748 20973 21058 I GStreamer+baseparse: 0:00:10.222442627 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.766 20973 21066 W GStreamer+amcvideodec: 0:00:10.240600585 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.001645945)
02-08 21:57:55.766 20973 21058 I GStreamer+baseparse: 0:00:10.240966795 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.786 20973 21058 I GStreamer+baseparse: 0:00:10.260498045 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.821 20973 21058 I GStreamer+baseparse: 0:00:10.295135497 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.841 20973 21058 I GStreamer+baseparse: 0:00:10.315673829 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.864 20973 21066 W GStreamer+amcvideodec: 0:00:10.338958740 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.009638525)
02-08 21:57:55.865 20973 21058 I GStreamer+baseparse: 0:00:10.339355468 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.869 20973 21058 I GStreamer+baseparse: 0:00:10.343414306 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.885 20973 21058 I GStreamer+baseparse: 0:00:10.359130858 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.899 20973 21058 I GStreamer+videodecoder: 0:00:10.373626707 0x9c73fe30 gstvideodecoder.c:1432:gst_video_decoder_sink_event_default:<amcvideodec-omxqcomvideodecoderavc0> upstream tags: taglist, video-codec=(string)"H.264\ \(Baseline\ Profile\)", bitrate=(uint)59910406, language-code=(string)en, minimum-bitrate=(uint)58910134, maximum-bitrate=(uint)59366385;
02-08 21:57:55.903 20973 21066 W GStreamer+amcvideodec: 0:00:10.377716063 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.004104147)
02-08 21:57:55.904 20973 21058 I GStreamer+baseparse: 0:00:10.377990722 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.912 2307 21082 E Babel_NetworkQueue: Retried: 750 errorCode: 101 Request: cwr-11870619: devices/registerdevice created: 1454635419338
02-08 21:57:55.926 20973 21058 I GStreamer+baseparse: 0:00:10.400543212 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.948 20973 21066 W GStreamer+amcvideodec: 0:00:10.422058106 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.016453077)
02-08 21:57:55.949 20973 21058 I GStreamer+baseparse: 0:00:10.423583985 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:55.981 20973 21058 I GStreamer+baseparse: 0:00:10.455200194 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.002 20973 21066 W GStreamer+amcvideodec: 0:00:10.476593016 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.009638425)
02-08 21:57:56.003 20973 21058 I GStreamer+baseparse: 0:00:10.477233886 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.005 20973 21058 I GStreamer+baseparse: 0:00:10.479187010 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.020 20973 21058 I GStreamer+baseparse: 0:00:10.494720458 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.039 20973 21058 I GStreamer+baseparse: 0:00:10.513580322 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.040 20973 21066 W GStreamer+amcvideodec: 0:00:10.514678955 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.001967823)
02-08 21:57:56.057 20973 21058 I GStreamer+baseparse: 0:00:10.531799317 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.076 20973 21066 W GStreamer+amcvideodec: 0:00:10.550628661 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.004306981)
02-08 21:57:56.076 20973 21058 I GStreamer+baseparse: 0:00:10.550872802 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.096 20973 21058 I GStreamer+baseparse: 0:00:10.570129393 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.105 1107 2514 I Icing : Indexing 93084A8FE7164FF895579A6CF69743B7FB4364EC from com.google.android.gms
02-08 21:57:56.113 1107 2514 I Icing : Indexing done 93084A8FE7164FF895579A6CF69743B7FB4364EC
02-08 21:57:56.114 20973 21058 I GStreamer+baseparse: 0:00:10.588836669 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.132 20973 21066 W GStreamer+amcvideodec: 0:00:10.606964111 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.003568601)
02-08 21:57:56.135 20973 21058 I GStreamer+baseparse: 0:00:10.609161377 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.152 20973 21058 I GStreamer+baseparse: 0:00:10.626098633 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.170 20973 21058 I GStreamer+baseparse: 0:00:10.644805909 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.190 20973 21058 I GStreamer+baseparse: 0:00:10.664916991 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.210 20973 21058 I GStreamer+baseparse: 0:00:10.684173582 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.229 20973 21058 I GStreamer+baseparse: 0:00:10.703430174 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.249 20973 21058 I GStreamer+baseparse: 0:00:10.723602294 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.271 20973 21066 W GStreamer+amcvideodec: 0:00:10.745483399 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.006742335)
02-08 21:57:56.272 20973 21058 I GStreamer+baseparse: 0:00:10.746704103 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.286 20973 21058 I GStreamer+baseparse: 0:00:10.760040283 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.307 20973 21066 W GStreamer+amcvideodec: 0:00:10.781860350 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.002306591)
02-08 21:57:56.308 20973 21058 I GStreamer+baseparse: 0:00:10.782714842 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.324 20973 21058 I GStreamer+baseparse: 0:00:10.798370360 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.347 20973 21058 I GStreamer+baseparse: 0:00:10.821044921 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.366 20973 21058 I GStreamer+baseparse: 0:00:10.840332031 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.385 20973 21058 I GStreamer+baseparse: 0:00:10.859802246 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.407 20973 21066 W GStreamer+amcvideodec: 0:00:10.880889891 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.005538425)
02-08 21:57:56.407 20973 21058 I GStreamer+baseparse: 0:00:10.881896971 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.419 20973 21058 I GStreamer+baseparse: 0:00:10.893798826 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.444 20973 21058 I GStreamer+baseparse: 0:00:10.918121337 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.463 20973 21058 I GStreamer+baseparse: 0:00:10.937591553 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.487 20973 21058 I GStreamer+baseparse: 0:00:10.961914063 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.512 20973 21066 W GStreamer+amcvideodec: 0:00:10.986450194 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.019068309)
02-08 21:57:56.515 20973 21058 I GStreamer+baseparse: 0:00:10.986145019 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.540 20973 21058 I GStreamer+baseparse: 0:00:11.014129637 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.563 20973 21066 W GStreamer+amcvideodec: 0:00:11.037109375 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.009662939)
02-08 21:57:56.565 20973 21058 I GStreamer+baseparse: 0:00:11.039428711 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.568 20973 21058 I GStreamer+baseparse: 0:00:11.042541503 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.599 20973 21058 I GStreamer+baseparse: 0:00:11.073852540 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.606 20973 21058 I GStreamer+baseparse: 0:00:11.080596924 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.607 20973 21066 W GStreamer+amcvideodec: 0:00:11.081481934 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.003318215)
02-08 21:57:56.627 20973 21058 I GStreamer+baseparse: 0:00:11.101226805 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.655 20973 21052 W GStreamer+amcvideodec: 0:00:11.129577636 0x9d2ec6f0 gstamcvideodec.c:1145:_amc_gl_wait gl_sync 0x9d2d06a0 could not wait for frame, took too long
02-08 21:57:56.666 20973 21066 W GStreamer+amcvideodec: 0:00:11.140106201 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.059018993)
02-08 21:57:56.668 20973 21058 I GStreamer+baseparse: 0:00:11.142028808 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.669 20973 21066 W GStreamer+amcvideodec: 0:00:11.143432616 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.039618993)
02-08 21:57:56.672 20973 21058 I GStreamer+baseparse: 0:00:11.146453857 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.688 20973 21066 W GStreamer+amcvideodec: 0:00:11.162139893 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.020018993)
02-08 21:57:56.690 20973 21058 I GStreamer+baseparse: 0:00:11.164245605 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.705 20973 21066 W GStreamer+amcvideodec: 0:00:11.179931641 0x96c68380 gstamcvideodec.c:1307:gst_amc_video_dec_loop:<amcvideodec-omxqcomvideodecoderavc0> Frame is too late, dropping (deadline -0:00:00.000618993)
02-08 21:57:56.707 20973 21058 I GStreamer+baseparse: 0:00:11.181610107 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.725 20973 21058 I GStreamer+baseparse: 0:00:11.199951171 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.774 20973 21058 I GStreamer+baseparse: 0:00:11.248809814 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.812 20973 21052 W GStreamer+amcvideodec: 0:00:11.286834717 0x9d2ec6f0 gstamcvideodec.c:1145:_amc_gl_wait gl_sync 0x9d2d06a0 could not wait for frame, took too long
02-08 21:57:56.822 20973 21058 I GStreamer+baseparse: 0:00:11.296356201 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.864 20973 21058 I GStreamer+baseparse: 0:00:11.338958739 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.918 20973 21058 I GStreamer+baseparse: 0:00:11.392211915 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.953 20973 21058 I GStreamer+baseparse: 0:00:11.427764891 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:56.994 20973 21058 I GStreamer+baseparse: 0:00:11.468902588 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:57.037 20973 21058 I GStreamer+baseparse: 0:00:11.511230468 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:57.072 20973 21052 W GStreamer+amcvideodec: 0:00:11.546234130 0x9d2ec6f0 gstamcvideodec.c:1145:_amc_gl_wait gl_sync 0x9d2d0940 could not wait for frame, took too long
02-08 21:57:57.086 20973 21058 I GStreamer+baseparse: 0:00:11.560089111 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:57.131 20973 21058 I GStreamer+baseparse: 0:00:11.605743409 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:57.205 20973 21058 I GStreamer+baseparse: 0:00:11.679229737 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:57.209 20973 21058 I GStreamer+baseparse: 0:00:11.683959961 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:57.236 20973 21058 I GStreamer+baseparse: 0:00:11.710815428 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:57.254 20973 21058 I GStreamer+baseparse: 0:00:11.728118896 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:57.263 20973 21058 I GStreamer+baseparse: 0:00:11.737121582 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:57.281 20973 21058 I GStreamer+baseparse: 0:00:11.755615235 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:57.297 20973 21058 I GStreamer+baseparse: 0:00:11.771606445 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:57.316 20973 21058 I GStreamer+baseparse: 0:00:11.790130614 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:57.336 20973 21058 I GStreamer+baseparse: 0:00:11.810577392 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:57.353 20973 21058 I GStreamer+baseparse: 0:00:11.827117920 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:57.355 20973 21058 I GStreamer+videodecoder: 0:00:11.829467774 0x9c73fe30 gstvideodecoder.c:1432:gst_video_decoder_sink_event_default:<amcvideodec-omxqcomvideodecoderavc0> upstream tags: taglist, video-codec=(string)"H.264\ \(Baseline\ Profile\)", bitrate=(uint)59910406, language-code=(string)en, minimum-bitrate=(uint)58910134, maximum-bitrate=(uint)59403756;
02-08 21:57:57.373 20973 21058 I GStreamer+baseparse: 0:00:11.847290039 0x9c73fe30 gstbaseparse.c:3943:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.019480519, 0:00:00.019480519
02-08 21:57:57.377 20973 20973 D GStreamer+aiTennis3D: 0:00:11.851043701 0xaebeae00 gstreamer/aigstreamer.c:1152:gst_native_pause Setting state to PAUSED
02-08 21:57:57.377 20973 20973 I GStreamer+GST_STATES: 0:00:11.851257324 0xaebeae00 gstbin.c:2316:gst_bin_element_set_state:<glimagesinkbin0> current PLAYING pending VOID_PENDING, desired next PAUSED
02-08 21:57:57.377 20973 20973 I GStreamer+GST_STATES: 0:00:11.851440430 0xaebeae00 gstbin.c:2316:gst_bin_element_set_state:<sink> current PLAYING pending VOID_PENDING, desired next PAUSED
02-08 21:57:57.377 20973 20973 I GStreamer+GST_STATES: 0:00:11.851562500 0xaebeae00 gstbin.c:2770:gst_bin_change_state_func:<glimagesinkbin0> child 'sink' is changing state asynchronously to PAUSED
02-08 21:57:57.377 20973 20973 I GStreamer+GST_STATES: 0:00:11.851684570 0xaebeae00 gstbin.c:2316:gst_bin_element_set_state:<glcolorbalance0> current PLAYING pending VOID_PENDING, desired next PAUSED
02-08 21:57:57.377 20973 20973 I GStreamer+GST_STATES: 0:00:11.851776123 0xaebeae00 gstelement.c:2350:gst_element_continue_state:<glcolorbalance0> completed state change to PAUSED
02-08 21:57:57.377 20973 20973 I GStreamer+GST_STATES: 0:00:11.851898193 0xaebeae00 gstelement.c:2255:_priv_gst_element_state_changed:<glcolorbalance0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
02-08 21:57:57.378 20973 20973 I GStreamer+GST_STATES: 0:00:11.852539062 0xaebeae00 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'glcolorbalance0' changed state to 3(PAUSED) successfully
02-08 21:57:57.378 20973 20973 I GStreamer+GST_STATES: 0:00:11.852661133 0xaebeae00 gstbin.c:2316:gst_bin_element_set_state:<glcolorconvertelement0> current PLAYING pending VOID_PENDING, desired next PAUSED
02-08 21:57:57.378 20973 20973 I GStreamer+GST_STATES: 0:00:11.852752685 0xaebeae00 gstelement.c:2350:gst_element_continue_state:<glcolorconvertelement0> completed state change to PAUSED
02-08 21:57:57.378 20973 20973 I GStreamer+GST_STATES: 0:00:11.852844238 0xaebeae00 gstelement.c:2255:_priv_gst_element_state_changed:<glcolorconvertelement0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
02-08 21:57:57.379 20973 20973 I GStreamer+GST_STATES: 0:00:11.852996826 0xaebeae00 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'glcolorconvertelement0' changed state to 3(PAUSED) successfully
02-08 21:57:57.379 20973 20973 I GStreamer+GST_STATES: 0:00:11.853149414 0xaebeae00 gstbin.c:2316:gst_bin_element_set_state:<gluploadelement0> current PLAYING pending VOID_PENDING, desired next PAUSED
02-08 21:57:57.379 20973 20973 I GStreamer+GST_STATES: 0:00:11.853240967 0xaebeae00 gstelement.c:2350:gst_element_continue_state:<gluploadelement0> completed state change to PAUSED
02-08 21:57:57.379 20973 20973 I GStreamer+GST_STATES: 0:00:11.853332519 0xaebeae00 gstelement.c:2255:_priv_gst_element_state_changed:<gluploadelement0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
02-08 21:57:57.379 20973 20973 I GStreamer+GST_STATES: 0:00:11.853576660 0xaebeae00 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'gluploadelement0' changed state to 3(PAUSED) successfully
02-08 21:57:57.379 20973 20973 I GStreamer+GST_STATES: 0:00:11.853698730 0xaebeae00 gstbin.c:2770:gst_bin_change_state_func:<pipeline0> child 'glimagesinkbin0' is changing state asynchronously to PAUSED
02-08 21:57:57.379 20973 20973 I GStreamer+GST_STATES: 0:00:11.853820801 0xaebeae00 gstbin.c:2316:gst_bin_element_set_state:<decodebin_0> current PLAYING pending VOID_PENDING, desired next PAUSED
02-08 21:57:57.379 20973 20973 I GStreamer+GST_STATES: 0:00:11.853973388 0xaebeae00 gstbin.c:2316:gst_bin_element_set_state:<amcvideodec-omxqcomvideodecoderavc0> current PLAYING pending VOID_PENDING, desired next PAUSED
02-08 21:57:57.380 20973 20973 I GStreamer+GST_STATES: 0:00:11.854095459 0xaebeae00 gstelement.c:2350:gst_element_continue_state:<amcvideodec-omxqcomvideodecoderavc0> completed state change to PAUSED
02-08 21:57:57.380 20973 20973 I GStreamer+GST_STATES: 0:00:11.854187012 0xaebeae00 gstelement.c:2255:_priv_gst_element_state_changed:<amcvideodec-omxqcomvideodecoderavc0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
02-08 21:57:57.380 20973 20973 I GStreamer+GST_STATES: 0:00:11.854431152 0xaebeae00 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'amcvideodec-omxqcomvideodecoderavc0' changed state to 3(PAUSED) successfully
02-08 21:57:57.380 20973 20973 I GStreamer+GST_STATES: 0:00:11.854553222 0xaebeae00 gstbin.c:2316:gst_bin_element_set_state:<capsfilter0> current PLAYING pending VOID_PENDING, desired next PAUSED
02-08 21:57:57.380 20973 20973 I GStreamer+GST_STATES: 0:00:11.854644775 0xaebeae00 gstelement.c:2350:gst_element_continue_state:<capsfilter0> completed state change to PAUSED
02-08 21:57:57.380 20973 20973 I GStreamer+GST_STATES: 0:00:11.854736328 0xaebeae00 gstelement.c:2255:_priv_gst_element_state_changed:<capsfilter0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
02-08 21:57:57.381 20973 20973 I GStreamer+GST_STATES: 0:00:11.855041504 0xaebeae00 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'capsfilter0' changed state to 3(PAUSED) successfully
02-08 21:57:57.381 20973 20973 I GStreamer+GST_STATES: 0:00:11.855133056 0xaebeae00 gstbin.c:2316:gst_bin_element_set_state:<h264parse0> current PLAYING pending VOID_PENDING, desired next PAUSED
02-08 21:57:57.381 20973 20973 I GStreamer+GST_STATES: 0:00:11.855285644 0xaebeae00 gstelement.c:2350:gst_element_continue_state:<h264parse0> completed state change to PAUSED
02-08 21:57:57.381 20973 20973 I GStreamer+GST_STATES: 0:00:11.855377197 0xaebeae00 gstelement.c:2255:_priv_gst_element_state_changed:<h264parse0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
02-08 21:57:57.381 20973 20973 I GStreamer+GST_STATES: 0:00:11.855621338 0xaebeae00 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'h264parse0' changed state to 3(PAUSED) successfully
02-08 21:57:57.381 20973 20973 I GStreamer+GST_STATES: 0:00:11.855743408 0xaebeae00 gstbin.c:2316:gst_bin_element_set_state:<multiqueue0> current PLAYING pending VOID_PENDING, desired next PAUSED
02-08 21:57:57.381 20973 20973 I GStreamer+GST_STATES: 0:00:11.855834961 0xaebeae00 gstelement.c:2350:gst_element_continue_state:<multiqueue0> completed state change to PAUSED
02-08 21:57:57.381 20973 20973 I GStreamer+GST_STATES: 0:00:11.855926513 0xaebeae00 gstelement.c:2255:_priv_gst_element_state_changed:<multiqueue0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
02-08 21:57:57.382 20973 20973 I GStreamer+GST_STATES: 0:00:11.856170654 0xaebeae00 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'multiqueue0' changed state to 3(PAUSED) successfully
02-08 21:57:57.382 20973 20973 I GStreamer+GST_STATES: 0:00:11.856262207 0xaebeae00 gstbin.c:2316:gst_bin_element_set_state:<qtdemux0> current PLAYING pending VOID_PENDING, desired next PAUSED
02-08 21:57:57.382 20973 20973 I GStreamer+GST_STATES: 0:00:11.856384277 0xaebeae00 gstelement.c:2350:gst_element_continue_state:<qtdemux0> completed state change to PAUSED
02-08 21:57:57.382 20973 20973 I GStreamer+GST_STATES: 0:00:11.856475830 0xaebeae00 gstelement.c:2255:_priv_gst_element_state_changed:<qtdemux0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
02-08 21:57:57.383 20973 20973 I GStreamer+GST_STATES: 0:00:11.857269287 0xaebeae00 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'qtdemux0' changed state to 3(PAUSED) successfully
02-08 21:57:57.383 20973 20973 I GStreamer+GST_STATES: 0:00:11.857360839 0xaebeae00 gstbin.c:2316:gst_bin_element_set_state:<typefind> current PLAYING pending VOID_PENDING, desired next PAUSED
02-08 21:57:57.383 20973 20973 I GStreamer+GST_STATES: 0:00:11.857482910 0xaebeae00 gstelement.c:2350:gst_element_continue_state:<typefind> completed state change to PAUSED
02-08 21:57:57.383 20973 20973 I GStreamer+GST_STATES: 0:00:11.857574463 0xaebeae00 gstelement.c:2255:_priv_gst_element_state_changed:<typefind> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
02-08 21:57:57.383 20973 20973 I GStreamer+GST_STATES: 0:00:11.857818603 0xaebeae00 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'typefind' changed state to 3(PAUSED) successfully
02-08 21:57:57.383 20973 20973 I GStreamer+GST_STATES: 0:00:11.857910156 0xaebeae00 gstelement.c:2350:gst_element_continue_state:<decodebin_0> completed state change to PAUSED
02-08 21:57:57.383 20973 20973 I GStreamer+GST_STATES: 0:00:11.858001709 0xaebeae00 gstelement.c:2255:_priv_gst_element_state_changed:<decodebin_0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
02-08 21:57:57.384 20973 20973 I GStreamer+GST_STATES: 0:00:11.858245849 0xaebeae00 gstbin.c:2764:gst_bin_change_state_func:<pipeline0> child 'decodebin_0' changed state to 3(PAUSED) successfully
02-08 21:57:57.384 20973 20973 I GStreamer+GST_STATES: 0:00:11.858337402 0xaebeae00 gstbin.c:2316:gst_bin_element_set_state:<filesrc0> current PLAYING pending VOID_PENDING, desired next PAUSED
02-08 21:57:57.384 20973 20973 I GStreamer+GST_STATES: 0:00:11.858428955 0xaebeae00 gstelement.c:2350:gst_element_continue_state:<filesrc0> completed state change to PAUSED
02-08 21:57:57.384 20973 20973 I GStreamer+GST_STATES: 0:00:11.858520507 0xaebeae00 gstelement.c:2255:_priv_gst_element_state_changed:<filesrc0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
02-08 21:57:57.384 20973 20973 I GStreamer+GST_STATES: 0:00:11.858764648 0xaebeae00 gstbin.c:2764:gst_bin_change_state_func:<pipeline0> child 'filesrc0' changed state to 3(PAUSED) successfully
02-08 21:57:57.385 20973 20973 D GStreamer+aiTennis3D: 0:00:11.859466552 0xaebeae00 gstreamer/aigstreamer.c:1102:gst_native_finalize Quitting main loops...
02-08 21:57:57.385 20973 21038 D GStreamer+aiTennis3D: 0:00:11.859924316 0x9d319f20 gstreamer/aigstreamer.c:1052:app_function Exited main loop
02-08 21:57:57.386 20973 21038 I GStreamer+GST_STATES: 0:00:11.860107421 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<glimagesinkbin0> current PLAYING pending PAUSED, desired next READY
02-08 21:57:57.386 20973 21038 I GStreamer+GST_STATES: 0:00:11.860260009 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<sink> current PLAYING pending PAUSED, desired next READY
02-08 21:57:57.386 20973 21038 I GStreamer+GST_STATES: 0:00:11.860717773 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<sink> completed state change to READY
02-08 21:57:57.386 20973 21038 I GStreamer+GST_STATES: 0:00:11.860839843 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<sink> notifying about state-changed PLAYING to READY (VOID_PENDING pending)
02-08 21:57:57.386 20973 21038 I GStreamer+GST_STATES: 0:00:11.860961913 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'sink' changed state to 2(READY) successfully
02-08 21:57:57.387 20973 21038 I GStreamer+GST_STATES: 0:00:11.861053466 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<glcolorbalance0> current PAUSED pending VOID_PENDING, desired next READY
02-08 21:57:57.387 20973 20973 D GStreamer+aiTennis3D: 0:00:11.861206054 0xaebeae00 gstreamer/aigstreamer.c:1105:gst_native_finalize Waiting for thread0 to finish...
02-08 21:57:57.387 20973 21038 I GStreamer+GST_STATES: 0:00:11.861419677 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<glcolorbalance0> completed state change to READY
02-08 21:57:57.387 20973 21038 I GStreamer+GST_STATES: 0:00:11.861511230 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<glcolorbalance0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
02-08 21:57:57.387 20973 21038 I GStreamer+GST_STATES: 0:00:11.861633300 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'glcolorbalance0' changed state to 2(READY) successfully
02-08 21:57:57.387 20973 21038 I GStreamer+GST_STATES: 0:00:11.861724853 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<glcolorconvertelement0> current PAUSED pending VOID_PENDING, desired next READY
02-08 21:57:57.390 20973 21066 I GStreamer+task: 0:00:11.864685058 0x96c68380 gsttask.c:315:gst_task_func:<amcvideodec-omxqcomvideodecoderavc0:src> Task going to paused
02-08 21:57:57.391 20973 21058 E GStreamer+amcvideodec: 0:00:11.864990234 0x9c73fe30 gstamcvideodec.c:2236:gst_amc_video_dec_handle_frame:<amcvideodec-omxqcomvideodecoderavc0> Downstream returned flushing
02-08 21:57:57.391 20973 21057 I GStreamer+task: 0:00:11.865570067 0x96bfef50 gsttask.c:315:gst_task_func:<qtdemux0:sink> Task going to paused
02-08 21:57:57.391 20973 21058 I GStreamer+task: 0:00:11.865875243 0x9c73fe30 gsttask.c:315:gst_task_func:<multiqueue0:src_0> Task going to paused
02-08 21:57:57.396 20973 21038 I GStreamer+GST_STATES: 0:00:11.870819091 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<glcolorconvertelement0> completed state change to READY
02-08 21:57:57.396 20973 21038 I GStreamer+GST_STATES: 0:00:11.870941161 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<glcolorconvertelement0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
02-08 21:57:57.397 20973 21038 I GStreamer+GST_STATES: 0:00:11.871063231 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'glcolorconvertelement0' changed state to 2(READY) successfully
02-08 21:57:57.397 20973 21038 I GStreamer+GST_STATES: 0:00:11.871185302 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<gluploadelement0> current PAUSED pending VOID_PENDING, desired next READY
02-08 21:57:57.397 20973 21038 I GStreamer+GST_STATES: 0:00:11.871368407 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<gluploadelement0> completed state change to READY
02-08 21:57:57.397 20973 21038 I GStreamer+GST_STATES: 0:00:11.871459960 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<gluploadelement0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
02-08 21:57:57.397 20973 21038 I GStreamer+GST_STATES: 0:00:11.871582030 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'gluploadelement0' changed state to 2(READY) successfully
02-08 21:57:57.397 20973 21038 I GStreamer+GST_STATES: 0:00:11.871765135 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<glimagesinkbin0> completed state change to READY
02-08 21:57:57.397 20973 21038 I GStreamer+GST_STATES: 0:00:11.871856688 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<glimagesinkbin0> notifying about state-changed PLAYING to READY (VOID_PENDING pending)
02-08 21:57:57.397 20973 21038 I GStreamer+GST_STATES: 0:00:11.871978758 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<pipeline0> child 'glimagesinkbin0' changed state to 2(READY) successfully
02-08 21:57:57.398 20973 21038 I GStreamer+GST_STATES: 0:00:11.872100829 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<decodebin_0> current PAUSED pending VOID_PENDING, desired next READY
02-08 21:57:57.398 20973 21038 I GStreamer+GST_STATES: 0:00:11.872314452 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<amcvideodec-omxqcomvideodecoderavc0> current PAUSED pending VOID_PENDING, desired next READY
02-08 21:57:57.425 20973 21066 I GStreamer+task: 0:00:11.899017334 0x96c68380 gsttask.c:317:gst_task_func:<amcvideodec-omxqcomvideodecoderavc0:src> Task resume from paused
02-08 21:57:57.425 20973 21038 I GStreamer+GST_STATES: 0:00:11.899475098 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<amcvideodec-omxqcomvideodecoderavc0> completed state change to READY
02-08 21:57:57.425 20973 21038 I GStreamer+GST_STATES: 0:00:11.899597168 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<amcvideodec-omxqcomvideodecoderavc0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
02-08 21:57:57.425 20973 21038 I GStreamer+GST_STATES: 0:00:11.899719238 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'amcvideodec-omxqcomvideodecoderavc0' changed state to 2(READY) successfully
02-08 21:57:57.425 20973 21038 I GStreamer+GST_STATES: 0:00:11.899841309 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<capsfilter0> current PAUSED pending VOID_PENDING, desired next READY
02-08 21:57:57.426 20973 21038 I GStreamer+GST_STATES: 0:00:11.900024414 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<capsfilter0> completed state change to READY
02-08 21:57:57.426 20973 21038 I GStreamer+GST_STATES: 0:00:11.900115967 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<capsfilter0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
02-08 21:57:57.426 20973 21038 I GStreamer+GST_STATES: 0:00:11.900238037 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'capsfilter0' changed state to 2(READY) successfully
02-08 21:57:57.426 20973 21038 I GStreamer+GST_STATES: 0:00:11.900360107 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<h264parse0> current PAUSED pending VOID_PENDING, desired next READY
02-08 21:57:57.426 20973 21038 I GStreamer+baseparse: 0:00:11.900726318 0x9d319f20 gstbaseparse.c:4716:gst_base_parse_set_upstream_tags:<h264parse0> upstream tags: (NULL)
02-08 21:57:57.426 20973 21038 I GStreamer+GST_STATES: 0:00:11.900817871 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<h264parse0> completed state change to READY
02-08 21:57:57.426 20973 21038 I GStreamer+GST_STATES: 0:00:11.900909424 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<h264parse0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
02-08 21:57:57.427 20973 21038 I GStreamer+GST_STATES: 0:00:11.901031494 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'h264parse0' changed state to 2(READY) successfully
02-08 21:57:57.427 20973 21038 I GStreamer+GST_STATES: 0:00:11.901153564 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<multiqueue0> current PAUSED pending VOID_PENDING, desired next READY
02-08 21:57:57.427 20973 21058 I GStreamer+task: 0:00:11.901306152 0x9c73fe30 gsttask.c:317:gst_task_func:<multiqueue0:src_0> Task resume from paused
02-08 21:57:57.427 20973 21038 I GStreamer+GST_STATES: 0:00:11.901519775 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<multiqueue0> completed state change to READY
02-08 21:57:57.427 20973 21038 I GStreamer+GST_STATES: 0:00:11.901611328 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<multiqueue0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
02-08 21:57:57.427 20973 21038 I GStreamer+GST_STATES: 0:00:11.901733398 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'multiqueue0' changed state to 2(READY) successfully
02-08 21:57:57.427 20973 21038 I GStreamer+GST_STATES: 0:00:11.901855469 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<qtdemux0> current PAUSED pending VOID_PENDING, desired next READY
02-08 21:57:57.428 20973 21056 I GStreamer+task: 0:00:11.902221680 0x9d319630 gsttask.c:317:gst_task_func:<typefind:sink> Task resume from paused
02-08 21:57:57.428 20973 21057 I GStreamer+task: 0:00:11.902496338 0x96bfef50 gsttask.c:317:gst_task_func:<qtdemux0:sink> Task resume from paused
02-08 21:57:57.428 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:11.902709961 0x9d319f20 gstelement.c:776:gst_element_remove_pad:<qtdemux0> removing pad 'video_0'
02-08 21:57:57.428 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:11.902801513 0x9d319f20 gstpad.c:2048:gst_pad_unlink unlinking qtdemux0:video_0(0x99018568) and multiqueue0:sink_0(0x990186b8)
02-08 21:57:57.428 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:11.902923584 0x9d319f20 gstpad.c:2102:gst_pad_unlink unlinked qtdemux0:video_0 and multiqueue0:sink_0
02-08 21:57:57.429 20973 21038 I GStreamer+GST_STATES: 0:00:11.903045654 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<qtdemux0> completed state change to READY
02-08 21:57:57.429 20973 21038 I GStreamer+GST_STATES: 0:00:11.903137207 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<qtdemux0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
02-08 21:57:57.429 20973 21038 I GStreamer+GST_STATES: 0:00:11.903259277 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'qtdemux0' changed state to 2(READY) successfully
02-08 21:57:57.429 20973 21038 I GStreamer+GST_STATES: 0:00:11.903411865 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<amcvideodec-omxqcomvideodecoderavc0> current READY pending VOID_PENDING, desired next READY
02-08 21:57:57.429 20973 21038 I GStreamer+GST_STATES: 0:00:11.903503418 0x9d319f20 gstbin.c:2433:gst_bin_element_set_state:<amcvideodec-omxqcomvideodecoderavc0> skipping transition from READY to READY
02-08 21:57:57.429 20973 21038 I GStreamer+GST_STATES: 0:00:11.903594970 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'amcvideodec-omxqcomvideodecoderavc0' changed state to 2(READY) successfully
02-08 21:57:57.429 20973 21038 I GStreamer+GST_STATES: 0:00:11.903717041 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<capsfilter0> current READY pending VOID_PENDING, desired next READY
02-08 21:57:57.429 20973 21038 I GStreamer+GST_STATES: 0:00:11.903808593 0x9d319f20 gstbin.c:2433:gst_bin_element_set_state:<capsfilter0> skipping transition from READY to READY
02-08 21:57:57.429 20973 21038 I GStreamer+GST_STATES: 0:00:11.903900146 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'capsfilter0' changed state to 2(READY) successfully
02-08 21:57:57.429 20973 21038 I GStreamer+GST_STATES: 0:00:11.903991699 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<h264parse0> current READY pending VOID_PENDING, desired next READY
02-08 21:57:57.430 20973 21038 I GStreamer+GST_STATES: 0:00:11.904083252 0x9d319f20 gstbin.c:2433:gst_bin_element_set_state:<h264parse0> skipping transition from READY to READY
02-08 21:57:57.430 20973 21038 I GStreamer+GST_STATES: 0:00:11.904174804 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'h264parse0' changed state to 2(READY) successfully
02-08 21:57:57.430 20973 21038 I GStreamer+GST_STATES: 0:00:11.904296875 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<multiqueue0> current READY pending VOID_PENDING, desired next READY
02-08 21:57:57.430 20973 21038 I GStreamer+GST_STATES: 0:00:11.904388427 0x9d319f20 gstbin.c:2433:gst_bin_element_set_state:<multiqueue0> skipping transition from READY to READY
02-08 21:57:57.430 20973 21038 I GStreamer+GST_STATES: 0:00:11.904449463 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'multiqueue0' changed state to 2(READY) successfully
02-08 21:57:57.430 20973 21038 I GStreamer+GST_STATES: 0:00:11.904571533 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<qtdemux0> current READY pending VOID_PENDING, desired next READY
02-08 21:57:57.430 20973 21038 I GStreamer+GST_STATES: 0:00:11.904663086 0x9d319f20 gstbin.c:2433:gst_bin_element_set_state:<qtdemux0> skipping transition from READY to READY
02-08 21:57:57.430 20973 21038 I GStreamer+GST_STATES: 0:00:11.904754638 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'qtdemux0' changed state to 2(READY) successfully
02-08 21:57:57.430 20973 21038 I GStreamer+GST_STATES: 0:00:11.904846191 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<typefind> current PAUSED pending VOID_PENDING, desired next READY
02-08 21:57:57.430 20973 21038 I GStreamer+GST_STATES: 0:00:11.904968261 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<typefind> completed state change to READY
02-08 21:57:57.431 20973 21038 I GStreamer+GST_STATES: 0:00:11.905059814 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<typefind> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
02-08 21:57:57.431 20973 21038 I GStreamer+GST_STATES: 0:00:11.905181884 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'typefind' changed state to 2(READY) successfully
02-08 21:57:57.431 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:11.905548096 0x9d319f20 gstpad.c:2048:gst_pad_unlink unlinking capsfilter0:src(0x99018d48) and amcvideodec-omxqcomvideodecoderavc0:sink(0xaedf1040)
02-08 21:57:57.431 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:11.905700684 0x9d319f20 gstpad.c:2102:gst_pad_unlink unlinked capsfilter0:src and amcvideodec-omxqcomvideodecoderavc0:sink
02-08 21:57:57.431 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:11.905853272 0x9d319f20 gstpad.c:2048:gst_pad_unlink unlinking amcvideodec-omxqcomvideodecoderavc0:src(0xaedf1190) and src_0:proxypad3(0x9c61d658)
02-08 21:57:57.431 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:11.906005860 0x9d319f20 gstpad.c:2102:gst_pad_unlink unlinked amcvideodec-omxqcomvideodecoderavc0:src and src_0:proxypad3
02-08 21:57:57.432 20973 21038 I GStreamer+GST_PARENTAGE: 0:00:11.906127930 0x9d319f20 gstbin.c:1630:gst_bin_remove_func:<decodebin_0> removed child "amcvideodec-omxqcomvideodecoderavc0"
02-08 21:57:57.432 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:11.906311036 0x9d319f20 gstpad.c:2048:gst_pad_unlink unlinking h264parse0:src(0x99018aa8) and capsfilter0:sink(0x99018bf8)
02-08 21:57:57.432 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:11.906433106 0x9d319f20 gstpad.c:2102:gst_pad_unlink unlinked h264parse0:src and capsfilter0:sink
02-08 21:57:57.432 20973 21038 I GStreamer+GST_PARENTAGE: 0:00:11.906585694 0x9d319f20 gstbin.c:1630:gst_bin_remove_func:<decodebin_0> removed child "capsfilter0"
02-08 21:57:57.432 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:11.906738282 0x9d319f20 gstpad.c:2048:gst_pad_unlink unlinking multiqueue0:src_0(0x99018808) and h264parse0:sink(0x99018958)
02-08 21:57:57.432 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:11.906890870 0x9d319f20 gstpad.c:2102:gst_pad_unlink unlinked multiqueue0:src_0 and h264parse0:sink
02-08 21:57:57.433 20973 21038 I GStreamer+GST_PARENTAGE: 0:00:11.907043458 0x9d319f20 gstbin.c:1630:gst_bin_remove_func:<decodebin_0> removed child "h264parse0"
02-08 21:57:57.433 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:11.907226563 0x9d319f20 gstelement.c:776:gst_element_remove_pad:<decodebin_0> removing pad 'src_0'
02-08 21:57:57.433 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:11.907348633 0x9d319f20 gstpad.c:2048:gst_pad_unlink unlinking decodebin_0:src_0(0x96bed080) and glimagesinkbin0:sink(0x9c6191a0)
02-08 21:57:57.433 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:11.907470704 0x9d319f20 gstpad.c:2102:gst_pad_unlink unlinked decodebin_0:src_0 and glimagesinkbin0:sink
02-08 21:57:57.433 20973 21038 I GStreamer+GST_PARENTAGE: 0:00:11.907714844 0x9d319f20 gstbin.c:1630:gst_bin_remove_func:<decodebin_0> removed child "multiqueue0"
02-08 21:57:57.433 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:11.907897950 0x9d319f20 gstpad.c:2048:gst_pad_unlink unlinking typefind:src(0x9d2ef2d8) and qtdemux0:sink(0x99018028)
02-08 21:57:57.434 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:11.908020020 0x9d319f20 gstpad.c:2102:gst_pad_unlink unlinked typefind:src and qtdemux0:sink
02-08 21:57:57.434 20973 21038 I GStreamer+GST_PARENTAGE: 0:00:11.908172608 0x9d319f20 gstbin.c:1630:gst_bin_remove_func:<decodebin_0> removed child "qtdemux0"
02-08 21:57:57.434 20973 21038 I GStreamer+amcvideodec: 0:00:11.908447266 0x9d319f20 gstamcvideodec.c:468:gst_amc_video_dec_close:<amcvideodec-omxqcomvideodecoderavc0> shutting down gl queue pushed 88 ready 88 released 88
02-08 21:57:57.455 195 21064 E OMX-VDEC-1080P:
02-08 21:57:57.455 195 21064 E OMX-VDEC-1080P: Error in ioctl read next msg
02-08 21:57:57.455 195 3995 E :
02-08 21:57:57.455 195 3995 E : Destroy C2D instance
02-08 21:57:57.455 195 3995 E :
02-08 21:57:57.455 195 3995 E : Destroy C2D instance
02-08 21:57:57.456 20973 21059 W AMessage: failed to deliver message as target handler 10 is gone.
02-08 21:57:57.457 20973 21038 I GStreamer+GST_STATES: 0:00:11.931823730 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<amcvideodec-omxqcomvideodecoderavc0> completed state change to NULL
02-08 21:57:57.457 20973 21038 I GStreamer+GST_STATES: 0:00:11.931976318 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<amcvideodec-omxqcomvideodecoderavc0> notifying about state-changed READY to NULL (VOID_PENDING pending)
02-08 21:57:57.458 20973 21038 I GStreamer+GST_STATES: 0:00:11.932159424 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<capsfilter0> completed state change to NULL
02-08 21:57:57.458 20973 21038 I GStreamer+GST_STATES: 0:00:11.932281494 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<capsfilter0> notifying about state-changed READY to NULL (VOID_PENDING pending)
02-08 21:57:57.458 20973 21038 I GStreamer+GST_STATES: 0:00:11.932464600 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<h264parse0> completed state change to NULL
02-08 21:57:57.458 20973 21038 I GStreamer+GST_STATES: 0:00:11.932586670 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<h264parse0> notifying about state-changed READY to NULL (VOID_PENDING pending)
02-08 21:57:57.458 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:11.932739258 0x9d319f20 gstelement.c:776:gst_element_remove_pad:<multiqueue0> removing pad 'src_0'
02-08 21:57:57.458 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:11.932861328 0x9d319f20 gstelement.c:776:gst_element_remove_pad:<multiqueue0> removing pad 'sink_0'
02-08 21:57:57.459 20973 21038 I GStreamer+GST_STATES: 0:00:11.933074951 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<multiqueue0> completed state change to NULL
02-08 21:57:57.459 20973 21038 I GStreamer+GST_STATES: 0:00:11.933197021 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<multiqueue0> notifying about state-changed READY to NULL (VOID_PENDING pending)
02-08 21:57:57.459 20973 21038 I GStreamer+GST_STATES: 0:00:11.933349609 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<qtdemux0> completed state change to NULL
02-08 21:57:57.459 20973 21038 I GStreamer+GST_STATES: 0:00:11.933471680 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<qtdemux0> notifying about state-changed READY to NULL (VOID_PENDING pending)
02-08 21:57:57.459 20973 21038 I GStreamer+GST_STATES: 0:00:11.933624267 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<decodebin_0> completed state change to READY
02-08 21:57:57.459 20973 21038 I GStreamer+GST_STATES: 0:00:11.933746338 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<decodebin_0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
02-08 21:57:57.459 20973 21038 I GStreamer+GST_STATES: 0:00:11.933898926 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<pipeline0> child 'decodebin_0' changed state to 2(READY) successfully
02-08 21:57:57.460 20973 21038 I GStreamer+GST_STATES: 0:00:11.934082031 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<glimagesinkbin0> current READY pending VOID_PENDING, desired next READY
02-08 21:57:57.460 20973 21038 I GStreamer+GST_STATES: 0:00:11.934265137 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<sink> current READY pending VOID_PENDING, desired next READY
02-08 21:57:57.460 20973 21038 I GStreamer+GST_STATES: 0:00:11.934387207 0x9d319f20 gstbin.c:2433:gst_bin_element_set_state:<sink> skipping transition from READY to READY
02-08 21:57:57.460 20973 21038 I GStreamer+GST_STATES: 0:00:11.934509277 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'sink' changed state to 2(READY) successfully
02-08 21:57:57.460 20973 21038 I GStreamer+GST_STATES: 0:00:11.934661865 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<glcolorbalance0> current READY pending VOID_PENDING, desired next READY
02-08 21:57:57.460 20973 21038 I GStreamer+GST_STATES: 0:00:11.934783935 0x9d319f20 gstbin.c:2433:gst_bin_element_set_state:<glcolorbalance0> skipping transition from READY to READY
02-08 21:57:57.460 20973 21038 I GStreamer+GST_STATES: 0:00:11.934906006 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'glcolorbalance0' changed state to 2(READY) successfully
02-08 21:57:57.461 20973 21038 I GStreamer+GST_STATES: 0:00:11.935028076 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<glcolorconvertelement0> current READY pending VOID_PENDING, desired next READY
02-08 21:57:57.461 20973 21038 I GStreamer+GST_STATES: 0:00:11.935150146 0x9d319f20 gstbin.c:2433:gst_bin_element_set_state:<glcolorconvertelement0> skipping transition from READY to READY
02-08 21:57:57.461 20973 21038 I GStreamer+GST_STATES: 0:00:11.935363769 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'glcolorconvertelement0' changed state to 2(READY) successfully
02-08 21:57:57.461 20973 21038 I GStreamer+GST_STATES: 0:00:11.935516357 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<gluploadelement0> current READY pending VOID_PENDING, desired next READY
02-08 21:57:57.461 20973 21038 I GStreamer+GST_STATES: 0:00:11.935638427 0x9d319f20 gstbin.c:2433:gst_bin_element_set_state:<gluploadelement0> skipping transition from READY to READY
02-08 21:57:57.461 20973 21038 I GStreamer+GST_STATES: 0:00:11.935760498 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'gluploadelement0' changed state to 2(READY) successfully
02-08 21:57:57.461 20973 21038 I GStreamer+GST_STATES: 0:00:11.935882568 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<glimagesinkbin0> completed state change to READY
02-08 21:57:57.462 20973 21038 I GStreamer+GST_STATES: 0:00:11.936004638 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<pipeline0> child 'glimagesinkbin0' changed state to 2(READY) successfully
02-08 21:57:57.462 20973 21038 I GStreamer+GST_STATES: 0:00:11.936157226 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<decodebin_0> current READY pending VOID_PENDING, desired next READY
02-08 21:57:57.462 20973 21038 I GStreamer+GST_STATES: 0:00:11.936340332 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<typefind> current READY pending VOID_PENDING, desired next READY
02-08 21:57:57.462 20973 21038 I GStreamer+GST_STATES: 0:00:11.936462402 0x9d319f20 gstbin.c:2433:gst_bin_element_set_state:<typefind> skipping transition from READY to READY
02-08 21:57:57.462 20973 21038 I GStreamer+GST_STATES: 0:00:11.936584472 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'typefind' changed state to 2(READY) successfully
02-08 21:57:57.462 20973 21038 I GStreamer+GST_STATES: 0:00:11.936706543 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<decodebin_0> completed state change to READY
02-08 21:57:57.462 20973 21038 I GStreamer+GST_STATES: 0:00:11.936828613 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<pipeline0> child 'decodebin_0' changed state to 2(READY) successfully
02-08 21:57:57.462 20973 21038 I GStreamer+GST_STATES: 0:00:11.936981201 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<filesrc0> current PAUSED pending VOID_PENDING, desired next READY
02-08 21:57:57.463 20973 21038 I GStreamer+GST_STATES: 0:00:11.937103271 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<filesrc0> completed state change to READY
02-08 21:57:57.463 20973 21038 I GStreamer+GST_STATES: 0:00:11.937225341 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<filesrc0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
02-08 21:57:57.463 20973 21038 I GStreamer+GST_STATES: 0:00:11.937896728 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<pipeline0> child 'filesrc0' changed state to 2(READY) successfully
02-08 21:57:57.464 20973 21038 I GStreamer+GST_STATES: 0:00:11.938049316 0x9d319f20 gstelement.c:2325:gst_element_continue_state:<pipeline0> committing state from PLAYING to READY, pending NULL, next NULL
02-08 21:57:57.464 20973 21038 I GStreamer+GST_STATES: 0:00:11.938171386 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<pipeline0> notifying about state-changed PLAYING to READY (NULL pending)
02-08 21:57:57.464 20973 21038 I GStreamer+GST_STATES: 0:00:11.938323974 0x9d319f20 gstelement.c:2332:gst_element_continue_state:<pipeline0> continue state change READY to NULL, final NULL
02-08 21:57:57.464 20973 21038 I GStreamer+GST_STATES: 0:00:11.938476562 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<glimagesinkbin0> current READY pending VOID_PENDING, desired next NULL
02-08 21:57:57.464 20973 21038 I GStreamer+GST_STATES: 0:00:11.938690185 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<sink> current READY pending VOID_PENDING, desired next NULL
02-08 21:57:57.465 20973 21038 I GStreamer+glcontext: 0:00:11.939514160 0x9d319f20 gstglcontext.c:645:gst_gl_context_finalize:<glcontextegl0> send quit gl window loop
02-08 21:57:57.465 20973 21038 I GStreamer+glwindow: 0:00:11.939636230 0x9d319f20 gstglwindow.c:586:gst_gl_window_quit quit sent to gl window loop
02-08 21:57:57.465 20973 21038 I GStreamer+glcontext: 0:00:11.939697265 0x9d319f20 gstglcontext.c:648:gst_gl_context_finalize:<glcontextegl0> joining gl thread
02-08 21:57:57.466 20973 21052 I GStreamer+glcontext: 0:00:11.940032958 0x9d2ec6f0 gstglcontext.c:1236:gst_gl_context_create_thread:<glcontextegl0> loop exited
02-08 21:57:57.472 20973 21052 W art : Native thread exiting without having called DetachCurrentThread (maybe it's going to use a pthread_key_create destructor?): Thread[21,tid=21052,Native,Thread*=0x9caf3f00,peer=0x12db80a0,"Thread-2492"]
02-08 21:57:57.475 20973 21038 I GStreamer+glcontext: 0:00:11.949432372 0x9d319f20 gstglcontext.c:650:gst_gl_context_finalize:<glcontextegl0> gl thread joined
02-08 21:57:57.475 20973 21038 I GStreamer+glwindow: 0:00:11.949554442 0x9d319f20 gstglwindow.c:332:gst_gl_window_finalize quit navigation loop
02-08 21:57:57.475 20973 21051 I GStreamer+glwindow: 0:00:11.949737548 0x9d2ec460 gstglwindow.c:948:gst_gl_window_navigation_thread navigation loop exited
02-08 21:57:57.475 20973 21051 I GStreamer+glwindow:
02-08 21:57:57.476 20973 21038 I GStreamer+GST_STATES: 0:00:11.950500487 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<sink> completed state change to NULL
02-08 21:57:57.476 20973 21038 I GStreamer+GST_STATES: 0:00:11.950622557 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<sink> notifying about state-changed READY to NULL (VOID_PENDING pending)
02-08 21:57:57.476 20973 21038 I GStreamer+GST_STATES: 0:00:11.950805663 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'sink' changed state to 1(NULL) successfully
02-08 21:57:57.476 20973 21038 I GStreamer+GST_STATES: 0:00:11.950958251 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<glcolorbalance0> current READY pending VOID_PENDING, desired next NULL
02-08 21:57:57.477 20973 21038 I GStreamer+GST_STATES: 0:00:11.951110839 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<glcolorbalance0> completed state change to NULL
02-08 21:57:57.477 20973 21038 I GStreamer+GST_STATES: 0:00:11.951232909 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<glcolorbalance0> notifying about state-changed READY to NULL (VOID_PENDING pending)
02-08 21:57:57.477 20973 21038 I GStreamer+GST_STATES: 0:00:11.951385497 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'glcolorbalance0' changed state to 1(NULL) successfully
02-08 21:57:57.477 20973 21038 I GStreamer+GST_STATES: 0:00:11.951507567 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<glcolorconvertelement0> current READY pending VOID_PENDING, desired next NULL
02-08 21:57:57.477 20973 21038 I GStreamer+GST_STATES: 0:00:11.951751708 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<glcolorconvertelement0> completed state change to NULL
02-08 21:57:57.477 20973 21038 I GStreamer+GST_STATES: 0:00:11.951904296 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<glcolorconvertelement0> notifying about state-changed READY to NULL (VOID_PENDING pending)
02-08 21:57:57.478 20973 21038 I GStreamer+GST_STATES: 0:00:11.952056883 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'glcolorconvertelement0' changed state to 1(NULL) successfully
02-08 21:57:57.478 20973 21038 I GStreamer+GST_STATES: 0:00:11.952209471 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<gluploadelement0> current READY pending VOID_PENDING, desired next NULL
02-08 21:57:57.478 20973 21038 I GStreamer+GST_STATES: 0:00:11.952362059 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<gluploadelement0> completed state change to NULL
02-08 21:57:57.478 20973 21038 I GStreamer+GST_STATES: 0:00:11.952484130 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<gluploadelement0> notifying about state-changed READY to NULL (VOID_PENDING pending)
02-08 21:57:57.478 20973 21038 I GStreamer+GST_STATES: 0:00:11.952636717 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<glimagesinkbin0> child 'gluploadelement0' changed state to 1(NULL) successfully
02-08 21:57:57.478 20973 21038 I GStreamer+GST_STATES: 0:00:11.952819823 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<glimagesinkbin0> completed state change to NULL
02-08 21:57:57.478 20973 21038 I GStreamer+GST_STATES: 0:00:11.952941893 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<glimagesinkbin0> notifying about state-changed READY to NULL (VOID_PENDING pending)
02-08 21:57:57.479 20973 21038 I GStreamer+GST_STATES: 0:00:11.953094481 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<pipeline0> child 'glimagesinkbin0' changed state to 1(NULL) successfully
02-08 21:57:57.479 20973 21038 I GStreamer+GST_STATES: 0:00:11.953247069 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<decodebin_0> current READY pending VOID_PENDING, desired next NULL
02-08 21:57:57.479 20973 21038 I GStreamer+GST_STATES: 0:00:11.953430174 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<typefind> current READY pending VOID_PENDING, desired next NULL
02-08 21:57:57.479 20973 21038 I GStreamer+GST_STATES: 0:00:11.953582762 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<typefind> completed state change to NULL
02-08 21:57:57.479 20973 21038 I GStreamer+GST_STATES: 0:00:11.953704833 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<typefind> notifying about state-changed READY to NULL (VOID_PENDING pending)
02-08 21:57:57.479 20973 21038 I GStreamer+GST_STATES: 0:00:11.953857420 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<decodebin_0> child 'typefind' changed state to 1(NULL) successfully
02-08 21:57:57.480 20973 21038 I GStreamer+GST_STATES: 0:00:11.954010008 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<decodebin_0> completed state change to NULL
02-08 21:57:57.480 20973 21038 I GStreamer+GST_STATES: 0:00:11.954132079 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<decodebin_0> notifying about state-changed READY to NULL (VOID_PENDING pending)
02-08 21:57:57.480 20973 21038 I GStreamer+GST_STATES: 0:00:11.954284667 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<pipeline0> child 'decodebin_0' changed state to 1(NULL) successfully
02-08 21:57:57.480 20973 21038 I GStreamer+GST_STATES: 0:00:11.954437254 0x9d319f20 gstbin.c:2316:gst_bin_element_set_state:<filesrc0> current READY pending VOID_PENDING, desired next NULL
02-08 21:57:57.480 20973 21038 I GStreamer+GST_STATES: 0:00:11.954589842 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<filesrc0> completed state change to NULL
02-08 21:57:57.480 20973 21038 I GStreamer+GST_STATES: 0:00:11.954711913 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<filesrc0> notifying about state-changed READY to NULL (VOID_PENDING pending)
02-08 21:57:57.480 20973 21038 I GStreamer+GST_STATES: 0:00:11.954833983 0x9d319f20 gstbin.c:2764:gst_bin_change_state_func:<pipeline0> child 'filesrc0' changed state to 1(NULL) successfully
02-08 21:57:57.481 20973 21038 I GStreamer+GST_REFCOUNTING: 0:00:11.955169676 0x9d319f20 gstelement.c:2925:gst_element_dispose:<qtdemux0> dispose
02-08 21:57:57.481 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:11.955566406 0x9d319f20 gstelement.c:776:gst_element_remove_pad:<qtdemux0> removing pad 'sink'
02-08 21:57:57.481 20973 21038 I GStreamer+GST_REFCOUNTING: 0:00:11.955657958 0x9d319f20 gstelement.c:2970:gst_element_dispose:<qtdemux0> parent class dispose
02-08 21:57:57.481 20973 21038 I GStreamer+GST_REFCOUNTING: 0:00:11.955749511 0x9d319f20 gstelement.c:3001:gst_element_finalize:<qtdemux0> finalize
02-08 21:57:57.481 20973 21038 I GStreamer+GST_REFCOUNTING: 0:00:11.955841064 0x9d319f20 gstelement.c:3006:gst_element_finalize:<qtdemux0> finalize parent
02-08 21:57:57.482 20973 21038 I GStreamer+GST_REFCOUNTING: 0:00:11.956024169 0x9d319f20 gstelement.c:2925:gst_element_dispose:<multiqueue0> dispose
02-08 21:57:57.482 20973 21038 I GStreamer+GST_REFCOUNTING: 0:00:11.956115722 0x9d319f20 gstelement.c:2970:gst_element_dispose:<multiqueue0> parent class dispose
02-08 21:57:57.482 20973 21038 I GStreamer+GST_REFCOUNTING: 0:00:11.956207275 0x9d319f20 gstelement.c:3001:gst_element_finalize:<multiqueue0> finalize
02-08 21:57:57.482 20973 21038 I GStreamer+GST_REFCOUNTING: 0:00:11.956298828 0x9d319f20 gstelement.c:3006:gst_element_finalize:<multiqueue0> finalize parent
02-08 21:57:57.482 20973 21038 I GStreamer+GST_REFCOUNTING: 0:00:11.956420898 0x9d319f20 gstelement.c:2925:gst_element_dispose:<h264parse0> dispose
02-08 21:57:57.482 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:11.956512451 0x9d319f20 gstelement.c:776:gst_element_remove_pad:<h264parse0> removing pad 'sink'
02-08 21:57:57.482 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:11.956604003 0x9d319f20 gstelement.c:776:gst_element_remove_pad:<h264parse0> removing pad 'src'
02-08 21:57:57.482 20973 21038 I GStreamer+GST_REFCOUNTING: 0:00:11.956695556 0x9d319f20 gstelement.c:2970:gst_element_dispose:<h264parse0> parent class dispose
02-08 21:57:57.482 20973 21038 I GStreamer+GST_REFCOUNTING: 0:00:11.956848144 0x9d319f20 gstelement.c:3001:gst_element_finalize:<h264parse0> finalize
02-08 21:57:57.482 20973 21038 I GStreamer+GST_REFCOUNTING: 0:00:11.956939697 0x9d319f20 gstelement.c:3006:gst_element_finalize:<h264parse0> finalize parent
02-08 21:57:57.483 20973 21038 I GStreamer+GST_REFCOUNTING: 0:00:11.957092285 0x9d319f20 gstelement.c:2925:gst_element_dispose:<capsfilter0> dispose
02-08 21:57:57.483 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:11.957183837 0x9d319f20 gstelement.c:776:gst_element_remove_pad:<capsfilter0> removing pad 'sink'
02-08 21:57:57.483 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:11.957275390 0x9d319f20 gstelement.c:776:gst_element_remove_pad:<capsfilter0> removing pad 'src'
02-08 21:57:57.483 20973 21038 I GStreamer+GST_REFCOUNTING: 0:00:11.957366943 0x9d319f20 gstelement.c:2970:gst_element_dispose:<capsfilter0> parent class dispose
02-08 21:57:57.483 20973 21038 I GStreamer+GST_REFCOUNTING: 0:00:11.957458495 0x9d319f20 gstelement.c:3001:gst_element_finalize:<capsfilter0> finalize
02-08 21:57:57.483 20973 21038 I GStreamer+GST_REFCOUNTING: 0:00:11.957550048 0x9d319f20 gstelement.c:3006:gst_element_finalize:<capsfilter0> finalize parent
02-08 21:57:57.483 20973 21038 I GStreamer+GST_REFCOUNTING: 0:00:11.957672119 0x9d319f20 gstelement.c:2925:gst_element_dispose:<amcvideodec-omxqcomvideodecoderavc0> dispose
02-08 21:57:57.483 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:11.957763671 0x9d319f20 gstelement.c:776:gst_element_remove_pad:<amcvideodec-omxqcomvideodecoderavc0> removing pad 'sink'
02-08 21:57:57.483 20973 21038 I GStreamer+GST_ELEMENT_PADS: 0:00:11.957855224 0x9d319f20 gstelement.c:776:gst_element_remove_pad:<amcvideodec-omxqcomvideodecoderavc0> removing pad 'src'
02-08 21:57:57.483 20973 21038 I GStreamer+GST_REFCOUNTING: 0:00:11.957977294 0x9d319f20 gstelement.c:2970:gst_element_dispose:<amcvideodec-omxqcomvideodecoderavc0> parent class dispose
02-08 21:57:57.484 20973 21038 I GStreamer+GST_REFCOUNTING: 0:00:11.958099365 0x9d319f20 gstelement.c:3001:gst_element_finalize:<amcvideodec-omxqcomvideodecoderavc0> finalize
02-08 21:57:57.484 20973 21038 I GStreamer+GST_REFCOUNTING: 0:00:11.958160400 0x9d319f20 gstelement.c:3006:gst_element_finalize:<amcvideodec-omxqcomvideodecoderavc0> finalize parent
02-08 21:57:57.484 20973 21038 I GStreamer+GST_STATES: 0:00:11.958343505 0x9d319f20 gstelement.c:2350:gst_element_continue_state:<pipeline0> completed state change to NULL
02-08 21:57:57.484 20973 21038 I GStreamer+GST_STATES: 0:00:11.958465575 0x9d319f20 gstelement.c:2255:_priv_gst_element_state_changed:<pipeline0> notifying about state-changed READY to NULL (VOID_PENDING pending)
02-08 21:57:57.484 20973 21038 W art : Native thread exiting without having called DetachCurrentThread (maybe it's going to use a pthread_key_create destructor?): Thread[17,tid=21038,Native,Thread*=0x9c747300,peer=0x12d730a0,"Thread-2488"]
02-08 21:57:57.485 20973 21038 D GStreamer+aiTennis3D: 0:00:11.959136962 0x9d319f20 gstreamer/aigstreamer.c:171:detach_current_thread Detaching thread 0x9d319f20
02-08 21:57:57.485 20973 20973 D GStreamer+aiTennis3D: 0:00:11.959930419 0xaebeae00 gstreamer/aigstreamer.c:1128:gst_native_finalize Deleting GlobalRef for app object at 0x5fe
02-08 21:57:57.485 20973 20973 D GStreamer+aiTennis3D: 0:00:11.959991454 0xaebeae00 gstreamer/aigstreamer.c:1130:gst_native_finalize Freeing CustomData at 0x9d30e480
02-08 21:57:57.486 20973 20973 D GStreamer+aiTennis3D: 0:00:11.960113525 0xaebeae00 gstreamer/aigstreamer.c:1133:gst_native_finalize Done finalizing
02-08 21:57:57.925 20973 21066 W art : Native thread exiting without having called DetachCurrentThread (maybe it's going to use a pthread_key_create destructor?): Thread[20,tid=21066,Native,Thread*=0x9c749b00,peer=0x12db30a0,"Thread-2491"]
02-08 21:57:57.926 20973 21066 D GStreamer+aiTennis3D: 0:00:12.400024415 0x96c68380 gstreamer/aigstreamer.c:171:detach_current_thread Detaching thread 0x96c68380
02-08 21:57:57.927 20973 21058 W art : Native thread exiting without having called DetachCurrentThread (maybe it's going to use a pthread_key_create destructor?): Thread[18,tid=21058,Native,Thread*=0x96a7cd00,peer=0x12da80a0,"Thread-2489"]
^C
More information about the gstreamer-android
mailing list