TDA4VM: TDA4 High: pre-processing and post-processing nodes to run on different cores

srikar varaganti

Part Number: TDA4VM
Other Parts Discussed in Thread: TDA4VH

Hello,

EVM: TDA4VH, SDK: ti-processor-sdk-rtos-j784s4-evm-09_00_00_02

We are currently working on building a custom Object detection application that would work on different C7 cores. On going through the existing code present in SDK we realized that the c7 cores used for Pre-processing and Post-processing nodes are mapped to C7_2 only. Is there a specific reason for using only C7_2 for this? We figured this out from the macros provided in tivx_soc_j784s4.h where

TIVX_CPU_ID_DSP1 = TIVX_CPU_ID_DSP_C7_2

and

TIVX_TARGET_DSP1 = TIVX_TARGET_DSP_C7_2

We tried to update these macros to map DSP1 to C7_1 and vice versa and updated the make-file for the respective C7 cores as well, but found out that the pre-processing step is failing in other cores except when it is running in C7_2 core. Could you suggest on how can we implement these nodes (Pre-processing and post-processing) on different cores.

The error I am getting while running the nodes in C7_1 node is:

Thank you,

Srikar. V

over 2 years ago

0 Nikhil Dasan over 2 years ago

TI__Guru* 90151 points

Hi Srikar,

Could you let me know if you are seeing the same issue when you are running it on C7_3 and C7_4?

Regards,

Nikhil

0 srikar varaganti over 2 years ago in reply to Nikhil Dasan

Intellectual 300 points

Hi Nikhil,

This issue is currently occuring only in C7x_1 core. I tried with 3 and 4 but they seem to work fine.

I made the following changes in the code:

/home/svaraganti/ti-processor-sdk-rtos-j784s4-evm-09_00_00_02/vision_apps/platform/j784s4/rtos/common/app_init.c

/home/svaraganti/ti-processor-sdk-rtos-j784s4-evm-09_00_00_02/vision_apps/platform/j784s4/rtos/c7x_1/concerto_c7x_1_inc.mak

/home/svaraganti/ti-processor-sdk-rtos-j784s4-evm-09_00_00_02/tiovx/kernels/common/host/tivx_kernels_host_utils.c

/home/svaraganti/ti-processor-sdk-rtos-j784s4-evm-09_00_00_02/tiovx/kernels/common/target/tivx_kernels_target_utils.c

Please advise.

0 Nikhil Dasan over 2 years ago in reply to srikar varaganti

TI__Guru* 90151 points

Yes, As expected, the kernels of preproc and post procs are only present in C7x 2,3 and 4.

Could you add them in 1 and test again?

i.e. in concerto_c7x_2_inc.mak, you could find below

STATIC_LIBS += vx_target_kernels_srv_c66
STATIC_LIBS += vx_target_kernels_img_proc_c66
STATIC_LIBS += vx_target_kernels_stereo

This is also seen in c7x_3 and c7x_4_inc.mak... Could you add this in c7x_1_inc.mak in ${PSDKRA}/vision_apps/platform/j784s4/rtos/c7x_1/concerto_c7x_1_inc.mak

Regards,

Nikhil

0 srikar varaganti over 2 years ago in reply to Nikhil Dasan

Intellectual 300 points

Hi Nikhil,

I forgot to include this in previous response, we have added these lines in C7x_1_inc.mak as well.

0 Nikhil Dasan over 2 years ago in reply to srikar varaganti

TI__Guru* 90151 points

Hi,

I believe your TIDL node is also running on C7x_1 right? Could you try by moving it to different core? Do you still see this error?

Could you share the full logs in a .txt file (along with the remote core logs, i.e. run ./vision_apps_init.sh before running your demo)

Regards,

Nikhil

0 srikar varaganti over 2 years ago in reply to Nikhil Dasan

Intellectual 300 points

The goal is to run all the nodes in a app in single node. Furthermore, for the other apps running on C7x_2/3/4 tidl node is operating in the respective core itself and there is no issue with object detection with that app.

I tried running the app with TIDL node in a different core(C7X_2 and other nodes in C7x_1) still the issue is persistent and same.

I am trying to add the .txt files for log but not able to upload them here. Can you send me a link or suggest another way to send these files to you.

0 Nikhil Dasan over 2 years ago in reply to srikar varaganti

TI__Guru* 90151 points

Hi,

srikar varaganti said:
Furthermore, for the other apps running on C7x_2/3/4 tidl node is operating in the respective core itself

oh..ok.. So all nodes including TIDL on C7x_2 /3/4 works fine, but all the nodes of C7x_1 including TIDL does not work fine.. Is my understanding of your issue correct?

srikar varaganti said:
I am trying to add the .txt files for log but not able to upload them here

You could directly Drag and drop in the text file into the comments here or Click Insert -> Code -> paste the contents into it.

Regards,

Nikhil

0 srikar varaganti over 2 years ago in reply to Nikhil Dasan

Intellectual 300 points

1. Yes, that's correct. for the app that's processing all nodes in C7x_1 core, i tried changing preprocessing and post-processing nodes into C7x_2 (whilst TIDl node is in C7x_1) the app isn't showing this issue. That is how we understood that these nodes (preprocessing and post-processing) are causing the issue.

2. I requested Daviel to share the logs over to you via email.

0 srikar varaganti over 2 years ago in reply to srikar varaganti

Intellectual 300 points

Logs for the OD app processing all the nodes in C7x_1 core

J784S4-EVM@QNX:/ti_fs/vision_apps# vx_od_batch.out --cfg app_od_data_v1_1b_1.cfg 
APP: Init QNX ... !!!
appIpcInit: IPC: Init QNX ... !!!
appIpcInit: IPC: Init ... Done !!!
   221.264581 s: REMOTE_SERVICE: Init ... !!!
   221.264712 s: REMOTE_SERVICE: Init ... Done !!!
   221.264747 s: GTC Frequency = 200 MHz
APP: Init ... Done !!!
   221.264775 s:  VX_ZONE_INIT:Enabled
   221.264789 s:  VX_ZONE_ERROR:Enabled
   221.264803 s:  VX_ZONE_WARNING:Enabled
   221.265045 s:  VX_ZONE_INIT:[tivxInitLocal:130] Initialization Done !!!
   221.265090 s:  VX_ZONE_INIT:[tivxHostInitLocal:96] Initialization Done for HOST !!!
1.
#### Setting Default Parameters ####
1.>>>> Pre-Proc Defaults <<<<
1.      Padding = 0
1.>>>> Pipeline Parameters Defaults <<<<
1.Default param set! 
1.
#### Parsing Config file Parameters ####
1.<<<< Setting config file, network file and input file path default to root dir>>>>
1.argc: 3
1.argc i: 0
1.argc i: 1
1.Go into config file
1.<<<< Parse config file >>>>
1.       tidl_config file path: /ti_fs/vision_apps/test_data/psdkra/tidl_models/tidl_io_model_v1_1C_2I1.bin 
1.       tidl_network file path: /ti_fs/vision_apps/test_data/psdkra/tidl_models/tidl_net_model_v1_1C_2I.bin 
1.       input_file_path file path: /ti_fs/vision_apps/test_data/psdkra/od_data_1 
1.       output_file_path file path: /ti_fs/vision_apps/app_tidl_od_model_v1_out 
1.       start_frame: 0 
1.       num_frames: 250 
1.       in_size Width: 1920 x Height 1280 
1.       dl_size Width: 320 x Height: 320 
1.       out_size Width: 1920 x Height: 1280 
1.       viz_th: 0.200000 
1.       num_classes: 4 
1.       delay_in_msecs: 0 
1.       en_out_img_write: 1 
1.       display_option: 1 
1.       num_iterations: 1 
1.       is_interactive: 1 
1.Done with config file parsing!
1.Parsed user params! 
1.
#### Update Parameters bbox, mosiac ####
1.<<<< Update Draw Detections Defaults >>>> 
1.       Width x Height: 1920 x 1280 
1.       Set Color maps for each class 
1.<<<< Update mosiac parameters >>>> 
1.       Output dim: 1920 x 1080 
1.       Windows Start X x Y: 500 x 200 
1.       Windows Dim X x Y: 1920 x 1280 
1.       Input Select: 0 
1.       Channel Select: 0 
1.       num_windows: 1 
1.       clear_count: 4 
1.Updated user params! 
1.
#### APP INIT ####
1.       Created OpenVx context 
1.       Set and load h/w, imgproc and TIDl kernals 
   221.272439 s:  VX_ZONE_INFO:Enabled
1.       Created Input image Object and input images array 
1.Scaler output1 width   = 320
1.Scaler output1 height  = 320
1.Scaler output2 width   = 1920
1.Scaler output2 height  = 1280
1.       Scaler Init Done! 
1.Scaler Init Done! 
1.       <<< app_init_tidl >>> 
Computing checksum at 0x000000491C2DC040, size = 18394968
1.       TIDL Init Done! 
1.TIDL Init Done! 
1.       Pre Proc Update Done! 
1.Pre Proc Update Done! 
1.       Pre Proc Init Done! 
1.Pre Proc Init Done! 
1.       Draw detections Update Done! 
1.Draw detections Update Done! 
1.       Draw Detections Init Done! 
1.Draw Detections Init Done! 
1.       Img Mosaic Init Done! 
1.Img Mosaic Init Done! 
1.       Display Init Done! 
1.Display Init Done! 
1.       Set Performance 
1.       init params init
   221.832957 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel com.ti.display
   221.833097 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_153
   221.833795 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_153 completed
   221.833831 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel com.ti.display ...
   221.833856 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel com.ti.display ... done !!!
   221.860365 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=62, pipe=0)
   221.860423 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=59, pipe=0)
   221.860713 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=62, pipe=0)
   221.860741 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
1.       grpx init
1.       Init appGrpx 
1.App Init Done! 
1.
#### Create Graph ####
   221.862552 s:  VX_ZONE_INFO:[ownKernelGetTarget:564] inside first if loop inside kernels.c   221.862566 s:  VX_ZONE_INFO:[ownKernelGetTarget:572] inside second if loop inside kernels.c   221.862580 s:
  VX_ZONE_INFO:[ownKernelGetTarget:584] Target String VPAC_MSC1
   221.862607 s:  VX_ZONE_INFO:[ownKernelGetTarget:585] Kernel Num_targets 4
   221.862627 s:  VX_ZONE_INFO:[ownKernelGetTarget:586] target_id 515
   221.862689 s:  VX_ZONE_INFO:[ownIsValidReference:122] Reference was NULL
   221.862718 s:  VX_ZONE_INFO:[ownIsValidReference:122] Reference was NULL
   221.862746 s:  VX_ZONE_INFO:[ownIsValidReference:122] Reference was NULL
1.       Scaler Node Created 
Target for pre processing 1
   221.862822 s:  VX_ZONE_INFO:[ownKernelGetTarget:564] inside first if loop inside kernels.c   221.862836 s:  VX_ZONE_INFO:[ownKernelGetTarget:572] inside second if loop inside kernels.c   221.862848 s:
  VX_ZONE_INFO:[ownKernelGetTarget:584] Target String DSP_C7-1
   221.862872 s:  VX_ZONE_INFO:[ownKernelGetTarget:585] Kernel Num_targets 35
   221.862891 s:  VX_ZONE_INFO:[ownKernelGetTarget:586] target_id 256
1.       Pre Proc Node Created 
   221.997475 s:  VX_ZONE_INFO:[tivxTIDLNode:87] Number of parameters in kernel definition = 8 
   221.997502 s:  VX_ZONE_INFO:[tivxTIDLNode:132] Kernel used for node creation = 5418dbf480 
   221.997542 s:  VX_ZONE_INFO:[ownKernelGetTarget:564] inside first if loop inside kernels.c   221.997556 s:  VX_ZONE_INFO:[ownKernelGetTarget:572] inside second if loop inside kernels.c   221.997569 s:
  VX_ZONE_INFO:[ownKernelGetTarget:584] Target String DSP_C7-1
   221.997594 s:  VX_ZONE_INFO:[ownKernelGetTarget:585] Kernel Num_targets 35
   221.997612 s:  VX_ZONE_INFO:[ownKernelGetTarget:586] target_id 256
   221.997680 s:  VX_ZONE_INFO:[ownIsValidReference:122] Reference was NULL
1.       STIDL Node Created 
Target for draw detections 1
   221.997772 s:  VX_ZONE_INFO:[ownKernelGetTarget:564] inside first if loop inside kernels.c   221.997786 s:  VX_ZONE_INFO:[ownKernelGetTarget:572] inside second if loop inside kernels.c   221.997799 s:
  VX_ZONE_INFO:[ownKernelGetTarget:584] Target String DSP_C7-1
   221.997824 s:  VX_ZONE_INFO:[ownKernelGetTarget:585] Kernel Num_targets 35
   221.997843 s:  VX_ZONE_INFO:[ownKernelGetTarget:586] target_id 256
1.       draw detections Node Created 
1.       Mosaic Node Created 
   221.997957 s:  VX_ZONE_INFO:[ownKernelGetTarget:564] inside first if loop inside kernels.c   221.997970 s:  VX_ZONE_INFO:[ownKernelGetTarget:572] inside second if loop inside kernels.c   221.997983 s:
  VX_ZONE_INFO:[ownKernelGetTarget:584] Target String DISPLAY1
   221.998006 s:  VX_ZONE_INFO:[ownKernelGetTarget:585] Kernel Num_targets 2
   222.132522 s:  VX_ZONE_INFO:[ownKernelGetTarget:586] target_id 520
1.       Display Node Created 
1.       Pipelining flow enabled 
1.
Setting Node Paramete Bufs
1.scaler
1.scaler output
1.preproc
1.tidl
1.draw detections
1.App Create Graph Done! 
1.
#### Verify Graph ####
   222.132972 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel com.ti.hwa.vpac_msc_multi_scale
   222.133006 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel com.ti.img_proc.img.preprocess
   222.133033 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel com.ti.tidl:1:1
   222.133078 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel com.ti.img_proc.od.draw.box.detections
   222.133103 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel com.ti.display
   222.136868 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node scaler_node
   222.140546 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node scaler_node completed
   222.140709 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel com.ti.hwa.vpac_msc_multi_scale ...
   222.140735 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel com.ti.hwa.vpac_msc_multi_scale ... done !!!
   222.140763 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node PreProcNode
   222.141760 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node PreProcNode completed
   222.141923 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 1, kernel com.ti.img_proc.img.preprocess ...
   222.141948 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 1, kernel com.ti.img_proc.img.preprocess ... done !!!
   222.277173 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node tidl_node
   222.606787 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node tidl_node completed
   222.607000 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 2, kernel com.ti.tidl:1:1 ...
   222.607025 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 2, kernel com.ti.tidl:1:1 ... done !!!
   222.607049 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node DrawBoxDetectionsNode
   222.607989 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node DrawBoxDetectionsNode completed
   222.608155 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 3, kernel com.ti.img_proc.od.draw.box.detections ...
   222.608183 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 3, kernel com.ti.img_proc.od.draw.box.detections ... done !!!
   222.608213 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node DisplayNode
   222.608979 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node DisplayNode completed
   222.609138 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 4, kernel com.ti.display ...
   222.609161 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 4, kernel com.ti.display ... done !!!
1.      App Verify Graph Done!
1.App Verify Graph Done!
1.App Send MSC Command Done!
1.App Send MSC Command Done!
1.App Verify Graph Done! 
1.
#### Graph Interactive ####
1.PIPELINE_FLOW enabled 
1.>>>> Pipeline Parameters Defaults <<<<
1.Running frame 0
1.Running frame 0
1.in app_run_graph_for_one_frame_pipeline 
1.       (Enqueing) Pipeline number: -3 
1.       Reading input image file from path at enque count: 0 and yuv counter: 0
1.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000000.bmp


 =========================
 TIDL Demo - Object Detection
 =========================

 p: Print performance statistics

 x: Exit

 Enter Choice: 1.        time taken for reading .bmp file : in 189736 msec!
   222.927198 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   222.927276 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   222.927521 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   222.927551 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   222.927576 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   222.927847 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=215, pipe=0)
   222.927874 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=212, pipe=0)
   222.951619 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=215, pipe=0)
   222.951647 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   222.951794 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.       time taken for converting rgb to yuv file : in 24956 msec!
1.       Reading input image file from path at enque count: 0 and yuv counter: 1
1.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000001.bmp
1.       time taken for reading .bmp file : in 189793 msec!
   223.142074 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   223.142152 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   223.142393 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   223.142422 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   223.142447 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   223.142652 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=219, pipe=0)
   223.142678 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=216, pipe=0)
   223.166440 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=219, pipe=0)
   223.166470 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   223.166610 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.       time taken for converting rgb to yuv file : in 24898 msec!
1.App Reading Input Done!
1.       Enque input image array at enque count: 0 
   223.166684 s:  VX_ZONE_INFO:[ownDataRefQueueEnqueueReadyRef:91] Q (queue=170, ref=5)
   223.166714 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=207, pipe=0)
   223.166736 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=63, pipe=0)
1.App Process Graph Done!
1.App Process Graph Done!
1.app_tidl_od: Frame ID 0 of 250 Total Time : 429... Done.
1.app_tidl_od: Frame ID 0 of 250 ... Done.
1.Running frame 2
1.Running frame 2
1.in app_run_graph_for_one_frame_pipeline 
1.       (Enqueing) Pipeline number: -2 
1.       Reading input image file from path at enque count: 1 and yuv counter: 2
1.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000002.bmp
   223.195118 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=207, pipe=0)
   223.195153 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
[C7x_1 ]    223.194448 s: RTOS: semhandle was NULL
[C7x_1 ]    223.194469 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    223.194481 s: RTOS: semhandle was NULL
[C7x_1 ]    223.194496 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    223.194507 s: RTOS: semhandle was NULL
[C7x_1 ]    223.194522 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    223.194725 s: RTOS: semhandle was NULL
[C7x_1 ]    223.194741 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    223.194752 s: RTOS: semhandle was NULL
[C7x_1 ]    223.194771 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    223.194782 s: RTOS: semhandle was NULL
[C7x_1 ]    223.194797 s: UDMA : ERROR: Channel queue failed!!
1.       time taken for reading .bmp file : in 172744 msec!
   223.340037 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   223.340196 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   223.340444 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   223.340474 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   223.340498 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   223.340766 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=223, pipe=0)
   223.340792 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=220, pipe=0)
   223.364861 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=223, pipe=0)
   223.364893 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   223.365039 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.       time taken for converting rgb to yuv file : in 25429 msec!
1.       Reading input image file from path at enque count: 1 and yuv counter: 3
1.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000003.bmp
1.       time taken for reading .bmp file : in 170212 msec!
   223.535796 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   223.535964 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   223.536203 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   223.536232 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   223.536259 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   223.536482 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=227, pipe=0)
   223.536508 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=224, pipe=0)
   223.560480 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=227, pipe=0)
   223.560511 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   223.560658 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.       time taken for converting rgb to yuv file : in 25286 msec!
1.App Reading Input Done!
1.       Enque input image array at enque count: 1 
   223.560733 s:  VX_ZONE_INFO:[ownDataRefQueueEnqueueReadyRef:91] Q (queue=170, ref=8)
   223.560762 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=208, pipe=1)
   223.560785 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=70, pipe=1)
1.App Process Graph Done!
1.App Process Graph Done!
1.app_tidl_od: Frame ID 2 of 250 Total Time : 394... Done.
1.app_tidl_od: Frame ID 2 of 250 ... Done.
1.Running frame 4
1.Running frame 4
1.in app_run_graph_for_one_frame_pipeline 
1.       (Enqueing) Pipeline number: -1 
1.       Reading input image file from path at enque count: 2 and yuv counter: 4
1.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000004.bmp
   223.596754 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=208, pipe=1)
   223.596790 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
[C7x_1 ]    223.588051 s: RTOS: semhandle was NULL
[C7x_1 ]    223.588072 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    223.588084 s: RTOS: semhandle was NULL
[C7x_1 ]    223.588099 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    223.588110 s: RTOS: semhandle was NULL
[C7x_1 ]    223.588125 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    223.588330 s: RTOS: semhandle was NULL
[C7x_1 ]    223.588346 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    223.588357 s: RTOS: semhandle was NULL
[C7x_1 ]    223.588372 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    223.588383 s: RTOS: semhandle was NULL
[C7x_1 ]    223.588398 s: UDMA : ERROR: Channel queue failed!!
1.       time taken for reading .bmp file : in 172465 msec!
   223.733803 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   223.733967 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   223.734214 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   223.734243 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   223.734269 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   223.734544 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=231, pipe=0)
   223.734569 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=228, pipe=0)
   223.758568 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=231, pipe=0)
   223.758600 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   223.758745 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.       time taken for converting rgb to yuv file : in 25364 msec!
1.       Reading input image file from path at enque count: 2 and yuv counter: 5
1.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000005.bmp
1.       time taken for reading .bmp file : in 187833 msec!
   223.947132 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   223.947300 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   223.947539 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   223.947567 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   223.947592 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   223.947865 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=235, pipe=0)
   223.947891 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=232, pipe=0)
   223.971845 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=235, pipe=0)
   223.971876 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   223.972022 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.       time taken for converting rgb to yuv file : in 25320 msec!
1.App Reading Input Done!
1.       Enque input image array at enque count: 2 
   223.972098 s:  VX_ZONE_INFO:[ownDataRefQueueEnqueueReadyRef:91] Q (queue=170, ref=11)
   223.972129 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=209, pipe=2)
   223.972150 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=71, pipe=2)
1.App Process Graph Done!
1.App Process Graph Done!
1.app_tidl_od: Frame ID 4 of 250 Total Time : 411... Done.
1.app_tidl_od: Frame ID 4 of 250 ... Done.
1.Running frame 6
1.Running frame 6
1.in app_run_graph_for_one_frame_pipeline 
1.       (Enqueing) Pipeline number: 0 
1.       Reading input image file from path at enque count: 3 and yuv counter: 6
1.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000006.bmp
[C7x_1 ]    223.999506 s: RTOS: semhandle was NULL
[C7x_1 ]    223.999526 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    223.999539 s: RTOS: semhandle was NULL
[C7x_1 ]    223.999554 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    223.999565 s: RTOS: semhandle was NULL
[C7x_1 ]    223.999580 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    223.999786 s: RTOS: semhandle was NULL
[C7x_1 ]    223.999802 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    223.999814 s: RTOS: semhandle was NULL
[C7x_1 ]    223.999829 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    223.999840 s: RTOS: semhandle was NULL
[C7x_1 ]    223.999855 s: UDMA : ERROR: Channel queue failed!!
   224.013429 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=209, pipe=2)
   224.013460 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
1.       time taken for reading .bmp file : in 174603 msec!
   224.147256 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   224.147412 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   224.147664 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   224.147693 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   224.147719 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   224.147998 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=239, pipe=0)
   224.148024 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=236, pipe=0)
   224.172015 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=239, pipe=0)
   224.172048 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   224.172198 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.       time taken for converting rgb to yuv file : in 25309 msec!
1.       Reading input image file from path at enque count: 3 and yuv counter: 7
1.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000007.bmp
1.       time taken for reading .bmp file : in 169804 msec!
   224.342488 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   224.342657 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   224.342895 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   224.342924 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   224.342949 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   224.343165 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=243, pipe=0)
   224.343191 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=240, pipe=0)
   224.367237 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=243, pipe=0)
   224.367267 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   224.367416 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.       time taken for converting rgb to yuv file : in 25295 msec!
1.App Reading Input Done!
1.       Enque input image array at enque count: 3 
   224.367491 s:  VX_ZONE_INFO:[ownDataRefQueueEnqueueReadyRef:91] Q (queue=170, ref=14)
   224.367521 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=210, pipe=3)
   224.367545 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=72, pipe=3)
1.       (Dequeing) Pipeline count: 1 
1.       Deque Input image
   224.367605 s:  VX_ZONE_INFO:[ownDataRefQueueDequeueDoneRef:156] DQ (queue=171, ref=5)
1.       Reading input image file from path at enque count: 0 and yuv counter: 0
1.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000006.bmp
   224.396754 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=210, pipe=3)
   224.396788 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
[C7x_1 ]    224.394877 s: RTOS: semhandle was NULL
[C7x_1 ]    224.394898 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    224.394910 s: RTOS: semhandle was NULL
[C7x_1 ]    224.394925 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    224.394936 s: RTOS: semhandle was NULL
[C7x_1 ]    224.394951 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    224.395153 s: RTOS: semhandle was NULL
[C7x_1 ]    224.395169 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    224.395181 s: RTOS: semhandle was NULL
[C7x_1 ]    224.395196 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    224.395206 s: RTOS: semhandle was NULL
[C7x_1 ]    224.395221 s: UDMA : ERROR: Channel queue failed!!
1.       time taken for reading .bmp file : in 175337 msec!
   224.543410 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   224.543482 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   224.543735 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   224.543765 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   224.543791 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   224.544019 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=247, pipe=0)
   224.544045 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=244, pipe=0)
   224.567907 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=247, pipe=0)
   224.567938 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   224.568084 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.       time taken for converting rgb to yuv file : in 25093 msec!
1.       Reading input image file from path at enque count: 0 and yuv counter: 1
1.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000007.bmp
1.       time taken for reading .bmp file : in 169558 msec!
   224.738142 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   224.738218 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   224.738455 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   224.738483 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   224.738510 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   224.738715 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=251, pipe=0)
   224.738742 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=248, pipe=0)
   224.762583 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=251, pipe=0)
   224.762612 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   224.762762 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.       time taken for converting rgb to yuv file : in 24996 msec!
   224.762819 s:  VX_ZONE_INFO:[ownDataRefQueueEnqueueReadyRef:91] Q (queue=170, ref=5)
   224.762850 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=211, pipe=4)
   224.762872 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=73, pipe=4)
1.App Process Graph Done!
1.App Process Graph Done!
1.app_tidl_od: Frame ID 6 of 250 Total Time : 790... Done.
1.app_tidl_od: Frame ID 6 of 250 ... Done.
1.Running frame 8
1.Running frame 8
1.in app_run_graph_for_one_frame_pipeline 
1.       (Dequeing) Pipeline count: 1 
1.       Deque Input image
   224.762995 s:  VX_ZONE_INFO:[ownDataRefQueueDequeueDoneRef:156] DQ (queue=171, ref=8)
1.       Reading input image file from path at enque count: 1 and yuv counter: 2
1.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000008.bmp
   224.796747 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=211, pipe=4)
   224.796782 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
[C7x_1 ]    224.790165 s: RTOS: semhandle was NULL
[C7x_1 ]    224.790187 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    224.790199 s: RTOS: semhandle was NULL
[C7x_1 ]    224.790215 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    224.790226 s: RTOS: semhandle was NULL
[C7x_1 ]    224.790241 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    224.790443 s: RTOS: semhandle was NULL
[C7x_1 ]    224.790459 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    224.790470 s: RTOS: semhandle was NULL
[C7x_1 ]    224.790489 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    224.790499 s: RTOS: semhandle was NULL
[C7x_1 ]    224.790514 s: UDMA : ERROR: Channel queue failed!!
1.       time taken for reading .bmp file : in 174763 msec!
   224.938162 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   224.938231 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   224.938470 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   224.938497 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   224.938521 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   224.938728 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=255, pipe=0)
   224.938753 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=252, pipe=0)
   224.962641 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=255, pipe=0)
   224.962672 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   224.962822 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.       time taken for converting rgb to yuv file : in 25014 msec!
1.       Reading input image file from path at enque count: 1 and yuv counter: 3
1.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000009.bmp
1.       time taken for reading .bmp file : in 184061 msec!
   225.147356 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   225.147434 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   225.147674 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   225.147703 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   225.147729 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   225.147941 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=259, pipe=0)
   225.147967 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=256, pipe=0)
   225.171801 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=259, pipe=0)
   225.171832 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   225.171977 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.       time taken for converting rgb to yuv file : in 24974 msec!
   225.172031 s:  VX_ZONE_INFO:[ownDataRefQueueEnqueueReadyRef:91] Q (queue=170, ref=8)
   225.172062 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=207, pipe=0)
   225.172085 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=63, pipe=0)
1.App Process Graph Done!
1.App Process Graph Done!
1.app_tidl_od: Frame ID 8 of 250 Total Time : 409... Done.
1.app_tidl_od: Frame ID 8 of 250 ... Done.
1.Running frame 10
1.Running frame 10
1.in app_run_graph_for_one_frame_pipeline 
1.       (Dequeing) Pipeline count: 1 
1.       Deque Input image
   225.172210 s:  VX_ZONE_INFO:[ownDataRefQueueDequeueDoneRef:156] DQ (queue=171, ref=11)
1.       Reading input image file from path at enque count: 2 and yuv counter: 4
1.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000010.bmp
[C7x_1 ]    225.199412 s: RTOS: semhandle was NULL
[C7x_1 ]    225.199433 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    225.199445 s: RTOS: semhandle was NULL
[C7x_1 ]    225.199461 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    225.199472 s: RTOS: semhandle was NULL
[C7x_1 ]    225.199486 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    225.199689 s: RTOS: semhandle was NULL
[C7x_1 ]    225.199705 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    225.199717 s: RTOS: semhandle was NULL
[C7x_1 ]    225.199732 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    225.199742 s: RTOS: semhandle was NULL
[C7x_1 ]    225.199757 s: UDMA : ERROR: Channel queue failed!!
   225.213430 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=207, pipe=0)
   225.213459 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
1.       time taken for reading .bmp file : in 175225 msec!
   225.347838 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   225.347908 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   225.348159 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   225.348188 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   225.348215 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   225.348420 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=263, pipe=0)
   225.348446 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=260, pipe=0)
   225.372348 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=263, pipe=0)
   225.372380 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   225.372529 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.       time taken for converting rgb to yuv file : in 25053 msec!
1.       Reading input image file from path at enque count: 2 and yuv counter: 5
1.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000011.bmp
1.       time taken for reading .bmp file : in 170282 msec!
   225.543287 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   225.543366 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   225.543606 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   225.543634 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   225.543659 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   225.543868 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=267, pipe=0)
   225.543893 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=264, pipe=0)
   225.567691 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=267, pipe=0)
   225.567720 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   225.567865 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.       time taken for converting rgb to yuv file : in 24929 msec!
   225.567918 s:  VX_ZONE_INFO:[ownDataRefQueueEnqueueReadyRef:91] Q (queue=170, ref=11)
   225.567948 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=208, pipe=1)
   225.567970 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=70, pipe=1)
1.App Process Graph Done!
1.App Process Graph Done!
1.app_tidl_od: Frame ID 10 of 250 Total Time : 395... Done.
1.app_tidl_od: Frame ID 10 of 250 ... Done.
1.Running frame 12
1.Running frame 12
1.in app_run_graph_for_one_frame_pipeline 
1.       (Dequeing) Pipeline count: 1 
1.       Deque Input image
   225.568094 s:  VX_ZONE_INFO:[ownDataRefQueueDequeueDoneRef:156] DQ (queue=171, ref=14)
1.       Reading input image file from path at enque count: 3 and yuv counter: 6
1.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000012.bmp
   225.596742 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=208, pipe=1)
   225.596776 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
[C7x_1 ]    225.595273 s: RTOS: semhandle was NULL
[C7x_1 ]    225.595295 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    225.595307 s: RTOS: semhandle was NULL
[C7x_1 ]    225.595322 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    225.595333 s: RTOS: semhandle was NULL
[C7x_1 ]    225.595348 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    225.595552 s: RTOS: semhandle was NULL
[C7x_1 ]    225.595568 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    225.595579 s: RTOS: semhandle was NULL
[C7x_1 ]    225.595594 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    225.595604 s: RTOS: semhandle was NULL
[C7x_1 ]    225.595619 s: UDMA : ERROR: Channel queue failed!!
1.       time taken for reading .bmp file : in 170506 msec!
   225.740695 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   225.740767 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   225.741021 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   225.741049 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   225.741076 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   225.741331 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=271, pipe=0)
   225.741356 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=268, pipe=0)
   225.765182 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=271, pipe=0)
   225.765209 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   225.765358 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.       time taken for converting rgb to yuv file : in 25083 msec!
1.       Reading input image file from path at enque count: 3 and yuv counter: 7
1.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000013.bmp
1.       time taken for reading .bmp file : in 174806 msec!
   225.940659 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   225.940737 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   225.940981 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   225.941010 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   225.941036 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   225.941307 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=275, pipe=0)
   225.941333 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=272, pipe=0)
   225.965136 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=275, pipe=0)
   225.965166 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   225.965315 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.       time taken for converting rgb to yuv file : in 25028 msec!
   225.965368 s:  VX_ZONE_INFO:[ownDataRefQueueEnqueueReadyRef:91] Q (queue=170, ref=14)
   225.965397 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=209, pipe=2)
   225.965418 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=71, pipe=2)
1.App Process Graph Done!
1.App Process Graph Done!
1.app_tidl_od: Frame ID 12 of 250 Total Time : 397... Done.
1.app_tidl_od: Frame ID 12 of 250 ... Done.
1.Running frame 14
1.Running frame 14
1.in app_run_graph_for_one_frame_pipeline 
1.       (Dequeing) Pipeline count: 1 
1.       Deque Input image
   225.965540 s:  VX_ZONE_INFO:[ownDataRefQueueDequeueDoneRef:156] DQ (queue=171, ref=5)
1.       Reading input image file from path at enque count: 0 and yuv counter: 0
1.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000014.bmp
[C7x_1 ]    225.992702 s: RTOS: semhandle was NULL
[C7x_1 ]    225.992723 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    225.992737 s: RTOS: semhandle was NULL
[C7x_1 ]    225.992752 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    225.992763 s: RTOS: semhandle was NULL
[C7x_1 ]    225.992782 s: UDMA : ERROR: Channel queue failed!!
   225.996752 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=209, pipe=2)
   225.996781 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
[C7x_1 ]    225.992985 s: RTOS: semhandle was NULL
[C7x_1 ]    225.993002 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    225.993013 s: RTOS: semhandle was NULL
[C7x_1 ]    225.993028 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    225.993038 s: RTOS: semhandle was NULL
[C7x_1 ]    225.993053 s: UDMA : ERROR: Channel queue failed!!
1.       time taken for reading .bmp file : in 187903 msec!
   226.153862 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   226.153934 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   226.154182 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   226.154211 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   226.154236 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   226.154452 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=279, pipe=0)
   226.154478 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=276, pipe=0)
   226.178309 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=279, pipe=0)
   226.178338 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   226.178487 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.       time taken for converting rgb to yuv file : in 24997 msec!
1.       Reading input image file from path at enque count: 0 and yuv counter: 1
1.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000015.bmp
1.       time taken for reading .bmp file : in 173018 msec!
   226.352063 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   226.352143 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   226.352389 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   226.352418 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   226.352444 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   226.352714 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=283, pipe=0)
   226.352740 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=280, pipe=0)
   226.376641 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=283, pipe=0)
   226.376670 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   226.376827 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.       time taken for converting rgb to yuv file : in 25193 msec!
   226.376880 s:  VX_ZONE_INFO:[ownDataRefQueueEnqueueReadyRef:91] Q (queue=170, ref=5)
   226.376910 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=210, pipe=3)
   226.376931 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=72, pipe=3)
1.App Process Graph Done!
1.App Process Graph Done!
1.app_tidl_od: Frame ID 14 of 250 Total Time : 411... Done.
1.app_tidl_od: Frame ID 14 of 250 ... Done.
1.Running frame 16
1.Running frame 16
1.in app_run_graph_for_one_frame_pipeline 
1.       (Dequeing) Pipeline count: 1 
1.       Deque Input image
   226.377052 s:  VX_ZONE_INFO:[ownDataRefQueueDequeueDoneRef:156] DQ (queue=171, ref=8)
1.       Reading input image file from path at enque count: 1 and yuv counter: 2
1.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000016.bmp
   226.413434 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=210, pipe=3)
   226.413470 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
[C7x_1 ]    226.404196 s: RTOS: semhandle was NULL
[C7x_1 ]    226.404218 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    226.404231 s: RTOS: semhandle was NULL
[C7x_1 ]    226.404246 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    226.404256 s: RTOS: semhandle was NULL
[C7x_1 ]    226.404271 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    226.404473 s: RTOS: semhandle was NULL
[C7x_1 ]    226.404489 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    226.404500 s: RTOS: semhandle was NULL
[C7x_1 ]    226.404515 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    226.404526 s: RTOS: semhandle was NULL
[C7x_1 ]    226.404540 s: UDMA : ERROR: Channel queue failed!!
1.       time taken for reading .bmp file : in 170062 msec!
   226.549693 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   226.549784 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   226.550087 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   226.550122 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   226.550147 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   226.550394 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=287, pipe=0)
   226.550420 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=284, pipe=0)
   226.574251 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=287, pipe=0)
   226.574283 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   226.574434 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.       time taken for converting rgb to yuv file : in 25101 msec!
1.       Reading input image file from path at enque count: 1 and yuv counter: 3
1.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000017.bmp
1.       time taken for reading .bmp file : in 186621 msec!
   226.761537 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   226.761616 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   226.761893 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   226.761943 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   226.761968 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   226.762204 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=291, pipe=0)
   226.762231 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=288, pipe=0)
   226.786062 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=291, pipe=0)
   226.786090 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   226.786246 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.       time taken for converting rgb to yuv file : in 25070 msec!
   226.786302 s:  VX_ZONE_INFO:[ownDataRefQueueEnqueueReadyRef:91] Q (queue=170, ref=8)
   226.786332 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=211, pipe=4)
   226.786353 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=73, pipe=4)
1.App Process Graph Done!
1.App Process Graph Done!
1.app_tidl_od: Frame ID 16 of 250 Total Time : 409... Done.
1.app_tidl_od: Frame ID 16 of 250 ... Done.
1.Running frame 18
1.Running frame 18
1.in app_run_graph_for_one_frame_pipeline 
1.       (Dequeing) Pipeline count: 1 
1.       Deque Input image
   226.786474 s:  VX_ZONE_INFO:[ownDataRefQueueDequeueDoneRef:156] DQ (queue=171, ref=11)
1.       Reading input image file from path at enque count: 2 and yuv counter: 4
1.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000018.bmp
[C7x_1 ]    226.813604 s: RTOS: semhandle was NULL
[C7x_1 ]    226.813626 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    226.813639 s: RTOS: semhandle was NULL
[C7x_1 ]    226.813655 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    226.813666 s: RTOS: semhandle was NULL
[C7x_1 ]    226.813681 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    226.813887 s: RTOS: semhandle was NULL
[C7x_1 ]    226.813903 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    226.813914 s: RTOS: semhandle was NULL
[C7x_1 ]    226.813929 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    226.813940 s: RTOS: semhandle was NULL
[C7x_1 ]    226.813955 s: UDMA : ERROR: Channel queue failed!!
   226.830081 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=211, pipe=4)
   226.830111 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
1.       time taken for reading .bmp file : in 188374 msec!
   226.975267 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   226.975339 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   226.975586 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   226.975614 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   226.975639 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   226.975850 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=295, pipe=0)
   226.975876 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=292, pipe=0)
   226.999743 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=295, pipe=0)
   226.999772 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   226.999920 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.       time taken for converting rgb to yuv file : in 25019 msec!
1.       Reading input image file from path at enque count: 2 and yuv counter: 5
1.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000019.bmp
x1.      time taken for reading .bmp file : in 172722 msec!
   227.173128 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   227.173206 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   227.173451 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   227.173479 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   227.173503 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   227.173706 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=299, pipe=0)
   227.173731 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=296, pipe=0)
   227.197523 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=299, pipe=0)
   227.197551 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   227.197702 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.       time taken for converting rgb to yuv file : in 24937 msec!
   227.197756 s:  VX_ZONE_INFO:[ownDataRefQueueEnqueueReadyRef:91] Q (queue=170, ref=11)
   227.197786 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=207, pipe=0)
   227.197807 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=63, pipe=0)
1.App Process Graph Done!
1.App Process Graph Done!
1.app_tidl_od: Frame ID 18 of 250 Total Time : 411... Done.
1.app_tidl_od: Frame ID 18 of 250 ... Done.
1.Running frame 20
1.Running frame 20
1.in app_run_graph_for_one_frame_pipeline 
1.       (Dequeing) Pipeline count: 1 
1.       Deque Input image
   227.197930 s:  VX_ZONE_INFO:[ownDataRefQueueDequeueDoneRef:156] DQ (queue=171, ref=14)
1.       Reading input image file from path at enque count: 3 and yuv counter: 6
1.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000020.bmp
   227.230109 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=207, pipe=0)
   227.230171 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
[C7x_1 ]    227.225133 s: RTOS: semhandle was NULL
[C7x_1 ]    227.225153 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    227.225166 s: RTOS: semhandle was NULL
[C7x_1 ]    227.225181 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    227.225192 s: RTOS: semhandle was NULL
[C7x_1 ]    227.225207 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    227.225409 s: RTOS: semhandle was NULL
[C7x_1 ]    227.225426 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    227.225437 s: RTOS: semhandle was NULL
[C7x_1 ]    227.225452 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    227.225463 
s: RTOS: semhandle was NULL
1.
[C7x_1 ]    227.225477 s: UDMA : ERROR: Channel queue failed!!
1.       time taken for reading .bmp file : in 169868 msec!
   227.368207 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   227.368277 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   227.368518 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   227.368545 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   227.368571 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   227.368787 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=303, pipe=0)
   227.368818 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=300, pipe=0)
   227.392627 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=303, pipe=0)
   227.392656 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   227.392803 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.       time taken for converting rgb to yuv file : in 24961 msec!
1.       Reading input image file from path at enque count: 3 and yuv counter: 7
1.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000021.bmp
1.       time taken for reading .bmp file : in 173885 msec!
   227.567187 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   227.567264 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   227.567506 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   227.567533 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   227.567558 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   227.567785 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=307, pipe=0)
   227.567817 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=304, pipe=0)
   227.591613 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=307, pipe=0)
   227.591643 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   227.591790 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.       time taken for converting rgb to yuv file : in 24972 msec!
   227.591846 s:  VX_ZONE_INFO:[ownDataRefQueueEnqueueReadyRef:91] Q (queue=170, ref=14)
   227.591877 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=208, pipe=1)
   227.591898 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=70, pipe=1)
1.App Process Graph Done!
1.App Process Graph Done!
1.app_tidl_od: Frame ID 20 of 250 Total Time : 394... Done.
1.app_tidl_od: Frame ID 20 of 250 ... Done.
1.app_tidl_od: Iteration 0 of 1000000000 ... Done.
[C7x_1 ]    227.619185 s: RTOS: semhandle was NULL
[C7x_1 ]    227.619206 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    227.619219 s: RTOS: semhandle was NULL
[C7x_1 ]    227.619235 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    227.619246 s: RTOS: semhandle was NULL
[C7x_1 ]    227.619261 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    227.619463 s: RTOS: semhandle was NULL
[C7x_1 ]    227.619479 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    227.619490 s: RTOS: semhandle was NULL
[C7x_1 ]    227.619505 s: UDMA : ERROR: Channel queue failed!!
[C7x_1 ]    227.619516 s: RTOS: semhandle was NULL
   227.630070 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=208, pipe=1)
[C7x_1 ]    227.619531 s: UDMA : ERROR: Channel queue failed!!
   227.630093 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
1.       App Run Graph Done!
1.App Run Graph Done! 
   227.668855 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.img.mosaic:1 destructor called (removed from context)
   227.681660 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.tidl:1:1 destructor called (removed from context)
   227.681840 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.App Delete Graph Done! 
1.App Delete Graph Done! 
1.Input image release Done! 
1.Scaler deinit Done! 
1.Pre-Proc deinit Done! 
1.TIDL deinit Done! 
1.Draw detections deinit Done! 
1.Mosaic deinit Done! 
1.Display deinit Done! 
   227.896763 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.Graphics deinit Done! 
   227.897283 s:  VX_ZONE_INFO:[tivxRemoveTargetKernelTIDL_mpu:699] Removing MPU_1 target kernel 
   227.897311 s:  VX_ZONE_INFO:[tivxRemoveTargetKernelTIDL_mpu:704] Removing MPU_1 target kernel  -- done 
1.TIDL unload Done! 
   227.897346 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.img.preprocess destructor called (removed from context)
   227.897377 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.oc.pre.proc destructor called (removed from context)
   227.897408 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.oc.post.proc destructor called (removed from context)
   227.897439 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.od.postprocess destructor called (removed from context)
   227.897470 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.dof.plane.seperation destructor called (removed from context)
   227.897501 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.pose.visualization destructor called (removed from context)
   227.897533 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.visual.localization destructor called (removed from context)
   227.897564 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.od.draw.keypoint.detections destructor called (removed from context)
   227.897597 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.od.draw.box.detections destructor called (removed from context)
   227.897629 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.img.hist destructor called (removed from context)
   227.897659 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.sfm destructor called (removed from context)
   227.897690 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.dl.pre.proc destructor called (removed from context)
   227.897720 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.dl.color.blend destructor called (removed from context)
   227.897751 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.dl.draw.box destructor called (removed from context)
   228.032295 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.dl.color.convert destructor called (removed from context)
1.ImgProc unload Done! 
   228.032342 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.display destructor called (removed from context)
   228.032371 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.displaym2m destructor called (removed from context)
   228.032398 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.capture destructor called (removed from context)
   228.032425 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.csitx destructor called (removed from context)
   228.032454 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.hwa.vpac_nf_generic destructor called (removed from context)
   228.032483 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.hwa.vpac_nf_bilateral destructor called (removed from context)
   228.032511 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.hwa.dmpac_sde destructor called (removed from context)
   228.032539 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.hwa.vpac_ldc destructor called (removed from context)
   228.032566 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.hwa.vpac_msc_multi_scale destructor called (removed from context)
   228.032594 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.hwa.vpac_msc_pyramid destructor called (removed from context)
   228.032623 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.hwa.dmpac_dof destructor called (removed from context)
   228.167138 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.hwa.dof_visualize destructor called (removed from context)
   228.167167 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.hwa.vpac_viss destructor called (removed from context)
1.HWA unload Done! 
   228.167203 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.absdiff destructor called (removed from context)
   228.167232 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.accumulate destructor called (removed from context)
   228.167261 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.accumulate_square destructor called (removed from context)
   228.167289 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.accumulate_weighted destructor called (removed from context)
   228.167319 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.add destructor called (removed from context)
   228.167347 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.and destructor called (removed from context)
   228.167374 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.box_3x3 destructor called (removed from context)
   228.167402 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.canny_edge_detector destructor called (removed from context)
   228.167431 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.channel_combine destructor called (removed from context)
   228.301212 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.channel_extract destructor called (removed from context)
   228.301241 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.color_convert destructor called (removed from context)
   228.301269 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.convertdepth destructor called (removed from context)
   228.301298 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.custom_convolution destructor called (removed from context)
   228.301328 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.dilate_3x3 destructor called (removed from context)
   228.301356 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.equalize_histogram destructor called (removed from context)
   228.301386 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.erode_3x3 destructor called (removed from context)
   228.301414 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.fast_corners destructor called (removed from context)
   228.301443 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.gaussian_pyramid destructor called (removed from context)
   228.301472 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.gaussian_3x3 destructor called (removed from context)
   228.301501 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.harris_corners destructor called (removed from context)
   228.301529 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.halfscale_gaussian destructor called (removed from context)
   228.435371 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.histogram destructor called (removed from context)
   228.435400 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.integral_image destructor called (removed from context)
   228.435428 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.laplacian_pyramid destructor called (removed from context)
   228.435458 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.laplacian_reconstruct destructor called (removed from context)
   228.435487 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.table_lookup destructor called (removed from context)
   228.435515 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.magnitude destructor called (removed from context)
   228.435545 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.mean_stddev destructor called (removed from context)
   228.435573 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.median_3x3 destructor called (removed from context)
   228.435602 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.minmaxloc destructor called (removed from context)
   228.435630 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.multiply destructor called (removed from context)
   228.435658 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.non_linear_filter destructor called (removed from context)
   228.569445 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.not destructor called (removed from context)
   228.569473 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.optical_flow_pyr_lk destructor called (removed from context)
   228.569502 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.or destructor called (removed from context)
   228.569531 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.phase destructor called (removed from context)
   228.569558 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.remap destructor called (removed from context)
   228.569586 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.scale_image destructor called (removed from context)
   228.569615 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.sobel_3x3 destructor called (removed from context)
   228.569643 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.subtract destructor called (removed from context)
   228.569671 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.threshold destructor called (removed from context)
   228.569700 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.warp_affine destructor called (removed from context)
   228.569729 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.warp_perspective destructor called (removed from context)
   228.569758 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.xor destructor called (removed from context)
   228.703604 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.supernode destructor called (removed from context)
   228.703696 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 5418dba9a8 of type 00000804 at external count 1, internal count 1, releasing it
   228.703724 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=com.ti.fileio.write.vx_array) now as a part of garbage collection
   228.703751 s:  VX_ZONE_WARNING:[vxReleaseContext:1099] A kernel with name com.ti.fileio.write.vx_array has not been removed, possibly due to a kernel module not being unloaded.
   228.703780 s:  VX_ZONE_WARNING:[vxReleaseContext:1100] Removing as a part of garbage collection
   228.703803 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.fileio.write.vx_array destructor called (removed from context)
   228.703834 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 5418dbb8a0 of type 00000804 at external count 1, internal count 1, releasing it
   228.703862 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=com.ti.fileio.write.vx_image) now as a part of garbage collection
   228.703888 s:  VX_ZONE_WARNING:[vxReleaseContext:1099] A kernel with name com.ti.fileio.write.vx_image has not been removed, possibly due to a kernel module not being unloaded.
   228.703916 s:  VX_ZONE_WARNING:[vxReleaseContext:1100] Removing as a part of garbage collection
   228.703940 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.fileio.write.vx_image destructor called (removed from context)
   228.837764 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 5418dbc798 of type 00000804 at external count 1, internal count 1, releasing it
   228.837791 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=com.ti.fileio.write.tivx_raw_image) now as a part of garbage collection
   228.837821 s:  VX_ZONE_WARNING:[vxReleaseContext:1099] A kernel with name com.ti.fileio.write.tivx_raw_image has not been removed, possibly due to a kernel module not being unloaded.
   228.837851 s:  VX_ZONE_WARNING:[vxReleaseContext:1100] Removing as a part of garbage collection
   228.837874 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.fileio.write.tivx_raw_image destructor called (removed from context)
   228.837902 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 5418dbd690 of type 00000804 at external count 1, internal count 1, releasing it
   228.837929 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=com.ti.fileio.write.vx_tensor) now as a part of garbage collection
   228.837956 s:  VX_ZONE_WARNING:[vxReleaseContext:1099] A kernel with name com.ti.fileio.write.vx_tensor has not been removed, possibly due to a kernel module not being unloaded.
   228.837984 s:  VX_ZONE_WARNING:[vxReleaseContext:1100] Removing as a part of garbage collection
   228.838008 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.fileio.write.vx_tensor destructor called (removed from context)
   228.971843 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 5418dbe588 of type 00000804 at external count 1, internal count 1, releasing it
   228.971871 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=com.ti.fileio.write.vx_user_data_object) now as a part of garbage collection
   228.971899 s:  VX_ZONE_WARNING:[vxReleaseContext:1099] A kernel with name com.ti.fileio.write.vx_user_data_object has not been removed, possibly due to a kernel module not being unloaded.
   228.971929 s:  VX_ZONE_WARNING:[vxReleaseContext:1100] Removing as a part of garbage collection
   228.971952 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.fileio.write.vx_user_data_object destructor called (removed from context)
   228.971981 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 5418e42aa8 of type 0000080f at external count 1, internal count 0, releasing it
   228.972008 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=image_105) now as a part of garbage collection
   228.972376 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 5418e42d60 of type 0000080f at external count 1, internal count 0, releasing it
   228.972409 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=image_106) now as a part of garbage collection
   228.972776 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 5418e43018 of type 0000080f at external count 1, internal count 0, releasing it
   228.972808 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=image_108) now as a part of garbage collection
   229.106945 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 5418e432d0 of type 0000080f at external count 1, internal count 0, releasing it
   229.106978 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=image_109) now as a part of garbage collection
   229.107347 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 5418e43588 of type 0000080f at external count 1, internal count 0, releasing it
   229.107380 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=image_111) now as a part of garbage collection
   229.107743 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 5418e43840 of type 0000080f at external count 1, internal count 0, releasing it
   229.107774 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=image_112) now as a part of garbage collection
   229.108141 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 5418e43af8 of type 0000080f at external count 1, internal count 0, releasing it
   229.108173 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=image_114) now as a part of garbage collection
   229.108538 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 5418e43db0 of type 0000080f at external count 1, internal count 0, releasing it
   229.108569 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=image_115) now as a part of garbage collection
   229.108937 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 5418e44b48 of type 0000080f at external count 1, internal count 0, releasing it
   229.242754 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=image_142) now as a part of garbage collection
   229.243127 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 5418e45b98 of type 0000080f at external count 1, internal count 0, releasing it
   229.243160 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=image_159) now as a part of garbage collection
   229.243191 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 5418e45e50 of type 0000080f at external count 1, internal count 0, releasing it
   229.243219 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=image_160) now as a part of garbage collection
   229.243947 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
   229.243976 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
   229.244002 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
   229.244027 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
1.Context released! 
1.App De-init Done! 
1.App De-init Done! 
   229.244091 s:  VX_ZONE_INIT:[tivxHostDeInitLocal:110] De-Initialization Done for HOST !!!
   229.245820 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
   229.380819 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
   229.382819 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
   229.384819 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
   229.384891 s:  VX_ZONE_INIT:[tivxDeInitLocal:193] De-Initialization Done !!!
APP: Deinit ... !!!
   229.384919 s: REMOTE_SERVICE: Deinit ... !!!
   229.384998 s: REMOTE_SERVICE: Deinit ... Done !!!
IPC: Deinit ... !!!
IPC: Deinit ... Done !!!
APP: Deinit ... Done !!!

0 srikar varaganti over 2 years ago in reply to srikar varaganti

Intellectual 300 points

Logs for OD app processing all nodes in C7x_2 core:

J784S4-EVM@QNX:/ti_fs/vision_apps# vx_2_od_batch.out --cfg app_od_data_v1_1b_1.cfg  
APP: Init QNX ... !!!
appIpcInit: IPC: Init QNX ... !!!
appIpcInit: IPC: Init ... Done !!!
   307.986223 s: REMOTE_SERVICE: Init ... !!!
   307.986320 s: REMOTE_SERVICE: Init ... Done !!!
   307.986352 s: GTC Frequency = 200 MHz
APP: Init ... Done !!!
   307.986379 s:  VX_ZONE_INIT:Enabled
   307.986393 s:  VX_ZONE_ERROR:Enabled
   307.986406 s:  VX_ZONE_WARNING:Enabled
   307.986649 s:  VX_ZONE_INIT:[tivxInitLocal:130] Initialization Done !!!
   307.986695 s:  VX_ZONE_INIT:[tivxHostInitLocal:96] Initialization Done for HOST !!!
2.
#### Setting Default Parameters ####
2.>>>> Scalar defaults <<<<
2.>>>> Pre-Proc Defaults <<<<
2.      Padding = 0
2.>>>> Pipeline Parameters Defaults <<<<
2.Default param set! 
2.
#### Parsing Config file Parameters ####
2.<<<< Setting config file, network file and input file path default to root dir>>>>
2.argc: 3
2.argc i: 0
2.argc i: 1
2.Go into config file
2.<<<< Parse config file >>>>
2.       tidl_config file path: /ti_fs/vision_apps/test_data/psdkra/tidl_models/tidl_io_model_v1_1C_2I1.bin 
2.       tidl_network file path: /ti_fs/vision_apps/test_data/psdkra/tidl_models/tidl_net_model_v1_1C_2I.bin 
2.       input_file_path file path: /ti_fs/vision_apps/test_data/psdkra/od_data_1 
2.       output_file_path file path: /ti_fs/vision_apps/app_tidl_od_model_v1_out 
2.       start_frame: 0 
2.       num_frames: 250 
2.       in_size Width: 1920 x Height 1280 
2.       dl_size Width: 320 x Height: 320 
2.       out_size Width: 1920 x Height: 1280 
2.       viz_th: 0.200000 
2.       num_classes: 4 
2.       delay_in_msecs: 0 
2.       en_out_img_write: 1 
2.       display_option: 1 
2.       num_iterations: 1 
2.       is_interactive: 1 
2.Done with config file parsing!
2.Parsed user params! 
2.
#### Update Parameters bbox, mosiac ####
2.<<<< Update Draw Detections Defaults >>>> 
2.<<<< Update mosiac parameters >>>> 
2.       Output dim: 1920 x 1080 
2.Updated user params! 
2.
#### APP INIT ####
2.       Created OpenVx context 
2.       Set and load h/w, imgproc and TIDl kernals 
   307.993341 s:  VX_ZONE_INFO:Enabled
2.       Created Input image Object and input images array 
2.       Scaler Init Done! 
Computing checksum at 0x00000043F1B39040, size = 18394968
2.       TIDL Init Done! 
2.       Pre Proc Update Done! 
2.       Pre Proc Init Done! 
2.       Draw detections Update Done! 
2.       Draw Detections Init Done! 
2.       Img Mosaic Init Done! 
2.       Display Init Done! 
2.       Set Performance 
2.       init params init
   308.411270 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel com.ti.display
   308.411412 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_153
   308.412114 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_153 completed
   308.412142 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel com.ti.display ...
   308.412165 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel com.ti.display ... done !!!
   308.438117 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=62, pipe=0)
   308.438182 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=59, pipe=0)
   308.438440 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=62, pipe=0)
   308.438492 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
2.       grpx init
2.App Init Done! 
2.
#### Create Graph ####
   308.439986 s:  VX_ZONE_INFO:[ownKernelGetTarget:564] inside first if loop inside kernels.c   308.440000 s:  VX_ZONE_INFO:[ownKernelGetTarget:572] inside second if loop inside kernels.c   308.440014 s:
  VX_ZONE_INFO:[ownKernelGetTarget:584] Target String VPAC_MSC2
   308.440041 s:  VX_ZONE_INFO:[ownKernelGetTarget:585] Kernel Num_targets 4
   308.440059 s:  VX_ZONE_INFO:[ownKernelGetTarget:586] target_id 516
   308.440111 s:  VX_ZONE_INFO:[ownIsValidReference:122] Reference was NULL
   308.440139 s:  VX_ZONE_INFO:[ownIsValidReference:122] Reference was NULL
   308.440165 s:  VX_ZONE_INFO:[ownIsValidReference:122] Reference was NULL
2.       Scaler Node Created 
   308.440220 s:  VX_ZONE_INFO:[ownKernelGetTarget:564] inside first if loop inside kernels.c   308.440234 s:  VX_ZONE_INFO:[ownKernelGetTarget:572] inside second if loop inside kernels.c   308.440249 s:
  VX_ZONE_INFO:[ownKernelGetTarget:584] Target String DSP_C7-2
   308.440273 s:  VX_ZONE_INFO:[ownKernelGetTarget:585] Kernel Num_targets 35
   308.440292 s:  VX_ZONE_INFO:[ownKernelGetTarget:586] target_id 0
2.       Pre Proc Node Created 
   308.440350 s:  VX_ZONE_INFO:[tivxTIDLNode:87] Number of parameters in kernel definition = 8 
   308.440375 s:  VX_ZONE_INFO:[tivxTIDLNode:132] Kernel used for node creation = 3289a47480 
   308.574941 s:  VX_ZONE_INFO:[ownKernelGetTarget:564] inside first if loop inside kernels.c   308.574955 s:  VX_ZONE_INFO:[ownKernelGetTarget:572] inside second if loop inside kernels.c   308.574972 s:
  VX_ZONE_INFO:[ownKernelGetTarget:584] Target String DSP_C7-2
   308.574998 s:  VX_ZONE_INFO:[ownKernelGetTarget:585] Kernel Num_targets 35
   308.575016 s:  VX_ZONE_INFO:[ownKernelGetTarget:586] target_id 0
   308.575088 s:  VX_ZONE_INFO:[ownIsValidReference:122] Reference was NULL
2.       STIDL Node Created 
   308.575170 s:  VX_ZONE_INFO:[ownKernelGetTarget:564] inside first if loop inside kernels.c   308.575183 s:  VX_ZONE_INFO:[ownKernelGetTarget:572] inside second if loop inside kernels.c   308.575197 s:
  VX_ZONE_INFO:[ownKernelGetTarget:584] Target String DSP_C7-2
   308.575221 s:  VX_ZONE_INFO:[ownKernelGetTarget:585] Kernel Num_targets 35
   308.575239 s:  VX_ZONE_INFO:[ownKernelGetTarget:586] target_id 0
2.       draw detections Node Created 
2.       Mosaic Node Created 
   308.575353 s:  VX_ZONE_INFO:[ownKernelGetTarget:564] inside first if loop inside kernels.c   308.575367 s:  VX_ZONE_INFO:[ownKernelGetTarget:572] inside second if loop inside kernels.c   308.575380 s:
  VX_ZONE_INFO:[ownKernelGetTarget:584] Target String DISPLAY1
   308.575404 s:  VX_ZONE_INFO:[ownKernelGetTarget:585] Kernel Num_targets 2
   308.575423 s:  VX_ZONE_INFO:[ownKernelGetTarget:586] target_id 520
2.       Display Node Created 
2.       Pipelining flow enabled 
2.
Setting Node Paramete Bufs
2.scaler
2.scaler output
2.preproc
2.tidl
2.draw detections
2.App Create Graph Done! 
2.
#### Verify Graph ####
   308.710363 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel com.ti.hwa.vpac_msc_multi_scale
   308.710395 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel com.ti.img_proc.img.preprocess
   308.710420 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel com.ti.tidl:1:1
   308.710465 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel com.ti.img_proc.od.draw.box.detections
   308.710490 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel com.ti.display
   308.714351 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node scaler_node
   308.718150 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node scaler_node completed
   308.718308 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel com.ti.hwa.vpac_msc_multi_scale ...
   308.718335 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel com.ti.hwa.vpac_msc_multi_scale ... done !!!
   308.718361 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node PreProcNode
   308.719483 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node PreProcNode completed
   308.719644 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 1, kernel com.ti.img_proc.img.preprocess ...
   308.719671 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 1, kernel com.ti.img_proc.img.preprocess ... done !!!
   308.719696 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node tidl_node
   309.048808 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node tidl_node completed
   309.049025 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 2, kernel com.ti.tidl:1:1 ...
   309.049050 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 2, kernel com.ti.tidl:1:1 ... done !!!
   309.049074 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node DrawBoxDetectionsNode
   309.050133 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node DrawBoxDetectionsNode completed
   309.050295 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 3, kernel com.ti.img_proc.od.draw.box.detections ...
   309.050321 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 3, kernel com.ti.img_proc.od.draw.box.detections ... done !!!
   309.050350 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node DisplayNode
   309.051090 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node DisplayNode completed
   309.051247 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 4, kernel com.ti.display ...
   309.051271 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 4, kernel com.ti.display ... done !!!
2.      App Verify Graph Done!
2.App Verify Graph Done!
2.App Send MSC Command Done!
2.App Send MSC Command Done!
2.App Verify Graph Done! 
2.
#### Graph Interactive ####
2.PIPELINE_FLOW enabled 
2.>>>> Pipeline Parameters Defaults <<<<
2.Running frame 0
2.Running frame 0
2.in app_run_graph_for_one_frame_pipeline 
2.       (Enqueing) Pipeline number: -3 
2.       Reading input image file from path at enque count: 0 and yuv counter: 0
2.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000000.bmp


 =========================
 TIDL Demo - Object Detection
 =========================

 p: Print performance statistics

 x: Exit

 Enter Choice: 2.        time taken for reading .bmp file : in 188431 msec!
   309.365969 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   309.366049 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   309.366295 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   309.366325 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   309.366350 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   309.366619 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=215, pipe=0)
   309.366643 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=212, pipe=0)
   309.390159 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=215, pipe=0)
   309.390193 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   309.390346 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
2.       time taken for converting rgb to yuv file : in 24808 msec!
2.       Reading input image file from path at enque count: 0 and yuv counter: 1
2.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000001.bmp
2.       time taken for reading .bmp file : in 189976 msec!
   309.580805 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   309.580892 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   309.581134 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   309.581162 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   309.581187 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   309.581455 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=219, pipe=0)
   309.581479 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=216, pipe=0)
   309.604961 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=219, pipe=0)
   309.604994 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   309.605143 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
2.       time taken for converting rgb to yuv file : in 24696 msec!
2.App Reading Input Done!
2.       Enque input image array at enque count: 0 
   309.605215 s:  VX_ZONE_INFO:[ownDataRefQueueEnqueueReadyRef:91] Q (queue=170, ref=5)
   309.605246 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=207, pipe=0)
   309.605268 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=63, pipe=0)
2.App Process Graph Done!
2.app_tidl_od: Frame ID 0 of 250 ... Done.
2.Running frame 2
2.Running frame 2
2.in app_run_graph_for_one_frame_pipeline 
2.       (Enqueing) Pipeline number: -2 
2.       Reading input image file from path at enque count: 1 and yuv counter: 2
2.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000002.bmp
   309.648005 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=207, pipe=0)
   309.648043 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
2.       time taken for reading .bmp file : in 174088 msec!
   309.779887 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   309.780055 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   309.780290 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   309.780320 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   309.780347 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   309.780555 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=223, pipe=0)
   309.780581 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=220, pipe=0)
   309.804322 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=223, pipe=0)
   309.804351 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   309.804501 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
2.       time taken for converting rgb to yuv file : in 24980 msec!
2.       Reading input image file from path at enque count: 1 and yuv counter: 3
2.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000003.bmp
2.       time taken for reading .bmp file : in 169986 msec!
   309.974979 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   309.975147 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   309.975389 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   309.975419 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   309.975445 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   309.975654 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=227, pipe=0)
   309.975680 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=224, pipe=0)
   309.999346 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=227, pipe=0)
   309.999374 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   309.999522 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
2.       time taken for converting rgb to yuv file : in 24913 msec!
2.App Reading Input Done!
2.       Enque input image array at enque count: 1 
   309.999595 s:  VX_ZONE_INFO:[ownDataRefQueueEnqueueReadyRef:91] Q (queue=170, ref=8)
   309.999624 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=208, pipe=1)
   309.999645 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=70, pipe=1)
2.App Process Graph Done!
2.app_tidl_od: Frame ID 2 of 250 ... Done.
2.Running frame 4
2.Running frame 4
2.in app_run_graph_for_one_frame_pipeline 
2.       (Enqueing) Pipeline number: -1 
2.       Reading input image file from path at enque count: 2 and yuv counter: 4
2.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000004.bmp
   310.046754 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=208, pipe=1)
   310.046792 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
2.       time taken for reading .bmp file : in 171809 msec!
   310.171991 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   310.172157 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   310.172397 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   310.172427 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   310.172453 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   310.172662 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=231, pipe=0)
   310.172688 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=228, pipe=0)
   310.196391 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=231, pipe=0)
   310.196420 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   310.196563 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
2.       time taken for converting rgb to yuv file : in 24941 msec!
2.       Reading input image file from path at enque count: 2 and yuv counter: 5
2.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000005.bmp
2.       time taken for reading .bmp file : in 187850 msec!
   310.384907 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   310.385080 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   310.385314 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   310.385347 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   310.385374 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   310.385582 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=235, pipe=0)
   310.385607 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=232, pipe=0)
   310.409263 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=235, pipe=0)
   310.409290 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   310.409436 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
2.       time taken for converting rgb to yuv file : in 24903 msec!
2.App Reading Input Done!
2.       Enque input image array at enque count: 2 
   310.409508 s:  VX_ZONE_INFO:[ownDataRefQueueEnqueueReadyRef:91] Q (queue=170, ref=11)
   310.409536 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=209, pipe=2)
   310.409557 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=71, pipe=2)
2.App Process Graph Done!
2.app_tidl_od: Frame ID 4 of 250 ... Done.
2.Running frame 6
2.Running frame 6
2.in app_run_graph_for_one_frame_pipeline 
2.       (Enqueing) Pipeline number: 0 
2.       Reading input image file from path at enque count: 3 and yuv counter: 6
2.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000006.bmp
   310.463428 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=209, pipe=2)
   310.463468 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
2.       time taken for reading .bmp file : in 175727 msec!
   310.585816 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   310.585981 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   310.586225 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   310.586254 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   310.586278 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   310.586485 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=239, pipe=0)
   310.586509 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=236, pipe=0)
   310.610204 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=239, pipe=0)
   310.610231 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   310.610382 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
2.       time taken for converting rgb to yuv file : in 24932 msec!
2.       Reading input image file from path at enque count: 3 and yuv counter: 7
2.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000007.bmp
2.       time taken for reading .bmp file : in 169701 msec!
   310.780558 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   310.780725 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   310.780965 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   310.780995 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   310.781022 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   310.781231 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=243, pipe=0)
   310.781256 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=240, pipe=0)
   310.804937 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=243, pipe=0)
   310.804963 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   310.805107 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
2.       time taken for converting rgb to yuv file : in 24905 msec!
2.App Reading Input Done!
2.       Enque input image array at enque count: 3 
   310.805180 s:  VX_ZONE_INFO:[ownDataRefQueueEnqueueReadyRef:91] Q (queue=170, ref=14)
   310.805209 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=210, pipe=3)
   310.805231 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=72, pipe=3)
2.       (Dequeing) Pipeline count: 1 
2.       Deque Input image
   310.805293 s:  VX_ZONE_INFO:[ownDataRefQueueDequeueDoneRef:156] DQ (queue=171, ref=5)
2.       Reading input image file from path at enque count: 0 and yuv counter: 0
2.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000006.bmp
   310.863453 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=210, pipe=3)
   310.863492 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
2.       time taken for reading .bmp file : in 175829 msec!
   310.981578 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   310.981654 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   310.981902 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   310.981933 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   310.981958 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   310.982227 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=247, pipe=0)
   310.982251 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=244, pipe=0)
   311.005795 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=247, pipe=0)
   311.005828 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   311.005976 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
2.       time taken for converting rgb to yuv file : in 24758 msec!
2.       Reading input image file from path at enque count: 0 and yuv counter: 1
2.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000007.bmp
2.       time taken for reading .bmp file : in 169844 msec!
   311.176294 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   311.176370 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   311.176612 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   311.176641 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   311.176667 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   311.176936 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=251, pipe=0)
   311.176961 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=248, pipe=0)
   311.200501 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=251, pipe=0)
   311.200534 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   311.200681 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
2.       time taken for converting rgb to yuv file : in 24744 msec!
   311.200735 s:  VX_ZONE_INFO:[ownDataRefQueueEnqueueReadyRef:91] Q (queue=170, ref=5)
   311.200764 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=211, pipe=4)
   311.200785 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=73, pipe=4)
2.App Process Graph Done!
2.app_tidl_od: Frame ID 6 of 250 ... Done.
2.Running frame 8
2.Running frame 8
2.in app_run_graph_for_one_frame_pipeline 
2.       (Dequeing) Pipeline count: 1 
2.       Deque Input image
   311.200893 s:  VX_ZONE_INFO:[ownDataRefQueueDequeueDoneRef:156] DQ (queue=171, ref=8)
2.       Reading input image file from path at enque count: 1 and yuv counter: 2
2.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000008.bmp
   311.246765 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=211, pipe=4)
   311.246800 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
2.       time taken for reading .bmp file : in 175222 msec!
   311.376572 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   311.376648 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   311.376896 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   311.376927 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   311.376952 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   311.377222 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=255, pipe=0)
   311.377246 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=252, pipe=0)
x   311.400802 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=255, pipe=0)
   311.400830 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   311.400974 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
2.       time taken for converting rgb to yuv file : in 24763 msec!
2.       Reading input image file from path at enque count: 1 and yuv counter: 3
2.File Name: /ti_fs/vision_apps/test_data/psdkra/od_data_1/0000000009.bmp

2.
2.       time taken for reading .bmp file : in 184236 msec!
   311.585685 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel org.khronos.openvx.color_convert
   311.585763 s:  VX_ZONE_INFO:[ownNodeKernelInit:512] Calling create callback for node node_163
   311.586008 s:  VX_ZONE_INFO:[ownNodeKernelInit:518] Create callback for node node_163 completed
   311.586039 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel org.khronos.openvx.color_convert ...
   311.586064 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel org.khronos.openvx.color_convert ... done !!!
   311.586336 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=259, pipe=0)
   311.586362 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=256, pipe=0)
   311.609894 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=259, pipe=0)
   311.609926 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
   311.610076 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
2.       time taken for converting rgb to yuv file : in 24747 msec!
   311.610132 s:  VX_ZONE_INFO:[ownDataRefQueueEnqueueReadyRef:91] Q (queue=170, ref=8)
   311.610162 s:  VX_ZONE_INFO:[ownGraphScheduleGraph:782] Scheduling Graph (graph=207, pipe=0)
   311.610184 s:  VX_ZONE_INFO:[ownNodeKernelSchedule:619] Scheduling Node (node=63, pipe=0)
2.App Process Graph Done!
2.app_tidl_od: Frame ID 8 of 250 ... Done.
2.app_tidl_od: Iteration 0 of 1000000000 ... Done.
   311.663386 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:695] Graph Completed (graph=207, pipe=0)
   311.663413 s:  VX_ZONE_INFO:[ownCheckGraphCompleted:720] All Graphs Completed
2.       App Run Graph Done!
2.App Run Graph Done! 
   311.717853 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.img.mosaic:1 destructor called (removed from context)
   311.731666 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.tidl:1:1 destructor called (removed from context)
   311.731840 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
2.App Delete Graph Done! 
2.App Delete Graph Done! 
2.Input image release Done! 
2.Scaler deinit Done! 
2.Pre-Proc deinit Done! 
2.TIDL deinit Done! 
2.Draw detections deinit Done! 
2.Mosaic deinit Done! 
2.Display deinit Done! 
   311.963433 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
2.Graphics deinit Done! 
   311.963956 s:  VX_ZONE_INFO:[tivxRemoveTargetKernelTIDL_mpu:699] Removing MPU_1 target kernel 
   311.963984 s:  VX_ZONE_INFO:[tivxRemoveTargetKernelTIDL_mpu:704] Removing MPU_1 target kernel  -- done 
2.TIDL unload Done! 
   311.964020 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.img.preprocess destructor called (removed from context)
   311.964052 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.oc.pre.proc destructor called (removed from context)
   311.964082 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.oc.post.proc destructor called (removed from context)
   311.964114 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.od.postprocess destructor called (removed from context)
   311.964145 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.dof.plane.seperation destructor called (removed from context)
   311.964177 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.pose.visualization destructor called (removed from context)
   311.964208 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.visual.localization destructor called (removed from context)
   311.964240 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.od.draw.keypoint.detections destructor called (removed from context)
   311.964273 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.od.draw.box.detections destructor called (removed from context)
   311.964305 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.img.hist destructor called (removed from context)
   311.964334 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.sfm destructor called (removed from context)
   311.964365 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.dl.pre.proc destructor called (removed from context)
   311.964395 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.dl.color.blend destructor called (removed from context)
   311.964425 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.dl.draw.box destructor called (removed from context)
   312.098962 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.img_proc.dl.color.convert destructor called (removed from context)
2.ImgProc unload Done! 
   312.099011 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.display destructor called (removed from context)
   312.099041 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.displaym2m destructor called (removed from context)
   312.099071 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.capture destructor called (removed from context)
   312.099099 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.csitx destructor called (removed from context)
   312.099130 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.hwa.vpac_nf_generic destructor called (removed from context)
   312.099161 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.hwa.vpac_nf_bilateral destructor called (removed from context)
   312.099192 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.hwa.dmpac_sde destructor called (removed from context)
   312.099222 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.hwa.vpac_ldc destructor called (removed from context)
   312.099251 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.hwa.vpac_msc_multi_scale destructor called (removed from context)
   312.099282 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.hwa.vpac_msc_pyramid destructor called (removed from context)
   312.099313 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.hwa.dmpac_dof destructor called (removed from context)
   312.233807 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.hwa.dof_visualize destructor called (removed from context)
   312.233839 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.hwa.vpac_viss destructor called (removed from context)
2.HWA unload Done! 
   312.233878 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.absdiff destructor called (removed from context)
   312.233907 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.accumulate destructor called (removed from context)
   312.233936 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.accumulate_square destructor called (removed from context)
   312.233966 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.accumulate_weighted destructor called (removed from context)
   312.233995 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.add destructor called (removed from context)
   312.234023 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.and destructor called (removed from context)
   312.234051 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.box_3x3 destructor called (removed from context)
   312.234079 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.canny_edge_detector destructor called (removed from context)
   312.234108 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.channel_combine destructor called (removed from context)
   312.367967 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.channel_extract destructor called (removed from context)
   312.367997 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.color_convert destructor called (removed from context)
   312.368026 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.convertdepth destructor called (removed from context)
   312.368056 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.custom_convolution destructor called (removed from context)
   312.368086 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.dilate_3x3 destructor called (removed from context)
   312.368115 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.equalize_histogram destructor called (removed from context)
   312.368145 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.erode_3x3 destructor called (removed from context)
   312.368173 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.fast_corners destructor called (removed from context)
   312.368202 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.gaussian_pyramid destructor called (removed from context)
   312.368231 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.gaussian_3x3 destructor called (removed from context)
   312.368260 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.harris_corners destructor called (removed from context)
   312.368290 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.halfscale_gaussian destructor called (removed from context)
   312.502127 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.histogram destructor called (removed from context)
   312.502158 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.integral_image destructor called (removed from context)
   312.502188 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.laplacian_pyramid destructor called (removed from context)
   312.502220 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.laplacian_reconstruct destructor called (removed from context)
   312.502250 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.table_lookup destructor called (removed from context)
   312.502281 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.magnitude destructor called (removed from context)
   312.502311 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.mean_stddev destructor called (removed from context)
   312.502341 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.median_3x3 destructor called (removed from context)
   312.502371 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.minmaxloc destructor called (removed from context)
   312.502401 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.multiply destructor called (removed from context)
   312.502430 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.non_linear_filter destructor called (removed from context)
   312.636287 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.not destructor called (removed from context)
   312.636317 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.optical_flow_pyr_lk destructor called (removed from context)
   312.636348 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.or destructor called (removed from context)
   312.636378 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.phase destructor called (removed from context)
   312.636407 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.remap destructor called (removed from context)
   312.636436 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.scale_image destructor called (removed from context)
   312.636467 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.sobel_3x3 destructor called (removed from context)
   312.636497 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.subtract destructor called (removed from context)
   312.636526 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.threshold destructor called (removed from context)
   312.636557 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.warp_affine destructor called (removed from context)
   312.636587 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.warp_perspective destructor called (removed from context)
   312.636618 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel org.khronos.openvx.xor destructor called (removed from context)
   312.770449 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.supernode destructor called (removed from context)
   312.770544 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 3289a429a8 of type 00000804 at external count 1, internal count 1, releasing it
   312.770574 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=com.ti.fileio.write.vx_array) now as a part of garbage collection
   312.770605 s:  VX_ZONE_WARNING:[vxReleaseContext:1099] A kernel with name com.ti.fileio.write.vx_array has not been removed, possibly due to a kernel module not being unloaded.
   312.770636 s:  VX_ZONE_WARNING:[vxReleaseContext:1100] Removing as a part of garbage collection
   312.770660 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.fileio.write.vx_array destructor called (removed from context)
   312.770690 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 3289a438a0 of type 00000804 at external count 1, internal count 1, releasing it
   312.770719 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=com.ti.fileio.write.vx_image) now as a part of garbage collection
   312.770748 s:  VX_ZONE_WARNING:[vxReleaseContext:1099] A kernel with name com.ti.fileio.write.vx_image has not been removed, possibly due to a kernel module not being unloaded.
   312.770779 s:  VX_ZONE_WARNING:[vxReleaseContext:1100] Removing as a part of garbage collection
   312.770803 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.fileio.write.vx_image destructor called (removed from context)
   312.904606 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 3289a44798 of type 00000804 at external count 1, internal count 1, releasing it
   312.904636 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=com.ti.fileio.write.tivx_raw_image) now as a part of garbage collection
   312.904666 s:  VX_ZONE_WARNING:[vxReleaseContext:1099] A kernel with name com.ti.fileio.write.tivx_raw_image has not been removed, possibly due to a kernel module not being unloaded.
   312.904698 s:  VX_ZONE_WARNING:[vxReleaseContext:1100] Removing as a part of garbage collection
   312.904722 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.fileio.write.tivx_raw_image destructor called (removed from context)
   312.904752 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 3289a45690 of type 00000804 at external count 1, internal count 1, releasing it
   312.904782 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=com.ti.fileio.write.vx_tensor) now as a part of garbage collection
   312.904814 s:  VX_ZONE_WARNING:[vxReleaseContext:1099] A kernel with name com.ti.fileio.write.vx_tensor has not been removed, possibly due to a kernel module not being unloaded.
   312.904846 s:  VX_ZONE_WARNING:[vxReleaseContext:1100] Removing as a part of garbage collection
   312.904870 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.fileio.write.vx_tensor destructor called (removed from context)
   313.038679 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 3289a46588 of type 00000804 at external count 1, internal count 1, releasing it
   313.038709 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=com.ti.fileio.write.vx_user_data_object) now as a part of garbage collection
   313.038740 s:  VX_ZONE_WARNING:[vxReleaseContext:1099] A kernel with name com.ti.fileio.write.vx_user_data_object has not been removed, possibly due to a kernel module not being unloaded.
   313.038773 s:  VX_ZONE_WARNING:[vxReleaseContext:1100] Removing as a part of garbage collection
   313.038797 s:  VX_ZONE_INFO:[ownDestructKernel:32] Kernel com.ti.fileio.write.vx_user_data_object destructor called (removed from context)
   313.038830 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 3289acaaa8 of type 0000080f at external count 1, internal count 0, releasing it
   313.038860 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=image_105) now as a part of garbage collection
   313.039237 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 3289acad60 of type 0000080f at external count 1, internal count 0, releasing it
   313.039273 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=image_106) now as a part of garbage collection
   313.039646 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 3289acb018 of type 0000080f at external count 1, internal count 0, releasing it
   313.039680 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=image_108) now as a part of garbage collection
   313.173876 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 3289acb2d0 of type 0000080f at external count 1, internal count 0, releasing it
   313.173912 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=image_109) now as a part of garbage collection
   313.174281 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 3289acb588 of type 0000080f at external count 1, internal count 0, releasing it
   313.174316 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=image_111) now as a part of garbage collection
   313.174684 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 3289acb840 of type 0000080f at external count 1, internal count 0, releasing it
   313.174718 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=image_112) now as a part of garbage collection
   313.175082 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 3289acbaf8 of type 0000080f at external count 1, internal count 0, releasing it
   313.175117 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=image_114) now as a part of garbage collection
   313.175478 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 3289acbdb0 of type 0000080f at external count 1, internal count 0, releasing it
   313.175513 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=image_115) now as a part of garbage collection
   313.175883 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 3289accb48 of type 0000080f at external count 1, internal count 0, releasing it
   313.309683 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=image_142) now as a part of garbage collection
   313.310065 s:  VX_ZONE_WARNING:[vxReleaseContext:1089] Found a reference 3289acdb98 of type 0000080f at external count 1, internal count 0, releasing it
   313.310101 s:  VX_ZONE_WARNING:[vxReleaseContext:1090] Releasing reference (name=image_159) now as a part of garbage collection
   313.310168 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
   313.310197 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
   313.310225 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
   313.310251 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
2.Context released! 
2.App De-init Done! 
2.App De-init Done! 
   313.310318 s:  VX_ZONE_INIT:[tivxHostDeInitLocal:110] De-Initialization Done for HOST !!!
   313.311821 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
   313.313824 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
   313.315820 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
   313.317820 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
   313.451683 s:  VX_ZONE_INIT:[tivxDeInitLocal:193] De-Initialization Done !!!
APP: Deinit ... !!!
   313.451709 s: REMOTE_SERVICE: Deinit ... !!!
   313.451772 s: REMOTE_SERVICE: Deinit ... Done !!!
IPC: Deinit ... !!!
IPC: Deinit ... Done !!!
APP: Deinit ... Done !!!

0 srikar varaganti over 2 years ago in reply to srikar varaganti

Intellectual 300 points

Logs from vision_apps_init.sh script:

J784S4-EVM@QNX:/ti_fs/vision_apps# . ./vision_apps_init.sh 
[1] 544790
J784S4-EVM@QNX:/ti_fs/vision_apps# [MCU2_0]      4.606916 s: CIO: Init ... Done !!!
[MCU2_0]      4.606965 s: ### CPU Frequency = 1000000000 Hz
[MCU2_0]      4.606989 s: CPU is running FreeRTOS
[MCU2_0]      4.607005 s: APP: Init ... !!!
[MCU2_0]      4.607040 s: SCICLIENT: Init ... !!!
[MCU2_0]      4.607170 s: SCICLIENT: DMSC FW version [9.0.6--v09.00.06 (Kool Koala)]
[MCU2_0]      4.607199 s: SCICLIENT: DMSC FW revision 0x9  
[MCU2_0]      4.607218 s: SCICLIENT: DMSC FW ABI revision 3.1
[MCU2_0]      4.607238 s: SCICLIENT: Init ... Done !!!
[MCU2_0]      4.607256 s: UDMA: Init ... !!!
[MCU2_0]      4.608120 s: UDMA: Init ... Done !!!
[MCU2_0]      4.608157 s: UDMA: Init for CSITX/CSIRX ... !!!
[MCU2_0]      4.608602 s: UDMA: Init for CSITX/CSIRX ... Done !!!
[MCU2_0]      4.608639 s: MEM: Init ... !!!
[MCU2_0]      4.608662 s: MEM: Created heap (DDR_LOCAL_MEM, id=0, flags=0x00000004) @ e1000000 of size 16777216 bytes !!!
[MCU2_0]      4.608706 s: MEM: Created heap (L3_MEM, id=1, flags=0x00000000) @ 60000000 of size 262144 bytes !!!
[MCU2_0]      4.608742 s: MEM: Init ... Done !!!
[MCU2_0]      4.608758 s: IPC: Init ... !!!
[MCU2_0]      4.608798 s: IPC: 11 CPUs participating in IPC !!!
[MCU2_0]      4.615177 s: IPC: Init ... Done !!!
[MCU2_0]      4.615214 s: APP: Syncing with 10 CPUs ... !!!
[MCU2_0]      5.290834 s: APP: Syncing with 10 CPUs ... Done !!!
[MCU2_0]      5.290857 s: REMOTE_SERVICE: Init ... !!!
[MCU2_0]      5.291665 s: REMOTE_SERVICE: Init ... Done !!!
[MCU2_0]      5.291698 s: ETHFW: Init ... !!!
[MCU2_0]      5.398077 s: Warning: Using 6 MAC address(es) from static pool
[MCU2_0]      5.398213 s: ETHFW: Shared multicasts (software fanout):
[MCU2_0]      5.398241 s: ETHFW: Reserved multicasts:
[MCU2_0]      5.398259 s:   01:80:c2:00:00:0e
[MCU2_0]      5.398280 s:   01:1b:19:00:00:00
[MCU2_0]      5.398415 s: EnetMcm: CPSW_9G on MAIN NAVSS
[MCU2_0]      5.412831 s: 
[MCU2_0] ETHFW Version   : 0.02.00
[MCU2_0]      5.412863 s: ETHFW Build Date: Nov 17, 2023
[MCU2_0]      5.412882 s: ETHFW Build Time: 15:33:15
[MCU2_0]      5.412899 s: ETHFW Commit SHA: 
[MCU2_0]      5.412934 s: ETHFW: Init ... DONE !!!
[MCU2_0]      5.412977 s: unibase-1.1.4-jacinto
[MCU2_0]      5.413473 s: Starting lwIP, local interface IP is dhcp-enabled
[MCU2_0]      5.419158 s: Host MAC address: 70:ff:76:1d:92:c3
[MCU2_0]      5.421476 s: ETHFW: Enable gPTP on MAC port 3 (tilld3)
[MCU2_0]      5.421563 s: ETHFW: TimeSync PTP enabled
[MCU2_0]      5.421585 s: ETHFW: Remove server Init ... !!!
[MCU2_0]      5.421683 s: CpswProxyServer: Virtual port configuration:
[MCU2_0]      5.421717 s:   mpu_1_0 <-> Switch port 0: mpu_1_0_ethswitch-device-0
[MCU2_0]      5.421745 s:   mcu_2_1 <-> Switch port 1: mcu_2_1_ethswitch-device-1
[MCU2_0]      5.421778 s:   mpu_1_0 <-> MAC port 1: mpu_1_0_ethmac-device-1
[MCU2_0]      5.421804 s:   mcu_2_1 <-> MAC port 4: mcu_2_1_ethmac-device-4
[MCU2_0]      5.422253 s: CpswProxyServer: initialization completed (core: mcu2_0)
[MCU2_0]      5.422288 s: ETHFW: Remove server Init ... DONE !!!
[MCU2_0]      5.423126 s: [LWIPIF_LWIP] Enet LLD netif initialized successfully
[MCU2_0]      5.423185 s: Added interface 'ti1', IP is 0.0.0.0
[MCU2_0]      5.471785 s: FVID2: Init ... !!!
[MCU2_0]      5.471854 s: FVID2: Init ... Done !!!
[MCU2_0]      5.471873 s: SCICLIENT: Sciclient_pmSetModuleState module=275 state=2
[MCU2_0]      5.472005 s: SCICLIENT: Sciclient_pmSetModuleState success
[MCU2_0]      5.472040 s: DSS: Init ... !!!
[MCU2_0]      5.472057 s: DSS: Display type is eDP !!!
[MCU2_0]      5.472073 s: DSS: M2M Path is enabled !!!
[MCU2_0]      5.472091 s: DSS: SoC init ... !!!
[MCU2_0]      5.472106 s: SCICLIENT: Sciclient_pmSetModuleState module=218 state=0
[MCU2_0]      5.472169 s: SCICLIENT: Sciclient_pmSetModuleState success
[MCU2_0]      5.472191 s: SCICLIENT: Sciclient_pmSetModuleState module=404 state=2
[MCU2_0]      5.472315 s: SCICLIENT: Sciclient_pmSetModuleState success
[MCU2_0]      5.472335 s: SCICLIENT: Sciclient_pmSetModuleState module=217 state=2
[MCU2_0]      5.472425 s: SCICLIENT: Sciclient_pmSetModuleState success
[MCU2_0]      5.472446 s: SCICLIENT: Sciclient_pmSetModuleState module=404 state=2
[MCU2_0]      5.472495 s: SCICLIENT: Sciclient_pmSetModuleState success
[MCU2_0]      5.472515 s: SCICLIENT: Sciclient_pmSetModuleState module=217 state=2
[MCU2_0]      5.472561 s: SCICLIENT: Sciclient_pmSetModuleState success
[MCU2_0]      5.472581 s: SCICLIENT: Sciclient_pmSetModuleState module=218 state=0
[MCU2_0]      5.472626 s: SCICLIENT: Sciclient_pmSetModuleState success
[MCU2_0]      5.472648 s: SCICLIENT: Sciclient_pmSetModuleClkFreq module=218 clk=3 freq=148500000
[MCU2_0]      5.472747 s: SCICLIENT: Sciclient_pmSetModuleClkFreq success
[MCU2_0]      5.472770 s: SCICLIENT: Sciclient_pmModuleClkRequest module=218 clk=3 state=2 flag=2
[MCU2_0]      5.472896 s: SCICLIENT: Sciclient_pmModuleClkRequest success
[MCU2_0]      5.472921 s: SCICLIENT: Sciclient_pmSetModuleState module=218 state=2
[MCU2_0]      5.473013 s: SCICLIENT: Sciclient_pmSetModuleState success
[MCU2_0]      5.473034 s: DSS: SoC init ... Done !!!
[MCU2_0]      5.473051 s: DSS: Board init ... !!!
[MCU2_0]      5.473066 s: DSS: Board init ... Done !!!
[MCU2_0]      5.535826 s: DSS: Init ... Done !!!
[MCU2_0]      5.535876 s: VHWA: VPAC Init ... !!!
[MCU2_0]      5.535895 s: SCICLIENT: Sciclient_pmSetModuleState module=399 state=2
[MCU2_0]      5.536015 s: SCICLIENT: Sciclient_pmSetModuleState success
[MCU2_0]      5.536041 s: VHWA: LDC Init ... !!!
[MCU2_0]      5.537847 s: VHWA: LDC Init ... Done !!!
[MCU2_0]      5.537886 s: VHWA: MSC Init ... !!!
[MCU2_0]      5.544137 s: VHWA: MSC Init ... Done !!!
[MCU2_0]      5.544175 s: VHWA: NF Init ... !!!
[MCU2_0]      5.545076 s: VHWA: NF Init ... Done !!!
[MCU2_0]      5.545113 s: VHWA: VISS Init ... !!!
[MCU2_0]      5.550957 s: VHWA: VISS Init ... Done !!!
[MCU2_0]      5.551001 s: VHWA: VPAC Init ... Done !!!
[MCU2_0]      5.551033 s:  VX_ZONE_INIT:Enabled
[MCU2_0]      5.551052 s:  VX_ZONE_ERROR:Enabled
[MCU2_0]      5.551069 s:  VX_ZONE_WARNING:Enabled
[MCU2_0]      5.552423 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target MCU2-0 
[MCU2_0]      5.552533 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target VPAC_NF 
[MCU2_0]      5.552622 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target VPAC_LDC1 
[MCU2_0]      5.552715 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target VPAC_MSC1 
[MCU2_0]      5.552895 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target VPAC_MSC2 
[MCU2_0]      5.553014 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target VPAC_VISS1 
[MCU2_0]      5.553110 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target CAPTURE1 
[MCU2_0]      5.553211 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target CAPTURE2 
[MCU2_0]      5.553305 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target DISPLAY1 
[MCU2_0]      5.553411 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target DISPLAY2 
[MCU2_0]      5.553508 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target CSITX 
[MCU2_0]      5.553610 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target CAPTURE3 
[MCU2_0]      5.553711 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target CAPTURE4 
[MCU2_0]      5.553893 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target CAPTURE5 
[MCU2_0]      5.553999 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target CAPTURE6 
[MCU2_0]      5.554096 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target CAPTURE7 
[MCU2_0]      5.554204 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target CAPTURE8 
[MCU2_0]      5.554307 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target DSS_M2M1 
[MCU2_0]      5.554406 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target DSS_M2M2 
[MCU2_0]      5.554488 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target DSS_M2M3 
[MCU2_0]      5.554581 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target DSS_M2M4 
[MCU2_0]      5.554670 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target CSITX2 
[MCU2_0]      5.554827 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target CAPTURE9 
[MCU2_0]      5.554964 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target CAPTURE10 
[MCU2_0]      5.555064 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target CAPTURE11 
[MCU2_0]      5.555172 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target CAPTURE12 
[MCU2_0]      5.555208 s:  VX_ZONE_INIT:[tivxInitLocal:130] Initialization Done !!!
[MCU2_0]      5.555231 s: APP: OpenVX Target kernel init ... !!!
[MCU2_0]      5.562642 s: APP: OpenVX Target kernel init ... Done !!!
[MCU2_0]      5.562674 s: CSI2RX: Init ... !!!
[MCU2_0]      5.562690 s: SCICLIENT: Sciclient_pmSetModuleState module=189 state=2
[MCU2_0]      5.562764 s: SCICLIENT: Sciclient_pmSetModuleState success
[MCU2_0]      5.562843 s: SCICLIENT: Sciclient_pmSetModuleState module=72 state=2
[MCU2_0]      5.562944 s: SCICLIENT: Sciclient_pmSetModuleState success
[MCU2_0]      5.562966 s: SCICLIENT: Sciclient_pmSetModuleState module=73 state=2
[MCU2_0]      5.563039 s: SCICLIENT: Sciclient_pmSetModuleState success
[MCU2_0]      5.563060 s: SCICLIENT: Sciclient_pmSetModuleState module=74 state=2
[MCU2_0]      5.563128 s: SCICLIENT: Sciclient_pmSetModuleState success
[MCU2_0]      5.563175 s: SCICLIENT: Sciclient_pmSetModuleState module=212 state=2
[MCU2_0]      5.563236 s: SCICLIENT: Sciclient_pmSetModuleState success
[MCU2_0]      5.563257 s: SCICLIENT: Sciclient_pmSetModuleState module=213 state=2
[MCU2_0]      5.563330 s: SCICLIENT: Sciclient_pmSetModuleState success
[MCU2_0]      5.563352 s: SCICLIENT: Sciclient_pmSetModuleState module=214 state=2
[MCU2_0]      5.563402 s: SCICLIENT: Sciclient_pmSetModuleState success
[MCU2_0]      5.563587 s: CSI2RX: Init ... Done !!!
[MCU2_0]      5.563612 s: CSI2TX: Init ... !!!
[MCU2_0]      5.563626 s: SCICLIENT: Sciclient_pmSetModuleState module=189 state=2
[MCU2_0]      5.563677 s: SCICLIENT: Sciclient_pmSetModuleState success
[MCU2_0]      5.563698 s: SCICLIENT: Sciclient_pmSetModuleState module=75 state=2
[MCU2_0]      5.563761 s: SCICLIENT: Sciclient_pmSetModuleState success
[MCU2_0]      5.563837 s: SCICLIENT: Sciclient_pmSetModuleState module=76 state=2
[MCU2_0]      5.563911 s: SCICLIENT: Sciclient_pmSetModuleState success
[MCU2_0]      5.563933 s: SCICLIENT: Sciclient_pmSetModuleState module=402 state=2
[MCU2_0]      5.563994 s: SCICLIENT: Sciclient_pmSetModuleState success
[MCU2_0]      5.564057 s: CSI2TX: Init ... Done !!!
[MCU2_0]      5.564075 s: ISS: Init ... !!!
[MCU2_0]      5.564101 s: IssSensor_Init ... Done !!!
[MCU2_0]      5.564159 s: IttRemoteServer_Init ... Done !!!
[MCU2_0]      5.564177 s: ISS: Init ... Done !!!
[MCU2_0]      5.564194 s: VISS REMOTE SERVICE: Init ... !!!
[MCU2_0]      5.564241 s: VISS REMOTE SERVICE: Init ... Done !!!
[MCU2_0]      5.564262 s: UDMA Copy: Init ... !!!
[MCU2_0]      5.565281 s: UDMA Copy: Init ... Done !!!
[MCU2_0]      5.565353 s: APP: Init ... Done !!!
[MCU2_0]      5.565373 s: APP: Run ... !!!
[MCU2_0]      5.565388 s: IPC: Starting echo test ...
[MCU2_0]      5.566648 s: APP: Run ... Done !!!
[MCU2_0]      5.568045 s: IPC: Echo status: mpu1_0[x] mcu2_0[s] mcu2_1[P] mcu3_0[.] mcu3_1[.] mcu4_0[.] mcu4_1[.] c7x_1[.] c7x_2[.] c7x_3[.] c7x_4[.] 
[MCU2_0]      5.568128 s: IPC: Echo status: mpu1_0[x] mcu2_0[s] mcu2_1[P] mcu3_0[P] mcu3_1[.] mcu4_0[.] mcu4_1[.] c7x_1[.] c7x_2[.] c7x_3[.] c7x_4[.] 
[MCU2_0]      5.568198 s: IPC: Echo status: mpu1_0[x] mcu2_0[s] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[.] mcu4_1[.] c7x_1[.] c7x_2[.] c7x_3[.] c7x_4[.] 
[MCU2_0]      5.568266 s: IPC: Echo status: mpu1_0[x] mcu2_0[s] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[P] mcu4_1[.] c7x_1[.] c7x_2[.] c7x_3[.] c7x_4[.] 
[MCU2_0]      5.568332 s: IPC: Echo status: mpu1_0[x] mcu2_0[s] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[P] mcu4_1[P] c7x_1[.] c7x_2[.] c7x_3[.] c7x_4[.] 
[MCU2_0]      5.568399 s: IPC: Echo status: mpu1_0[x] mcu2_0[s] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[P] mcu4_1[P] c7x_1[P] c7x_2[.] c7x_3[.] c7x_4[.] 
[MCU2_0]      5.568464 s: IPC: Echo status: mpu1_0[x] mcu2_0[s] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[P] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[.] c7x_4[.] 
[MCU2_0]      5.568531 s: IPC: Echo status: mpu1_0[x] mcu2_0[s] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[P] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[P] c7x_4[.] 
[MCU2_0]      5.568596 s: IPC: Echo status: mpu1_0[x] mcu2_0[s] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[P] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[P] c7x_4[P] 
[MCU2_0]      6.412835 s: INF:cbase:cb_rawsock_open:dmaTxChId=-1 dmaRxChId=-1 nTxPkts=0 nRxPkts=0 pktSize=0
[MCU2_0] INF:gptp:gptpnet_init:Open lldtsync OK!
[MCU2_0] INF:gptp:000000-880935:domainIndex=0, GM changed old=00:00:00:00:00:00:00:00, new=70:FF:76:FF:FE:1D:92:C3
[MCU2_0] INF:gptp:set_phase_offsetGM:domainNumber=0, New adjustment(New GM?)
[MCU2_1]      4.616843 s: CIO: Init ... Done !!!
[MCU2_1]      4.616893 s: ### CPU Frequency = 1000000000 Hz
[MCU2_1]      4.616916 s: CPU is running FreeRTOS
[MCU2_1]      4.616932 s: APP: Init ... !!!
[MCU2_1]      4.616967 s: SCICLIENT: Init ... !!!
[MCU2_1]      4.617090 s: SCICLIENT: DMSC FW version [9.0.6--v09.00.06 (Kool Koala)]
[MCU2_1]      4.617119 s: SCICLIENT: DMSC FW revision 0x9  
[MCU2_1]      4.617138 s: SCICLIENT: DMSC FW ABI revision 3.1
[MCU2_1]      4.617158 s: SCICLIENT: Init ... Done !!!
[MCU2_1]      4.617176 s: UDMA: Init ... !!!
[MCU2_1]      4.618044 s: UDMA: Init ... Done !!!
[MCU2_1]      4.618077 s: MEM: Init ... !!!
[MCU2_1]      4.618101 s: MEM: Created heap (DDR_LOCAL_MEM, id=0, flags=0x00000004) @ e2000000 of size 16777216 bytes !!!
[MCU2_1]      4.618142 s: MEM: Init ... Done !!!
[MCU2_1]      4.618158 s: IPC: Init ... !!!
[MCU2_1]      4.618194 s: IPC: 11 CPUs participating in IPC !!!
[MCU2_1]      4.624604 s: IPC: Init ... Done !!!
[MCU2_1]      4.624640 s: APP: Syncing with 10 CPUs ... !!!
[MCU2_1]      5.290831 s: APP: Syncing with 10 CPUs ... Done !!!
[MCU2_1]      5.290855 s: REMOTE_SERVICE: Init ... !!!
[MCU2_1]      5.291658 s: REMOTE_SERVICE: Init ... Done !!!
[MCU2_1]      5.291694 s: FVID2: Init ... !!!
[MCU2_1]      5.291735 s: FVID2: Init ... Done !!!
[MCU2_1]      5.291754 s: VHWA: DMPAC: Init ... !!!
[MCU2_1]      5.291771 s: SCICLIENT: Sciclient_pmSetModuleState module=92 state=2
[MCU2_1]      5.291891 s: SCICLIENT: Sciclient_pmSetModuleState success
[MCU2_1]      5.291915 s: SCICLIENT: Sciclient_pmSetModuleState module=96 state=2
[MCU2_1]      5.292006 s: SCICLIENT: Sciclient_pmSetModuleState success
[MCU2_1]      5.292028 s: VHWA: DOF Init ... !!!
[MCU2_1]      5.298513 s: VHWA: DOF Init ... Done !!!
[MCU2_1]      5.298548 s: VHWA: SDE Init ... !!!
[MCU2_1]      5.300550 s: VHWA: SDE Init ... Done !!!
[MCU2_1]      5.300575 s: VHWA: DMPAC: Init ... Done !!!
[MCU2_1]      5.300604 s:  VX_ZONE_INIT:Enabled
[MCU2_1]      5.300623 s:  VX_ZONE_ERROR:Enabled
[MCU2_1]      5.300643 s:  VX_ZONE_WARNING:Enabled
[MCU2_1]      5.301962 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target MCU2-1 
[MCU2_1]      5.302064 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target DMPAC_SDE 
[MCU2_1]      5.302159 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target DMPAC_DOF 
[MCU2_1]      5.302196 s:  VX_ZONE_INIT:[tivxInitLocal:130] Initialization Done !!!
[MCU2_1]      5.302221 s: APP: OpenVX Target kernel init ... !!!
[MCU2_1]      5.302352 s: APP: OpenVX Target kernel init ... Done !!!
[MCU2_1]      5.302378 s: APP: Init ... Done !!!
[MCU2_1]      5.302395 s: APP: Run ... !!!
[MCU2_1]      5.302410 s: IPC: Starting echo test ...
[MCU2_1]      5.303551 s: APP: Run ... Done !!!
[MCU2_1]      5.304582 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[s] mcu3_0[.] mcu3_1[.] mcu4_0[x] mcu4_1[P] c7x_1[.] c7x_2[.] c7x_3[.] c7x_4[.] 
[MCU2_1]      5.304663 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[s] mcu3_0[P] mcu3_1[.] mcu4_0[x] mcu4_1[P] c7x_1[.] c7x_2[.] c7x_3[.] c7x_4[.] 
[MCU2_1]      5.304736 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[s] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[.] c7x_2[.] c7x_3[.] c7x_4[.] 
[MCU2_1]      5.304806 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[s] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[.] c7x_3[.] c7x_4[.] 
[MCU2_1]      5.304872 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[s] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[.] c7x_4[.] 
[MCU2_1]      5.304936 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[s] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[P] c7x_4[.] 
[MCU2_1]      5.305001 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[s] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[P] c7x_4[P] 
[MCU2_1]      5.321748 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[s] mcu3_0[P] mcu3_1[P] mcu4_0[P] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[P] c7x_4[P] 
[MCU2_1]      5.567660 s: IPC: Echo status: mpu1_0[x] mcu2_0[P] mcu2_1[s] mcu3_0[P] mcu3_1[P] mcu4_0[P] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[P] c7x_4[P] 
[MCU3_0]      4.656987 s: CIO: Init ... Done !!!
[MCU3_0]      4.657039 s: ### CPU Frequency = 1000000000 Hz
[MCU3_0]      4.657061 s: CPU is running FreeRTOS
[MCU3_0]      4.657077 s: APP: Init ... !!!
[MCU3_0]      4.657107 s: SCICLIENT: Init ... !!!
[MCU3_0]      4.657229 s: SCICLIENT: DMSC FW version [9.0.6--v09.00.06 (Kool Koala)]
[MCU3_0]      4.657258 s: SCICLIENT: DMSC FW revision 0x9  
[MCU3_0]      4.657277 s: SCICLIENT: DMSC FW ABI revision 3.1
[MCU3_0]      4.657296 s: SCICLIENT: Init ... Done !!!
[MCU3_0]      4.657314 s: MEM: Init ... !!!
[MCU3_0]      4.657336 s: MEM: Created heap (DDR_LOCAL_MEM, id=0, flags=0x00000004) @ e3000000 of size 8388608 bytes !!!
[MCU3_0]      4.657374 s: MEM: Init ... Done !!!
[MCU3_0]      4.657390 s: IPC: Init ... !!!
[MCU3_0]      4.657425 s: IPC: 11 CPUs participating in IPC !!!
[MCU3_0]      4.663829 s: IPC: Init ... Done !!!
[MCU3_0]      4.663865 s: APP: Syncing with 10 CPUs ... !!!
[MCU3_0]      5.290832 s: APP: Syncing with 10 CPUs ... Done !!!
[MCU3_0]      5.290854 s: REMOTE_SERVICE: Init ... !!!
[MCU3_0]      5.291635 s: REMOTE_SERVICE: Init ... Done !!!
[MCU3_0]      5.291681 s:  VX_ZONE_INIT:Enabled
[MCU3_0]      5.291701 s:  VX_ZONE_ERROR:Enabled
[MCU3_0]      5.291718 s:  VX_ZONE_WARNING:Enabled
[MCU3_0]      5.292961 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target MCU3-0 
[MCU3_0]      5.293002 s:  VX_ZONE_INIT:[tivxInitLocal:130] Initialization Done !!!
[MCU3_0]      5.293025 s: APP: OpenVX Target kernel init ... !!!
[MCU3_0]      5.293044 s: APP: OpenVX Target kernel init ... Done !!!
[MCU3_0]      5.293064 s: APP: Init ... Done !!!
[MCU3_0]      5.293081 s: APP: Run ... !!!
[MCU3_0]      5.293095 s: IPC: Starting echo test ...
[MCU3_0]      5.294293 s: APP: Run ... Done !!!
[MCU3_0]      5.294947 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[s] mcu3_1[.] mcu4_0[x] mcu4_1[.] c7x_1[P] c7x_2[x] c7x_3[x] c7x_4[x] 
[MCU3_0]      5.295058 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[s] mcu3_1[P] mcu4_0[x] mcu4_1[.] c7x_1[P] c7x_2[x] c7x_3[x] c7x_4[x] 
[MCU3_0]      5.295136 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[s] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[x] c7x_3[x] c7x_4[x] 
[MCU3_0]      5.296824 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[s] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[x] c7x_4[.] 
[MCU3_0]      5.297186 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[s] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[.] c7x_4[P] 
[MCU3_0]      5.297254 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[s] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[P] c7x_4[P] 
[MCU3_0]      5.303996 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[P] mcu3_0[s] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[P] c7x_4[P] 
[MCU3_0]      5.321706 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[P] mcu3_0[s] mcu3_1[P] mcu4_0[P] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[P] c7x_4[P] 
[MCU3_0]      5.567666 s: IPC: Echo status: mpu1_0[x] mcu2_0[P] mcu2_1[P] mcu3_0[s] mcu3_1[P] mcu4_0[P] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[P] c7x_4[P] 
[MCU3_1]      4.698016 s: CIO: Init ... Done !!!
[MCU3_1]      4.698069 s: ### CPU Frequency = 1000000000 Hz
[MCU3_1]      4.698093 s: CPU is running FreeRTOS
[MCU3_1]      4.698110 s: APP: Init ... !!!
[MCU3_1]      4.698139 s: SCICLIENT: Init ... !!!
[MCU3_1]      4.698261 s: SCICLIENT: DMSC FW version [9.0.6--v09.00.06 (Kool Koala)]
[MCU3_1]      4.698291 s: SCICLIENT: DMSC FW revision 0x9  
[MCU3_1]      4.698310 s: SCICLIENT: DMSC FW ABI revision 3.1
[MCU3_1]      4.698330 s: SCICLIENT: Init ... Done !!!
[MCU3_1]      4.698348 s: MEM: Init ... !!!
[MCU3_1]      4.698369 s: MEM: Created heap (DDR_LOCAL_MEM, id=0, flags=0x00000004) @ e3800000 of size 8388608 bytes !!!
[MCU3_1]      4.698406 s: MEM: Init ... Done !!!
[MCU3_1]      4.698422 s: IPC: Init ... !!!
[MCU3_1]      4.698457 s: IPC: 11 CPUs participating in IPC !!!
[MCU3_1]      4.704874 s: IPC: Init ... Done !!!
[MCU3_1]      4.704916 s: APP: Syncing with 10 CPUs ... !!!
[MCU3_1]      5.290832 s: APP: Syncing with 10 CPUs ... Done !!!
[MCU3_1]      5.290856 s: REMOTE_SERVICE: Init ... !!!
[MCU3_1]      5.291631 s: REMOTE_SERVICE: Init ... Done !!!
[MCU3_1]      5.291679 s:  VX_ZONE_INIT:Enabled
[MCU3_1]      5.291699 s:  VX_ZONE_ERROR:Enabled
[MCU3_1]      5.291716 s:  VX_ZONE_WARNING:Enabled
[MCU3_1]      5.292960 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target MCU3-1 
[MCU3_1]      5.292998 s:  VX_ZONE_INIT:[tivxInitLocal:130] Initialization Done !!!
[MCU3_1]      5.293021 s: APP: OpenVX Target kernel init ... !!!
[MCU3_1]      5.293040 s: APP: OpenVX Target kernel init ... Done !!!
[MCU3_1]      5.293060 s: APP: Init ... Done !!!
[MCU3_1]      5.293077 s: APP: Run ... !!!
[MCU3_1]      5.293093 s: IPC: Starting echo test ...
[MCU3_1]      5.294295 s: APP: Run ... Done !!!
[MCU3_1]      5.294988 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[.] mcu3_1[s] mcu4_0[x] mcu4_1[.] c7x_1[P] c7x_2[x] c7x_3[x] c7x_4[x] 
[MCU3_1]      5.295069 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[s] mcu4_0[x] mcu4_1[.] c7x_1[P] c7x_2[x] c7x_3[x] c7x_4[x] 
[MCU3_1]      5.295146 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[s] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[x] c7x_3[x] c7x_4[x] 
[MCU3_1]      5.296842 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[s] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[x] c7x_4[.] 
[MCU3_1]      5.297190 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[s] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[.] c7x_4[P] 
[MCU3_1]      5.297255 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[s] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[P] c7x_4[P] 
[MCU3_1]      5.304005 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[P] mcu3_0[P] mcu3_1[s] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[P] c7x_4[P] 
[MCU3_1]      5.321728 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[P] mcu3_0[P] mcu3_1[s] mcu4_0[P] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[P] c7x_4[P] 
[MCU3_1]      5.567684 s: IPC: Echo status: mpu1_0[x] mcu2_0[P] mcu2_1[P] mcu3_0[P] mcu3_1[s] mcu4_0[P] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[P] c7x_4[P] 
[MCU4_0]      4.755334 s: CIO: Init ... Done !!!
[MCU4_0]      4.755386 s: ### CPU Frequency = 1000000000 Hz
[MCU4_0]      4.755410 s: CPU is running FreeRTOS
[MCU4_0]      4.755426 s: APP: Init ... !!!
[MCU4_0]      4.755461 s: SCICLIENT: Init ... !!!
[MCU4_0]      4.755582 s: SCICLIENT: DMSC FW version [9.0.6--v09.00.06 (Kool Koala)]
[MCU4_0]      4.755610 s: SCICLIENT: DMSC FW revision 0x9  
[MCU4_0]      4.755630 s: SCICLIENT: DMSC FW ABI revision 3.1
[MCU4_0]      4.755650 s: SCICLIENT: Init ... Done !!!
[MCU4_0]      4.755668 s: UDMA: Init ... !!!
[MCU4_0]      4.756577 s: UDMA: Init ... Done !!!
[MCU4_0]      4.756608 s: MEM: Init ... !!!
[MCU4_0]      4.756632 s: MEM: Created heap (DDR_LOCAL_MEM, id=0, flags=0x00000004) @ e4000000 of size 8388608 bytes !!!
[MCU4_0]      4.756673 s: MEM: Init ... Done !!!
[MCU4_0]      4.756690 s: IPC: Init ... !!!
[MCU4_0]      4.756727 s: IPC: 11 CPUs participating in IPC !!!
[MCU4_0]      4.763187 s: IPC: Init ... Done !!!
[MCU4_0]      4.763232 s: APP: Syncing with 10 CPUs ... !!!
[MCU4_0]      5.290834 s: APP: Syncing with 10 CPUs ... Done !!!
[MCU4_0]      5.290855 s: REMOTE_SERVICE: Init ... !!!
[MCU4_0]      5.291670 s: REMOTE_SERVICE: Init ... Done !!!
[MCU4_0]      5.291705 s: FVID2: Init ... !!!
[MCU4_0]      5.291743 s: FVID2: Init ... Done !!!
[MCU4_0]      5.291765 s: VHWA: VPAC Init ... !!!
[MCU4_0]      5.291781 s: SCICLIENT: Sciclient_pmSetModuleState module=400 state=2
[MCU4_0]      5.291957 s: SCICLIENT: Sciclient_pmSetModuleState success
[MCU4_0]      5.291980 s: VHWA: LDC Init ... !!!
[MCU4_0]      5.295974 s: VHWA: LDC Init ... Done !!!
[MCU4_0]      5.296011 s: VHWA: MSC Init ... !!!
[MCU4_0]      5.303631 s: VHWA: MSC Init ... Done !!!
[MCU4_0]      5.303666 s: VHWA: NF Init ... !!!
[MCU4_0]      5.304573 s: VHWA: NF Init ... Done !!!
[MCU4_0]      5.304600 s: VHWA: VISS Init ... !!!
[MCU4_0]      5.310160 s: VHWA: VISS Init ... Done !!!
[MCU4_0]      5.310200 s: VHWA: VPAC Init ... Done !!!
[MCU4_0]      5.310235 s:  VX_ZONE_INIT:Enabled
[MCU4_0]      5.310254 s:  VX_ZONE_ERROR:Enabled
[MCU4_0]      5.310271 s:  VX_ZONE_WARNING:Enabled
[MCU4_0]      5.311513 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target MCU4-0 
[MCU4_0]      5.311615 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target VPAC2_NF 
[MCU4_0]      5.311705 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target VPAC2_LDC1 
[MCU4_0]      5.311785 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target VPAC2_MSC1 
[MCU4_0]      5.311863 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target VPAC2_MSC2 
[MCU4_0]      5.312013 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target VPAC2_VISS1 
[MCU4_0]      5.312046 s:  VX_ZONE_INIT:[tivxInitLocal:130] Initialization Done !!!
[MCU4_0]      5.312070 s: APP: OpenVX Target kernel init ... !!!
[MCU4_0]      5.318978 s: APP: OpenVX Target kernel init ... Done !!!
[MCU4_0]      5.319010 s: VISS REMOTE SERVICE: Init ... !!!
[MCU4_0]      5.319058 s: VISS REMOTE SERVICE: Init ... Done !!!
[MCU4_0]      5.319079 s: UDMA Copy: Init ... !!!
[MCU4_0]      5.320036 s: UDMA Copy: Init ... Done !!!
[MCU4_0]      5.320073 s: APP: Init ... Done !!!
[MCU4_0]      5.320091 s: APP: Run ... !!!
[MCU4_0]      5.320106 s: IPC: Starting echo test ...
[MCU4_0]      5.321271 s: APP: Run ... Done !!!
[MCU4_0]      5.322310 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[P] mcu3_0[.] mcu3_1[.] mcu4_0[s] mcu4_1[.] c7x_1[.] c7x_2[.] c7x_3[.] c7x_4[.] 
[MCU4_0]      5.322423 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[P] mcu3_0[P] mcu3_1[.] mcu4_0[s] mcu4_1[.] c7x_1[.] c7x_2[.] c7x_3[.] c7x_4[.] 
[MCU4_0]      5.322493 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[s] mcu4_1[.] c7x_1[.] c7x_2[.] c7x_3[.] c7x_4[.] 
[MCU4_0]      5.322560 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[s] mcu4_1[P] c7x_1[.] c7x_2[.] c7x_3[.] c7x_4[.] 
[MCU4_0]      5.322631 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[s] mcu4_1[P] c7x_1[P] c7x_2[.] c7x_3[.] c7x_4[.] 
[MCU4_0]      5.322698 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[s] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[.] c7x_4[.] 
[MCU4_0]      5.322765 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[s] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[P] c7x_4[.] 
[MCU4_0]      5.322831 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[s] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[P] c7x_4[P] 
[MCU4_0]      5.567698 s: IPC: Echo status: mpu1_0[x] mcu2_0[P] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[s] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[P] c7x_4[P] 
[MCU4_1]      4.784875 s: CIO: Init ... Done !!!
[MCU4_1]      4.784928 s: ### CPU Frequency = 1000000000 Hz
[MCU4_1]      4.784950 s: CPU is running FreeRTOS
[MCU4_1]      4.784966 s: APP: Init ... !!!
[MCU4_1]      4.784995 s: SCICLIENT: Init ... !!!
[MCU4_1]      4.785118 s: SCICLIENT: DMSC FW version [9.0.6--v09.00.06 (Kool Koala)]
[MCU4_1]      4.785147 s: SCICLIENT: DMSC FW revision 0x9  
[MCU4_1]      4.785166 s: SCICLIENT: DMSC FW ABI revision 3.1
[MCU4_1]      4.785186 s: SCICLIENT: Init ... Done !!!
[MCU4_1]      4.785204 s: MEM: Init ... !!!
[MCU4_1]      4.785226 s: MEM: Created heap (DDR_LOCAL_MEM, id=0, flags=0x00000004) @ e4800000 of size 8388608 bytes !!!
[MCU4_1]      4.785264 s: MEM: Init ... Done !!!
[MCU4_1]      4.785280 s: IPC: Init ... !!!
[MCU4_1]      4.785315 s: IPC: 11 CPUs participating in IPC !!!
[MCU4_1]      4.791787 s: IPC: Init ... Done !!!
[MCU4_1]      4.791823 s: APP: Syncing with 10 CPUs ... !!!
[MCU4_1]      5.290833 s: APP: Syncing with 10 CPUs ... Done !!!
[MCU4_1]      5.290856 s: REMOTE_SERVICE: Init ... !!!
[MCU4_1]      5.291641 s: REMOTE_SERVICE: Init ... Done !!!
[MCU4_1]      5.291688 s:  VX_ZONE_INIT:Enabled
[MCU4_1]      5.291708 s:  VX_ZONE_ERROR:Enabled
[MCU4_1]      5.291725 s:  VX_ZONE_WARNING:Enabled
[MCU4_1]      5.292978 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:66] Added target MCU4-1 
[MCU4_1]      5.293014 s:  VX_ZONE_INIT:[tivxInitLocal:130] Initialization Done !!!
[MCU4_1]      5.293038 s: APP: OpenVX Target kernel init ... !!!
[MCU4_1]      5.293057 s: APP: OpenVX Target kernel init ... Done !!!
[MCU4_1]      5.293077 s: APP: Init ... Done !!!
[MCU4_1]      5.293094 s: APP: Run ... !!!
[MCU4_1]      5.293109 s: IPC: Starting echo test ...
[MCU4_1]      5.294322 s: APP: Run ... Done !!!
[MCU4_1]      5.295006 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[.] mcu3_1[.] mcu4_0[x] mcu4_1[s] c7x_1[P] c7x_2[x] c7x_3[x] c7x_4[x] 
[MCU4_1]      5.295233 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[.] mcu4_0[x] mcu4_1[s] c7x_1[P] c7x_2[x] c7x_3[x] c7x_4[x] 
[MCU4_1]      5.295301 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[s] c7x_1[P] c7x_2[x] c7x_3[x] c7x_4[x] 
[MCU4_1]      5.296864 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[s] c7x_1[P] c7x_2[P] c7x_3[x] c7x_4[.] 
[MCU4_1]      5.297200 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[s] c7x_1[P] c7x_2[P] c7x_3[.] c7x_4[P] 
[MCU4_1]      5.297264 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[s] c7x_1[P] c7x_2[P] c7x_3[P] c7x_4[P] 
[MCU4_1]      5.304015 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[s] c7x_1[P] c7x_2[P] c7x_3[P] c7x_4[P] 
[MCU4_1]      5.321737 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[P] mcu4_1[s] c7x_1[P] c7x_2[P] c7x_3[P] c7x_4[P] 
[MCU4_1]      5.567701 s: IPC: Echo status: mpu1_0[x] mcu2_0[P] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[P] mcu4_1[s] c7x_1[P] c7x_2[P] c7x_3[P] c7x_4[P] 
[C7x_1 ]      4.933839 s: CIO: Init ... Done !!!
[C7x_1 ]      4.933853 s: ### CPU Frequency = 1000000000 Hz
[C7x_1 ]      4.933865 s: CPU is running FreeRTOS
[C7x_1 ]      4.933873 s: APP: Init ... !!!
[C7x_1 ]      4.933881 s: SCICLIENT: Init ... !!!
[C7x_1 ]      4.933991 s: SCICLIENT: DMSC FW version [9.0.6--v09.00.06 (Kool Koala)]
[C7x_1 ]      4.934005 s: SCICLIENT: DMSC FW revision 0x9  
[C7x_1 ]      4.934015 s: SCICLIENT: DMSC FW ABI revision 3.1
[C7x_1 ]      4.934026 s: SCICLIENT: Init ... Done !!!
[C7x_1 ]      4.934035 s: UDMA: Init ... !!!
[C7x_1 ]      4.934834 s: UDMA: Init ... Done !!!
[C7x_1 ]      4.934845 s: MEM: Init ... !!!
[C7x_1 ]      4.934856 s: MEM: Created heap (DDR_LOCAL_MEM, id=0, flags=0x00000004) @ 108000000 of size 134217728 bytes !!!
[C7x_1 ]      4.934876 s: MEM: Init ... Done !!!
[C7x_1 ]      4.934885 s: IPC: Init ... !!!
[C7x_1 ]      4.934898 s: IPC: 11 CPUs participating in IPC !!!
[C7x_1 ]      4.937995 s: IPC: Init ... Done !!!
[C7x_1 ]      4.938009 s: APP: Syncing with 10 CPUs ... !!!
[C7x_1 ]      5.290834 s: APP: Syncing with 10 CPUs ... Done !!!
[C7x_1 ]      5.290849 s: REMOTE_SERVICE: Init ... !!!
[C7x_1 ]      5.291087 s: REMOTE_SERVICE: Init ... Done !!!
[C7x_1 ]      5.291159 s:  VX_ZONE_INIT:Enabled
[C7x_1 ]      5.291170 s:  VX_ZONE_ERROR:Enabled
[C7x_1 ]      5.291229 s:  VX_ZONE_WARNING:Enabled
[C7x_1 ]      5.291832 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-1 
[C7x_1 ]      5.291891 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-1_PRI_2 
[C7x_1 ]      5.291952 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-1_PRI_3 
[C7x_1 ]      5.292017 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-1_PRI_4 
[C7x_1 ]      5.292080 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-1_PRI_5 
[C7x_1 ]      5.292144 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-1_PRI_6 
[C7x_1 ]      5.292207 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-1_PRI_7 
[C7x_1 ]      5.292273 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-1_PRI_8 
[C7x_1 ]      5.292299 s:  VX_ZONE_INIT:[tivxInitLocal:130] Initialization Done !!!
[C7x_1 ]      5.292313 s: APP: OpenVX Target kernel init ... !!!
[C7x_1 ]      5.293428 s: APP: OpenVX Target kernel init ... Done !!!
[C7x_1 ]      5.293442 s: APP: Init ... Done !!!
[C7x_1 ]      5.293451 s: APP: Run ... !!!
[C7x_1 ]      5.293460 s: IPC: Starting echo test ...
[C7x_1 ]      5.293706 s: APP: Run ... Done !!!
[C7x_1 ]      5.294914 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[.] mcu4_0[x] mcu4_1[.] c7x_1[s] c7x_2[x] c7x_3[x] c7x_4[x] 
[C7x_1 ]      5.294970 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[.] c7x_1[s] c7x_2[x] c7x_3[x] c7x_4[x] 
[C7x_1 ]      5.295012 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[s] c7x_2[x] c7x_3[x] c7x_4[x] 
[C7x_1 ]      5.296824 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[s] c7x_2[P] c7x_3[x] c7x_4[.] 
[C7x_1 ]      5.297180 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[s] c7x_2[P] c7x_3[.] c7x_4[P] 
[C7x_1 ]      5.297224 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[s] c7x_2[P] c7x_3[P] c7x_4[P] 
[C7x_1 ]      5.304025 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[s] c7x_2[P] c7x_3[P] c7x_4[P] 
[C7x_1 ]      5.321752 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[P] mcu4_1[P] c7x_1[s] c7x_2[P] c7x_3[P] c7x_4[P] 
[C7x_1 ]      5.567711 s: IPC: Echo status: mpu1_0[x] mcu2_0[P] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[P] mcu4_1[P] c7x_1[s] c7x_2[P] c7x_3[P] c7x_4[P] 
[C7x_2 ]      5.048241 s: CIO: Init ... Done !!!
[C7x_2 ]      5.048255 s: ### CPU Frequency = 1000000000 Hz
[C7x_2 ]      5.048266 s: CPU is running FreeRTOS
[C7x_2 ]      5.048275 s: APP: Init ... !!!
[C7x_2 ]      5.048283 s: SCICLIENT: Init ... !!!
[C7x_2 ]      5.048391 s: SCICLIENT: DMSC FW version [9.0.6--v09.00.06 (Kool Koala)]
[C7x_2 ]      5.048404 s: SCICLIENT: DMSC FW revision 0x9  
[C7x_2 ]      5.048414 s: SCICLIENT: DMSC FW ABI revision 3.1
[C7x_2 ]      5.048425 s: SCICLIENT: Init ... Done !!!
[C7x_2 ]      5.048434 s: UDMA: Init ... !!!
[C7x_2 ]      5.049247 s: UDMA: Init ... Done !!!
[C7x_2 ]      5.049258 s: MEM: Init ... !!!
[C7x_2 ]      5.049269 s: MEM: Created heap (DDR_LOCAL_MEM, id=0, flags=0x00000004) @ 108000000 of size 134217728 bytes !!!
[C7x_2 ]      5.049290 s: MEM: Init ... Done !!!
[C7x_2 ]      5.049298 s: IPC: Init ... !!!
[C7x_2 ]      5.049313 s: IPC: 11 CPUs participating in IPC !!!
[C7x_2 ]      5.052422 s: IPC: Init ... Done !!!
[C7x_2 ]      5.052434 s: APP: Syncing with 10 CPUs ... !!!
[C7x_2 ]      5.290835 s: APP: Syncing with 10 CPUs ... Done !!!
[C7x_2 ]      5.290848 s: REMOTE_SERVICE: Init ... !!!
[C7x_2 ]      5.291102 s: REMOTE_SERVICE: Init ... Done !!!
[C7x_2 ]      5.291229 s:  VX_ZONE_INIT:Enabled
[C7x_2 ]      5.291242 s:  VX_ZONE_ERROR:Enabled
[C7x_2 ]      5.291256 s:  VX_ZONE_WARNING:Enabled
[C7x_2 ]      5.291970 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-2 
[C7x_2 ]      5.292046 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-2_PRI_2 
[C7x_2 ]      5.292122 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-2_PRI_3 
[C7x_2 ]      5.292207 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-2_PRI_4 
[C7x_2 ]      5.292285 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-2_PRI_5 
[C7x_2 ]      5.292360 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-2_PRI_6 
[C7x_2 ]      5.292435 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-2_PRI_7 
[C7x_2 ]      5.292505 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-2_PRI_8 
[C7x_2 ]      5.292529 s:  VX_ZONE_INIT:[tivxInitLocal:130] Initialization Done !!!
[C7x_2 ]      5.292542 s: APP: OpenVX Target kernel init ... !!!
[C7x_2 ]      5.293733 s: APP: OpenVX Target kernel init ... Done !!!
[C7x_2 ]      5.293748 s: UDMA Copy: Init ... !!!
[C7x_2 ]      5.296035 s: UDMA Copy: Init ... Done !!!
[C7x_2 ]      5.296049 s: APP: Init ... Done !!!
[C7x_2 ]      5.296058 s: APP: Run ... !!!
[C7x_2 ]      5.296066 s: IPC: Starting echo test ...
[C7x_2 ]      5.296315 s: APP: Run ... Done !!!
[C7x_2 ]      5.297014 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[.] mcu3_1[.] mcu4_0[x] mcu4_1[.] c7x_1[P] c7x_2[s] c7x_3[x] c7x_4[.] 
[C7x_2 ]      5.297089 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[.] mcu3_1[.] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[s] c7x_3[.] c7x_4[.] 
[C7x_2 ]      5.297147 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[.] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[s] c7x_3[.] c7x_4[.] 
[C7x_2 ]      5.297209 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[s] c7x_3[.] c7x_4[.] 
[C7x_2 ]      5.297289 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[s] c7x_3[.] c7x_4[P] 
[C7x_2 ]      5.297521 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[s] c7x_3[P] c7x_4[P] 
[C7x_2 ]      5.304037 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[s] c7x_3[P] c7x_4[P] 
[C7x_2 ]      5.321760 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[P] mcu4_1[P] c7x_1[P] c7x_2[s] c7x_3[P] c7x_4[P] 
[C7x_2 ]      5.567718 s: IPC: Echo status: mpu1_0[x] mcu2_0[P] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[P] mcu4_1[P] c7x_1[P] c7x_2[s] c7x_3[P] c7x_4[P] 
[C7x_3 ]      5.167681 s: CIO: Init ... Done !!!
[C7x_3 ]      5.167695 s: ### CPU Frequency = 1000000000 Hz
[C7x_3 ]      5.167707 s: CPU is running FreeRTOS
[C7x_3 ]      5.167715 s: APP: Init ... !!!
[C7x_3 ]      5.167723 s: SCICLIENT: Init ... !!!
[C7x_3 ]      5.167831 s: SCICLIENT: DMSC FW version [9.0.6--v09.00.06 (Kool Koala)]
[C7x_3 ]      5.167845 s: SCICLIENT: DMSC FW revision 0x9  
[C7x_3 ]      5.167855 s: SCICLIENT: DMSC FW ABI revision 3.1
[C7x_3 ]      5.167867 s: SCICLIENT: Init ... Done !!!
[C7x_3 ]      5.167875 s: UDMA: Init ... !!!
[C7x_3 ]      5.168686 s: UDMA: Init ... Done !!!
[C7x_3 ]      5.168697 s: MEM: Init ... !!!
[C7x_3 ]      5.168708 s: MEM: Created heap (DDR_LOCAL_MEM, id=0, flags=0x00000004) @ 108000000 of size 134217728 bytes !!!
[C7x_3 ]      5.168729 s: MEM: Init ... Done !!!
[C7x_3 ]      5.168737 s: IPC: Init ... !!!
[C7x_3 ]      5.168751 s: IPC: 11 CPUs participating in IPC !!!
[C7x_3 ]      5.171881 s: IPC: Init ... Done !!!
[C7x_3 ]      5.171894 s: APP: Syncing with 10 CPUs ... !!!
[C7x_3 ]      5.290835 s: APP: Syncing with 10 CPUs ... Done !!!
[C7x_3 ]      5.290848 s: REMOTE_SERVICE: Init ... !!!
[C7x_3 ]      5.291099 s: REMOTE_SERVICE: Init ... Done !!!
[C7x_3 ]      5.291174 s:  VX_ZONE_INIT:Enabled
[C7x_3 ]      5.291192 s:  VX_ZONE_ERROR:Enabled
[C7x_3 ]      5.291206 s:  VX_ZONE_WARNING:Enabled
[C7x_3 ]      5.292025 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-3 
[C7x_3 ]      5.292098 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-3_PRI_2 
[C7x_3 ]      5.292172 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-3_PRI_3 
[C7x_3 ]      5.292246 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-3_PRI_4 
[C7x_3 ]      5.292319 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-3_PRI_5 
[C7x_3 ]      5.292390 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-3_PRI_6 
[C7x_3 ]      5.292465 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-3_PRI_7 
[C7x_3 ]      5.292535 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-3_PRI_8 
[C7x_3 ]      5.292558 s:  VX_ZONE_INIT:[tivxInitLocal:130] Initialization Done !!!
[C7x_3 ]      5.292572 s: APP: OpenVX Target kernel init ... !!!
[C7x_3 ]      5.293802 s: APP: OpenVX Target kernel init ... Done !!!
[C7x_3 ]      5.293851 s: UDMA Copy: Init ... !!!
[C7x_3 ]      5.296217 s: UDMA Copy: Init ... Done !!!
[C7x_3 ]      5.296233 s: APP: Init ... Done !!!
[C7x_3 ]      5.296243 s: APP: Run ... !!!
[C7x_3 ]      5.296251 s: IPC: Starting echo test ...
[C7x_3 ]      5.296534 s: APP: Run ... Done !!!
[C7x_3 ]      5.297204 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[.] mcu4_0[x] mcu4_1[.] c7x_1[.] c7x_2[.] c7x_3[s] c7x_4[.] 
[C7x_3 ]      5.297263 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[.] c7x_1[.] c7x_2[.] c7x_3[s] c7x_4[.] 
[C7x_3 ]      5.297316 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[.] c7x_2[.] c7x_3[s] c7x_4[.] 
[C7x_3 ]      5.297369 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[.] c7x_3[s] c7x_4[.] 
[C7x_3 ]      5.297413 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[.] c7x_3[s] c7x_4[P] 
[C7x_3 ]      5.297456 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[s] c7x_4[P] 
[C7x_3 ]      5.304045 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[s] c7x_4[P] 
[C7x_3 ]      5.321767 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[P] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[s] c7x_4[P] 
[C7x_3 ]      5.567728 s: IPC: Echo status: mpu1_0[x] mcu2_0[P] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[P] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[s] c7x_4[P] 
[C7x_4 ]      5.286596 s: CIO: Init ... Done !!!
[C7x_4 ]      5.286610 s: ### CPU Frequency = 1000000000 Hz
[C7x_4 ]      5.286622 s: CPU is running FreeRTOS
[C7x_4 ]      5.286630 s: APP: Init ... !!!
[C7x_4 ]      5.286638 s: SCICLIENT: Init ... !!!
[C7x_4 ]      5.286745 s: SCICLIENT: DMSC FW version [9.0.6--v09.00.06 (Kool Koala)]
[C7x_4 ]      5.286759 s: SCICLIENT: DMSC FW revision 0x9  
[C7x_4 ]      5.286769 s: SCICLIENT: DMSC FW ABI revision 3.1
[C7x_4 ]      5.286780 s: SCICLIENT: Init ... Done !!!
[C7x_4 ]      5.286789 s: UDMA: Init ... !!!
[C7x_4 ]      5.287593 s: UDMA: Init ... Done !!!
[C7x_4 ]      5.287605 s: MEM: Init ... !!!
[C7x_4 ]      5.287616 s: MEM: Created heap (DDR_LOCAL_MEM, id=0, flags=0x00000004) @ 108000000 of size 134217728 bytes !!!
[C7x_4 ]      5.287637 s: MEM: Init ... Done !!!
[C7x_4 ]      5.287645 s: IPC: Init ... !!!
[C7x_4 ]      5.287659 s: IPC: 11 CPUs participating in IPC !!!
[C7x_4 ]      5.290806 s: IPC: Init ... Done !!!
[C7x_4 ]      5.290818 s: APP: Syncing with 10 CPUs ... !!!
[C7x_4 ]      5.290833 s: APP: Syncing with 10 CPUs ... Done !!!
[C7x_4 ]      5.290844 s: REMOTE_SERVICE: Init ... !!!
[C7x_4 ]      5.291031 s: REMOTE_SERVICE: Init ... Done !!!
[C7x_4 ]      5.291054 s:  VX_ZONE_INIT:Enabled
[C7x_4 ]      5.291065 s:  VX_ZONE_ERROR:Enabled
[C7x_4 ]      5.291075 s:  VX_ZONE_WARNING:Enabled
[C7x_4 ]      5.291977 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-4 
[C7x_4 ]      5.292055 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-4_PRI_2 
[C7x_4 ]      5.292126 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-4_PRI_3 
[C7x_4 ]      5.292199 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-4_PRI_4 
[C7x_4 ]      5.292274 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-4_PRI_5 
[C7x_4 ]      5.292349 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-4_PRI_6 
[C7x_4 ]      5.292422 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-4_PRI_7 
[C7x_4 ]      5.292494 s:  VX_ZONE_INIT:[tivxPlatformCreateTargetId:59] Added target DSP_C7-4_PRI_8 
[C7x_4 ]      5.292515 s:  VX_ZONE_INIT:[tivxInitLocal:130] Initialization Done !!!
[C7x_4 ]      5.292533 s: APP: OpenVX Target kernel init ... !!!
[C7x_4 ]      5.293790 s: APP: OpenVX Target kernel init ... Done !!!
[C7x_4 ]      5.293804 s: UDMA Copy: Init ... !!!
[C7x_4 ]      5.296160 s: UDMA Copy: Init ... Done !!!
[C7x_4 ]      5.296176 s: APP: Init ... Done !!!
[C7x_4 ]      5.296186 s: APP: Run ... !!!
[C7x_4 ]      5.296194 s: IPC: Starting echo test ...
[C7x_4 ]      5.296481 s: APP: Run ... Done !!!
[C7x_4 ]      5.297084 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[.] mcu3_1[.] mcu4_0[x] mcu4_1[.] c7x_1[P] c7x_2[.] c7x_3[x] c7x_4[s] 
[C7x_4 ]      5.297206 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[.] mcu3_1[P] mcu4_0[x] mcu4_1[.] c7x_1[P] c7x_2[.] c7x_3[.] c7x_4[s] 
[C7x_4 ]      5.297252 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[.] c7x_1[P] c7x_2[.] c7x_3[.] c7x_4[s] 
[C7x_4 ]      5.297309 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[.] c7x_3[.] c7x_4[s] 
[C7x_4 ]      5.297363 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[.] c7x_4[s] 
[C7x_4 ]      5.297531 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[x] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[P] c7x_4[s] 
[C7x_4 ]      5.304055 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[x] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[P] c7x_4[s] 
[C7x_4 ]      5.321774 s: IPC: Echo status: mpu1_0[x] mcu2_0[x] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[P] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[P] c7x_4[s] 
[C7x_4 ]      5.567734 s: IPC: Echo status: mpu1_0[x] mcu2_0[P] mcu2_1[P] mcu3_0[P] mcu3_1[P] mcu4_0[P] mcu4_1[P] c7x_1[P] c7x_2[P] c7x_3[P] c7x_4[s]

0 Nikhil Dasan over 2 years ago in reply to srikar varaganti

TI__Guru* 90151 points

Hi,

I'm suspecting the cause of this issue to be because of #undef ENABLE_UDMA_COPY in vision_apps/platform/j784s4/rtos/common/app_cfg_c7x_1.h

Let me check internally why this is disabled only on C7x_1..
Meanwhile, could you enable this and check if this works for you?

Regards,

Nikhil

0 srikar varaganti over 2 years ago in reply to Nikhil Dasan

Intellectual 300 points

Yes Nikhil,

This change within app_cfg_c7x_1.h worked, Now i am able to run the application without the error. Please let me know once you have discussion with your team on why was this disabled initially.

Thank and Regards,

Srikar. V

0 Nikhil Dasan over 2 years ago in reply to srikar varaganti

TI__Guru* 90151 points

Sure Srikar,

I shall check and get back to you

Regards,

Nikhil

+1 srikar varaganti over 2 years ago in reply to Nikhil Dasan

Intellectual 300 points

Hi Nikhil,

Do you have any update on this?

+1 Nikhil Dasan over 2 years ago in reply to srikar varaganti

TI__Guru* 90151 points

Hi Srikar,

This was disabled because this UDMA copy channel create was used mostly by the pre-proc and post-proc nodes on C7x_2, 3, 4.

Hence, it was disabled on C7x_1.

Regards,

Nikhil

Processors

Processors forum

TDA4VM: TDA4 High: pre-processing and post-processing nodes to run on different cores