TDA4VM: TDA4VH: TIDL hangs after repeated build operation

xueming tian

Part Number: TDA4VM
Other Parts Discussed in Thread: TDA4VH

ONNX: resnet18v1.onnx
1. Model Importer Operation
shell command : ./tidl_model_import.out tidl_import_cfg.txt

2. Model Inference Operation

Environment: TDA4VH board with sdk 8.5
The model hangs after repeated build operation.
When the test_tidl_repeat test case is executed, the vxReleaseGraph interface hangs.

The print log is shown as below.

Please refer to the attachment resnet18v1.rar for more details about the configuration, model and test case.resnet18v1.rar

over 1 year ago

0 Nikhil Dasan over 1 year ago

TI__Guru* 80046 points

Hi,

Sorry for delay in response.

In your app_delete_graph() function, could you do vxReleaseGraph(&obj->graph); at the end of this function after releasing all the other parameters like nodes, data objects tensor etc.

Please try out this change and let me know if this works for you.

Regards,
Nikhil

0 xueming tian over 1 year ago in reply to Nikhil Dasan

Intellectual 621 points

Thanks for your reply. I tried this but it didn't work.

0 Nikhil Dasan over 1 year ago in reply to xueming tian

TI__Guru* 80046 points

Hi,

There seems to be a hang in the C7x core. Could you please connect CCS (Code composer studio) and put a breakpoint on tivxKernelTIDLDelete() function in ${PSDKRA}/tiovx/kernels_j7/tidl/dsp/vx_tidl_target.c and step in to check where exactly the hang is?

Regards,
Nikhil

0 Diwakar Dhyani over 1 year ago in reply to Nikhil Dasan

TI__Mastermind 42990 points

Hi Nikhil

Can you apply the test code and see if you are able to reproduce the issue at your end.

e2e.ti.com/.../resnet18v1.rar

Regards
Diwakar

0 Nikhil Dasan over 1 year ago in reply to Diwakar Dhyani

TI__Guru* 80046 points

Hi,

Thank you providing the source code. I am able to reproduce this at my end. My initial level of debugging points this hang while freeing the memory of C7x

Please find the logs below with debug logs enabled. To get the below logs, I enabled APP_MEM_DEBUG in vision_apps/utils/mem/src/app_mem_free_rtos.c and tivx_set_debug_zone((int32_t)VX_ZONE_INFO) in tiovx/source/platform/psdk_j7/common/tivx_init.c

root@j784s4-evm:/opt/vision_apps# ./run_app_tidl.sh 
APP: Init ... !!!
MEM: Init ... !!!
MEM: Initialized DMA HEAP (fd=4) !!!
MEM: Init ... Done !!!
IPC: Init ... !!!
IPC: Init ... Done !!!
REMOTE_SERVICE: Init ... !!!
REMOTE_SERVICE: Init ... Done !!!
    54.708771 s: GTC Frequency = 200 MHz
APP: Init ... Done !!!
    54.720742 s:  VX_ZONE_INIT:Enabled
    54.720777 s:  VX_ZONE_ERROR:Enabled
    54.720793 s:  VX_ZONE_WARNING:Enabled
    54.720801 s:  VX_ZONE_INFO:Enabled
    54.722426 s:  VX_ZONE_INIT:[tivxInitLocal:145] Initialization Done !!!
    54.723043 s:  VX_ZONE_INIT:[tivxHostInitLocal:93] Initialization Done for HOST !!!
app_tidl: Init ... 
app_tidl: Reading config file /opt/vision_apps/test_data/tivx/tidl_models/tidl_io_mobilenet_v1_1.bin ...
app_tidl: Reading config file /opt/vision_apps/test_data/tivx/tidl_models/tidl_io_mobilenet_v1_1.bin ... Done. 37256 bytes
app_tidl: Tensors, input = 1, output = 1
app_tidl: Reading network file /opt/vision_apps/test_data/tivx/tidl_models/tidl_net_mobilenet_v1.bin ... 
app_tidl: Reading network file /opt/vision_apps/test_data/tivx/tidl_models/tidl_net_mobilenet_v1.bin ... Done. 4980176 bytes
app_tidl: Init ... Done.
app_tidl: Creating graph ... 
app_tidl: Creating graph ... Done.
app_tidl: Verifying graph ... 
    54.972115 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel com.ti.tidl:1:1
    54.972453 s:  VX_ZONE_INFO:[ownNodeKernelInit:510] Calling create callback for node TIDLNode
[C7x_1 ]     54.972620 s:  VX_ZONE_INFO:[ownTargetKernelInstanceAlloc:107] attempting to call kernel com.ti.tidl:1:1 on this CPU
[C7x_1 ]     54.972670 s:  VX_ZONE_INFO:[ownTargetKernelCreate:316] Executing create callback for kernel [com.ti.tidl]
[C7x_1 ]     54.972694 s: MEM: Allocated 62848 bytes @ 0x04000100
[C7x_1 ]     54.972866 s: MEM: Allocated 4980224 bytes @ 0x0400f800
[C7x_1 ]     54.982497 s:  VX_ZONE_INFO:[tivxKernelTIDLCreate:663] Enabling preemption
[C7x_1 ]     54.982529 s:  VX_ZONE_INFO:[tivxKernelTIDLCreate:686] L1 = 16 KB, L2 = 448 KB, L3 = 3072 KB
[C7x_1 ]     54.982553 s:  VX_ZONE_INFO:[tivxAlgiVisionCreate:311] Calling ialg.algNumAlloc ...
[C7x_1 ]     54.982574 s:  VX_ZONE_INFO:[tivxAlgiVisionCreate:315] Allocating 16 memory records ...
[C7x_1 ]     54.982593 s: MEM: Allocated 384 bytes @ 0x044cf700
[C7x_1 ]     54.982612 s:  VX_ZONE_INFO:[tivxAlgiVisionCreate:325] Calling ialg.algAlloc ...
[C7x_1 ]     54.982879 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 0 @ space = 18, size = 15216, align = 128 ... 
[C7x_1 ]     54.982914 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 0 @ space = 18 and size = 15216, addr = 100000000 ... 
[C7x_1 ]     54.982944 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 1 @ space = 17, size = 144, align = 128 ... 
[C7x_1 ]     54.982966 s: MEM: Allocated 256 bytes @ 0x044cfa00
[C7x_1 ]     54.982991 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 1 @ space = 17 and size = 144, addr = 1044cfa00 ... 
[C7x_1 ]     54.983020 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 2 @ space = 0, size = 16384, align = 128 ... 
[C7x_1 ]     54.983051 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 2 @ space = 0 and size = 16384, addr = 64e00000 ... 
[C7x_1 ]     54.983081 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 3 @ space = 1, size = 458752, align = 128 ... 
[C7x_1 ]     54.983112 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 3 @ space = 1 and size = 458752, addr = 64800000 ... 
[C7x_1 ]     54.983142 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 4 @ space = 2, size = 3014656, align = 128 ... 
[C7x_1 ]     54.983173 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 4 @ space = 2 and size = 3014656, addr = 68000000 ... 
[C7x_1 ]     54.983202 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 5 @ space = 18, size = 5148080, align = 128 ... 
[C7x_1 ]     54.983233 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 5 @ space = 18 and size = 5148080, addr = 100003c00 ... 
[C7x_1 ]     54.983263 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 6 @ space = 18, size = 256, align = 128 ... 
[C7x_1 ]     54.983293 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 6 @ space = 18 and size = 256, addr = 108000000 ... 
[C7x_1 ]     54.983323 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 7 @ space = 18, size = 151552, align = 128 ... 
[C7x_1 ]     54.983353 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 7 @ space = 18 and size = 151552, addr = 1004ecc00 ... 
[C7x_1 ]     54.983383 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 8 @ space = 18, size = 128, align = 128 ... 
[C7x_1 ]     54.983413 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 8 @ space = 18 and size = 128, addr = 108000400 ... 
[C7x_1 ]     54.983451 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 9 @ space = 18, size = 3200, align = 128 ... 
[C7x_1 ]     54.983484 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 9 @ space = 18 and size = 3200, addr = 108000800 ... 
[C7x_1 ]     54.983514 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 10 @ space = 18, size = 128, align = 128 ... 
    55.025846 s:  VX_ZONE_INFO:[ownNodeKernelInit:516] Create callback for node TIDLNode completed
[C7x_1 ]     54.983544 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 10 @ space = 18 and size = 128, addr = 108001800 ... 
    55.318049 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel com.ti.tidl:1:1 ...
    55.318064 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel com.ti.tidl:1:1 ... done !!!
[C7x_1 ]     54.983574 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 11 @ space = 17, size = 452224, align = 128 ... 
[C7x_1 ]     54.983603 s: MEM: Allocated 452224 bytes @ 0x044cfc00
[C7x_1 ]     54.983628 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 11 @ space = 17 and size = 452224, addr = 1044cfc00 ... 
[C7x_1 ]     54.983658 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 12 @ space = 17, size = 524544, align = 128 ... 
[C7x_1 ]     54.983703 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 12 @ space = 17 and size = 524544, addr = 10c000000 ... 
[C7x_1 ]     54.983734 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 13 @ space = 18, size = 3014656, align = 128 ... 
[C7x_1 ]     54.983767 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 13 @ space = 18 and size = 3014656, addr = 100511c00 ... 
app_tidl: Verifying graph ... Done.
app_tidl: Init ... 
[C7x_1 ]     54.983797 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 14 @ space = 17, size = 128, align = 128 ... 
app_tidl: Reading config file /opt/vision_apps/test_data/tivx/tidl_models/tidl_io_mobilenet_v1_1.bin ...
[C7x_1 ]     54.983817 s: MEM: Allocated 128 bytes @ 0x0453e400
[C7x_1 ]     54.983840 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 14 @ space = 17 and size = 128, addr = 10453e400 ... 
[C7x_1 ]     54.983870 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 15 @ space = 18, size = 128, align = 128 ... 
[C7x_1 ]     54.983901 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 15 @ space = 18 and size = 128, addr = 1007f1c00 ... 
[C7x_1 ]     54.983930 s:  VX_ZONE_INFO:[tivxAlgiVisionCreate:336] Calling ialg.algInit ...
[C7x_1 ]     55.025539 s: MEM: Freeing 384 bytes @ 0x044cf700
[C7x_1 ]     55.025568 s:  VX_ZONE_INFO:[tivxAlgiVisionCreate:386] Created AlgiVision handle.
[C7x_1 ]     55.025597 s:  VX_ZONE_INFO:[ownTargetKernelCreate:322] Done executing create callback for kernel [com.ti.tidl]
app_tidl: Reading config file /opt/vision_apps/test_data/tivx/tidl_models/tidl_io_mobilenet_v1_1.bin ... Done. 37256 bytes
app_tidl: Tensors, input = 1, output = 1
app_tidl: Reading network file /opt/vision_apps/test_data/tivx/tidl_models/tidl_net_mobilenet_v1.bin ... 
app_tidl: Reading network file /opt/vision_apps/test_data/tivx/tidl_models/tidl_net_mobilenet_v1.bin ... Done. 4980176 bytes
app_tidl: Init ... Done.
app_tidl: Creating graph ... 
app_tidl: Creating graph ... Done.
app_tidl: Verifying graph ... 
    55.331527 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel com.ti.tidl:1:1
    55.331822 s:  VX_ZONE_INFO:[ownNodeKernelInit:510] Calling create callback for node TIDLNode
[C7x_1 ]     55.331974 s:  VX_ZONE_INFO:[ownTargetKernelInstanceAlloc:107] attempting to call kernel com.ti.tidl:1:1 on this CPU
[C7x_1 ]     55.332025 s:  VX_ZONE_INFO:[ownTargetKernelCreate:316] Executing create callback for kernel [com.ti.tidl]
[C7x_1 ]     55.332049 s: MEM: Allocated 62848 bytes @ 0x0453e600
[C7x_1 ]     55.332213 s: MEM: Allocated 4980224 bytes @ 0x0454dd00
[C7x_1 ]     55.341724 s:  VX_ZONE_INFO:[tivxKernelTIDLCreate:663] Enabling preemption
[C7x_1 ]     55.341754 s:  VX_ZONE_INFO:[tivxKernelTIDLCreate:686] L1 = 16 KB, L2 = 448 KB, L3 = 3072 KB
[C7x_1 ]     55.341778 s:  VX_ZONE_INFO:[tivxAlgiVisionCreate:311] Calling ialg.algNumAlloc ...
[C7x_1 ]     55.341800 s:  VX_ZONE_INFO:[tivxAlgiVisionCreate:315] Allocating 16 memory records ...
    55.385093 s:  VX_ZONE_INFO:[ownNodeKernelInit:516] Create callback for node TIDLNode completed
[C7x_1 ]     55.341818 s: MEM: Allocated 384 bytes @ 0x044cf700
    55.678995 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel com.ti.tidl:1:1 ...
    55.679008 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel com.ti.tidl:1:1 ... done !!!
[C7x_1 ]     55.341838 s:  VX_ZONE_INFO:[tivxAlgiVisionCreate:325] Calling ialg.algAlloc ...
[C7x_1 ]     55.342103 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 0 @ space = 18, size = 15216, align = 128 ... 
[C7x_1 ]     55.342138 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 0 @ space = 18 and size = 15216, addr = 1007f2000 ... 
[C7x_1 ]     55.342170 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 1 @ space = 17, size = 144, align = 128 ... 
[C7x_1 ]     55.342191 s: MEM: Allocated 256 bytes @ 0x04a0dc00
[C7x_1 ]     55.342217 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 1 @ space = 17 and size = 144, addr = 104a0dc00 ... 
[C7x_1 ]     55.342247 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 2 @ space = 0, size = 16384, align = 128 ... 
app_tidl: Verifying graph ... Done.
app_tidl: Delete ... 
[C7x_1 ]     55.342278 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 2 @ space = 0 and size = 16384, addr = 64e00000 ... 
[C7x_1 ]     55.342308 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 3 @ space = 1, size = 458752, align = 128 ... 
[C7x_1 ]     55.342338 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 3 @ space = 1 and size = 458752, addr = 64800000 ... 
[C7x_1 ]     55.342368 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 4 @ space = 2, size = 3014656, align = 128 ... 
[C7x_1 ]     55.342398 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 4 @ space = 2 and size = 3014656, addr = 68000000 ... 
[C7x_1 ]     55.342428 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 5 @ space = 18, size = 5148080, align = 128 ... 
[C7x_1 ]     55.342469 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 5 @ space = 18 and size = 5148080, addr = 1007f5c00 ... 
[C7x_1 ]     55.342499 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 6 @ space = 18, size = 256, align = 128 ... 
[C7x_1 ]     55.342531 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 6 @ space = 18 and size = 256, addr = 108001c00 ... 
[C7x_1 ]     55.342562 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 7 @ space = 18, size = 151552, align = 128 ... 
[C7x_1 ]     55.342592 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 7 @ space = 18 and size = 151552, addr = 100cdec00 ... 
[C7x_1 ]     55.342623 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 8 @ space = 18, size = 128, align = 128 ... 
[C7x_1 ]     55.342653 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 8 @ space = 18 and size = 128, addr = 108002000 ... 
[C7x_1 ]     55.342684 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 9 @ space = 18, size = 3200, align = 128 ... 
[C7x_1 ]     55.342715 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 9 @ space = 18 and size = 3200, addr = 108002400 ... 
[C7x_1 ]     55.342746 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 10 @ space = 18, size = 128, align = 128 ... 
[C7x_1 ]     55.342776 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 10 @ space = 18 and size = 128, addr = 108003400 ... 
    55.681070 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
[C7x_1 ]     55.342806 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 11 @ space = 17, size = 452224, align = 128 ... 
[C7x_1 ]     55.342834 s: MEM: Allocated 452224 bytes @ 0x04a0de00
[C7x_1 ]     55.342860 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 11 @ space = 17 and size = 452224, addr = 104a0de00 ... 
[C7x_1 ]     55.342890 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 12 @ space = 17, size = 524544, align = 128 ... 
[C7x_1 ]     55.342921 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 12 @ space = 17 and size = 524544, addr = 10c000000 ... 
[C7x_1 ]     55.342953 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 13 @ space = 18, size = 128, align = 128 ... 
[C7x_1 ]     55.342983 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 13 @ space = 18 and size = 128, addr = 100d03c00 ... 
[C7x_1 ]     55.343012 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 14 @ space = 17, size = 128, align = 128 ... 
[C7x_1 ]     55.343033 s: MEM: Allocated 128 bytes @ 0x04a7c600
[C7x_1 ]     55.343055 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 14 @ space = 17 and size = 128, addr = 104a7c600 ... 
[C7x_1 ]     55.343084 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 15 @ space = 18, size = 128, align = 128 ... 
[C7x_1 ]     55.343115 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 15 @ space = 18 and size = 128, addr = 100d04000 ... 
[C7x_1 ]     55.343143 s:  VX_ZONE_INFO:[tivxAlgiVisionCreate:336] Calling ialg.algInit ...
[C7x_1 ]     55.384809 s: MEM: Freeing 384 bytes @ 0x044cf700
[C7x_1 ]     55.384837 s:  VX_ZONE_INFO:[tivxAlgiVisionCreate:386] Created AlgiVision handle.
[C7x_1 ]     55.384865 s:  VX_ZONE_INFO:[ownTargetKernelCreate:322] Done executing create callback for kernel [com.ti.tidl]
[C7x_1 ]     55.679311 s:  VX_ZONE_INFO:[ownTargetKernelDelete:348] Executing delete callback for kernel [com.ti.tidl]
[C7x_1 ]     55.679336 s:  VX_ZONE_INFO:[tivxKernelTIDLDelete:802] 1
[C7x_1 ]     55.679352 s:  VX_ZONE_INFO:[tivxAlgiVisionDelete:399] 2
[C7x_1 ]     55.679368 s:  VX_ZONE_INFO:[tivxAlgiVisionDelete:401] 3
[C7x_1 ]     55.679386 s:  VX_ZONE_INFO:[tivxAlgiVisionDeleteAlg:273] 4
[C7x_1 ]     55.679401 s: MEM: Allocated 384 bytes @ 0x044cf700
[C7x_1 ]     55.679421 s:  VX_ZONE_INFO:[tivxAlgiVisionDeleteAlg:279] 5
[C7x_1 ]     55.679450 s:  VX_ZONE_INFO:[tivxAlgiVisionDeleteAlg:282] 6
[C7x_1 ]     55.679466 s:  VX_ZONE_INFO:[tivxAlgiVisionDeleteAlg:286] 7
[C7x_1 ]     55.679483 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.0
[C7x_1 ]     55.679499 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.0
[C7x_1 ]     55.679515 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.0
[C7x_1 ]     55.679533 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.0
[C7x_1 ]     55.679550 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.1
[C7x_1 ]     55.679567 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.1
[C7x_1 ]     55.679583 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.1
[C7x_1 ]     55.679595 s: MEM: Freeing 256 bytes @ 0x044cfa00
[C7x_1 ]     55.679614 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.1
[C7x_1 ]     55.679631 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.2
[C7x_1 ]     55.679647 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.2
[C7x_1 ]     55.679663 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.2
[C7x_1 ]     55.679680 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.2
app_tidl: Delete ... Done.
[C7x_1 ]     55.679696 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.3
app_tidl: De-init ... 
[C7x_1 ]     55.679713 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.3
[C7x_1 ]     55.679729 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.4
[C7x_1 ]     55.679746 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.4
[C7x_1 ]     55.679762 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.4
[C7x_1 ]     55.679779 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.4
[C7x_1 ]     55.679795 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.5
[C7x_1 ]     55.679812 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.5
[C7x_1 ]     55.679828 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.5
[C7x_1 ]     55.679845 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.5
[C7x_1 ]     55.679862 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.6
[C7x_1 ]     55.679878 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.6
[C7x_1 ]     55.679894 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.6
[C7x_1 ]     55.679911 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.6
[C7x_1 ]     55.679927 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.7
[C7x_1 ]     55.679943 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.7
[C7x_1 ]     55.679960 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.7
[C7x_1 ]     55.679977 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.7
[C7x_1 ]     55.679993 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.8
[C7x_1 ]     55.680009 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.8
[C7x_1 ]     55.680025 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.8
[C7x_1 ]     55.680041 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.8
[C7x_1 ]     55.680058 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.9
[C7x_1 ]     55.680074 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.9
[C7x_1 ]     55.680090 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.9
[C7x_1 ]     55.680107 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.9
[C7x_1 ]     55.680123 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.10
[C7x_1 ]     55.680140 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.10
[C7x_1 ]     55.680156 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.10
[C7x_1 ]     55.680173 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.10
[C7x_1 ]     55.680189 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.11
[C7x_1 ]     55.680206 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.11
[C7x_1 ]     55.680221 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.11
[C7x_1 ]     55.680240 s: MEM: Freeing 452224 bytes @ 0x044cfc00
[C7x_1 ]     55.680258 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.11
[C7x_1 ]     55.680276 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.12
[C7x_1 ]     55.680292 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.12
[C7x_1 ]     55.680308 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.12
[C7x_1 ]     55.680325 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.12
[C7x_1 ]     55.680342 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.13
[C7x_1 ]     55.680358 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.13
[C7x_1 ]     55.680374 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.13
[C7x_1 ]     55.680391 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.13
[C7x_1 ]     55.680407 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.14
[C7x_1 ]     55.680424 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.14
[C7x_1 ]     55.680446 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.14
[C7x_1 ]     55.680459 s: MEM: Freeing 128 bytes @ 0x0453e400
[C7x_1 ]     55.680476 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.14
[C7x_1 ]     55.680493 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.15
[C7x_1 ]     55.680510 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.15
app_tidl: De-init ... Done.
app_tidl: Init ... 
[C7x_1 ]     55.680527 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.15
app_tidl: Reading config file /opt/vision_apps/test_data/tivx/tidl_models/tidl_io_mobilenet_v1_1.bin ...
[C7x_1 ]     55.680544 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.15
[C7x_1 ]     55.680561 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:256] 7.7.16
[C7x_1 ]     55.680577 s:  VX_ZONE_INFO:[tivxAlgiVisionDeleteAlg:288] 8
[C7x_1 ]     55.680588 s: MEM: Freeing 384 bytes @ 0x044cf700
[C7x_1 ]     55.680604 s:  VX_ZONE_INFO:[tivxAlgiVisionDeleteAlg:292] 9
[C7x_1 ]     55.680620 s:  VX_ZONE_INFO:[tivxKernelTIDLDelete:804] 10
[C7x_1 ]     55.680636 s:  VX_ZONE_INFO:[tivxKernelTIDLDelete:809] 11
[C7x_1 ]     55.680725 s: MEM: Freeing 4980224 bytes @ 0x0400f800
[C7x_1 ]     55.680742 s:  VX_ZONE_INFO:[tivxKernelTIDLDelete:811] 12
[C7x_1 ]     55.680758 s:  VX_ZONE_INFO:[tivxKernelTIDLDelete:814] 13
[C7x_1 ]     55.680770 s: MEM: Freeing 62848 bytes @ 0x04000100
[C7x_1 ]     55.680786 s:  VX_ZONE_INFO:[tivxKernelTIDLDelete:816] 14
[C7x_1 ]     55.680805 s:  VX_ZONE_INFO:[ownTargetKernelDelete:354] Done executing delete callback for kernel [com.ti.tidl]
app_tidl: Reading config file /opt/vision_apps/test_data/tivx/tidl_models/tidl_io_mobilenet_v1_1.bin ... Done. 37256 bytes
app_tidl: Tensors, input = 1, output = 1
app_tidl: Reading network file /opt/vision_apps/test_data/tivx/tidl_models/tidl_net_mobilenet_v1.bin ... 
app_tidl: Reading network file /opt/vision_apps/test_data/tivx/tidl_models/tidl_net_mobilenet_v1.bin ... Done. 4980176 bytes
app_tidl: Init ... Done.
app_tidl: Creating graph ... 
app_tidl: Creating graph ... Done.
app_tidl: Verifying graph ... 
    56.690900 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel com.ti.tidl:1:1
    56.691179 s:  VX_ZONE_INFO:[ownNodeKernelInit:510] Calling create callback for node TIDLNode
[C7x_1 ]     56.691328 s:  VX_ZONE_INFO:[ownTargetKernelInstanceAlloc:107] attempting to call kernel com.ti.tidl:1:1 on this CPU
[C7x_1 ]     56.691377 s:  VX_ZONE_INFO:[ownTargetKernelCreate:316] Executing create callback for kernel [com.ti.tidl]
[C7x_1 ]     56.691401 s: MEM: Allocated 62848 bytes @ 0x04000100
[C7x_1 ]     56.691577 s: MEM: Allocated 4980224 bytes @ 0x0400f800
[C7x_1 ]     56.701217 s:  VX_ZONE_INFO:[tivxKernelTIDLCreate:663] Enabling preemption
[C7x_1 ]     56.701249 s:  VX_ZONE_INFO:[tivxKernelTIDLCreate:686] L1 = 16 KB, L2 = 448 KB, L3 = 3072 KB
[C7x_1 ]     56.701274 s:  VX_ZONE_INFO:[tivxAlgiVisionCreate:311] Calling ialg.algNumAlloc ...
[C7x_1 ]     56.701296 s:  VX_ZONE_INFO:[tivxAlgiVisionCreate:315] Allocating 16 memory records ...
[C7x_1 ]     56.701313 s: MEM: Allocated 384 bytes @ 0x044cf700
[C7x_1 ]     56.701333 s:  VX_ZONE_INFO:[tivxAlgiVisionCreate:325] Calling ialg.algAlloc ...
[C7x_1 ]     56.701605 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 0 @ space = 18, size = 15216, align = 128 ... 
[C7x_1 ]     56.701641 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 0 @ space = 18 and size = 15216, addr = 100000000 ... 
[C7x_1 ]     56.701672 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 1 @ space = 17, size = 144, align = 128 ... 
[C7x_1 ]     56.701692 s: MEM: Allocated 256 bytes @ 0x044cfa00
[C7x_1 ]     56.701716 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 1 @ space = 17 and size = 144, addr = 1044cfa00 ... 
[C7x_1 ]     56.701747 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 2 @ space = 0, size = 16384, align = 128 ... 
[C7x_1 ]     56.701778 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 2 @ space = 0 and size = 16384, addr = 64e00000 ... 
[C7x_1 ]     56.701808 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 3 @ space = 1, size = 458752, align = 128 ... 
[C7x_1 ]     56.701839 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 3 @ space = 1 and size = 458752, addr = 64800000 ... 
[C7x_1 ]     56.701869 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 4 @ space = 2, size = 3014656, align = 128 ... 
    56.744546 s:  VX_ZONE_INFO:[ownNodeKernelInit:516] Create callback for node TIDLNode completed
[C7x_1 ]     56.701900 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 4 @ space = 2 and size = 3014656, addr = 68000000 ... 
    57.039376 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel com.ti.tidl:1:1 ...
    57.039391 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel com.ti.tidl:1:1 ... done !!!
[C7x_1 ]     56.701930 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 5 @ space = 18, size = 5148080, align = 128 ... 
[C7x_1 ]     56.701962 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 5 @ space = 18 and size = 5148080, addr = 100003c00 ... 
[C7x_1 ]     56.701991 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 6 @ space = 18, size = 256, align = 128 ... 
[C7x_1 ]     56.702022 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 6 @ space = 18 and size = 256, addr = 108000000 ... 
[C7x_1 ]     56.702052 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 7 @ space = 18, size = 151552, align = 128 ... 
[C7x_1 ]     56.702083 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 7 @ space = 18 and size = 151552, addr = 1004ecc00 ... 
app_tidl: Verifying graph ... Done.
[C7x_1 ]     56.702113 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 8 @ space = 18, size = 128, align = 128 ... 
app_tidl: Delete ... 
[C7x_1 ]     56.702143 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 8 @ space = 18 and size = 128, addr = 108000400 ... 
[C7x_1 ]     56.702173 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 9 @ space = 18, size = 3200, align = 128 ... 
[C7x_1 ]     56.702204 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 9 @ space = 18 and size = 3200, addr = 108000800 ... 
[C7x_1 ]     56.702234 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 10 @ space = 18, size = 128, align = 128 ... 
[C7x_1 ]     56.702265 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 10 @ space = 18 and size = 128, addr = 108001800 ... 
[C7x_1 ]     56.702295 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 11 @ space = 17, size = 452224, align = 128 ... 
[C7x_1 ]     56.702324 s: MEM: Allocated 452224 bytes @ 0x044cfc00
[C7x_1 ]     56.702350 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 11 @ space = 17 and size = 452224, addr = 1044cfc00 ... 
[C7x_1 ]     56.702381 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 12 @ space = 17, size = 524544, align = 128 ... 
[C7x_1 ]     56.702413 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 12 @ space = 17 and size = 524544, addr = 10c000000 ... 
[C7x_1 ]     56.702450 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 13 @ space = 18, size = 128, align = 128 ... 
[C7x_1 ]     56.702482 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 13 @ space = 18 and size = 128, addr = 100511c00 ... 
[C7x_1 ]     56.702512 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 14 @ space = 17, size = 128, align = 128 ... 
[C7x_1 ]     56.702534 s: MEM: Allocated 128 bytes @ 0x04a7c800
[C7x_1 ]     56.702557 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 14 @ space = 17 and size = 128, addr = 104a7c800 ... 
[C7x_1 ]     56.702587 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 15 @ space = 18, size = 128, align = 128 ... 
    57.041397 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
[C7x_1 ]     56.702618 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 15 @ space = 18 and size = 128, addr = 100512000 ... 
[C7x_1 ]     56.702648 s:  VX_ZONE_INFO:[tivxAlgiVisionCreate:336] Calling ialg.algInit ...
[C7x_1 ]     56.744276 s: MEM: Freeing 384 bytes @ 0x044cf700
[C7x_1 ]     56.744304 s:  VX_ZONE_INFO:[tivxAlgiVisionCreate:386] Created AlgiVision handle.
[C7x_1 ]     56.744333 s:  VX_ZONE_INFO:[ownTargetKernelCreate:322] Done executing create callback for kernel [com.ti.tidl]
[C7x_1 ]     57.039692 s:  VX_ZONE_INFO:[ownTargetKernelDelete:348] Executing delete callback for kernel [com.ti.tidl]
[C7x_1 ]     57.039718 s:  VX_ZONE_INFO:[tivxKernelTIDLDelete:802] 1
[C7x_1 ]     57.039734 s:  VX_ZONE_INFO:[tivxAlgiVisionDelete:399] 2
[C7x_1 ]     57.039750 s:  VX_ZONE_INFO:[tivxAlgiVisionDelete:401] 3
[C7x_1 ]     57.039768 s:  VX_ZONE_INFO:[tivxAlgiVisionDeleteAlg:273] 4
[C7x_1 ]     57.039784 s: MEM: Allocated 384 bytes @ 0x044cf700
[C7x_1 ]     57.039803 s:  VX_ZONE_INFO:[tivxAlgiVisionDeleteAlg:279] 5
[C7x_1 ]     57.039826 s:  VX_ZONE_INFO:[tivxAlgiVisionDeleteAlg:282] 6
[C7x_1 ]     57.039841 s:  VX_ZONE_INFO:[tivxAlgiVisionDeleteAlg:286] 7
[C7x_1 ]     57.039858 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.0
[C7x_1 ]     57.039874 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.0
[C7x_1 ]     57.039890 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.0
[C7x_1 ]     57.039908 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.0
[C7x_1 ]     57.039925 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.1
[C7x_1 ]     57.039941 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.1
[C7x_1 ]     57.039957 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.1
[C7x_1 ]     57.039969 s: MEM: Freeing 256 bytes @ 0x04a0dc00
[C7x_1 ]     57.039987 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.1
[C7x_1 ]     57.040004 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.2
[C7x_1 ]     57.040020 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.2
[C7x_1 ]     57.040036 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.2
[C7x_1 ]     57.040054 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.2
[C7x_1 ]     57.040071 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.3
[C7x_1 ]     57.040087 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.3
[C7x_1 ]     57.040104 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.4
[C7x_1 ]     57.040121 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.4
[C7x_1 ]     57.040138 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.4
[C7x_1 ]     57.040156 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.4
[C7x_1 ]     57.040173 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.5
[C7x_1 ]     57.040190 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.5
[C7x_1 ]     57.040207 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.5
[C7x_1 ]     57.040224 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.5
[C7x_1 ]     57.040240 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.6
[C7x_1 ]     57.040256 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.6
[C7x_1 ]     57.040272 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.6
[C7x_1 ]     57.040290 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.6
[C7x_1 ]     57.040306 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.7
[C7x_1 ]     57.040322 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.7
[C7x_1 ]     57.040338 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.7
[C7x_1 ]     57.040355 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.7
[C7x_1 ]     57.040371 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.8
[C7x_1 ]     57.040387 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.8
app_tidl: Delete ... Done.
app_tidl: De-init ... 
[C7x_1 ]     57.040404 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.8
[C7x_1 ]     57.040420 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.8
[C7x_1 ]     57.040444 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.9
[C7x_1 ]     57.040463 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.9
[C7x_1 ]     57.040479 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.9
[C7x_1 ]     57.040497 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.9
[C7x_1 ]     57.040514 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.10
[C7x_1 ]     57.040530 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.10
[C7x_1 ]     57.040547 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.10
[C7x_1 ]     57.040564 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.10
[C7x_1 ]     57.040580 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.11
[C7x_1 ]     57.040597 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.11
[C7x_1 ]     57.040613 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.11
[C7x_1 ]     57.040633 s: MEM: Freeing 452224 bytes @ 0x04a0de00
[C7x_1 ]     57.040651 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.11
[C7x_1 ]     57.040668 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.12
[C7x_1 ]     57.040685 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.12
[C7x_1 ]     57.040701 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.12
[C7x_1 ]     57.040719 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.12
[C7x_1 ]     57.040735 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.13
[C7x_1 ]     57.040752 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.13
[C7x_1 ]     57.040768 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.13
[C7x_1 ]     57.040785 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.13
[C7x_1 ]     57.040802 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.14
[C7x_1 ]     57.040819 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.14
[C7x_1 ]     57.040835 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.14
[C7x_1 ]     57.040846 s: MEM: Freeing 128 bytes @ 0x04a7c600
[C7x_1 ]     57.040864 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.14
[C7x_1 ]     57.040880 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.15
[C7x_1 ]     57.040897 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.15
[C7x_1 ]     57.040913 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.15
[C7x_1 ]     57.040931 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.15
[C7x_1 ]     57.040947 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:256] 7.7.16
[C7x_1 ]     57.040963 s:  VX_ZONE_INFO:[tivxAlgiVisionDeleteAlg:288] 8
[C7x_1 ]     57.040974 s: MEM: Freeing 384 bytes @ 0x044cf700
[C7x_1 ]     57.040991 s:  VX_ZONE_INFO:[tivxAlgiVisionDeleteAlg:292] 9
[C7x_1 ]     57.041008 s:  VX_ZONE_INFO:[tivxKernelTIDLDelete:804] 10
[C7x_1 ]     57.041023 s:  VX_ZONE_INFO:[tivxKernelTIDLDelete:809] 11
[C7x_1 ]     57.041046 s: MEM: Freeing 4980224 bytes @ 0x0454dd00
[C7x_1 ]     57.041129 s:  VX_ZONE_INFO:[tivxKernelTIDLDelete:811] 12
[C7x_1 ]     57.041144 s:  VX_ZONE_INFO:[tivxKernelTIDLDelete:814] 13
[C7x_1 ]     57.041156 s: MEM: Freeing 62848 bytes @ 0x0453e600
[C7x_1 ]     57.041173 s:  VX_ZONE_INFO:[tivxKernelTIDLDelete:816] 14
[C7x_1 ]     57.041192 s:  VX_ZONE_INFO:[ownTargetKernelDelete:354] Done executing delete callback for kernel [com.ti.tidl]
app_tidl: De-init ... Done.
app_tidl: Init ... 
app_tidl: Reading config file /opt/vision_apps/test_data/tivx/tidl_models/tidl_io_mobilenet_v1_1.bin ...
app_tidl: Reading config file /opt/vision_apps/test_data/tivx/tidl_models/tidl_io_mobilenet_v1_1.bin ... Done. 37256 bytes
app_tidl: Tensors, input = 1, output = 1
app_tidl: Reading network file /opt/vision_apps/test_data/tivx/tidl_models/tidl_net_mobilenet_v1.bin ... 
app_tidl: Reading network file /opt/vision_apps/test_data/tivx/tidl_models/tidl_net_mobilenet_v1.bin ... Done. 4980176 bytes
app_tidl: Init ... Done.
app_tidl: Creating graph ... 
app_tidl: Creating graph ... Done.
app_tidl: Verifying graph ... 
    58.050865 s:  VX_ZONE_INFO:[ownNodeKernelValidate:229] Validating kernel com.ti.tidl:1:1
    58.051140 s:  VX_ZONE_INFO:[ownNodeKernelInit:510] Calling create callback for node TIDLNode
[C7x_1 ]     58.051293 s:  VX_ZONE_INFO:[ownTargetKernelInstanceAlloc:107] attempting to call kernel com.ti.tidl:1:1 on this CPU
[C7x_1 ]     58.051343 s:  VX_ZONE_INFO:[ownTargetKernelCreate:316] Executing create callback for kernel [com.ti.tidl]
[C7x_1 ]     58.051366 s: MEM: Allocated 62848 bytes @ 0x0453e600
[C7x_1 ]     58.051536 s: MEM: Allocated 4980224 bytes @ 0x0454dd00
[C7x_1 ]     58.061053 s:  VX_ZONE_INFO:[tivxKernelTIDLCreate:663] Enabling preemption
[C7x_1 ]     58.061085 s:  VX_ZONE_INFO:[tivxKernelTIDLCreate:686] L1 = 16 KB, L2 = 448 KB, L3 = 3072 KB
[C7x_1 ]     58.061110 s:  VX_ZONE_INFO:[tivxAlgiVisionCreate:311] Calling ialg.algNumAlloc ...
[C7x_1 ]     58.061131 s:  VX_ZONE_INFO:[tivxAlgiVisionCreate:315] Allocating 16 memory records ...
[C7x_1 ]     58.061149 s: MEM: Allocated 384 bytes @ 0x044cf700
[C7x_1 ]     58.061168 s:  VX_ZONE_INFO:[tivxAlgiVisionCreate:325] Calling ialg.algAlloc ...
[C7x_1 ]     58.061440 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 0 @ space = 18, size = 15216, align = 128 ... 
[C7x_1 ]     58.061477 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 0 @ space = 18 and size = 15216, addr = 100000000 ... 
[C7x_1 ]     58.061509 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 1 @ space = 17, size = 144, align = 128 ... 
[C7x_1 ]     58.061530 s: MEM: Allocated 256 bytes @ 0x04a0dc00
[C7x_1 ]     58.061554 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 1 @ space = 17 and size = 144, addr = 104a0dc00 ... 
[C7x_1 ]     58.061585 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 2 @ space = 0, size = 16384, align = 128 ... 
[C7x_1 ]     58.061616 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 2 @ space = 0 and size = 16384, addr = 64e00000 ... 
[C7x_1 ]     58.061647 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 3 @ space = 1, size = 458752, align = 128 ... 
[C7x_1 ]     58.061678 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 3 @ space = 1 and size = 458752, addr = 64800000 ... 
[C7x_1 ]     58.061709 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 4 @ space = 2, size = 3014656, align = 128 ... 
[C7x_1 ]     58.061740 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 4 @ space = 2 and size = 3014656, addr = 68000000 ... 
[C7x_1 ]     58.061771 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 5 @ space = 18, size = 5148080, align = 128 ... 
[C7x_1 ]     58.061802 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 5 @ space = 18 and size = 5148080, addr = 100003c00 ... 
[C7x_1 ]     58.061833 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 6 @ space = 18, size = 256, align = 128 ... 
[C7x_1 ]     58.061864 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 6 @ space = 18 and size = 256, addr = 108000000 ... 
[C7x_1 ]     58.061894 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 7 @ space = 18, size = 151552, align = 128 ... 
[C7x_1 ]     58.061925 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 7 @ space = 18 and size = 151552, addr = 1004ecc00 ... 
[C7x_1 ]     58.061955 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 8 @ space = 18, size = 128, align = 128 ... 
[C7x_1 ]     58.061985 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 8 @ space = 18 and size = 128, addr = 108000400 ... 
[C7x_1 ]     58.062015 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 9 @ space = 18, size = 3200, align = 128 ... 
    58.104370 s:  VX_ZONE_INFO:[ownNodeKernelInit:516] Create callback for node TIDLNode completed
[C7x_1 ]     58.062045 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 9 @ space = 18 and size = 3200, addr = 108000800 ... 
    58.399831 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel com.ti.tidl:1:1 ...
    58.399844 s:  VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel com.ti.tidl:1:1 ... done !!!
[C7x_1 ]     58.062076 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 10 @ space = 18, size = 128, align = 128 ... 
[C7x_1 ]     58.062108 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 10 @ space = 18 and size = 128, addr = 108001800 ... 
[C7x_1 ]     58.062138 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 11 @ space = 17, size = 452224, align = 128 ... 
[C7x_1 ]     58.062166 s: MEM: Allocated 452224 bytes @ 0x04a0de00
[C7x_1 ]     58.062191 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 11 @ space = 17 and size = 452224, addr = 104a0de00 ... 
[C7x_1 ]     58.062221 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 12 @ space = 17, size = 524544, align = 128 ... 
app_tidl: Verifying graph ... Done.
[C7x_1 ]     58.062253 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 12 @ space = 17 and size = 524544, addr = 10c000000 ... 
app_tidl: Delete ... 
[C7x_1 ]     58.062283 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 13 @ space = 18, size = 128, align = 128 ... 
[C7x_1 ]     58.062313 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 13 @ space = 18 and size = 128, addr = 100511c00 ... 
[C7x_1 ]     58.062343 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 14 @ space = 17, size = 128, align = 128 ... 
[C7x_1 ]     58.062363 s: MEM: Allocated 128 bytes @ 0x04a7ca00
[C7x_1 ]     58.062385 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 14 @ space = 17 and size = 128, addr = 104a7ca00 ... 
[C7x_1 ]     58.062414 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:180] Allocating memory record 15 @ space = 18, size = 128, align = 128 ... 
[C7x_1 ]     58.062448 s:  VX_ZONE_INFO:[tivxAlgiVisionAllocMem:189] Allocated memory record 15 @ space = 18 and size = 128, addr = 100512000 ... 
[C7x_1 ]     58.062478 s:  VX_ZONE_INFO:[tivxAlgiVisionCreate:336] Calling ialg.algInit ...
[C7x_1 ]     58.104095 s: MEM: Freeing 384 bytes @ 0x044cf700
[C7x_1 ]     58.104124 s:  VX_ZONE_INFO:[tivxAlgiVisionCreate:386] Created AlgiVision handle.
[C7x_1 ]     58.104153 s:  VX_ZONE_INFO:[ownTargetKernelCreate:322] Done executing create callback for kernel [com.ti.tidl]
[C7x_1 ]     58.400135 s:  VX_ZONE_INFO:[ownTargetKernelDelete:348] Executing delete callback for kernel [com.ti.tidl]
[C7x_1 ]     58.400160 s:  VX_ZONE_INFO:[tivxKernelTIDLDelete:802] 1
[C7x_1 ]     58.400175 s:  VX_ZONE_INFO:[tivxAlgiVisionDelete:399] 2
[C7x_1 ]     58.400191 s:  VX_ZONE_INFO:[tivxAlgiVisionDelete:401] 3
[C7x_1 ]     58.400208 s:  VX_ZONE_INFO:[tivxAlgiVisionDeleteAlg:273] 4
[C7x_1 ]     58.400225 s: MEM: Allocated 384 bytes @ 0x044cf700
[C7x_1 ]     58.400244 s:  VX_ZONE_INFO:[tivxAlgiVisionDeleteAlg:279] 5
[C7x_1 ]     58.400267 s:  VX_ZONE_INFO:[tivxAlgiVisionDeleteAlg:282] 6
[C7x_1 ]     58.400283 s:  VX_ZONE_INFO:[tivxAlgiVisionDeleteAlg:286] 7
[C7x_1 ]     58.400300 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.0
[C7x_1 ]     58.400316 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.0
[C7x_1 ]     58.400332 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.0
[C7x_1 ]     58.400350 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.0
    58.401852 s:  VX_ZONE_INFO:[tivxQueueDelete:182] if this hangs, please ensure all application threads have been destroyed
[C7x_1 ]     58.400366 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.1
[C7x_1 ]     58.400383 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.1
[C7x_1 ]     58.400399 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.1
[C7x_1 ]     58.400412 s: MEM: Freeing 256 bytes @ 0x04a0dc00
[C7x_1 ]     58.400431 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.1
[C7x_1 ]     58.400457 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.2
[C7x_1 ]     58.400475 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.2
[C7x_1 ]     58.400491 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.2
[C7x_1 ]     58.400509 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.2
[C7x_1 ]     58.400525 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.3
[C7x_1 ]     58.400542 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.3
[C7x_1 ]     58.400558 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.4
[C7x_1 ]     58.400575 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.4
[C7x_1 ]     58.400592 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.4
[C7x_1 ]     58.400609 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.4
[C7x_1 ]     58.400626 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.5
[C7x_1 ]     58.400643 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.5
[C7x_1 ]     58.400659 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.5
[C7x_1 ]     58.400676 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.5
[C7x_1 ]     58.400693 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.6
[C7x_1 ]     58.400709 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.6
[C7x_1 ]     58.400725 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.6
[C7x_1 ]     58.400743 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.6
[C7x_1 ]     58.400759 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.7
[C7x_1 ]     58.400775 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.7
[C7x_1 ]     58.400792 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.7
[C7x_1 ]     58.400810 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.7
[C7x_1 ]     58.400826 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.8
[C7x_1 ]     58.400844 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.8
[C7x_1 ]     58.400861 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.8
[C7x_1 ]     58.400878 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.8
[C7x_1 ]     58.400894 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.9
[C7x_1 ]     58.400910 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.9
[C7x_1 ]     58.400927 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.9
[C7x_1 ]     58.400944 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.9
[C7x_1 ]     58.400960 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.10
[C7x_1 ]     58.400976 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.10
[C7x_1 ]     58.400992 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.10
[C7x_1 ]     58.401009 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.10
[C7x_1 ]     58.401025 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.11
[C7x_1 ]     58.401042 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.11
[C7x_1 ]     58.401058 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.11
[C7x_1 ]     58.401076 s: MEM: Freeing 452224 bytes @ 0x04a0de00
[C7x_1 ]     58.401095 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.11
[C7x_1 ]     58.401112 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.12
[C7x_1 ]     58.401129 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.12
[C7x_1 ]     58.401145 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.12
[C7x_1 ]     58.401161 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.12
[C7x_1 ]     58.401177 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.13
app_tidl: Delete ... Done.
app_tidl: De-init ... 
[C7x_1 ]     58.401194 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.13
[C7x_1 ]     58.401210 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.13
[C7x_1 ]     58.401227 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.13
[C7x_1 ]     58.401244 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.14
[C7x_1 ]     58.401260 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.14
[C7x_1 ]     58.401276 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.14
[C7x_1 ]     58.401288 s: MEM: Freeing 128 bytes @ 0x04a7ca00
[C7x_1 ]     58.401304 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.14
[C7x_1 ]     58.401320 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.15
[C7x_1 ]     58.401337 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.15
[C7x_1 ]     58.401353 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.15
[C7x_1 ]     58.401370 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.15
[C7x_1 ]     58.401386 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:256] 7.7.16
[C7x_1 ]     58.401401 s:  VX_ZONE_INFO:[tivxAlgiVisionDeleteAlg:288] 8
[C7x_1 ]     58.401413 s: MEM: Freeing 384 bytes @ 0x044cf700
[C7x_1 ]     58.401428 s:  VX_ZONE_INFO:[tivxAlgiVisionDeleteAlg:292] 9
[C7x_1 ]     58.401447 s:  VX_ZONE_INFO:[tivxKernelTIDLDelete:804] 10
[C7x_1 ]     58.401463 s:  VX_ZONE_INFO:[tivxKernelTIDLDelete:809] 11
[C7x_1 ]     58.401552 s: MEM: Freeing 4980224 bytes @ 0x0400f800
[C7x_1 ]     58.401569 s:  VX_ZONE_INFO:[tivxKernelTIDLDelete:811] 12
[C7x_1 ]     58.401584 s:  VX_ZONE_INFO:[tivxKernelTIDLDelete:814] 13
[C7x_1 ]     58.401595 s: MEM: Freeing 62848 bytes @ 0x04000100
[C7x_1 ]     58.401612 s:  VX_ZONE_INFO:[tivxKernelTIDLDelete:816] 14
[C7x_1 ]     58.401631 s:  VX_ZONE_INFO:[ownTargetKernelDelete:354] Done executing delete callback for kernel [com.ti.tidl]
app_tidl: De-init ... Done.
app_tidl: Delete ... 
[C7x_1 ]     59.068281 s:  VX_ZONE_INFO:[ownTargetKernelDelete:348] Executing delete callback for kernel [com.ti.tidl]
[C7x_1 ]     59.068306 s:  VX_ZONE_INFO:[tivxKernelTIDLDelete:802] 1
[C7x_1 ]     59.068322 s:  VX_ZONE_INFO:[tivxAlgiVisionDelete:399] 2
[C7x_1 ]     59.068339 s:  VX_ZONE_INFO:[tivxAlgiVisionDelete:401] 3
[C7x_1 ]     59.068356 s:  VX_ZONE_INFO:[tivxAlgiVisionDeleteAlg:273] 4
[C7x_1 ]     59.068371 s: MEM: Allocated 384 bytes @ 0x04000100
[C7x_1 ]     59.068390 s:  VX_ZONE_INFO:[tivxAlgiVisionDeleteAlg:279] 5
[C7x_1 ]     59.068412 s:  VX_ZONE_INFO:[tivxAlgiVisionDeleteAlg:282] 6
[C7x_1 ]     59.068429 s:  VX_ZONE_INFO:[tivxAlgiVisionDeleteAlg:286] 7
[C7x_1 ]     59.068454 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.0
[C7x_1 ]     59.068472 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.0
[C7x_1 ]     59.068488 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.0
[C7x_1 ]     59.068506 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:247] 7.4.0
[C7x_1 ]     59.068523 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:237] 7.1.1
[C7x_1 ]     59.068539 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:239] 7.2.1
[C7x_1 ]     59.068555 s:  VX_ZONE_INFO:[tivxAlgiVisionFreeMem:245] 7.3.1
[C7x_1 ]     59.068567 s: MEM: Freeing 256 bytes @ 0x04a0dc00

Could you please confirm the same at your end if the code is hanging in the same place?

Meanwhile, I am looking into finding the root cause of this issue.

Regards,

Nikhil

0 xueming tian over 1 year ago in reply to Nikhil Dasan

Intellectual 621 points

Yes, It hangs in the same place with you, and the call stack is as follows:
tivxKernelTIDLDelete -> tivxAlgiVisionDelete -> tivxAlgiVisionDeleteAlg -> tivxAlgiVisionFreeMem -> tivxMemFree -> appMemFree -> HeapP_free.

0 Nikhil Dasan over 1 year ago in reply to xueming tian

TI__Guru* 80046 points

Hi,

Changing the code a bit as shown below should resolve this issue.

    if(status == VX_SUCCESS)
    {
        status = app_init(obj1);
    }
    printf("CHECK1!!!!\n");
    if(status == VX_SUCCESS)
    {
        status = app_create_graph(obj1);
    }
    if(status == VX_SUCCESS)
    {
        status = app_verify_graph(obj1);
    }

    app_delete_graph(obj1);
    app_deinit(obj1);
    
    if(status == VX_SUCCESS)
    {
        status = app_init(obj2);
    }
    if(status == VX_SUCCESS)
    {
        status = app_create_graph(obj2);
    }
    if(status == VX_SUCCESS)
    {
        status = app_verify_graph(obj2);
    }

    app_delete_graph(obj2);
    app_deinit(obj2);
    
    if(status == VX_SUCCESS)
    {
        status = app_init(obj3);
    }
    if(status == VX_SUCCESS)
    {
        status = app_create_graph(obj3);
    }
    if(status == VX_SUCCESS)
    {
        status = app_verify_graph(obj3);
    }

    app_delete_graph(obj3);
    app_deinit(obj3);
    
    if(status == VX_SUCCESS)
    {
        status = app_init(obj4);
    }
    if(status == VX_SUCCESS)
    {
        status = app_create_graph(obj4);
    }
    if(status == VX_SUCCESS)
    {
        status = app_verify_graph(obj4);
    }
    
    app_delete_graph(obj4);
    app_deinit(obj4);

The corresponding objects has to undergo the complete phase of creation to delete before the second is started.

I'm suspecting the reason to be that the heap implementation stores relevant software book-keeping parameters in the unallocated/free heap regions. Overflow of a previous allocated buffer could corrupt this and could cause issues with successive heap allocations. Similarly this could also corrupt the contents of successive allocated buffers as well.

Please let me know if this satifies your usecase.

Regards,

Nikhil

0 xueming tian over 1 year ago in reply to Nikhil Dasan

Intellectual 621 points

Yes, this change will resolve this issue, and we have tried this modification. But, this change did not satify our usecase, and the same usecase in tda4vm8.5 run successfully.

0 Nikhil Dasan over 1 year ago in reply to xueming tian

TI__Guru* 80046 points

Hi,

I have raised this issue internally. Placing the internal JIRA link here for TI's tracking issue.

https://jira.itg.ti.com/browse/ADASVISION-5849

Meanwhile, could you please tell me what is the usecase here where you are creating multiple graphs and deleting the same without executing the graph? Also, may I know why the graphs are being deleted instead of stopping it and deleting at the end of application?

Regards,

Nikhil

Processors

Processors forum

TDA4VM: TDA4VH: TIDL hangs after repeated build operation