SK-AM62A-LP: Gstreamer pipeline with tiovxisp element hangs

0:00:00.000231090  3573      0x54ad400 INFO                GST_INIT gst.c:586:init_pre: Initializing GStreamer Core Library version 1.16.3
0:00:00.000344830  3573      0x54ad400 INFO                GST_INIT gst.c:587:init_pre: Using library installed in /usr/lib
0:00:00.000375890  3573      0x54ad400 INFO                GST_INIT gst.c:605:init_pre: Linux am62axx-evm 5.10.168-g2c23e6c538 #1 SMP PREEMPT Mon Feb 27 11:35:01 UTC 2023 aarch64
0:00:00.001093775  3573      0x54ad400 INFO                GST_INIT gstmessage.c:128:_priv_gst_message_initialize: init messages
0:00:00.002451335  3573      0x54ad400 INFO                GST_INIT gstcontext.c:84:_priv_gst_context_initialize: init contexts
0:00:00.003053505  3573      0x54ad400 INFO      GST_PLUGIN_LOADING gstplugin.c:318:_priv_gst_plugin_initialize: registering 0 static plugins
0:00:00.003376725  3573      0x54ad400 INFO      GST_PLUGIN_LOADING gstplugin.c:226:gst_plugin_register_static: registered static plugin "staticelements"
0:00:00.003414125  3573      0x54ad400 INFO      GST_PLUGIN_LOADING gstplugin.c:228:gst_plugin_register_static: added static plugin "staticelements", result: 1
0:00:00.003479945  3573      0x54ad400 INFO            GST_REGISTRY gstregistry.c:1733:ensure_current_registry: reading registry cache: /home/root/.cache/gstreamer-1.0/registry.aarch64.bin
0:00:00.059502430  3573      0x54ad400 INFO            GST_REGISTRY gstregistrybinary.c:621:priv_gst_registry_binary_read_cache: loaded /home/root/.cache/gstreamer-1.0/registry.aarch64.bin in 0.055932 seconds
0:00:00.059761790  3573      0x54ad400 INFO            GST_REGISTRY gstregistry.c:1592:scan_and_update_registry: Validating plugins from registry cache: /home/root/.cache/gstreamer-1.0/registry.aarch64.bin
0:00:00.063096035  3573      0x54ad400 INFO            GST_REGISTRY gstregistry.c:1691:scan_and_update_registry: Registry cache has not changed
0:00:00.063159410  3573      0x54ad400 INFO            GST_REGISTRY gstregistry.c:1768:ensure_current_registry: registry reading and updating done, result = 1
0:00:00.063183110  3573      0x54ad400 INFO                GST_INIT gst.c:806:init_post: GLib runtime version: 2.62.6
0:00:00.063207320  3573      0x54ad400 INFO                GST_INIT gst.c:808:init_post: GLib headers version: 2.62.6
0:00:00.063222090  3573      0x54ad400 INFO                GST_INIT gst.c:810:init_post: initialized GStreamer successfully
0:00:00.063337670  3573      0x54ad400 INFO            GST_PIPELINE gstparse.c:336:gst_parse_launch_full: parsing pipeline description 'v4l2src device=/dev/video2 io-mode=5 ! video/x-bayer,width=640,height=480,format=bggr, framerate=60/1 ! tiovxisp sensor-name=SENSOR_SONY_IMX219_RPI dcc-isp-file=/opt/imaging/imx219/dcc_viss_640x480.bin 
 '
0:00:00.083802705  3573      0x54ad400 INFO      GST_PLUGIN_LOADING gstplugin.c:902:_priv_gst_plugin_load_file_for_registry: plugin "/usr/lib/gstreamer-1.0/libgstvideo4linux2.so" loaded
0:00:00.083899355  3573      0x54ad400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "v4l2src"
0:00:00.087251920  3573      0x54ad400 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstBaseSrc@0x56c20e0> adding pad 'src'
0:00:00.122400350  3573      0x54ad400 INFO      GST_PLUGIN_LOADING gstplugin.c:902:_priv_gst_plugin_load_file_for_registry: plugin "/usr/lib/gstreamer-1.0/libgsttiovx.so" loaded
0:00:00.122488770  3573      0x54ad400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "tiovxisp"
0:00:00.123171125  3573      0x54ad400 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstAggregator@0x56f2130> adding pad 'src'
0:00:00.123266895  3573      0x54ad400 INFO            tiovxcontext gsttiovxcontext.c:141:gst_tiovx_context_init: Calling appInit() from GST!
0:00:00.148147535  3573      0x54ad400 INFO          tiovxallocator gsttiovxallocator.c:128:gst_tiovx_allocator_init:<GstTIOVXAllocator@0x56a0e40> New TIOVX allocator
0:00:00.148361860  3573      0x54ad400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "pipeline"
0:00:00.148752345  3573      0x54ad400 INFO            GST_PIPELINE grammar.y:648:gst_parse_perform_link: linking some pad of GstV4l2Src named v4l2src0 to some pad of GstTIOVXISP named tiovxisp0 (0/0) with caps "video/x-bayer, width=(int)640, height=(int)480, format=(string)bggr, framerate=(fraction)60/1"
0:00:00.150500235  3573      0x54ad400 INFO      GST_PLUGIN_LOADING gstplugin.c:902:_priv_gst_plugin_load_file_for_registry: plugin "/usr/lib/gstreamer-1.0/libgstcoreelements.so" loaded
0:00:00.150569660  3573      0x54ad400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "capsfilter"
0:00:00.150919815  3573      0x54ad400 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstBaseTransform@0x5702230> adding pad 'sink'
0:00:00.150983485  3573      0x54ad400 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstBaseTransform@0x5702230> adding pad 'src'
0:00:00.151031185  3573      0x54ad400 INFO              GST_STATES gstbin.c:2090:gst_bin_get_state_func:<pipeline0> getting state
0:00:00.151127595  3573      0x54ad400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<capsfilter0> completed state change to NULL
0:00:00.151169250  3573      0x54ad400 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:00.151208045  3573      0x54ad400 INFO        GST_ELEMENT_PADS gstutils.c:1771:gst_element_link_pads_full: trying to link element v4l2src0:(any) to element capsfilter0:sink
0:00:00.151235390  3573      0x54ad400 INFO        GST_ELEMENT_PADS gstelement.c:928:gst_element_get_static_pad: found pad capsfilter0:sink
0:00:00.151259840  3573      0x54ad400 INFO                GST_PADS gstutils.c:1587:prepare_link_maybe_ghosting: v4l2src0 and capsfilter0 in same bin, no need for ghost pads
0:00:00.151305740  3573      0x54ad400 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link v4l2src0:src and capsfilter0:sink
0:00:00.151352245  3573      0x54ad400 INFO                GST_PADS gstpad.c:4237:gst_pad_peer_query:<capsfilter0:src> pad has no peer
0:00:00.151412355  3573      0x54ad400 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked v4l2src0:src and capsfilter0:sink, successful
0:00:00.151430065  3573      0x54ad400 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:00.151448965  3573      0x54ad400 INFO               GST_EVENT gstpad.c:5812:gst_pad_send_event_unchecked:<v4l2src0:src> Received event on flushing pad. Discarding
0:00:00.151490425  3573      0x54ad400 INFO        GST_ELEMENT_PADS gstutils.c:1771:gst_element_link_pads_full: trying to link element capsfilter0:src to element tiovxisp0:(any)
0:00:00.151512345  3573      0x54ad400 INFO        GST_ELEMENT_PADS gstelement.c:928:gst_element_get_static_pad: found pad capsfilter0:src
0:00:00.151762110  3573      0x54ad400 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<tiovxisp0> adding pad 'sink_0'
0:00:00.151818220  3573      0x54ad400 INFO                GST_PADS gstutils.c:1587:prepare_link_maybe_ghosting: capsfilter0 and tiovxisp0 in same bin, no need for ghost pads
0:00:00.151855715  3573      0x54ad400 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link capsfilter0:src and tiovxisp0:sink_0
0:00:00.151936055  3573      0x54ad400 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked capsfilter0:src and tiovxisp0:sink_0, successful
0:00:00.151954355  3573      0x54ad400 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:00.151973445  3573      0x54ad400 INFO               GST_EVENT gstpad.c:5812:gst_pad_send_event_unchecked:<capsfilter0:src> Received event on flushing pad. Discarding
APP: Init ... !!!
MEM: Init ... !!!
MEM: Initialized DMA HEAP (fd=5) !!!
MEM: Init ... Done !!!
IPC: Init ... !!!
IPC: Init ... Done !!!
REMOTE_SERVICE: Init ... !!!
REMOTE_SERVICE: Init ... Done !!!
  2327.550272 s: GTC Frequency = 200 MHz
APP: Init ... Done !!!
  2327.550348 s:  VX_ZONE_INIT:Enabled
  2327.550353 s:  VX_ZONE_ERROR:Enabled
  2327.550357 s:  VX_ZONE_WARNING:Enabled
  2327.551013 s:  VX_ZONE_INIT:[tivxInitLocal:130] Initialization Done !!!
  2327.551132 s:  VX_ZONE_INIT:[tivxHostInitLocal:93] Initialization Done for HOST !!!
Setting pipeline to PAUSED ...
0:00:00.152160895  3573      0x54ad400 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<tiovxisp0> current NULL pending VOID_PENDING, desired next READY
0:00:00.152187035  3573      0x54ad400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<tiovxisp0> completed state change to READY
0:00:00.152211165  3573      0x54ad400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<tiovxisp0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.152278860  3573      0x54ad400 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'tiovxisp0' changed state to 2(READY) successfully
0:00:00.152311100  3573      0x54ad400 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<capsfilter0> current NULL pending VOID_PENDING, desired next READY
0:00:00.152334030  3573      0x54ad400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<capsfilter0> completed state change to READY
0:00:00.152356445  3573      0x54ad400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<capsfilter0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.152388105  3573      0x54ad400 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'capsfilter0' changed state to 2(READY) successfully
0:00:00.152415000  3573      0x54ad400 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<v4l2src0> current NULL pending VOID_PENDING, desired next READY
0:00:00.152603030  3573      0x54ad400 INFO                    v4l2 v4l2_calls.c:587:gst_v4l2_open:<v4l2src0:src> Opened device 'j721e-csi2rx' (/dev/video2) successfully
0:00:00.152643575  3573      0x54ad400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<v4l2src0> completed state change to READY
0:00:00.152668375  3573      0x54ad400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<v4l2src0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.152701790  3573      0x54ad400 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'v4l2src0' changed state to 2(READY) successfully
0:00:00.152740090  3573      0x54ad400 INFO              GST_STATES gstelement.c:2660:gst_element_continue_state:<pipeline0> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:00.152763860  3573      0x54ad400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<pipeline0> notifying about state-changed NULL to READY (PAUSED pending)
0:00:00.152871210  3573      0x54ad400 INFO              GST_STATES gstelement.c:2668:gst_element_continue_state:<pipeline0> continue state change READY to PAUSED, final PAUSED
0:00:00.152920835  3573      0x54ad400 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<tiovxisp0> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.155000155  3573      0x54ad400 INFO              aggregator gstaggregator.c:2139:gst_aggregator_src_pad_activate_mode_func:<tiovxisp0:src> Activating pad!
0:00:00.155053395  3573      0x54ad400 INFO              aggregator gstaggregator.c:1263:gst_aggregator_start_srcpad_task:<tiovxisp0> Starting srcpad task
0:00:00.155154755  3573      0x54ad400 INFO                    task gsttask.c:460:gst_task_set_lock: setting stream lock 0x56f3bc0 on task 0x5708050
0:00:00.155178320  3573      0x54ad400 INFO                GST_PADS gstpad.c:6159:gst_pad_start_task:<tiovxisp0:src> created task 0x5708050
0:00:00.155475475  3573      0x54ad400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<tiovxisp0> completed state change to PAUSED
0:00:00.155512095  3573      0x54ad400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<tiovxisp0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.155557845  3573      0x54ad400 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'tiovxisp0' changed state to 3(PAUSED) successfully
0:00:00.155600480  3573      0x54ad400 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<capsfilter0> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.155642755  3573      0x54ad400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<capsfilter0> completed state change to PAUSED
0:00:00.155666965  3573      0x54ad400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<capsfilter0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.155680105  3573      0x56f0e80 WARN                 v4l2src gstv4l2src.c:695:gst_v4l2src_query:<v4l2src0> Can't give latency since framerate isn't fixated !
0:00:00.155701310  3573      0x54ad400 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'capsfilter0' changed state to 3(PAUSED) successfully
0:00:00.155717240  3573      0x56f0e80 WARN              aggregator gstaggregator.c:1757:gst_aggregator_query_latency_unlocked:<tiovxisp0> Latency query failed
0:00:00.155728930  3573      0x54ad400 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<v4l2src0> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.155798535  3573      0x54ad400 INFO                 basesrc gstbasesrc.c:1373:gst_base_src_do_seek:<v4l2src0> seeking: time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=99:99:99.999999999, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.000000000, duration 99:99:99.999999999
0:00:00.155847870  3573      0x54ad400 INFO                    task gsttask.c:460:gst_task_set_lock: setting stream lock 0x56ce110 on task 0x5708170
0:00:00.155869390  3573      0x54ad400 INFO                GST_PADS gstpad.c:6159:gst_pad_start_task:<v4l2src0:src> created task 0x5708170
0:00:00.156054420  3573      0x54ad400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<v4l2src0> completed state change to PAUSED
0:00:00.156086105  3573      0x54ad400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<v4l2src0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.156128360  3573      0x54ad400 INFO              GST_STATES gstbin.c:2995:gst_bin_change_state_func:<pipeline0> child 'v4l2src0' changed state to 3(PAUSED) successfully without preroll
0:00:00.156164345  3573      0x54ad400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<pipeline0> completed state change to PAUSED
0:00:00.156201435  3573      0x54ad400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<pipeline0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.156218880  3573      0x56f0ed0 INFO        GST_ELEMENT_PADS gstelement.c:925:gst_element_get_static_pad: no such pad 'sink' in element "v4l2src0"
Pipeline is live and does not need PREROLL ...
0:00:00.156419960  3573      0x56f0e80 WARN                 v4l2src gstv4l2src.c:695:gst_v4l2src_query:<v4l2src0> Can't give latency since framerate isn't fixated !
0:00:00.156449195  3573      0x56f0e80 WARN              aggregator gstaggregator.c:1757:gst_aggregator_query_latency_unlocked:<tiovxisp0> Latency query failed
0:00:00.156523310  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:1245:gst_v4l2_object_fill_format_list:<v4l2src0:src> got 24 format(s):
0:00:00.156557295  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   YUYV
0:00:00.156580585  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   UYVY
0:00:00.156614895  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   IGB0
0:00:00.156637810  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   GIB0
0:00:00.156660925  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   IGR0
0:00:00.156688335  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   GIR0
0:00:00.156711390  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   GBI0
0:00:00.156733840  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   BGI0
0:00:00.156755225  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   GRI0
0:00:00.156776260  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   RGI0
0:00:00.156796880  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   RG12
0:00:00.156817910  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   BA12
0:00:00.156838365  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   GB12
0:00:00.156859475  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   BG12
0:00:00.156883925  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   RG10
0:00:00.156905560  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   BA10
0:00:00.156926335  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   GB10
0:00:00.156947030  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   BG10
0:00:00.156967530  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   RGGB
0:00:00.156988125  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   GRBG
0:00:00.157008770  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   GBRG
0:00:00.157029490  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   BA81
0:00:00.157049930  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   VYUY
0:00:00.157070625  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   YVYU
Setting pipeline to PLAYING ...
0:00:00.157613825  3573      0x54ad400 INFO               GST_EVENT gstevent.c:1449:gst_event_new_latency: creating latency event 0:00:00.000000000
0:00:00.157671130  3573      0x54ad400 INFO                     bin gstbin.c:2783:gst_bin_do_latency_func:<pipeline0> configured latency of 0:00:00.000000000
0:00:00.157727055  3573      0x54ad400 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<tiovxisp0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.157755285  3573      0x54ad400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<tiovxisp0> completed state change to PLAYING
0:00:00.157779550  3573      0x54ad400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<tiovxisp0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.157816760  3573      0x54ad400 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'tiovxisp0' changed state to 4(PLAYING) successfully
0:00:00.157846825  3573      0x54ad400 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<capsfilter0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.157869730  3573      0x54ad400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<capsfilter0> completed state change to PLAYING
0:00:00.157867670  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:4402:gst_v4l2_object_probe_caps:<v4l2src0:src> probed caps: video/x-raw, format=(string)YUY2, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-raw, format=(string)UYVY, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)iggb10, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)gibg10, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)iggr10, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)girg10, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)gbig10, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)bggi10, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)grig10, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)rggi10, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)rggb12, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)grbg12, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)gbrg12, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)bggr12, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)rggb10, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)grbg10, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)gbrg10, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)bggr10, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)rggb, width=(int)[ 16, 16777216, 16 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)grbg, width=(int)[ 16, 16777216, 16 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)gbrg, width=(int)[ 16, 16777216, 16 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)bggr, width=(int)[ 16, 16777216, 16 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-raw, format=(string)YVYU, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]
0:00:00.157914235  3573      0x54ad400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<capsfilter0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.157948505  3573      0x54ad400 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'capsfilter0' changed state to 4(PLAYING) successfully
0:00:00.157981175  3573      0x54ad400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<v4l2src0> completed state change to PLAYING
0:00:00.158004760  3573      0x54ad400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<v4l2src0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.158040180  3573      0x54ad400 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'v4l2src0' changed state to 4(PLAYING) successfully
0:00:00.158066215  3573      0x54ad400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<pipeline0> completed state change to PLAYING
0:00:00.158089160  3573      0x54ad400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<pipeline0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.158206615  3573      0x56f0ed0 INFO               GST_EVENT gstevent.c:820:gst_event_new_caps: creating caps event video/x-bayer, width=(int)640, height=(int)480, format=(string)bggr, framerate=(fraction)60/1, colorimetry=(string)sRGB, interlace-mode=(string)progressive
New clock: GstSystemClock
0:00:00.158353245  3573      0x56f0ed0 INFO           basetransform gstbasetransform.c:1317:gst_base_transform_setcaps:<capsfilter0> reuse caps
0:00:00.158398770  3573      0x56f0ed0 INFO               GST_EVENT gstevent.c:820:gst_event_new_caps: creating caps event video/x-bayer, width=(int)640, height=(int)480, format=(string)bggr, framerate=(fraction)60/1, colorimetry=(string)sRGB, interlace-mode=(string)progressive
0:00:00.158563805  3573      0x56f0e80 WARN                 v4l2src gstv4l2src.c:695:gst_v4l2src_query:<v4l2src0> Can't give latency since framerate isn't fixated !
0:00:00.158589930  3573      0x56f0e80 WARN              aggregator gstaggregator.c:1757:gst_aggregator_query_latency_unlocked:<tiovxisp0> Latency query failed
0:00:00.158685085  3573      0x56f0e80 INFO               tiovxmiso gsttiovxmiso.c:859:gst_tiovx_miso_propose_allocation:<tiovxisp0> Using temporary reference for configuration
0:00:00.158781455  3573      0x56f0e80 WARN              video-info video-info.c:189:validate_colorimetry: color matrix RGB is only supported with RGB format, ENCODED is not
0:00:00.158797295  3573      0x56f0e80 WARN              video-info video-info.c:513:gst_video_info_from_caps: invalid colorimetry, using default
0:00:00.158845300  3573      0x56f0e80 INFO               tiovxmiso gsttiovxutils.c:762:gst_tiovx_get_exemplar_from_caps:<tiovxisp0> creating raw image with width: 640	 height: 480	 format: 0x101001
0:00:00.158947995  3573      0x56f0e80 INFO               tiovxmiso gsttiovxbufferpoolutils.c:85:gst_tiovx_create_new_pool: Creating new pool
0:00:00.158963505  3573      0x56f0e80 INFO               tiovxmiso gsttiovxbufferpoolutils.c:97:gst_tiovx_create_new_pool: Creating Raw Image buffer pool
0:00:00.159188310  3573      0x56f0e80 INFO         tiovxbufferpool gsttiovxbufferpool.c:127:gst_tiovx_buffer_pool_init:<GstTIOVXBufferPool@0xffff9c007130> New TIOVX buffer pool
0:00:00.159292615  3573      0x56f0e80 INFO          tiovxallocator gsttiovxallocator.c:128:gst_tiovx_allocator_init:<GstTIOVXAllocator@0x56fa610> New TIOVX allocator
0:00:00.159336455  3573      0x56f0e80 INFO               tiovxmiso gsttiovxbufferpoolutils.c:144:gst_tiovx_add_new_pool: Adding new TIOVX pool with 2 buffers of 307200 size
0:00:00.159433920  3573      0x56f0e80 WARN                 v4l2src gstv4l2src.c:695:gst_v4l2src_query:<v4l2src0> Can't give latency since framerate isn't fixated !
0:00:00.159459210  3573      0x56f0ed0 WARN              video-info video-info.c:189:validate_colorimetry: color matrix RGB is only supported with RGB format, ENCODED is not
0:00:00.159464880  3573      0x56f0e80 WARN              aggregator gstaggregator.c:1757:gst_aggregator_query_latency_unlocked:<tiovxisp0> Latency query failed
0:00:00.159478005  3573      0x56f0ed0 WARN              video-info video-info.c:513:gst_video_info_from_caps: invalid colorimetry, using default
0:00:00.159541805  3573      0x56f0ed0 INFO                    v4l2 gstv4l2object.c:3077:gst_v4l2_object_setup_pool:<v4l2src0:src> accessing buffers via mode 5
0:00:00.159860820  3573      0x56f0ed0 INFO          v4l2bufferpool gstv4l2bufferpool.c:568:gst_v4l2_buffer_pool_set_config:<v4l2src0:pool:src> increasing minimum buffers to 2
0:00:00.159885320  3573      0x56f0ed0 INFO          v4l2bufferpool gstv4l2bufferpool.c:581:gst_v4l2_buffer_pool_set_config:<v4l2src0:pool:src> reducing maximum buffers to 32
0:00:00.159919190  3573      0x56f0ed0 WARN              video-info video-info.c:189:validate_colorimetry: color matrix RGB is only supported with RGB format, ENCODED is not
0:00:00.159940475  3573      0x56f0ed0 WARN              video-info video-info.c:513:gst_video_info_from_caps: invalid colorimetry, using default
0:00:00.160032370  3573      0x56f0ed0 INFO          v4l2bufferpool gstv4l2bufferpool.c:581:gst_v4l2_buffer_pool_set_config:<v4l2src0:pool:src> reducing maximum buffers to 32
0:00:00.160061105  3573      0x56f0ed0 WARN              video-info video-info.c:189:validate_colorimetry: color matrix RGB is only supported with RGB format, ENCODED is not
0:00:00.160075955  3573      0x56f0ed0 WARN              video-info video-info.c:513:gst_video_info_from_caps: invalid colorimetry, using default
0:00:00.160115510  3573      0x56f0ed0 WARN              video-info video-info.c:189:validate_colorimetry: color matrix RGB is only supported with RGB format, ENCODED is not
0:00:00.160130425  3573      0x56f0ed0 WARN              video-info video-info.c:513:gst_video_info_from_caps: invalid colorimetry, using default
0:00:00.160210315  3573      0x56f0ed0 INFO          tiovxallocator gsttiovxallocator.c:128:gst_tiovx_allocator_init:<GstTIOVXAllocator@0xffff9c008cb0> New TIOVX allocator

U-Boot SPL 2021.01-g2ee8efd654 (Feb 27 2023 - 11:47:43 +0000)
SYSFW ABI: 3.1 (firmware rev 0x0008 '8.6.4--v08.06.04 (Chill Capybar')
am62a_init: board_init_f done
am62a_init: spl_boot_device: devstat = 0x243 bootmedia = 0x8 bootindex = 0
Trying to boot from MMC2
am62a_init: spl_boot_device: devstat = 0x243 bootmedia = 0x8 bootindex = 0
Authentication passed
am62a_init: spl_boot_device: devstat = 0x243 bootmedia = 0x8 bootindex = 0
Authentication passed
am62a_init: spl_boot_device: devstat = 0x243 bootmedia = 0x8 bootindex = 0
Authentication passed
am62a_init: spl_boot_device: devstat = 0x243 bootmedia = 0x8 bootindex = 0
Authentication passed
am62a_init: spl_boot_device: devstat = 0x243 bootmedia = 0x8 bootindex = 0
Authentication passed
am62a_init: spl_boot_device: devstat = 0x243 bootmedia = 0x8 bootindex = 0
Starting ATF on ARM64 core...

NOTICE:  BL31: v2.8(release):v2.8-226-g2fcd408bb3-dirty
NOTICE:  BL31: Built : 11:09:43, Feb 27 2023

U-Boot SPL 2021.01-g2ee8efd654 (Feb 27 2023 - 11:13:07 +0000)
SYSFW ABI: 3.1 (firmware rev 0x0008 '8.6.4--v08.06.04 (Chill Capybar')
am62a_init: board_init_f done
am62a_init: spl_boot_device: devstat = 0x243 bootmedia = 0x8 bootindex = 0
Trying to boot from MMC2
am62a_init: spl_boot_device: devstat = 0x243 bootmedia = 0x8 bootindex = 0
Authentication passed
am62a_init: spl_boot_device: devstat = 0x243 bootmedia = 0x8 bootindex = 0
Authentication passed


U-Boot 2021.01-g2ee8efd654 (Feb 27 2023 - 11:13:07 +0000)

SoC:   AM62AX SR1.0 HS-FS
Model: Texas Instruments AM62A7 SK
EEPROM not available at 0x50, trying to read at 0x51
Board: AM62A-SKEVM rev E2
DRAM:  4 GiB
MMC:   mmc@fa10000: 0, mmc@fa00000: 1
Loading Environment from MMC... OK
In:    serial@2800000
Out:   serial@2800000
Err:   serial@2800000
Net:   eth0: ethernet@8000000port@1
Hit any key to stop autoboot:  2  1  0 
switch to partitions #0, OK
mmc1 is current device
SD/MMC found on device 1
Failed to load 'boot.scr'
486 bytes read in 4 ms (118.2 KiB/s)
Loaded env from uEnv.txt
Importing environment from mmc1 ...
Running uenvcmd ...
1 bytes read in 4 ms (0 Bytes/s)
Already setup.
19147264 bytes read in 762 ms (24 MiB/s)
47332 bytes read in 8 ms (5.6 MiB/s)
1923 bytes read in 5 ms (375 KiB/s)
## Flattened Device Tree blob at 88000000
   Booting using the fdt blob at 0x88000000
   Loading Device Tree to 000000008fef1000, end 000000008fffffff ... OK

Starting kernel ...

[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
[    0.000000] Linux version 5.10.168-g2c23e6c538 (oe-user@oe-host) (aarch64-none-linux-gnu-gcc (GNU Toolchain for the A-profile Architecture 9.2-2019.12 (arm-9.10)) 9.2.1 20191025, GNU ld (GNU Toolchain for the A-profile Architecture 9.2-2019.12 (arm-9.10)) 2.33.1.20191209) #1 SMP PREEMPT Mon Feb 27 11:35:01 UTC 2023
[    0.000000] Machine model: Texas Instruments AM62A7 SK
[    0.000000] earlycon: ns16550a0 at MMIO32 0x0000000002800000 (options '')
[    0.000000] printk: bootconsole [ns16550a0] enabled
[    0.000000] efi: UEFI not found.
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000099800000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node c7x-dma-memory@99800000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000099900000, size 30 MiB
[    0.000000] OF: reserved mem: initialized node c7x-memory@99900000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x000000009b800000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@9b800000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x000000009b900000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@9b900000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x000000009c800000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@9c800000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x000000009c900000, size 30 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@9c900000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a1000000, size 32 MiB
[    0.000000] OF: reserved mem: initialized node edgeai-dma-memory@a1000000, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: initialized node edgeai_shared-memories, compatible id dma-heap-carveout
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000ae000000, size 288 MiB
[    0.000000] OF: reserved mem: initialized node edgeai-core-heap-memory@ae000000, compatible id shared-dma-pool
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000080000000-0x00000000ffffffff]
[    0.000000]   DMA32    empty
[    0.000000]   Normal   [mem 0x0000000100000000-0x00000008ffffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000080000000-0x00000000997fffff]
[    0.000000]   node   0: [mem 0x0000000099800000-0x000000009b7fefff]
[    0.000000]   node   0: [mem 0x000000009b800000-0x000000009e6fffff]
[    0.000000]   node   0: [mem 0x000000009e700000-0x000000009e77ffff]
[    0.000000]   node   0: [mem 0x000000009e780000-0x00000000a2ffffff]
[    0.000000]   node   0: [mem 0x00000000a3000000-0x00000000adffffff]
[    0.000000]   node   0: [mem 0x00000000ae000000-0x00000000bfffffff]
[    0.000000]   node   0: [mem 0x00000000c0000000-0x00000000ffffffff]
[    0.000000]   node   0: [mem 0x0000000880000000-0x00000008ffffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000080000000-0x00000008ffffffff]
[    0.000000] cma: Reserved 512 MiB at 0x00000000e0000000
[    0.000000] psci: probing for conduit method from DT.
[    0.000000] psci: PSCIv1.1 detected in firmware.
[    0.000000] psci: Using standard PSCI v0.2 function IDs
[    0.000000] psci: Trusted OS migration not required
[    0.000000] psci: SMC Calling Convention v1.2
[    0.000000] percpu: Embedded 22 pages/cpu s51288 r8192 d30632 u90112
[    0.000000] Detected VIPT I-cache on CPU0
[    0.000000] CPU features: detected: ARM erratum 845719
[    0.000000] CPU features: detected: GIC system register CPU interface
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 1032191
[    0.000000] Kernel command line: console=ttyS2,115200n8 earlycon=ns16550a,mmio32,0x02800000 root=PARTUUID=0d52f5b8-02 rw rootfstype=ext4 rootwait
[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] software IO TLB: mapped [mem 0x00000000dc000000-0x00000000e0000000] (64MB)
[    0.000000] Memory: 2875000K/4194300K available (11200K kernel code, 1162K rwdata, 4308K rodata, 1920K init, 431K bss, 795012K reserved, 524288K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000] rcu: 	RCU event tracing is enabled.
[    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4.
[    0.000000] 	Trampoline variant of Tasks RCU enabled.
[    0.000000] 	Tracing variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] GICv3: GIC: Using split EOI/Deactivate mode
[    0.000000] GICv3: 256 SPIs implemented
[    0.000000] GICv3: 0 Extended SPIs implemented
[    0.000000] GICv3: Distributor has no Range Selector support
[    0.000000] GICv3: 16 PPIs implemented
[    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x0000000001880000
[    0.000000] ITS [mem 0x01820000-0x0182ffff]
[    0.000000] GIC: enabling workaround for ITS: Socionext Synquacer pre-ITS
[    0.000000] ITS@0x0000000001820000: Devices Table too large, reduce ids 20->19
[    0.000000] ITS@0x0000000001820000: allocated 524288 Devices @880800000 (flat, esz 8, psz 64K, shr 0)
[    0.000000] ITS: using cache flushing for cmd queue
[    0.000000] GICv3: using LPI property table @0x0000000880040000
[    0.000000] GIC: using cache flushing for LPI property table
[    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000880050000
[    0.000000] arch_timer: cp15 timer(s) running at 200.00MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x2e2049d3e8, max_idle_ns: 440795210634 ns
[    0.000003] sched_clock: 56 bits at 200MHz, resolution 5ns, wraps every 4398046511102ns
[    0.008461] Console: colour dummy device 80x25
[    0.013040] Calibrating delay loop (skipped), value calculated using timer frequency.. 400.00 BogoMIPS (lpj=800000)
[    0.023718] pid_max: default: 32768 minimum: 301
[    0.028515] LSM: Security Framework initializing
[    0.033285] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.040860] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.050268] rcu: Hierarchical SRCU implementation.
[    0.055389] Platform MSI: msi-controller@1820000 domain created
[    0.061622] PCI/MSI: /bus@f0000/interrupt-controller@1800000/msi-controller@1820000 domain created
[    0.070857] EFI services will not be available.
[    0.075708] smp: Bringing up secondary CPUs ...
[    0.080860] Detected VIPT I-cache on CPU1
[    0.080890] GICv3: CPU1: found redistributor 1 region 0:0x00000000018a0000
[    0.080902] GICv3: CPU1: using allocated LPI pending table @0x0000000880060000
[    0.080951] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
[    0.081505] Detected VIPT I-cache on CPU2
[    0.081527] GICv3: CPU2: found redistributor 2 region 0:0x00000000018c0000
[    0.081537] GICv3: CPU2: using allocated LPI pending table @0x0000000880070000
[    0.081569] CPU2: Booted secondary processor 0x0000000002 [0x410fd034]
[    0.082078] Detected VIPT I-cache on CPU3
[    0.082098] GICv3: CPU3: found redistributor 3 region 0:0x00000000018e0000
[    0.082108] GICv3: CPU3: using allocated LPI pending table @0x0000000880080000
[    0.082139] CPU3: Booted secondary processor 0x0000000003 [0x410fd034]
[    0.082199] smp: Brought up 1 node, 4 CPUs
[    0.161917] SMP: Total of 4 processors activated.
[    0.166728] CPU features: detected: 32-bit EL0 Support
[    0.171997] CPU features: detected: CRC32 instructions
[    0.183843] CPU: All CPU(s) started at EL2
[    0.188047] alternatives: patching kernel code
[    0.193532] devtmpfs: initialized
[    0.202540] KASLR disabled due to lack of seed
[    0.207250] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.217223] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.237278] pinctrl core: initialized pinctrl subsystem
[    0.243134] DMI not present or invalid.
[    0.247633] NET: Registered protocol family 16
[    0.253296] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations
[    0.260613] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.268713] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.277250] thermal_sys: Registered thermal governor 'step_wise'
[    0.277254] thermal_sys: Registered thermal governor 'power_allocator'
[    0.283915] cpuidle: using governor menu
[    0.294800] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.301810] ASID allocator initialised with 65536 entries
[    0.326274] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
[    0.333146] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages
[    0.339999] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.346850] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages
[    0.354589] cryptd: max_cpu_qlen set to 1000
[    0.361275] k3-chipinfo 43000014.chipid: Family:AM62AX rev:SR1.0 JTAGID[0x0bb8d02f] Detected
[    0.370352] vcc_5v0: supplied by vmain_pd
[    0.374794] vcc_3v3_main: supplied by vmain_pd
[    0.379695] vcc_3v3_sys: supplied by vcc_3v3_main
[    0.385169] iommu: Default domain type: Translated 
[    0.390434] SCSI subsystem initialized
[    0.394600] mc: Linux media interface: v0.10
[    0.398987] videodev: Linux video capture interface: v2.00
[    0.404655] pps_core: LinuxPPS API ver. 1 registered
[    0.409730] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.419077] PTP clock support registered
[    0.423109] EDAC MC: Ver: 3.0.0
[    0.426797] omap-mailbox 29000000.mailbox: omap mailbox rev 0x66fca100
[    0.433590] omap-mailbox 29010000.mailbox: omap mailbox rev 0x66fca100
[    0.440367] omap-mailbox 29020000.mailbox: omap mailbox rev 0x66fca100
[    0.447088] omap-mailbox 29030000.mailbox: no available mbox devices found
[    0.454436] FPGA manager framework
[    0.457982] Advanced Linux Sound Architecture Driver Initialized.
[    0.464957] clocksource: Switched to clocksource arch_sys_counter
[    0.471379] VFS: Disk quotas dquot_6.6.0
[    0.475434] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.487417] Carveout Heap: Exported 176 MiB at 0x00000000a3000000
[    0.493753] NET: Registered protocol family 2
[    0.498385] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)
[    0.507572] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)
[    0.516361] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    0.524658] TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear)
[    0.532531] TCP: Hash tables configured (established 32768 bind 32768)
[    0.539378] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)
[    0.546306] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)
[    0.553788] NET: Registered protocol family 1
[    0.558654] RPC: Registered named UNIX socket transport module.
[    0.564727] RPC: Registered udp transport module.
[    0.569556] RPC: Registered tcp transport module.
[    0.574369] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.580958] NET: Registered protocol family 44
[    0.585510] PCI: CLS 0 bytes, default 64
[    0.590196] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available
[    0.601633] Initialise system trusted keyrings
[    0.606350] workingset: timestamp_bits=46 max_order=20 bucket_order=0
[    0.616291] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.622726] NFS: Registering the id_resolver key type
[    0.627942] Key type id_resolver registered
[    0.632218] Key type id_legacy registered
[    0.636367] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.643221] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    0.650934] 9p: Installing v9fs 9p2000 file system support
[    0.691360] Key type asymmetric registered
[    0.695556] Asymmetric key parser 'x509' registered
[    0.700570] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243)
[    0.708135] io scheduler mq-deadline registered
[    0.712765] io scheduler kyber registered
[    0.718686] pinctrl-single f4000.pinctrl: 171 pins, size 684
[    0.730522] Serial: 8250/16550 driver, 10 ports, IRQ sharing enabled
[    0.747671] brd: module loaded
[    0.756231] loop: module loaded
[    0.760220] megasas: 07.714.04.00-rc1
[    0.766785] tun: Universal TUN/TAP device driver, 1.6
[    0.772386] igbvf: Intel(R) Gigabit Virtual Function Network Driver
[    0.778804] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
[    0.784896] sky2: driver version 1.30
[    0.789342] VFIO - User Level meta-driver version: 0.3
[    0.795302] i2c /dev entries driver
[    0.799855] sdhci: Secure Digital Host Controller Interface driver
[    0.806178] sdhci: Copyright(c) Pierre Ossman
[    0.810862] sdhci-pltfm: SDHCI platform and OF driver helper
[    0.817507] ledtrig-cpu: registered to indicate activity on CPUs
[    0.823907] SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping ....
[    0.831541] optee: probing for conduit method.
[    0.836116] optee: revision 3.20 (8e74d476)
[    0.836331] optee: dynamic shared memory is enabled
[    0.845927] optee: initialized driver
[    0.851185] NET: Registered protocol family 17
[    0.855868] 9pnet: Installing 9P2000 support
[    0.860302] Key type dns_resolver registered
[    0.865002] Loading compiled-in X.509 certificates
[    0.876600] ti-sci 44043000.system-controller: ABI: 3.1 (firmware rev 0x0008 '8.6.4--v08.06.04 (Chill Capybar')
[    0.901205] davinci-mcasp 2b10000.mcasp: IRQ common not found
[    0.909687] omap_i2c 20000000.i2c: bus 0 rev0.12 at 400 kHz
[    0.917267] pca953x 1-0023: supply vcc not found, using dummy regulator
[    0.924147] pca953x 1-0023: using AI
[    0.951027] omap_i2c 20010000.i2c: bus 1 rev0.12 at 100 kHz
[    0.981200] i2c i2c-2: Added multiplexed i2c bus 3
[    0.986485] i2c i2c-2: Added multiplexed i2c bus 4
[    0.991397] pca954x 2-0071: registered 2 multiplexed busses for I2C switch pca9543
[    0.999218] omap_i2c 20020000.i2c: bus 2 rev0.12 at 400 kHz
[    1.005305] ti-sci-intr 4210000.interrupt-controller: Interrupt Router 5 domain created
[    1.013623] ti-sci-intr bus@f0000:interrupt-controller@a00000: Interrupt Router 3 domain created
[    1.022831] ti-sci-inta 48000000.interrupt-controller: Interrupt Aggregator domain 28 created
[    1.031819] ti-sci-inta 4e0a0000.interrupt-controller: Interrupt Aggregator domain 200 created
[    1.040916] ti-udma 485c0100.dma-controller: Number of rings: 82
[    1.048723] ti-udma 485c0100.dma-controller: Channels: 48 (bchan: 18, tchan: 12, rchan: 18)
[    1.059443] ti-udma 485c0000.dma-controller: Number of rings: 150
[    1.068878] ti-udma 485c0000.dma-controller: Channels: 35 (tchan: 20, rchan: 15)
[    1.078141] ti-udma 4e230000.dma-controller: Number of rings: 6
[    1.084538] ti-udma 4e230000.dma-controller: Channels: 6 (bchan: 0, tchan: 0, rchan: 6)
[    1.093771] printk: console [ttyS2] disabled
[    1.098200] 2800000.serial: ttyS2 at MMIO 0x2800000 (irq = 16, base_baud = 3000000) is a 8250
[    1.106965] printk: console [ttyS2] enabled
[    1.106965] printk: console [ttyS2] enabled
[    1.115408] printk: bootconsole [ns16550a0] disabled
[    1.115408] printk: bootconsole [ns16550a0] disabled
[    1.128486] spi-nand spi0.0: Winbond SPI NAND was found.
[    1.133823] spi-nand spi0.0: 128 MiB, block size: 256 KiB, page size: 4096, OOB size: 128
[    1.142136] 7 fixed-partitions partitions found on MTD device spi0.0
[    1.148489] Creating 7 MTD partitions on "spi0.0":
[    1.153293] 0x000000000000-0x000000080000 : "ospi.tiboot3"
[    1.159984] 0x000000080000-0x000000280000 : "ospi.tispl"
[    1.166962] 0x000000280000-0x000000680000 : "ospi.u-boot"
[    1.174731] 0x000000680000-0x0000006c0000 : "ospi.env"
[    1.180836] 0x0000006c0000-0x000000700000 : "ospi.env.backup"
[    1.187577] 0x000002000000-0x000007fc0000 : "ospi.rootfs"
[    1.228702] 0x000007fc0000-0x000008000000 : "ospi.phypattern"
[    1.276983] davinci_mdio 8000f00.mdio: davinci mdio revision 9.7, bus freq 1000000
[    1.286014] davinci_mdio 8000f00.mdio: phy[0]: device 8000f00.mdio:00, driver TI DP83867
[    1.294216] am65-cpsw-nuss 8000000.ethernet: initializing am65 cpsw nuss version 0x6BA01103, cpsw version 0x6BA81103 Ports: 3 quirks:00000006
[    1.306984] am65-cpsw-nuss 8000000.ethernet: initialized cpsw ale version 1.5
[    1.314108] am65-cpsw-nuss 8000000.ethernet: ALE Table size 512
[    1.320645] am65-cpsw-nuss 8000000.ethernet: CPTS ver 0x4e8a010c, freq:500000000, add_val:1 pps:0
[    1.330693] am65-cpsw-nuss 8000000.ethernet: set new flow-id-base 19
[    1.440610] mmc0: CQHCI version 5.10
[    1.440753] davinci-mcasp 2b10000.mcasp: IRQ common not found
[    1.458921] pca953x 1-0022: supply vcc not found, using dummy regulator
[    1.465659] pca953x 1-0022: using AI
[    1.472496] sii902x 1-003b: supply iovcc not found, using dummy regulator
[    1.479394] sii902x 1-003b: supply cvcc12 not found, using dummy regulator
[    1.489013] i2c i2c-1: Added multiplexed i2c bus 5
[    1.493624] mmc0: SDHCI controller on fa10000.mmc [fa10000.mmc] using ADMA 64-bit
[    1.502529] [drm] Initialized tidss 1.0.0 20180215 for 30200000.dss on minor 0
[    1.581713] mmc0: Command Queue Engine enabled
[    1.581727] mmc0: new HS200 MMC card at address 0001
[    1.582276] mmcblk0: mmc0:0001 S0J56X 14.8 GiB 
[    1.582405] mmcblk0boot0: mmc0:0001 S0J56X partition 1 31.5 MiB
[    1.582538] mmcblk0boot1: mmc0:0001 S0J56X partition 2 31.5 MiB
[    1.582680] mmcblk0rpmb: mmc0:0001 S0J56X partition 3 4.00 MiB, chardev (237:0)
[    1.583629]  mmcblk0: p1 p2
[    1.595949] Console: switching to colour frame buffer device 240x67
[    1.669959] tidss 30200000.dss: [drm] fb0: tidssdrmfb frame buffer device
[    1.680496] debugfs: Directory 'pd:182' with parent 'pm_genpd' already present!
[    1.680900] mmc1: CQHCI version 5.10
[    1.688492] debugfs: Directory 'pd:182' with parent 'pm_genpd' already present!
[    1.698754] debugfs: Directory 'pd:182' with parent 'pm_genpd' already present!
[    1.708140] ALSA device list:
[    1.711117]   No soundcards found.
[    1.731544] mmc1: SDHCI controller on fa00000.mmc [fa00000.mmc] using ADMA 64-bit
[    1.739421] Waiting for root device PARTUUID=0d52f5b8-02...
[    1.772297] mmc1: new high speed SDXC card at address 59b4
[    1.778310] mmcblk1: mmc1:59b4 ED2S5 119 GiB 
[    1.784321]  mmcblk1: p1 p2
[    2.569267] EXT4-fs (mmcblk1p2): recovery complete
[    2.577786] EXT4-fs (mmcblk1p2): mounted filesystem with ordered data mode. Opts: (null)
[    2.585947] VFS: Mounted root (ext4 filesystem) on device 179:98.
[    2.593291] devtmpfs: mounted
[    2.596827] Freeing unused kernel memory: 1920K
[    2.601452] Run /sbin/init as init process
[    2.902079] systemd[1]: System time before build time, advancing clock.
[    2.990595] NET: Registered protocol family 10
[    2.995965] Segment Routing with IPv6
[    3.043967] systemd[1]: systemd 244.5+ running in system mode. (+PAM -AUDIT -SELINUX +IMA -APPARMOR -SMACK +SYSVINIT +UTMP -LIBCRYPTSETUP -GCRYPT -GNUTLS +ACL +XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -IDN2 -IDN -PCRE2 default-hierarchy=hybrid)
[    3.065943] systemd[1]: Detected architecture arm64.

Welcome to Arago 2021.09!

[    3.113819] systemd[1]: Set hostname to <am62axx-evm>.
[    3.403787] systemd[1]: /lib/systemd/system/irqbalanced.service:6: Unknown key name 'ConditionCPUs' in section 'Unit', ignoring.
[    3.439273] systemd[1]: /lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock ? /run/docker.sock; please update the unit file accordingly.
[    3.567927] random: systemd: uninitialized urandom read (16 bytes read)
[    3.577947] systemd[1]: Created slice system-getty.slice.
[  OK  ] Created slice system-getty.slice.
[    3.601128] random: systemd: uninitialized urandom read (16 bytes read)
[    3.609379] systemd[1]: Created slice system-serial\x2dgetty.slice.
[  OK  ] Created slice system-serial\x2dgetty.slice.
[    3.633094] random: systemd: uninitialized urandom read (16 bytes read)
[    3.641242] systemd[1]: Created slice system-syslog\x2dng.slice.
[  OK  ] Created slice system-syslog\x2dng.slice.
[    3.666606] systemd[1]: Created slice User and Session Slice.
[  OK  ] Created slice User and Session Slice.
[    3.689389] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[  OK  ] Started Dispatch Password �ts to Console Directory Watch.
[    3.713252] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[  OK  ] Started Forward Password R�uests to Wall Directory Watch.
[    3.737256] systemd[1]: Reached target Paths.
[  OK  ] Reached target Paths.
[    3.753085] systemd[1]: Reached target Remote File Systems.
[  OK  ] Reached target Remote File Systems.
[    3.773083] systemd[1]: Reached target Slices.
[  OK  ] Reached target Slices.
[    3.789100] systemd[1]: Reached target Swap.
[  OK  ] Reached target Swap.
[    3.819987] systemd[1]: Listening on RPCbind Server Activation Socket.
[  OK  ] Listening on RPCbind Server Activation Socket.
[    3.841296] systemd[1]: Reached target RPC Port Mapper.
[  OK  ] Reached target RPC Port Mapper.
[    3.866086] systemd[1]: Listening on Process Core Dump Socket.
[  OK  ] Listening on Process Core Dump Socket.
[    3.889370] systemd[1]: Listening on initctl Compatibility Named Pipe.
[  OK  ] Listening on initctl Compatibility Named Pipe.
[    3.916072] systemd[1]: Condition check resulted in Journal Audit Socket being skipped.
[    3.924746] systemd[1]: Listening on Journal Socket (/dev/log).
[  OK  ] Listening on Journal Socket (/dev/log).
[    3.945617] systemd[1]: Listening on Journal Socket.
[  OK  ] Listening on Journal Socket.
[    3.961769] systemd[1]: Listening on Network Service Netlink Socket.
[  OK  ] Listening on Network Service Netlink Socket.
[    3.985573] systemd[1]: Listening on udev Control Socket.
[  OK  ] Listening on udev Control Socket.
[    4.005381] systemd[1]: Listening on udev Kernel Socket.
[  OK  ] Listening on udev Kernel Socket.
[    4.029266] systemd[1]: Mounting Huge Pages File System...
         Mounting Huge Pages File System...
[    4.049101] systemd[1]: Mounting POSIX Message Queue File System...
         Mounting POSIX Message Queue File System...
[    4.077099] systemd[1]: Mounting Kernel Debug File System...
         Mounting Kernel Debug File System...
[    4.097811] systemd[1]: Mounting Temporary Directory (/tmp)...
         Mounting Temporary Directory (/tmp)...
[    4.117804] systemd[1]: Starting Create list of static device nodes for the current kernel...
         Starting Create list of st�odes for the current kernel...
[    4.145863] systemd[1]: Starting RPC Bind...
         Starting RPC Bind...
[    4.161355] systemd[1]: Condition check resulted in File System Check on Root Device being skipped.
[    4.176114] systemd[1]: Starting Journal Service...
         Starting Journal Service...
[    4.211384] systemd[1]: Starting Load Kernel Modules...
         Starting Load Kernel Modules...
[    4.229687] cryptodev: loading out-of-tree module taints kernel.
[    4.233210] systemd[1]: Starting Remount Root and Kernel File Systems...
         Starting Remount Root and Kernel File Systems cryptodev: driver 1.10 loaded.
[0m...
[    4.249732] EXT4-fs (mmcblk1p2): re-mounted. Opts: (null)
[    4.263023] systemd[1]: Starting udev Coldplug all Devices...
         Starting udev Coldplug all Devices...
[    4.294669] systemd[1]: Started RPC Bind.
[  OK  ] Started RPC Bind.
[    4.309652] systemd[1]: Started Journal Service.
[  OK  ] Started Journal Service.
[  OK  ] Mounted Huge Pages File System.
[  OK  ] Mounted POSIX Message Queue File System.
[  OK  ] Mounted Kernel Debug File System.
[  OK  ] Mounted Temporary Directory (/tmp).
[  OK  ] Started Create list of sta� nodes for the current kernel.
[  OK  ] Started Load Kernel Modules.
[  OK  ] Started Remount Root and Kernel File Systems.
         Mounting Kernel Configuration File System...
         Starting Flush Journal to Persistent Storage...
[    4.488518] systemd-journald[178]: Received client request to flush runtime journal.
         Starting Apply Kernel Variables...
         Starting Create Static Device Nodes in /dev...
[  OK  ] Mounted Kernel Configuration File System.
[  OK  ] Started Flush Journal to Persistent Storage.
[  OK  ] Started Apply Kernel Variables.
[  OK  ] Started Create Static Device Nodes in /dev.
[    4.614013] random: systemd: uninitialized urandom read (16 bytes read)
[  OK  ] Reached target Local File Systems (Pre).
[    4.637202] random: systemd: uninitialized urandom read (16 bytes read)
         Mounting /media/ram...
[    4.650291] random: systemd-journal: uninitialized urandom read (16 bytes read)
         Mounting /var/volatile...
         Starting udev Kernel Device Manager...
[  OK  ] Started udev Coldplug all Devices.
[  OK  ] Mounted /media/ram.
[  OK  ] Mounted /var/volatile.
         Starting udev Wait for Complete Device Initialization...
         Starting Load/Save Random Seed...
[  OK  ] Reached target Local File Systems.
         Starting Create Volatile Files and Directories...
[  OK  ] Started Create Volatile Files and Directories.
[  OK  ] Started udev Kernel Device Manager.
         Starting Network Time Synchronization...
         Starting Update UTMP about System Boot/Shutdown...
[  OK  ] Started Update UTMP about System Boot/Shutdown.
[  OK  ] Started Network Time Synchronization.
[  OK  ] Reached target System Time Set.
[  OK  ] Reached target System Time Synchronized.
[    5.155521] random: crng init done
[    5.159237] random: 65 urandom warning(s) missed due to ratelimiting
[  OK  ] Started Load/Save Random Seed.
[    5.219379] platform 79000000.r5f: configured R5F for remoteproc mode
[    5.227004] k3-dsp-rproc 7e000000.dsp: assigned reserved memory node c7x-dma-memory@99800000
[    5.237103] k3-dsp-rproc 7e000000.dsp: configured DSP for remoteproc mode
[    5.244123] platform 79000000.r5f: assigned reserved memory node r5f-dma-memory@9b800000
[    5.244646] remoteproc remoteproc1: 7e000000.dsp is available
[    5.254338] remoteproc remoteproc0: 79000000.r5f is available
[    5.273087] platform 78000000.r5f: R5F core may have been powered on by a different host, programmed state (0) != actual state (1)
[    5.286694] platform 78000000.r5f: configured R5F for IPC-only mode
[    5.293302] platform 78000000.r5f: assigned reserved memory node r5f-dma-memory@9c800000
[    5.301785] remoteproc remoteproc0: powering up 79000000.r5f
[    5.307736] remoteproc remoteproc0: Booting fw image am62a-mcu-r5f0_0-fw, size 52788
[    5.316522]  remoteproc0#vdev0buffer: assigned reserved memory node r5f-dma-memory@9b800000
[    5.325490] virtio_rpmsg_bus virtio0: rpmsg host is online
[    5.331400]  remoteproc0#vdev0buffer: registered virtio0 (type 7)
[    5.337744] remoteproc remoteproc0: remote processor 79000000.r5f is now up
[    5.353650] remoteproc remoteproc2: 78000000.r5f is available
[    5.359826] remoteproc remoteproc2: attaching to 78000000.r5f
[    5.365849] platform 78000000.r5f: R5F core initialized in IPC-only mode
[    5.372654]  remoteproc2#vdev0buffer: assigned reserved memory node r5f-dma-memory@9c800000
[    5.380786] virtio_rpmsg_bus virtio0: creating channel ti.ipc4.ping-pong addr 0xd
[    5.381751] virtio_rpmsg_bus virtio1: rpmsg host is online
[    5.388814] virtio_rpmsg_bus virtio0: creating channel rpmsg_chrdev addr 0xe
[    5.394460]  remoteproc2#vdev0buffer: registered virtio1 (type 7)
[    5.406875] virtio_rpmsg_bus virtio1: creating channel rpmsg_chrdev addr 0xd
[    5.407539] remoteproc remoteproc2: remote processor 78000000.r5f is now attached
[    5.428098] tlv320aic3x-codec 1-001b: supply DVDD not found, using dummy regulator
[    5.439910] tlv320aic3x-codec 1-001b: Invalid supply voltage(s) AVDD: 3300000, DVDD: -22
[    5.612157] vdec 30210000.video-codec: sram node not found
[    5.629942] vdec 30210000.video-codec: IRQ index 0 not found
[    5.636397] vdec 30210000.video-codec: failed to get irq resource
[    5.660867] imx219 4-0010: supply VANA not found, using dummy regulator
[    5.668633] imx219 4-0010: supply VDIG not found, using dummy regulator
[    5.678196] imx219 4-0010: supply VDDL not found, using dummy regulator
[    6.017239] remoteproc remoteproc1: powering up 7e000000.dsp
[    6.023209] remoteproc remoteproc1: Booting fw image am62a-c71_0-fw, size 14948104
[    6.030985] remoteproc remoteproc1: unsupported resource 65538
[    6.043567] k3-dsp-rproc 7e000000.dsp: booting DSP core using boot addr = 0x99a00000
[    6.061077]  remoteproc1#vdev0buffer: assigned reserved memory node c7x-dma-memory@99800000
[    6.070333] virtio_rpmsg_bus virtio2: rpmsg host is online
[    6.075997]  remoteproc1#vdev0buffer: registered virtio2 (type 7)
[    6.082131] remoteproc remoteproc1: remote processor 7e000000.dsp is now up
[    6.098222] virtio_rpmsg_bus virtio2: creating channel rpmsg_chrdev addr 0xd
[    6.105555] virtio_rpmsg_bus virtio2: creating channel rpmsg_chrdev addr 0x15
[    6.113196] virtio_rpmsg_bus virtio2: creating channel ti.ipc4.ping-pong addr 0xe
[    6.121153] virtio_rpmsg_bus virtio1: creating channel rpmsg_chrdev addr 0x15
[    6.128612] virtio_rpmsg_bus virtio1: creating channel ti.ipc4.ping-pong addr 0xe
[    6.815548] cdns-csi2rx 30101000.csi-bridge: Probed CSI2RX with 2/4 lanes, 4 streams, external D-PHY
[    6.847385] tps6598x 0-003f: Unable to find the interrupt, switching to polling
[  OK  ] Reached target Hardware activated USB gadget.
[    6.922991] usbcore: registered new interface driver usbfs
[    6.929622] usbcore: registered new interface driver hub
[    6.935836] usbcore: registered new device driver usb
[    6.963540] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
[    6.969112] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 1
[    6.977055] xhci-hcd xhci-hcd.1.auto: hcc params 0x0258fe6d hci version 0x110 quirks 0x0000000000010010
[    6.986611] xhci-hcd xhci-hcd.1.auto: irq 461, io mem 0x31100000
[    6.992860] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
[    6.998404] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 2
[    7.006104] xhci-hcd xhci-hcd.1.auto: Host supports USB 3.0 SuperSpeed
[    7.012854] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10
[    7.021196] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    7.028470] usb usb1: Product: xHCI Host Controller
[    7.033362] usb usb1: Manufacturer: Linux 5.10.168-g2c23e6c538 xhci-hcd
[    7.040006] usb usb1: SerialNumber: xhci-hcd.1.auto
[    7.045549] hub 1-0:1.0: USB hub found
[    7.049380] hub 1-0:1.0: 1 port detected
[    7.053845] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[    7.062067] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10
[    7.070394] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    7.077741] usb usb2: Product: xHCI Host Controller
[    7.082711] usb usb2: Manufacturer: Linux 5.10.168-g2c23e6c538 xhci-hcd
[    7.089372] usb usb2: SerialNumber: xhci-hcd.1.auto
[    7.094839] hub 2-0:1.0: USB hub found
[    7.098665] hub 2-0:1.0: config failed, hub doesn't have any ports! (err -19)
[  OK  ] Created slice system-systemd\x2dfsck.slice.
[  OK  ] Found device /dev/mmcblk0p1.
         Starting File System Check on /dev/mmcblk0p1...
[  OK  ] Started File System Check on /dev/mmcblk0p1.
         Mounting /run/media/mmcblk0p1...
[  OK  ] Mounted /run/media/mmcblk0p1.
[  OK  ] Found device /dev/mmcblk1p1.
[  OK  ] Started udev Wait for Complete Device Initialization.
[  OK  ] Started Hardware RNG Entropy Gatherer Daemon.
[  OK  ] Reached target System Initialization.
[  OK  ] Started Daily rotation of log files.
[  OK  ] Started Daily Cleanup of Temporary Directories.
[  OK  ] Reached target Timers.
[  OK  ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
[  OK  ] Listening on D-Bus System Message Bus Socket.
         Starting Docker Socket for the API.
[  OK  ] Listening on dropbear.socket.
         Starting Reboot and dump vmcore via kexec...
         Starting File System Check on /dev/mmcblk1p1...
[  OK  ] Listening on Docker Socket for the API.
[  OK  ] Started Reboot and dump vmcore via kexec.
[  OK  ] Reached target Sockets.
[  OK  ] Reached target Basic System.
         Starting Save/Restore Sound Card State...
[  OK  ] Started Job spooling tools.
[  OK  ] Started Periodic Command Scheduler.
[  OK  ] Started D-Bus System Message Bus.
         Starting Ethernet Bridge Filtering Tables...
         Starting Print notice about GPLv3 packages...
         Starting IPv6 Packet Filtering Framework...
         Starting IPv4 Packet Filtering Framework...
[  OK  ] Started irqbalance daemon.
         Starting System Logger Daemon "default" instance...
         Starting Login Service...
[  OK  ] Started TEE Supplicant.
         Starting weston.service...
[  OK  ] Started Save/Restore Sound Card State.
[  OK  ] Started Ethernet Bridge Filtering Tables.
[  OK  ] Reached target Sound Card.
[  OK  ] Started File System Check on /dev/mmcblk1p1.
[  OK  ] Started IPv6 Packet Filtering Framework.
[  OK  ] Started IPv4 Packet Filtering Framework.
[  OK  ] Reached target Network (Pre).
         Mounting /run/media/mmcblk1p1...
         Starting Network Service...
[  OK  ] Started Login Service.
[  OK  ] Started Network Service.
         Starting Wait for Network to be Configured...
         Starting Network Name Resolution...
[  OK  ] Mounted /run/media/mmcblk1p1.
[    8.393484] am65-cpsw-nuss 8000000.ethernet eth0: PHY [8000f00.mdio:00] driver [TI DP83867] (irq=POLL)
[    8.409265] am65-cpsw-nuss 8000000.ethernet eth0: configuring for phy/rgmii-rxid link mode
[  OK  ] Started System Logger Daemon "default" instance.
[  OK  ] Started Network Name Resolution.
[  OK  ] Reached target Network.
[  OK  ] Reached target Host and Network Name Lookups.
         Starting Avahi mDNS/DNS-SD Stack...
         Starting Enable and configure wl18xx bluetooth stack...
[  OK  ] Started NFS status monitor for NFSv2/3 locking..
         Starting Simple Network Ma�ent Protocol (SNMP) Daemon....
         Starting Permit User Sessions...
[  OK  ] Started Vsftpd ftp daemon.
[  OK  ] Started Enable and configure wl18xx bluetooth stack.
[  OK  ] Started Permit User Sessions.
[  OK  ] Started Getty on tty1.
[  OK  ] Started Serial Getty on ttyS0.
[  OK  ] Started Serial Getty on ttyS2.
[  OK  ] Reached target Login Prompts.
         Starting Synchronize System and HW clocks...
[  OK  ] Started Avahi mDNS/DNS-SD Stack.
[FAILED] Failed to start Synchronize System and HW clocks.
See 'systemctl status sync-clocks.service' for details.
[  OK  ] Started Simple Network Man�ement Protocol (SNMP) Daemon..
[  OK  ] Started weston.service.
         Starting EdgeAI OOB demos...
         Starting Matrix GUI...
         Starting telnetd.service...
[  OK  ] Started Matrix GUI.
[  OK  ] Started telnetd.service.
         Starting LSB: Expand Rootfs of boot device...
         Starting syslog.service...
***************************************************************
***************************************************************
NOTICE: This file system contains the following GPLv3 packages:
	autoconf
	bash-dev
	bash
	bc
	binutils
	cifs-utils
	coreutils-stdbuf
	coreutils
	cpio
	cpp-symlinks
	cpp
	dosfstools
	elfutils
	g++-symlinks
	g++
	gawk
	gcc-symlinks
	gcc
	gdb
	gdbserver
	gettext
	glmark2
	gstreamer1.0-libav
	gzip
	hidapi
	less
	libasm1
	libbfd
	libdw1
	libelf1
	libgdbm-compat4
	libgdbm-dev
	libgdbm6
	libgettextlib
	libgettextsrc
	libgmp10
	libidn2-0
	libmpc3
	libmpfr6
	libreadline-dev
	libreadline8
	libunistring2
	m4-dev
	m4
	make
	nettle
	parted
	python3-rfc3987
	python3-strict-rfc3339
	tar
	which
	zeromq

If you do not wish to distribute GPLv3 components please remove
the above packages prior to distribution.  This can be done using
the opkg remove command.  i.e.:
    opkg remove <package>
Where <package> is the name printed in the list above

NOTE: If the package is a dependency of another package you
      will be notified of the dependent packages.  You should
      use the --force-removal-of-dependent-packages option to
      also remove the dependent packages as well
***************************************************************
***************************************************************
[  OK  ] Started syslog.service.
[  OK  ] Started Print notice about GPLv3 packages.
[  OK  ] Started LSB: Expand Rootfs of boot device.

 _____                    _____           _         _   
|  _  |___ ___ ___ ___   |  _  |___ ___  |_|___ ___| |_ 
|     |  _| .'| . | . |  |   __|  _| . | | | -_|  _|  _|
|__|__|_| |__,|_  |___|  |__|  |_| |___|_| |___|___|_|  
              |___|                    |___|            

Arago Project am62axx-evm ttyS2

Arago 2021.09 am62axx-evm ttyS2

am62axx-evm login: root
CSI Camera 0 detected
    device = /dev/video2
    name = imx219
    format = [fmt:SRGGB8_1X8/1920x1080]
    subdev_id = /dev/v4l-subdev2
    isp_required = yes
root@am62axx-evm:/opt/edgeai-gst-apps# /etc/init.d/weston stopn 
Stopping Weston
root@am62axx-evm:/opt/edgeai-gst-apps# kmstest
Connector 0/@39: HDMI-A-1
  Crtc 0/@37: 1920x1080 148.500 1920/88/44/148/+ 1080/4/5/36/+ 60 (60.00) 0x5 0x48
  Plane 0/@31: 0,0-1920x1080
    Fb 49 1920x1080-XR24
press enter to exit

root@am62axx-evm:/opt/edgeai-gst-apps# /ca   ./camera_format.txt2
root@am62axx-evm:/opt/edgeai-gst-apps# ./e2e.txt 
APP: Init ... !!!
MEM: Init ... !!!
MEM: Initialized DMA HEAP (fd=5) !!!
MEM: Init ... Done !!!
IPC: Init ... !!!
IPC: Init ... Done !!!
REMOTE_SERVICE: Init ... !!!
REMOTE_SERVICE: Init ... Done !!!
   104.778480 s: GTC Frequency = 200 MHz
APP: Init ... Done !!!
   104.783982 s:  VX_ZONE_INIT:Enabled
   104.784021 s:  VX_ZONE_ERROR:Enabled
   104.784031 s:  VX_ZONE_WARNING:Enabled
   104.785317 s:  VX_ZONE_INIT:[tivxInitLocal:130] Initialization Done !!!
   104.785781 s:  VX_ZONE_INIT:[tivxHostInitLocal:93] Initialization Done for HOST !!!
Setting pipeline to PAUSED ...
Pipeline is live and does not need PREROLL ...
Setting pipeline to PLAYING ...
New clock: GstSystemClock
^Chandling interrupt.
Interrupt: Stopping pipeline ...
Execution ended after 0:00:22.710515400
Setting pipeline to NULL ...
Freeing pipeline ...
   127.579309 s:  VX_ZONE_INIT:[tivxHostDeInitLocal:107] De-Initialization Done for HOST !!!
   127.580574 s:  VX_ZONE_INIT:[tivxDeInitLocal:193] De-Initialization Done !!!
APP: Deinit ... !!!
REMOTE_SERVICE: Deinit ... !!!
REMOTE_SERVICE: Deinit ... Done !!!
IPC: Deinit ... !!!
IPC: DeInit ... Done !!!
MEM: Deinit ... !!!
DDR_SHARED_MEM: Alloc's: 4 alloc's of 1228800 bytes 
DDR_SHARED_MEM: Free's : 4 free's  of 1228800 bytes 
DDR_SHARED_MEM: Open's : 0 allocs  of 0 bytes 
DDR_SHARED_MEM: Total size: 184549376 bytes 
MEM: Deinit ... Done !!!
APP: Deinit ... Done !!!
./e2e.txt: line 4: sink_0::dcc-2a-file=/opt/imaging/imx219/dcc_2a_640x480.bin: No such file or directory
./e2e.txt: line 5: video/x-raw,format=NV12,: No such file or directory
root@am62axx-evm:/opt/edgeai-gst-apps# [  130.693406] Initializing XFRM netlink socket
[  133.488274] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[  133.505581] Bridge firewalling registered
[  134.526650] process 'docker/tmp/qemu-check729572410/check' started with executable stack

0:00:00.000230370  1908     0x2a259400 INFO                GST_INIT gst.c:586:init_pre: Initializing GStreamer Core Library version 1.16.3
0:00:00.000360600  1908     0x2a259400 INFO                GST_INIT gst.c:587:init_pre: Using library installed in /usr/lib
0:00:00.000392825  1908     0x2a259400 INFO                GST_INIT gst.c:605:init_pre: Linux am62axx-evm 5.10.168-g2c23e6c538 #1 SMP PREEMPT Mon Feb 27 11:35:01 UTC 2023 aarch64
0:00:00.001138265  1908     0x2a259400 INFO                GST_INIT gstmessage.c:128:_priv_gst_message_initialize: init messages
0:00:00.002567035  1908     0x2a259400 INFO                GST_INIT gstcontext.c:84:_priv_gst_context_initialize: init contexts
0:00:00.003137680  1908     0x2a259400 INFO      GST_PLUGIN_LOADING gstplugin.c:318:_priv_gst_plugin_initialize: registering 0 static plugins
0:00:00.003462045  1908     0x2a259400 INFO      GST_PLUGIN_LOADING gstplugin.c:226:gst_plugin_register_static: registered static plugin "staticelements"
0:00:00.003500070  1908     0x2a259400 INFO      GST_PLUGIN_LOADING gstplugin.c:228:gst_plugin_register_static: added static plugin "staticelements", result: 1
0:00:00.003572325  1908     0x2a259400 INFO            GST_REGISTRY gstregistry.c:1733:ensure_current_registry: reading registry cache: /home/root/.cache/gstreamer-1.0/registry.aarch64.bin
0:00:00.060353055  1908     0x2a259400 INFO            GST_REGISTRY gstregistrybinary.c:621:priv_gst_registry_binary_read_cache: loaded /home/root/.cache/gstreamer-1.0/registry.aarch64.bin in 0.056687 seconds
0:00:00.060641690  1908     0x2a259400 INFO            GST_REGISTRY gstregistry.c:1592:scan_and_update_registry: Validating plugins from registry cache: /home/root/.cache/gstreamer-1.0/registry.aarch64.bin
0:00:00.063986625  1908     0x2a259400 INFO            GST_REGISTRY gstregistry.c:1691:scan_and_update_registry: Registry cache has not changed
0:00:00.064060040  1908     0x2a259400 INFO            GST_REGISTRY gstregistry.c:1768:ensure_current_registry: registry reading and updating done, result = 1
0:00:00.064084555  1908     0x2a259400 INFO                GST_INIT gst.c:806:init_post: GLib runtime version: 2.62.6
0:00:00.064107385  1908     0x2a259400 INFO                GST_INIT gst.c:808:init_post: GLib headers version: 2.62.6
0:00:00.064122460  1908     0x2a259400 INFO                GST_INIT gst.c:810:init_post: initialized GStreamer successfully
0:00:00.064250050  1908     0x2a259400 INFO            GST_PIPELINE gstparse.c:336:gst_parse_launch_full: parsing pipeline description 'v4l2src device=/dev/video2 io-mode=5 ! video/x-bayer,width=640,height=480,format=bggr, framerate=60/1 ! tiovxisp sensor-name=SENSOR_SONY_IMX219_RPI dcc-isp-file=/opt/imaging/imx219/dcc_viss_640x480.bin sink_0::dcc-2a-file=/opt/imaging/imx219/dcc_2a_640x480.bin sink_0::device=/dev/v4l-subdev2 ! video/x-raw,format=NV12, width=640, height=480, framerate=60/1 ! kmssink driver-name=tidss sync=false '
0:00:00.085386595  1908     0x2a259400 INFO      GST_PLUGIN_LOADING gstplugin.c:902:_priv_gst_plugin_load_file_for_registry: plugin "/usr/lib/gstreamer-1.0/libgstvideo4linux2.so" loaded
0:00:00.085483405  1908     0x2a259400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "v4l2src"
0:00:00.088682765  1908     0x2a259400 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstBaseSrc@0x2a46c0e0> adding pad 'src'
0:00:00.125425885  1908     0x2a259400 INFO      GST_PLUGIN_LOADING gstplugin.c:902:_priv_gst_plugin_load_file_for_registry: plugin "/usr/lib/gstreamer-1.0/libgsttiovx.so" loaded
0:00:00.125519485  1908     0x2a259400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "tiovxisp"
0:00:00.126227905  1908     0x2a259400 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstAggregator@0x2a49d130> adding pad 'src'
0:00:00.126330350  1908     0x2a259400 INFO            tiovxcontext gsttiovxcontext.c:141:gst_tiovx_context_init: Calling appInit() from GST!
0:00:00.151790665  1908     0x2a259400 INFO          tiovxallocator gsttiovxallocator.c:128:gst_tiovx_allocator_init:<GstTIOVXAllocator@0x2a44be40> New TIOVX allocator
0:00:00.152001225  1908     0x2a259400 INFO                 default gstchildproxy.c:228:gst_child_proxy_lookup: no such object sink_0
0:00:00.152088055  1908     0x2a259400 INFO                 default gstchildproxy.c:228:gst_child_proxy_lookup: no such object sink_0
0:00:00.153877850  1908     0x2a259400 INFO      GST_PLUGIN_LOADING gstplugin.c:902:_priv_gst_plugin_load_file_for_registry: plugin "/usr/lib/gstreamer-1.0/libgstkms.so" loaded
0:00:00.153949410  1908     0x2a259400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "kmssink"
0:00:00.154843960  1908     0x2a259400 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstBaseSink@0x2a4aaa00> adding pad 'sink'
0:00:00.155086425  1908     0x2a259400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "pipeline"
0:00:00.155381655  1908     0x2a259400 INFO            GST_PIPELINE grammar.y:648:gst_parse_perform_link: linking some pad of GstV4l2Src named v4l2src0 to some pad of GstTIOVXISP named tiovxisp0 (0/0) with caps "video/x-bayer, width=(int)640, height=(int)480, format=(string)bggr, framerate=(fraction)60/1"
0:00:00.157158280  1908     0x2a259400 INFO      GST_PLUGIN_LOADING gstplugin.c:902:_priv_gst_plugin_load_file_for_registry: plugin "/usr/lib/gstreamer-1.0/libgstcoreelements.so" loaded
0:00:00.157211620  1908     0x2a259400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "capsfilter"
0:00:00.157490180  1908     0x2a259400 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstBaseTransform@0x2a4b42a0> adding pad 'sink'
0:00:00.157636610  1908     0x2a259400 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstBaseTransform@0x2a4b42a0> adding pad 'src'
0:00:00.157696060  1908     0x2a259400 INFO              GST_STATES gstbin.c:2090:gst_bin_get_state_func:<pipeline0> getting state
0:00:00.157779760  1908     0x2a259400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<capsfilter0> completed state change to NULL
0:00:00.157824665  1908     0x2a259400 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:00.157864585  1908     0x2a259400 INFO        GST_ELEMENT_PADS gstutils.c:1771:gst_element_link_pads_full: trying to link element v4l2src0:(any) to element capsfilter0:sink
0:00:00.157891260  1908     0x2a259400 INFO        GST_ELEMENT_PADS gstelement.c:928:gst_element_get_static_pad: found pad capsfilter0:sink
0:00:00.157915185  1908     0x2a259400 INFO                GST_PADS gstutils.c:1587:prepare_link_maybe_ghosting: v4l2src0 and capsfilter0 in same bin, no need for ghost pads
0:00:00.157962805  1908     0x2a259400 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link v4l2src0:src and capsfilter0:sink
0:00:00.158009715  1908     0x2a259400 INFO                GST_PADS gstpad.c:4237:gst_pad_peer_query:<capsfilter0:src> pad has no peer
0:00:00.158069725  1908     0x2a259400 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked v4l2src0:src and capsfilter0:sink, successful
0:00:00.158087350  1908     0x2a259400 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:00.158106780  1908     0x2a259400 INFO               GST_EVENT gstpad.c:5812:gst_pad_send_event_unchecked:<v4l2src0:src> Received event on flushing pad. Discarding
0:00:00.158150830  1908     0x2a259400 INFO        GST_ELEMENT_PADS gstutils.c:1771:gst_element_link_pads_full: trying to link element capsfilter0:src to element tiovxisp0:(any)
0:00:00.158173105  1908     0x2a259400 INFO        GST_ELEMENT_PADS gstelement.c:928:gst_element_get_static_pad: found pad capsfilter0:src
0:00:00.158395405  1908     0x2a259400 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<tiovxisp0> adding pad 'sink_0'
0:00:00.158513115  1908     0x2a259400 INFO                GST_PADS gstutils.c:1587:prepare_link_maybe_ghosting: capsfilter0 and tiovxisp0 in same bin, no need for ghost pads
0:00:00.158559330  1908     0x2a259400 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link capsfilter0:src and tiovxisp0:sink_0
0:00:00.158642655  1908     0x2a259400 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked capsfilter0:src and tiovxisp0:sink_0, successful
0:00:00.158661160  1908     0x2a259400 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:00.158680555  1908     0x2a259400 INFO               GST_EVENT gstpad.c:5812:gst_pad_send_event_unchecked:<capsfilter0:src> Received event on flushing pad. Discarding
0:00:00.158757965  1908     0x2a259400 INFO            GST_PIPELINE grammar.y:648:gst_parse_perform_link: linking some pad of GstTIOVXISP named tiovxisp0 to some pad of GstKMSSink named kmssink0 (0/0) with caps "video/x-raw, format=(string)NV12, width=(int)640, height=(int)480, framerate=(fraction)60/1"
0:00:00.158785585  1908     0x2a259400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "capsfilter"
0:00:00.158838855  1908     0x2a259400 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstBaseTransform@0x2a4b45e0> adding pad 'sink'
0:00:00.158884275  1908     0x2a259400 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstBaseTransform@0x2a4b45e0> adding pad 'src'
0:00:00.158922480  1908     0x2a259400 INFO              GST_STATES gstbin.c:2090:gst_bin_get_state_func:<pipeline0> getting state
0:00:00.158977975  1908     0x2a259400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<capsfilter1> completed state change to NULL
0:00:00.159003555  1908     0x2a259400 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:00.159034975  1908     0x2a259400 INFO        GST_ELEMENT_PADS gstutils.c:1771:gst_element_link_pads_full: trying to link element tiovxisp0:(any) to element capsfilter1:sink
0:00:00.159057925  1908     0x2a259400 INFO        GST_ELEMENT_PADS gstelement.c:928:gst_element_get_static_pad: found pad capsfilter1:sink
0:00:00.159080255  1908     0x2a259400 INFO                GST_PADS gstutils.c:1587:prepare_link_maybe_ghosting: tiovxisp0 and capsfilter1 in same bin, no need for ghost pads
0:00:00.159112635  1908     0x2a259400 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link tiovxisp0:src and capsfilter1:sink
0:00:00.159147200  1908     0x2a259400 INFO                GST_PADS gstpad.c:4237:gst_pad_peer_query:<capsfilter1:src> pad has no peer
0:00:00.159187075  1908     0x2a259400 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked tiovxisp0:src and capsfilter1:sink, successful
0:00:00.159204320  1908     0x2a259400 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:00.159222985  1908     0x2a259400 INFO               GST_EVENT gstpad.c:5812:gst_pad_send_event_unchecked:<tiovxisp0:src> Received event on flushing pad. Discarding
0:00:00.159259865  1908     0x2a259400 INFO        GST_ELEMENT_PADS gstutils.c:1771:gst_element_link_pads_full: trying to link element capsfilter1:src to element kmssink0:(any)
0:00:00.159286570  1908     0x2a259400 INFO        GST_ELEMENT_PADS gstelement.c:928:gst_element_get_static_pad: found pad capsfilter1:src
0:00:00.159315790  1908     0x2a259400 INFO                GST_PADS gstutils.c:1034:gst_pad_check_link: trying to link capsfilter1:src and kmssink0:sink
0:00:00.159386325  1908     0x2a259400 INFO                GST_PADS gstutils.c:1587:prepare_link_maybe_ghosting: capsfilter1 and kmssink0 in same bin, no need for ghost pads
0:00:00.159419370  1908     0x2a259400 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link capsfilter1:src and kmssink0:sink
0:00:00.159483275  1908     0x2a259400 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked capsfilter1:src and kmssink0:sink, successful
0:00:00.159500945  1908     0x2a259400 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:00.159518765  1908     0x2a259400 INFO               GST_EVENT gstpad.c:5812:gst_pad_send_event_unchecked:<capsfilter1:src> Received event on flushing pad. Discarding
APP: Init ... !!!
MEM: Init ... !!!
MEM: Initialized DMA HEAP (fd=5) !!!
MEM: Init ... Done !!!
IPC: Init ... !!!
IPC: Init ... Done !!!
REMOTE_SERVICE: Init ... !!!
REMOTE_SERVICE: Init ... Done !!!
   398.852233 s: GTC Frequency = 200 MHz
APP: Init ... Done !!!
   398.852301 s:  VX_ZONE_INIT:Enabled
   398.852306 s:  VX_ZONE_ERROR:Enabled
   398.852310 s:  VX_ZONE_WARNING:Enabled
   398.853021 s:  VX_ZONE_INIT:[tivxInitLocal:130] Initialization Done !!!
   398.853172 s:  VX_ZONE_INIT:[tivxHostInitLocal:93] Initialization Done for HOST !!!
Setting pipeline to PAUSED ...
0:00:00.159715735  1908     0x2a259400 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<kmssink0> current NULL pending VOID_PENDING, desired next READY
0:00:00.159875340  1908     0x2a259400 INFO                 kmssink gstkmssink.c:366:log_drm_version:<kmssink0> DRM v1.0.0 [tidss — TI Keystone DSS — 20180215]
0:00:00.159909310  1908     0x2a259400 INFO                 kmssink gstkmssink.c:411:get_drm_caps:<kmssink0> prime import (✓) / prime export (✓) / async page flip (✗)
0:00:00.278891460  1908     0x2a259400 INFO                 kmssink gstkmssink.c:535:ensure_allowed_caps:<kmssink0> ignoring format AR12
0:00:00.278941585  1908     0x2a259400 INFO                 kmssink gstkmssink.c:535:ensure_allowed_caps:<kmssink0> ignoring format AB12
0:00:00.278964205  1908     0x2a259400 INFO                 kmssink gstkmssink.c:535:ensure_allowed_caps:<kmssink0> ignoring format RA12
0:00:00.278985050  1908     0x2a259400 INFO                 kmssink gstkmssink.c:535:ensure_allowed_caps:<kmssink0> ignoring format RG16
0:00:00.279005265  1908     0x2a259400 INFO                 kmssink gstkmssink.c:535:ensure_allowed_caps:<kmssink0> ignoring format BG16
0:00:00.279025300  1908     0x2a259400 INFO                 kmssink gstkmssink.c:535:ensure_allowed_caps:<kmssink0> ignoring format AR15
0:00:00.279045620  1908     0x2a259400 INFO                 kmssink gstkmssink.c:535:ensure_allowed_caps:<kmssink0> ignoring format AB15
0:00:00.279125725  1908     0x2a259400 INFO                 kmssink gstkmssink.c:535:ensure_allowed_caps:<kmssink0> ignoring format RA24
0:00:00.279158770  1908     0x2a259400 INFO                 kmssink gstkmssink.c:535:ensure_allowed_caps:<kmssink0> ignoring format BA24
0:00:00.279203070  1908     0x2a259400 INFO                 kmssink gstkmssink.c:535:ensure_allowed_caps:<kmssink0> ignoring format AR30
0:00:00.279224780  1908     0x2a259400 INFO                 kmssink gstkmssink.c:535:ensure_allowed_caps:<kmssink0> ignoring format AB30
0:00:00.279245060  1908     0x2a259400 INFO                 kmssink gstkmssink.c:535:ensure_allowed_caps:<kmssink0> ignoring format XR12
0:00:00.279266055  1908     0x2a259400 INFO                 kmssink gstkmssink.c:535:ensure_allowed_caps:<kmssink0> ignoring format XB12
0:00:00.279286360  1908     0x2a259400 INFO                 kmssink gstkmssink.c:535:ensure_allowed_caps:<kmssink0> ignoring format RX12
0:00:00.279306400  1908     0x2a259400 INFO                 kmssink gstkmssink.c:535:ensure_allowed_caps:<kmssink0> ignoring format XR15
0:00:00.279326585  1908     0x2a259400 INFO                 kmssink gstkmssink.c:535:ensure_allowed_caps:<kmssink0> ignoring format XB15
0:00:00.279370600  1908     0x2a259400 INFO                 kmssink gstkmssink.c:535:ensure_allowed_caps:<kmssink0> ignoring format RX24
0:00:00.279392765  1908     0x2a259400 INFO                 kmssink gstkmssink.c:535:ensure_allowed_caps:<kmssink0> ignoring format BX24
0:00:00.279413615  1908     0x2a259400 INFO                 kmssink gstkmssink.c:535:ensure_allowed_caps:<kmssink0> ignoring format XR30
0:00:00.279434130  1908     0x2a259400 INFO                 kmssink gstkmssink.c:535:ensure_allowed_caps:<kmssink0> ignoring format XB30
0:00:00.279630160  1908     0x2a259400 INFO                 kmssink gstkmssink.c:776:gst_kms_sink_start:<kmssink0> connector id = 39 / crtc id = 37 / plane id = 40
0:00:00.279654950  1908     0x2a259400 INFO                 kmssink gstkmssink.c:798:gst_kms_sink_start:<kmssink0> display size: pixels = 1920x1080 / millimeters = 530x300
0:00:00.279704035  1908     0x2a259400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<kmssink0> completed state change to READY
0:00:00.279729825  1908     0x2a259400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<kmssink0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.279819475  1908     0x2a259400 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'kmssink0' changed state to 2(READY) successfully
0:00:00.279867415  1908     0x2a259400 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<capsfilter1> current NULL pending VOID_PENDING, desired next READY
0:00:00.279892265  1908     0x2a259400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<capsfilter1> completed state change to READY
0:00:00.279915990  1908     0x2a259400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<capsfilter1> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.279948895  1908     0x2a259400 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'capsfilter1' changed state to 2(READY) successfully
0:00:00.279979080  1908     0x2a259400 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<tiovxisp0> current NULL pending VOID_PENDING, desired next READY
0:00:00.280005405  1908     0x2a259400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<tiovxisp0> completed state change to READY
0:00:00.280028105  1908     0x2a259400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<tiovxisp0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.280059955  1908     0x2a259400 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'tiovxisp0' changed state to 2(READY) successfully
0:00:00.280090260  1908     0x2a259400 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<capsfilter0> current NULL pending VOID_PENDING, desired next READY
0:00:00.280112485  1908     0x2a259400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<capsfilter0> completed state change to READY
0:00:00.280134885  1908     0x2a259400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<capsfilter0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.280165865  1908     0x2a259400 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'capsfilter0' changed state to 2(READY) successfully
0:00:00.280192805  1908     0x2a259400 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<v4l2src0> current NULL pending VOID_PENDING, desired next READY
0:00:00.280398370  1908     0x2a259400 INFO                    v4l2 v4l2_calls.c:587:gst_v4l2_open:<v4l2src0:src> Opened device 'j721e-csi2rx' (/dev/video2) successfully
0:00:00.280446935  1908     0x2a259400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<v4l2src0> completed state change to READY
0:00:00.280471880  1908     0x2a259400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<v4l2src0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.280507260  1908     0x2a259400 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'v4l2src0' changed state to 2(READY) successfully
0:00:00.280567375  1908     0x2a259400 INFO              GST_STATES gstelement.c:2660:gst_element_continue_state:<pipeline0> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:00.280591220  1908     0x2a259400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<pipeline0> notifying about state-changed NULL to READY (PAUSED pending)
0:00:00.280712235  1908     0x2a259400 INFO              GST_STATES gstelement.c:2668:gst_element_continue_state:<pipeline0> continue state change READY to PAUSED, final PAUSED
0:00:00.280772130  1908     0x2a259400 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<kmssink0> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.280822965  1908     0x2a259400 INFO              GST_STATES gstbin.c:2959:gst_bin_change_state_func:<pipeline0> child 'kmssink0' is changing state asynchronously to PAUSED
0:00:00.280852875  1908     0x2a259400 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<capsfilter1> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.280890745  1908     0x2a259400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<capsfilter1> completed state change to PAUSED
0:00:00.280917730  1908     0x2a259400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<capsfilter1> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.280949235  1908     0x2a259400 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'capsfilter1' changed state to 3(PAUSED) successfully
0:00:00.280978980  1908     0x2a259400 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<tiovxisp0> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.283074675  1908     0x2a259400 INFO              aggregator gstaggregator.c:2139:gst_aggregator_src_pad_activate_mode_func:<tiovxisp0:src> Activating pad!
0:00:00.283118665  1908     0x2a259400 INFO              aggregator gstaggregator.c:1263:gst_aggregator_start_srcpad_task:<tiovxisp0> Starting srcpad task
0:00:00.283247580  1908     0x2a259400 INFO                    task gsttask.c:460:gst_task_set_lock: setting stream lock 0x2a49ebc0 on task 0x2a4b8050
0:00:00.283272460  1908     0x2a259400 INFO                GST_PADS gstpad.c:6159:gst_pad_start_task:<tiovxisp0:src> created task 0x2a4b8050
0:00:00.283591605  1908     0x2a259400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<tiovxisp0> completed state change to PAUSED
0:00:00.283627895  1908     0x2a259400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<tiovxisp0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.283673570  1908     0x2a259400 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'tiovxisp0' changed state to 3(PAUSED) successfully
0:00:00.283720015  1908     0x2a259400 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<capsfilter0> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.283763880  1908     0x2a259400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<capsfilter0> completed state change to PAUSED
0:00:00.283788435  1908     0x2a259400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<capsfilter0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.283821345  1908     0x2a259400 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'capsfilter0' changed state to 3(PAUSED) successfully
0:00:00.283814170  1908     0x2a49bf20 WARN                 v4l2src gstv4l2src.c:695:gst_v4l2src_query:<v4l2src0> Can't give latency since framerate isn't fixated !
0:00:00.283877435  1908     0x2a49bf20 WARN              aggregator gstaggregator.c:1757:gst_aggregator_query_latency_unlocked:<tiovxisp0> Latency query failed
0:00:00.283887340  1908     0x2a259400 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<v4l2src0> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.283960805  1908     0x2a259400 INFO                 basesrc gstbasesrc.c:1373:gst_base_src_do_seek:<v4l2src0> seeking: time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=99:99:99.999999999, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.000000000, duration 99:99:99.999999999
0:00:00.284017710  1908     0x2a259400 INFO                    task gsttask.c:460:gst_task_set_lock: setting stream lock 0x2a46e110 on task 0x2a4b8170
0:00:00.284040365  1908     0x2a259400 INFO                GST_PADS gstpad.c:6159:gst_pad_start_task:<v4l2src0:src> created task 0x2a4b8170
0:00:00.284240550  1908     0x2a259400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<v4l2src0> completed state change to PAUSED
0:00:00.284269480  1908     0x2a259400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<v4l2src0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.284309945  1908     0x2a259400 INFO              GST_STATES gstbin.c:2995:gst_bin_change_state_func:<pipeline0> child 'v4l2src0' changed state to 3(PAUSED) successfully without preroll
0:00:00.284347505  1908     0x2a259400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<pipeline0> completed state change to PAUSED
0:00:00.284371425  1908     0x2a259400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<pipeline0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.284385450  1908     0x2a49bf70 INFO        GST_ELEMENT_PADS gstelement.c:925:gst_element_get_static_pad: no such pad 'sink' in element "v4l2src0"
Pipeline is live and does not need PREROLL ...
0:00:00.284607575  1908     0x2a49bf20 WARN                 v4l2src gstv4l2src.c:695:gst_v4l2src_query:<v4l2src0> Can't give latency since framerate isn't fixated !
0:00:00.284636425  1908     0x2a49bf20 WARN              aggregator gstaggregator.c:1757:gst_aggregator_query_latency_unlocked:<tiovxisp0> Latency query failed
0:00:00.284685530  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:1245:gst_v4l2_object_fill_format_list:<v4l2src0:src> got 24 format(s):
0:00:00.284715760  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   YUYV
0:00:00.284744230  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   UYVY
0:00:00.284767130  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   IGB0
0:00:00.284790925  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   GIB0
0:00:00.284816835  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   IGR0
0:00:00.284839925  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   GIR0
0:00:00.284864800  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   GBI0
0:00:00.284886660  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   BGI0
0:00:00.284907715  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   GRI0
0:00:00.284931950  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   RGI0
0:00:00.284953340  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   RG12
0:00:00.284974630  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   BA12
0:00:00.284995345  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   GB12
0:00:00.285016255  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   BG12
0:00:00.285036990  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   RG10
0:00:00.285057540  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   BA10
0:00:00.285078335  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   GB10
0:00:00.285099255  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   BG10
0:00:00.285120665  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   RGGB
0:00:00.285141305  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   GRBG
0:00:00.285162115  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   GBRG
0:00:00.285183160  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   BA81
0:00:00.285203750  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   VYUY
0:00:00.285224685  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:1249:gst_v4l2_object_fill_format_list:<v4l2src0:src>   YVYU
Setting pipeline to PLAYING ...
0:00:00.286011475  1908     0x2a259400 INFO               GST_EVENT gstevent.c:1449:gst_event_new_latency: creating latency event 0:00:00.000000000
0:00:00.286082310  1908     0x2a259400 INFO                     bin gstbin.c:2783:gst_bin_do_latency_func:<pipeline0> configured latency of 0:00:00.000000000
0:00:00.286104770  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:4402:gst_v4l2_object_probe_caps:<v4l2src0:src> probed caps: video/x-raw, format=(string)YUY2, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-raw, format=(string)UYVY, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)iggb10, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)gibg10, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)iggr10, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)girg10, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)gbig10, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)bggi10, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)grig10, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)rggi10, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)rggb12, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)grbg12, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)gbrg12, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)bggr12, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)rggb10, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)grbg10, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)gbrg10, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)bggr10, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)rggb, width=(int)[ 16, 16777216, 16 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)grbg, width=(int)[ 16, 16777216, 16 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)gbrg, width=(int)[ 16, 16777216, 16 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-bayer, format=(string)bggr, width=(int)[ 16, 16777216, 16 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-raw, format=(string)YVYU, width=(int)[ 8, 16777216, 8 ], height=(int)[ 1, 16777216 ], framerate=(fraction)[ 0/1, 2147483647/1 ]
0:00:00.286149265  1908     0x2a259400 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<kmssink0> current READY pending PAUSED, desired next PLAYING
0:00:00.286175395  1908     0x2a259400 INFO              GST_STATES gstbin.c:2959:gst_bin_change_state_func:<pipeline0> child 'kmssink0' is changing state asynchronously to PLAYING
0:00:00.286207500  1908     0x2a259400 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<capsfilter1> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.286235620  1908     0x2a259400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<capsfilter1> completed state change to PLAYING
0:00:00.286262430  1908     0x2a259400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<capsfilter1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.286301155  1908     0x2a259400 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'capsfilter1' changed state to 4(PLAYING) successfully
0:00:00.286331040  1908     0x2a259400 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<tiovxisp0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.286353950  1908     0x2a259400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<tiovxisp0> completed state change to PLAYING
0:00:00.286377200  1908     0x2a259400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<tiovxisp0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.286409305  1908     0x2a259400 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'tiovxisp0' changed state to 4(PLAYING) successfully
0:00:00.286438500  1908     0x2a259400 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<capsfilter0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.286450080  1908     0x2a49bf70 INFO               GST_EVENT gstevent.c:820:gst_event_new_caps: creating caps event video/x-bayer, width=(int)640, height=(int)480, format=(string)bggr, framerate=(fraction)60/1, colorimetry=(string)sRGB, interlace-mode=(string)progressive
0:00:00.286460950  1908     0x2a259400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<capsfilter0> completed state change to PLAYING
0:00:00.286484010  1908     0x2a259400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<capsfilter0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.286515775  1908     0x2a259400 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'capsfilter0' changed state to 4(PLAYING) successfully
0:00:00.286548590  1908     0x2a259400 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<v4l2src0> completed state change to PLAYING
0:00:00.286571170  1908     0x2a259400 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<v4l2src0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.286592240  1908     0x2a49bf70 INFO           basetransform gstbasetransform.c:1317:gst_base_transform_setcaps:<capsfilter0> reuse caps
0:00:00.286602170  1908     0x2a259400 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'v4l2src0' changed state to 4(PLAYING) successfully
0:00:00.286636205  1908     0x2a49bf70 INFO               GST_EVENT gstevent.c:820:gst_event_new_caps: creating caps event video/x-bayer, width=(int)640, height=(int)480, format=(string)bggr, framerate=(fraction)60/1, colorimetry=(string)sRGB, interlace-mode=(string)progressive
0:00:00.286829485  1908     0x2a49bf20 WARN                 v4l2src gstv4l2src.c:695:gst_v4l2src_query:<v4l2src0> Can't give latency since framerate isn't fixated !
0:00:00.286858845  1908     0x2a49bf20 WARN              aggregator gstaggregator.c:1757:gst_aggregator_query_latency_unlocked:<tiovxisp0> Latency query failed
New clock: GstSystemClock
0:00:00.286933930  1908     0x2a49bf20 INFO               tiovxmiso gsttiovxmiso.c:859:gst_tiovx_miso_propose_allocation:<tiovxisp0> Using temporary reference for configuration
0:00:00.287040475  1908     0x2a49bf20 WARN              video-info video-info.c:189:validate_colorimetry: color matrix RGB is only supported with RGB format, ENCODED is not
0:00:00.287057340  1908     0x2a49bf20 WARN              video-info video-info.c:513:gst_video_info_from_caps: invalid colorimetry, using default
0:00:00.287088810  1908     0x2a49bf20 INFO               tiovxmiso gsttiovxutils.c:762:gst_tiovx_get_exemplar_from_caps:<tiovxisp0> creating raw image with width: 640	 height: 480	 format: 0x101001
0:00:00.287202440  1908     0x2a49bf20 INFO               tiovxmiso gsttiovxbufferpoolutils.c:85:gst_tiovx_create_new_pool: Creating new pool
0:00:00.287218490  1908     0x2a49bf20 INFO               tiovxmiso gsttiovxbufferpoolutils.c:97:gst_tiovx_create_new_pool: Creating Raw Image buffer pool
0:00:00.287452015  1908     0x2a49bf20 INFO         tiovxbufferpool gsttiovxbufferpool.c:127:gst_tiovx_buffer_pool_init:<GstTIOVXBufferPool@0xffff88008130> New TIOVX buffer pool
0:00:00.287557630  1908     0x2a49bf20 INFO          tiovxallocator gsttiovxallocator.c:128:gst_tiovx_allocator_init:<GstTIOVXAllocator@0x2a4a5610> New TIOVX allocator
0:00:00.287600945  1908     0x2a49bf20 INFO               tiovxmiso gsttiovxbufferpoolutils.c:144:gst_tiovx_add_new_pool: Adding new TIOVX pool with 2 buffers of 307200 size
0:00:00.287707855  1908     0x2a49bf20 WARN                 v4l2src gstv4l2src.c:695:gst_v4l2src_query:<v4l2src0> Can't give latency since framerate isn't fixated !
0:00:00.287734120  1908     0x2a49bf70 WARN              video-info video-info.c:189:validate_colorimetry: color matrix RGB is only supported with RGB format, ENCODED is not
0:00:00.287733375  1908     0x2a49bf20 WARN              aggregator gstaggregator.c:1757:gst_aggregator_query_latency_unlocked:<tiovxisp0> Latency query failed
0:00:00.287778865  1908     0x2a49bf70 WARN              video-info video-info.c:513:gst_video_info_from_caps: invalid colorimetry, using default
0:00:00.287848455  1908     0x2a49bf70 INFO                    v4l2 gstv4l2object.c:3077:gst_v4l2_object_setup_pool:<v4l2src0:src> accessing buffers via mode 5
0:00:00.288178815  1908     0x2a49bf70 INFO          v4l2bufferpool gstv4l2bufferpool.c:568:gst_v4l2_buffer_pool_set_config:<v4l2src0:pool:src> increasing minimum buffers to 2
0:00:00.288204310  1908     0x2a49bf70 INFO          v4l2bufferpool gstv4l2bufferpool.c:581:gst_v4l2_buffer_pool_set_config:<v4l2src0:pool:src> reducing maximum buffers to 32
0:00:00.288240765  1908     0x2a49bf70 WARN              video-info video-info.c:189:validate_colorimetry: color matrix RGB is only supported with RGB format, ENCODED is not
0:00:00.288263585  1908     0x2a49bf70 WARN              video-info video-info.c:513:gst_video_info_from_caps: invalid colorimetry, using default
0:00:00.288351170  1908     0x2a49bf70 INFO          v4l2bufferpool gstv4l2bufferpool.c:581:gst_v4l2_buffer_pool_set_config:<v4l2src0:pool:src> reducing maximum buffers to 32
0:00:00.288380130  1908     0x2a49bf70 WARN              video-info video-info.c:189:validate_colorimetry: color matrix RGB is only supported with RGB format, ENCODED is not
0:00:00.288398435  1908     0x2a49bf70 WARN              video-info video-info.c:513:gst_video_info_from_caps: invalid colorimetry, using default
0:00:00.288439055  1908     0x2a49bf70 WARN              video-info video-info.c:189:validate_colorimetry: color matrix RGB is only supported with RGB format, ENCODED is not
0:00:00.288454235  1908     0x2a49bf70 WARN              video-info video-info.c:513:gst_video_info_from_caps: invalid colorimetry, using default
0:00:00.288546575  1908     0x2a49bf70 INFO          tiovxallocator gsttiovxallocator.c:128:gst_tiovx_allocator_init:<GstTIOVXAllocator@0xffff8800a510> New TIOVX allocator