Hi all:
I'm going to replace the H.264 with videnc1_copy and viddec2_copy in order to add my own algorithm.
I think I have found the example files of videnc1_copy and viddec2_copy I want(in xdais/examples folder, am I right?).
What I have done is
1.) Copy them(videnc1_copy.c videnc1_copy_ti.h and videnc1_copy_ti_priv.h) into the ce_examples_dir/codecs and changed the configuration files and then make to generate .a64p file
2.) Use ce_examples_dir/servers/server_api_example to generate .x64p file. I changed the .tci and .tcf files to allocate memory
3.) Change encodedecode.cfg file in encodedecode and replace the server file with what I've just built. And make.
After these steps the videnc1_copy/viddec2_copy are successfully created.
Howerer, there comes another problem which I think is beyond my ability to solve it. I use CE_DEBUG=3 to get the debug information as what I have attached. The encodedecode stopped at the Ccv job. I have googled the problem and got clues but I'm not sure how to solve it. Could anyone plz help me?
the debug information(sorry that the log is so long):
root@dm6467t-evm:/opt/dvsdk/lxl# ./loadmodules.sh
CMEMK module: built on Jul 14 2010 at 15:49:56
Reference Linux version 2.6.32
File /sim/scratch_a0868396/arago-install/dvsdk_3_10_00_19/linuxutils_2_25_04_10/packages/ti/sdo/linuxutils/cmem/src/module/cmemk.c
allocated heap buffer 0xc9000000 of size 0x99000
cmemk initialized
DSPLINK Module (1.64) created on Date: Jul 14 2010 Time: 15:39:32
root@dm6467t-evm:/opt/dvsdk/lxl# cd myencodedecode/
root@dm6467t-evm:/opt/dvsdk/lxl/myencodedecode# CE_DEBUG=3 ./myencodedecode
@0,848,264us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - Global_init> This program was built with the following packages:
@0,850,092us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package gnu.targets.arm.rtsv5T (/opt/dvsdk/dvsdk_3_10_00_19/xdctools_3_16_01_27/packages/gnu/targets/arm/rtsv5T/) [1, 0, 0, 0]
@0,850,314us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.ce.global (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/global/) [1, 0, 0]
@0,850,425us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.xdais.dm (/opt/dvsdk/dvsdk_3_10_00_19/xdais_6_25_02_11/packages/ti/xdais/dm/) [1, 0, 5]
@0,850,527us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.utils.trace (/opt/dvsdk/dvsdk_3_10_00_19/framework_components_2_25_02_06/packages/ti/sdo/utils/trace/) [1, 0, 0]
@0,850,630us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.ce.utils.xdm (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/utils/xdm/) [1, 0, 2]
@0,850,732us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package dsplink.gpp (/opt/dvsdk/dvsdk_3_10_00_19/dsplink_linux_1_64/dsplink/gpp/) [5, 0, 0]
@0,850,830us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.linuxutils.cmem (/opt/dvsdk/dvsdk_3_10_00_19/linuxutils_2_25_04_10/packages/ti/sdo/linuxutils/cmem/) [2, 2, 0]
@0,850,931us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.xdais (/opt/dvsdk/dvsdk_3_10_00_19/xdais_6_25_02_11/packages/ti/xdais/) [1, 2.0, 1]
@0,851,027us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.ce.node (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/node/) [1, 0, 0]
@0,851,128us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.fc.global (/opt/dvsdk/dvsdk_3_10_00_19/framework_components_2_25_02_06/packages/ti/sdo/fc/global/) [1, 0, 0]
@0,851,229us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.fc.memutils (/opt/dvsdk/dvsdk_3_10_00_19/framework_components_2_25_02_06/packages/ti/sdo/fc/memutils/) [1, 0, 0]
@0,851,373us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.fc.utils (/opt/dvsdk/dvsdk_3_10_00_19/framework_components_2_25_02_06/packages/ti/sdo/fc/utils/) [1, 0, 2]
@0,851,482us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.fc.dman3 (/opt/dvsdk/dvsdk_3_10_00_19/framework_components_2_25_02_06/packages/ti/sdo/fc/dman3/) [1, 0, 4]
@0,851,586us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.fc.acpy3 (/opt/dvsdk/dvsdk_3_10_00_19/framework_components_2_25_02_06/packages/ti/sdo/fc/acpy3/) [1, 0, 4]
@0,851,688us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package gnu.targets (/opt/dvsdk/dvsdk_3_10_00_19/xdctools_3_16_01_27/packages/gnu/targets/) [1, 0, 1]
@0,851,786us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package gnu.targets.arm (/opt/dvsdk/dvsdk_3_10_00_19/xdctools_3_16_01_27/packages/gnu/targets/arm/) [1, 0, 0, 0]
@0,851,885us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.catalog.arm (/opt/dvsdk/dvsdk_3_10_00_19/xdctools_3_16_01_27/packages/ti/catalog/arm/) [1, 0, 1, 0]
@0,851,984us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.catalog (/opt/dvsdk/dvsdk_3_10_00_19/xdctools_3_16_01_27/packages/ti/catalog/) [1, 0, 0]
@0,852,080us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.catalog.c6000 (/opt/dvsdk/dvsdk_3_10_00_19/xdctools_3_16_01_27/packages/ti/catalog/c6000/) [1, 0, 0, 0]
@0,852,182us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.platforms.evmDM6467 (/opt/dvsdk/dvsdk_3_10_00_19/xdctools_3_16_01_27/packages/ti/platforms/evmDM6467/) [1, 0, 0, 0]
@0,852,282us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.ce.osal (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/osal/) [2, 0, 2]
@0,852,379us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.ce.ipc (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/ipc/) [2, 0, 1]
@0,852,478us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.ce.osal.linux (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/osal/linux/) [2, 0, 1]
@0,852,578us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.ce.ipc.dsplink (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/ipc/dsplink/) [2, 0, 1]
@1,186,027us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.ce.alg (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/alg/) [1, 0, 1]
@1,186,153us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.ce (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/) [1, 0, 6]
@1,186,260us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.ce.video1 (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/video1/) [1, 0, 2]
@1,186,362us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.ce.examples.codecs.videnc1_copy (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/examples/ti/sdo/ce/examples/codecs/videnc1_copy/) [1, 0, 0]
@1,186,469us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.ce.video2 (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/video2/) [1, 0, 2]
@1,186,570us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.ce.examples.codecs.viddec2_copy (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/examples/ti/sdo/ce/examples/codecs/viddec2_copy/) [1, 0, 0]
@1,186,676us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.ce.speech (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/speech/) [1, 0, 2]
@1,186,776us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.ce.speech1 (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/speech1/) [1, 0, 1]
@1,186,873us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.ce.audio (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/audio/) [1, 0, 2]
@1,186,973us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.ce.audio1 (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/audio1/) [1, 0, 1]
@1,187,073us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.ce.video (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/video/) [1, 0, 3]
@1,187,174us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.ce.image (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/image/) [1, 0, 3]
@1,187,272us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.ce.image1 (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/image1/) [1, 0, 1]
@1,187,370us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.dmai (/opt/dvsdk/dvsdk_3_10_00_19/dmai_2_10_00_12/packages/ti/sdo/dmai/) [1, 0, 0]
@1,187,467us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package ti.sdo.simplewidget (/opt/dvsdk/dvsdk_3_10_00_19/dvsdk_demos_3_10_00_16/packages/ti/sdo/simplewidget/) []
@1,187,567us: [+4 T:0x4001fcb0 S:0xbef35ac4] OG - package myencodedecode_config (/opt/dvsdk/dvsdk_3_10_00_19/dvsdk_demos_3_10_00_16/dm6467/myencodedecode/myencodedecode_config/) []
@1,187,720us: [+0 T:0x4001fcb0 S:0xbef35acc] OG - Global_atexit> enter (fxn=0x308e0)
@1,187,838us: [+0 T:0x4001fcb0 S:0xbef35acc] OG - Global_atexit> enter (fxn=0x2fd2c)
@1,188,068us: [+0 T:0x4001fcb0 S:0xbef35a9c] OM - Memory_alloc> Enter(0x18)
@1,188,187us: [+0 T:0x4001fcb0 S:0xbef35a9c] OM - Memory_alloc> return (0x610f0)
@1,188,491us: [+0 T:0x4001fcb0 S:0xbef35abc] OG - Global_atexit> enter (fxn=0x2dbb4)
@1,188,668us: [+0 T:0x4001fcb0 S:0xbef35a94] OM - Memory_alloc> Enter(0x18)
@1,188,817us: [+0 T:0x4001fcb0 S:0xbef35a94] OM - Memory_alloc> return (0x61140)
@1,188,921us: [+0 T:0x4001fcb0 S:0xbef35ab4] OG - Global_atexit> enter (fxn=0x2c9e0)
@1,189,090us: [+0 T:0x4001fcb0 S:0xbef35abc] OG - Global_atexit> enter (fxn=0x2f390)
@1,189,254us: [+0 T:0x4001fcb0 S:0xbef35ab4] ti.sdo.ce.osal.Sem - Sem_create> count: 0
@1,189,363us: [+0 T:0x4001fcb0 S:0xbef35a9c] OM - Memory_alloc> Enter(0x14)
@1,189,467us: [+0 T:0x4001fcb0 S:0xbef35a9c] OM - Memory_alloc> return (0x611c0)
@1,524,748us: [+0 T:0x4001fcb0 S:0xbef35ab4] ti.sdo.ce.osal.Sem - Leaving Sem_create> sem[0x611c0]
@1,524,869us: [+0 T:0x4001fcb0 S:0xbef35ab4] ti.sdo.ce.osal.Sem - Sem_create> count: 0
@1,524,966us: [+0 T:0x4001fcb0 S:0xbef35a9c] OM - Memory_alloc> Enter(0x14)
@1,525,066us: [+0 T:0x4001fcb0 S:0xbef35a9c] OM - Memory_alloc> return (0x611d8)
@1,525,164us: [+0 T:0x4001fcb0 S:0xbef35ab4] ti.sdo.ce.osal.Sem - Leaving Sem_create> sem[0x611d8]
@1,525,261us: [+0 T:0x4001fcb0 S:0xbef35a9c] OM - Memory_alloc> Enter(0x18)
@1,525,358us: [+0 T:0x4001fcb0 S:0xbef35a9c] OM - Memory_alloc> return (0x611f0)
@1,525,455us: [+0 T:0x4001fcb0 S:0xbef35a94] OT - Thread_create> Enter (fxn=0x2b1c8, attrs=0x0)
@1,525,552us: [+0 T:0x4001fcb0 S:0xbef35a7c] OM - Memory_alloc> Enter(0x64)
@1,525,650us: [+0 T:0x4001fcb0 S:0xbef35a7c] OM - Memory_alloc> return (0x61210)
@1,526,177us: [+0 T:0x4001fcb0 S:0xbef35a94] OT - Thread_create> Exit (task=0x61210)
@1,526,311us: [+0 T:0x4001fcb0 S:0xbef35acc] OG - Global_atexit> enter (fxn=0x2abe0)
@1,526,434us: [+0 T:0x4001fcb0 S:0xbef35acc] OG - Global_atexit> enter (fxn=0x2cf2c)
@1,526,641us: [+0 T:0x4001fcb0 S:0xbef35ab4] ti.sdo.ce.alg - ALG_init> Enter
@1,526,751us: [+0 T:0x4001fcb0 S:0xbef35ad4] ti.sdo.ce.alg - ALG_init> Exit
@1,526,847us: [+0 T:0x4001fcb0 S:0xbef35acc] OG - Global_atexit> enter (fxn=0x275a0)
@1,526,958us: [+0 T:0x4001fcb0 S:0xbef35aa4] OM - Memory_alloc> Enter(0x18)
@1,527,065us: [+0 T:0x4001fcb0 S:0xbef35aa4] OM - Memory_alloc> return (0x613e0)
@1,527,170us: [+0 T:0x4001fcb0 S:0xbef35acc] OG - Global_atexit> enter (fxn=0x2f880)
@1,527,314us: [+6 T:0x4001fcb0 S:0xbef35ac4] CE - Engine_init> CE debugging on (CE_DEBUG=3; allowed CE_DEBUG levels: 1=min, 2=good, 3=max)
@1,527,452us: [+0 T:0x4001fcb0 S:0xbef35ab4] OG - Global_atexit> enter (fxn=0x24760)
@1,527,562us: [+0 T:0x4001fcb0 S:0xbef35a94] OM - Memory_alloc> Enter(0x18)
@1,527,667us: [+0 T:0x4001fcb0 S:0xbef35a94] OM - Memory_alloc> return (0x61420)
@1,527,767us: [+0 T:0x4001fcb0 S:0xbef35a94] OM - Memory_alloc> Enter(0x18)
@1,528,110us: [+1 T:0x40bd6490 S:0x40bd5d64] OP - daemon> thread created.
@1,528,236us: [+0 T:0x40bd6490 S:0x40bd5d64] OP - getCmd_d> Enter (proc=0x40bd5dd8)
@1,528,337us: [+0 T:0x40bd6490 S:0x40bd5d44] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x611c0] timeout[0xffffffff]
@1,528,539us: [+0 T:0x4001fcb0 S:0xbef35a94] OM - Memory_alloc> return (0x61440)
@1,528,664us: [+0 T:0x4001fcb0 S:0xbef35a94] OM - Memory_alloc> Enter(0x18)
@1,528,773us: [+0 T:0x4001fcb0 S:0xbef35a94] OM - Memory_alloc> return (0x61460)
@1,528,943us: [+0 T:0x4001fcb0 S:0xbef35adc] CS - Server_init()
@1,529,049us: [+0 T:0x4001fcb0 S:0xbef35adc] CS - Server_init> Global_useLinkArbiter = 0
@1,529,154us: [+0 T:0x4001fcb0 S:0xbef35adc] OG - Global_atexit> enter (fxn=0x229a0)
Encodedecode demo started.
@1,530,292us: [+2 T:0x413d6490 S:0x413d5cec] ti.sdo.dmai - [BufTab] Allocating BufTab for 3 buffers
@1,530,466us: [+0 T:0x413d6490 S:0x413d5cac] OM - Memory_alloc> Enter(0xca800)
@1,530,576us: [+0 T:0x413d6490 S:0x413d5c64] OM - Memory_contigAlloc> Enter(size=829440, align=-1, cached=FALSE, heap=FALSE)
@1,530,840us: [+4 T:0x413d6490 S:0x413d5c64] OM - Memory_contigAlloc> CMEM_alloc(829440) = 0x413d7000.
@1,530,984us: [+4 T:0x413d6490 S:0x413d5c64] OM - Memory_contigAlloc> CMEM_getPhys(0x413d7000) = 0x8b91f000.
@1,531,091us: [+1 T:0x413d6490 S:0x413d5c1c] OM - Memory__addContigBuf> Enter(virtAddr=0x413d7000, size=829440, physAddr=0x8b91f000)
@1,531,195us: [+1 T:0x413d6490 S:0x413d5c1c] OM - Memory__addContigBuf> creating new contigBuf object
@1,531,284us: [+0 T:0x413d6490 S:0x413d5c04] OM - Memory_alloc> Enter(0x10)
@1,531,380us: [+0 T:0x413d6490 S:0x413d5c04] OM - Memory_alloc> return (0x61810)
@1,531,471us: [+1 T:0x413d6490 S:0x413d5c1c] OM - Memory__addContigBuf> returning: cb->phys=0x8b91f000, cb->size=829440, cb->virt=0x413d7000
@1,866,263us: [+0 T:0x413d6490 S:0x413d5c64] OM - Memory_contigAlloc> return (0x413d7000)
@1,866,381us: [+0 T:0x413d6490 S:0x413d5cac] OM - Memory_alloc> return (0x413d7000)
@1,866,479us: [+0 T:0x413d6490 S:0x413d5c8c] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x413d7000, size=4)
@1,866,576us: [+1 T:0x413d6490 S:0x413d5c8c] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x413d7000, size=4)
@1,866,669us: [+1 T:0x413d6490 S:0x413d5c8c] OM - Memory__getPhysicalAddress> found in cb(Sc=0x413d7000, Ec=0x414a1800, Ss=0x413d7000, Es=0x413d7004, PSc=0x8b91f000)
@1,866,773us: [+1 T:0x413d6490 S:0x413d5c8c] OM - Memory__getPhysicalAddress> returning physAddr=0x8b91f000
@1,866,864us: [+0 T:0x413d6490 S:0x413d5c8c] OM - Memory_getBufferPhysicalAddress> return (0x8b91f000)
@1,866,954us: [+2 T:0x413d6490 S:0x413d5cc4] ti.sdo.dmai - [Buffer] Alloc Buffer of size 829440 at 0x413d7000 (0x8b91f000 phys)
@1,867,063us: [+0 T:0x413d6490 S:0x413d5cac] OM - Memory_alloc> Enter(0xca800)
@1,867,161us: [+0 T:0x413d6490 S:0x413d5c64] OM - Memory_contigAlloc> Enter(size=829440, align=-1, cached=FALSE, heap=FALSE)
@1,867,366us: [+4 T:0x413d6490 S:0x413d5c64] OM - Memory_contigAlloc> CMEM_alloc(829440) = 0x424b8000.
@1,867,511us: [+4 T:0x413d6490 S:0x413d5c64] OM - Memory_contigAlloc> CMEM_getPhys(0x424b8000) = 0x8b83e000.
@1,867,619us: [+1 T:0x413d6490 S:0x413d5c1c] OM - Memory__addContigBuf> Enter(virtAddr=0x424b8000, size=829440, physAddr=0x8b83e000)
@1,867,723us: [+1 T:0x413d6490 S:0x413d5c1c] OM - Memory__addContigBuf> creating new contigBuf object
@1,867,812us: [+0 T:0x413d6490 S:0x413d5c04] OM - Memory_alloc> Enter(0x10)
@1,867,983us: [+0 T:0x413d6490 S:0x413d5c04] OM - Memory_alloc> return (0x61a38)
@1,868,085us: [+1 T:0x413d6490 S:0x413d5c1c] OM - Memory__addContigBuf> returning: cb->phys=0x8b83e000, cb->size=829440, cb->virt=0x424b8000
@1,868,191us: [+0 T:0x413d6490 S:0x413d5c64] OM - Memory_contigAlloc> return (0x424b8000)
@1,868,283us: [+0 T:0x413d6490 S:0x413d5cac] OM - Memory_alloc> return (0x424b8000)
@1,868,373us: [+0 T:0x413d6490 S:0x413d5c8c] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x424b8000, size=4)
@1,868,469us: [+1 T:0x413d6490 S:0x413d5c8c] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x424b8000, size=4)
@1,868,561us: [+1 T:0x413d6490 S:0x413d5c8c] OM - Memory__getPhysicalAddress> found in cb(Sc=0x424b8000, Ec=0x42582800, Ss=0x424b8000, Es=0x424b8004, PSc=0x8b83e000)
@1,868,664us: [+1 T:0x413d6490 S:0x413d5c8c] OM - Memory__getPhysicalAddress> returning physAddr=0x8b83e000
@1,868,756us: [+0 T:0x413d6490 S:0x413d5c8c] OM - Memory_getBufferPhysicalAddress> return (0x8b83e000)
@1,868,844us: [+2 T:0x413d6490 S:0x413d5cc4] ti.sdo.dmai - [Buffer] Alloc Buffer of size 829440 at 0x424b8000 (0x8b83e000 phys)
@1,868,975us: [+0 T:0x413d6490 S:0x413d5cac] OM - Memory_alloc> Enter(0xca800)
@1,869,077us: [+0 T:0x413d6490 S:0x413d5c64] OM - Memory_contigAlloc> Enter(size=829440, align=-1, cached=FALSE, heap=FALSE)
@1,869,308us: [+4 T:0x413d6490 S:0x413d5c64] OM - Memory_contigAlloc> CMEM_alloc(829440) = 0x42599000.
@1,869,454us: [+4 T:0x413d6490 S:0x413d5c64] OM - Memory_contigAlloc> CMEM_getPhys(0x42599000) = 0x8b6cd000.
@1,869,561us: [+1 T:0x413d6490 S:0x413d5c1c] OM - Memory__addContigBuf> Enter(virtAddr=0x42599000, size=829440, physAddr=0x8b6cd000)
@1,869,666us: [+1 T:0x413d6490 S:0x413d5c1c] OM - Memory__addContigBuf> creating new contigBuf object
@1,869,756us: [+0 T:0x413d6490 S:0x413d5c04] OM - Memory_alloc> Enter(0x10)
@1,869,852us: [+0 T:0x413d6490 S:0x413d5c04] OM - Memory_alloc> return (0x61ac8)
@1,869,943us: [+1 T:0x413d6490 S:0x413d5c1c] OM - Memory__addContigBuf> returning: cb->phys=0x8b6cd000, cb->size=829440, cb->virt=0x42599000
@1,870,042us: [+0 T:0x413d6490 S:0x413d5c64] OM - Memory_contigAlloc> return (0x42599000)
@1,870,135us: [+0 T:0x413d6490 S:0x413d5cac] OM - Memory_alloc> return (0x42599000)
@2,204,963us: [+0 T:0x413d6490 S:0x413d5c8c] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x42599000, size=4)
@2,205,079us: [+1 T:0x413d6490 S:0x413d5c8c] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x42599000, size=4)
@2,205,181us: [+1 T:0x413d6490 S:0x413d5c8c] OM - Memory__getPhysicalAddress> found in cb(Sc=0x42599000, Ec=0x42663800, Ss=0x42599000, Es=0x42599004, PSc=0x8b6cd000)
@2,205,287us: [+1 T:0x413d6490 S:0x413d5c8c] OM - Memory__getPhysicalAddress> returning physAddr=0x8b6cd000
@2,205,380us: [+0 T:0x413d6490 S:0x413d5c8c] OM - Memory_getBufferPhysicalAddress> return (0x8b6cd000)
@2,205,471us: [+2 T:0x413d6490 S:0x413d5cc4] ti.sdo.dmai - [Buffer] Alloc Buffer of size 829440 at 0x42599000 (0x8b6cd000 phys)
@2,205,738us: [+2 T:0x413d6490 S:0x413d5b04] ti.sdo.dmai - [Display] Output detected Composite
@2,206,044us: [+0 T:0x424b7490 S:0x424b6c34] CE - Engine_open> Enter('server_test2', 0x0, 0x424b6ca4)
@2,206,188us: [+0 T:0x424b7490 S:0x424b6c1c] OM - Memory_alloc> Enter(0x2c)
@2,206,358us: [+0 T:0x424b7490 S:0x424b6c1c] OM - Memory_alloc> return (0x61bb0)
@2,206,528us: [+0 T:0x424b7490 S:0x424b6c34] CE - rserverOpen('server_test2.x64P'), count = 0
@2,206,649us: [+0 T:0x424b7490 S:0x424b6bfc] OP - Processor_create> Enter(imageName='server_test2.x64P', linkCfg='(null)', attrs=0x424b6c88)
@2,207,157us: [+2 T:0x41cb7490 S:0x41cb6cd4] ti.sdo.dmai - [BufTab] Allocating BufTab for 4 buffers
@2,207,395us: [+0 T:0x41cb7490 S:0x41cb6c94] OM - Memory_alloc> Enter(0xca800)
@2,207,709us: [+0 T:0x41cb7490 S:0x41cb6c4c] OM - Memory_contigAlloc> Enter(size=829440, align=-1, cached=FALSE, heap=FALSE)
@2,208,090us: [+4 T:0x41cb7490 S:0x41cb6c4c] OM - Memory_contigAlloc> CMEM_alloc(829440) = 0x42699000.
@2,208,234us: [+4 T:0x41cb7490 S:0x41cb6c4c] OM - Memory_contigAlloc> CMEM_getPhys(0x42699000) = 0x857ca000.
@2,208,432us: [+1 T:0x41cb7490 S:0x41cb6c04] OM - Memory__addContigBuf> Enter(virtAddr=0x42699000, size=829440, physAddr=0x857ca000)
@2,208,540us: [+1 T:0x41cb7490 S:0x41cb6c04] OM - Memory__addContigBuf> creating new contigBuf object
@2,208,700us: [+0 T:0x41cb7490 S:0x41cb6bec] OM - Memory_alloc> Enter(0x10)
@2,208,824us: [+0 T:0x41cb7490 S:0x41cb6bec] OM - Memory_alloc> return (0x61c70)
@2,208,973us: [+1 T:0x41cb7490 S:0x41cb6c04] OM - Memory__addContigBuf> returning: cb->phys=0x857ca000, cb->size=829440, cb->virt=0x42699000
@2,209,091us: [+0 T:0x41cb7490 S:0x41cb6c4c] OM - Memory_contigAlloc> return (0x42699000)
@2,209,240us: [+0 T:0x41cb7490 S:0x41cb6c94] OM - Memory_alloc> return (0x42699000)
@2,209,343us: [+0 T:0x41cb7490 S:0x41cb6c74] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x42699000, size=4)
@2,209,521us: [+1 T:0x41cb7490 S:0x41cb6c74] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x42699000, size=4)
@2,209,638us: [+1 T:0x41cb7490 S:0x41cb6c74] OM - Memory__getPhysicalAddress> found in cb(Sc=0x42699000, Ec=0x42763800, Ss=0x42699000, Es=0x42699004, PSc=0x857ca000)
@2,209,846us: [+1 T:0x41cb7490 S:0x41cb6c74] OM - Memory__getPhysicalAddress> returning physAddr=0x857ca000
@2,209,970us: [+0 T:0x41cb7490 S:0x41cb6c74] OM - Memory_getBufferPhysicalAddress> return (0x857ca000)
@2,210,066us: [+2 T:0x41cb7490 S:0x41cb6cac] ti.sdo.dmai - [Buffer] Alloc Buffer of size 829440 at 0x42699000 (0x857ca000 phys)
@2,210,269us: [+0 T:0x41cb7490 S:0x41cb6c94] OM - Memory_alloc> Enter(0xca800)
@2,210,373us: [+0 T:0x41cb7490 S:0x41cb6c4c] OM - Memory_contigAlloc> Enter(size=829440, align=-1, cached=FALSE, heap=FALSE)
@2,210,714us: [+4 T:0x41cb7490 S:0x41cb6c4c] OM - Memory_contigAlloc> CMEM_alloc(829440) = 0x427fc000.
@2,211,020us: [+2 T:0x413d6490 S:0x413d5b04] ti.sdo.dmai - [Display] Display Output set to Composite; Display Standard is set to 6
@2,211,250us: [+2 T:0x413d6490 S:0x413d5bdc] ti.sdo.dmai - [Display] Video output set to size 720x576 pitch 720 imageSize 829440
@2,559,562us: [+4 T:0x41cb7490 S:0x41cb6c4c] OM - Memory_contigAlloc> CMEM_getPhys(0x427fc000) = 0x85667000.
@2,559,712us: [+1 T:0x41cb7490 S:0x41cb6c04] OM - Memory__addContigBuf> Enter(virtAddr=0x427fc000, size=829440, physAddr=0x85667000)
@2,559,897us: [+1 T:0x41cb7490 S:0x41cb6c04] OM - Memory__addContigBuf> creating new contigBuf object
@2,560,065us: [+0 T:0x41cb7490 S:0x41cb6bec] OM - Memory_alloc> Enter(0x10)
@2,560,190us: [+0 T:0x41cb7490 S:0x41cb6bec] OM - Memory_alloc> return (0x61de8)
@2,560,284us: [+1 T:0x41cb7490 S:0x41cb6c04] OM - Memory__addContigBuf> returning: cb->phys=0x85667000, cb->size=829440, cb->virt=0x427fc000
@2,560,494us: [+0 T:0x41cb7490 S:0x41cb6c4c] OM - Memory_contigAlloc> return (0x427fc000)
@2,560,593us: [+0 T:0x41cb7490 S:0x41cb6c94] OM - Memory_alloc> return (0x427fc000)
@2,560,756us: [+0 T:0x41cb7490 S:0x41cb6c74] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x427fc000, size=4)
@2,560,876us: [+1 T:0x41cb7490 S:0x41cb6c74] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x427fc000, size=4)
@2,561,028us: [+1 T:0x41cb7490 S:0x41cb6c74] OM - Memory__getPhysicalAddress> found in cb(Sc=0x427fc000, Ec=0x428c6800, Ss=0x427fc000, Es=0x427fc004, PSc=0x85667000)
@2,561,163us: [+1 T:0x41cb7490 S:0x41cb6c74] OM - Memory__getPhysicalAddress> returning physAddr=0x85667000
@2,561,259us: [+0 T:0x41cb7490 S:0x41cb6c74] OM - Memory_getBufferPhysicalAddress> return (0x85667000)
@2,561,426us: [+2 T:0x41cb7490 S:0x41cb6cac] ti.sdo.dmai - [Buffer] Alloc Buffer of size 829440 at 0x427fc000 (0x85667000 phys)
@2,561,561us: [+0 T:0x41cb7490 S:0x41cb6c94] OM - Memory_alloc> Enter(0xca800)
@2,561,724us: [+0 T:0x41cb7490 S:0x41cb6c4c] OM - Memory_contigAlloc> Enter(size=829440, align=-1, cached=FALSE, heap=FALSE)
@2,562,054us: [+4 T:0x41cb7490 S:0x41cb6c4c] OM - Memory_contigAlloc> CMEM_alloc(829440) = 0x4295f000.
@2,562,209us: [+4 T:0x41cb7490 S:0x41cb6c4c] OM - Memory_contigAlloc> CMEM_getPhys(0x4295f000) = 0x85504000.
@2,562,377us: [+1 T:0x41cb7490 S:0x41cb6c04] OM - Memory__addContigBuf> Enter(virtAddr=0x4295f000, size=829440, physAddr=0x85504000)
@2,562,503us: [+1 T:0x41cb7490 S:0x41cb6c04] OM - Memory__addContigBuf> creating new contigBuf object
@2,562,598us: [+0 T:0x41cb7490 S:0x41cb6bec] OM - Memory_alloc> Enter(0x10)
@2,562,771us: [+0 T:0x41cb7490 S:0x41cb6bec] OM - Memory_alloc> return (0x61e78)
@2,562,884us: [+1 T:0x41cb7490 S:0x41cb6c04] OM - Memory__addContigBuf> returning: cb->phys=0x85504000, cb->size=829440, cb->virt=0x4295f000
@2,563,051us: [+0 T:0x41cb7490 S:0x41cb6c4c] OM - Memory_contigAlloc> return (0x4295f000)
@2,563,187us: [+0 T:0x41cb7490 S:0x41cb6c94] OM - Memory_alloc> return (0x4295f000)
@2,563,287us: [+0 T:0x41cb7490 S:0x41cb6c74] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x4295f000, size=4)
@2,563,468us: [+1 T:0x41cb7490 S:0x41cb6c74] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x4295f000, size=4)
@2,563,570us: [+1 T:0x41cb7490 S:0x41cb6c74] OM - Memory__getPhysicalAddress> found in cb(Sc=0x4295f000, Ec=0x42a29800, Ss=0x4295f000, Es=0x4295f004, PSc=0x85504000)
@2,563,743us: [+1 T:0x41cb7490 S:0x41cb6c74] OM - Memory__getPhysicalAddress> returning physAddr=0x85504000
@2,563,862us: [+0 T:0x41cb7490 S:0x41cb6c74] OM - Memory_getBufferPhysicalAddress> return (0x85504000)
@2,563,959us: [+2 T:0x41cb7490 S:0x41cb6cac] ti.sdo.dmai - [Buffer] Alloc Buffer of size 829440 at 0x4295f000 (0x85504000 phys)
@2,564,155us: [+0 T:0x41cb7490 S:0x41cb6c94] OM - Memory_alloc> Enter(0xca800)
@2,564,258us: [+0 T:0x41cb7490 S:0x41cb6c4c] OM - Memory_contigAlloc> Enter(size=829440, align=-1, cached=FALSE, heap=FALSE)
@2,564,585us: [+4 T:0x41cb7490 S:0x41cb6c4c] OM - Memory_contigAlloc> CMEM_alloc(829440) = 0x42ac2000.
@2,564tvp514x routing 5 and 0.
,811us: [+4 T:0x41cb7490 S:0x41cs stream.
b6c4c] OM - Memory_contigAlloc> stream enable.
CMEM_getPhys(0x42ac2000) = 0x853chip id detected msb:0x51 lsb:0x47 rom version:0x7
a1000.
@2,564,920us: [+1 T:0x41tvp514x 1-005d: tvp5146 (Version - 0x07) found at 0xba (DaVinci I2C adapter)
cb7490 S:0x41cb6c04] OM - Memoryconfiugre tvp514x.
__addContigBuf> Enter(virtAddr=0x42ac2000, size=829440, physAddr=0x853a1000)
@2,899,229us: [+1 T:0x41cb7490 S:0x41cb6c04] OM - Memory__addContigBuf> creating new contigBuf object
@2,899,345us: [+0 T:0x41cb7490 S:0x41cb6bec] OM - Memory_alloc> Enter(0x10)
@2,899,445us: [+0 T:0x41cb7490 S:0x41cb6bec] OM - Memory_alloc> return (0x61f28)
@2,899,538us: [+1 T:0x41cb7490 S:0x41cb6c04] OM - Memory__addContigBuf> returning: cb->phys=0x853a1000, cb->size=829440, cb->virt=0x42ac2000
@2,899,638us: [+0 T:0x41cb7490 S:0x41cb6c4c] OM - Memory_contigAlloc> return (0x42ac2000)
@2,899,726us: [+0 T:0x41cb7490 S:0x41cb6c94] OM - Memory_alloc> return (0x42ac2000)
@2,899,815us: [+0 T:0x41cb7490 S:0x41cb6c74] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x42ac2000, size=4)
@2,899,907us: [+1 T:0x41cb7490 S:0x41cb6c74] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x42ac2000, size=4)
@2,900,000us: [+1 T:0x41cb7490 S:0x41cb6c74] OM - Memory__getPhysicalAddress> found in cb(Sc=0x42ac2000, Ec=0x42b8c800, Ss=0x42ac2000, Es=0x42ac2004, PSc=0x853a1000)
@2,900,106us: [+1 T:0x41cb7490 S:0x41cb6c74] OM - Memory__getPhysicalAddress> returning physAddr=0x853a1000
@2,900,196us: [+0 T:0x41cb7490 S:0x41cb6c74] OM - Memory_getBufferPhysicalAddress> return (0x853a1000)
@2,900,284us: [+2 T:0x41cb7490 S:0x41cb6cac] ti.sdo.dmai - [Buffer] Alloc Buffer of size 829440 at 0x42ac2000 (0x853a1000 phys)
open video capture devide.
dectect input.
111
222
@2,900,736us: [+2 T:0x41cb7490 S:0x41cb6a5c] ti.sdo.dmai - [Capture] Composite input selected
333.
@2,900,920us: [+0 T:0x424b7490 S:0x424b6be4] OM - Memory_alloc> Enter(0x24)
@2,901,061us: [+0 T:0x424b7490 S:0x424b6be4] OM - Memory_alloc> return (0x61f68)
@2,901,198us: [+0 T:0x424b7490 S:0x424b6bdc] OP - doCmd> Enter (cmdId=1, proc=0x61f68)
@2,901,296us: [+0 T:0x424b7490 S:0x424b6bcc] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x611c0]
@2,901,432us: [+0 T:0x424b7490 S:0x424b6bdc] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x611c0]
@2,901,540us: [+0 T:0x424b7490 S:0x424b6bbc] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x611d8] timeout[0xffffffff]
@2,901,707us: [+0 T:0x40bd6490 S:0x40bd5d44] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x611c0] status[0]
@2,901,822us: [+0 T:0x40bd6490 S:0x40bd5d64] OP - getCmd_d> Exit (result=1)
@2,915,099us: [+0 T:0x40bd6490 S:0x40bd5d64] OP - Processor_create_d> Enter(proc=0x61f68)
@2,915,543us: [+2 T:0x40bd6490 S:0x40bd5d64] OP - Processor_create_d> Initializing DSP PROC...
@2,915,678us: [+2 T:0x40bd6490 S:0x40bd5d64] OP - Processor_create_d> Using DspLink config data for entry #0 [server 'server_test2.x64P']
@2,916,390us: [+0 T:0x40bd6490 S:0x40bd5d4c] OM - Memory_alloc> Enter(0xe0)
@2,937,480us: [+0 T:0x40bd6490 S:0x40bd5d4c] OM - Memory_alloc> return (0x61f90)
@2,938,048us: [+2 T:0x40bd6490 S:0x40bd5d64] OP - Processor_create_d> Adding DSP segment #0 to Link configuration: name='DDR2', startAddress=0x8fa00000, sizeInBytes=0x400000, shared=1, syncd=0
@2,938,216us: [+2 T:0x40bd6490 S:0x40bd5d64] OP - Processor_create_d> Adding DSP segment #1 to Link configuration: name='DSPLINKMEM', startAddress=0x8fe00000, sizeInBytes=0x100000, shared=1, syncd=0
@2,938,415us: [+2 T:0x40bd6490 S:0x40bd5d64] OP - Processor_create_d> Adding DSP segment #2 to Link configuration: name='RESET_VECTOR', startAddress=0x8ff00000, sizeInBytes=0x80, shared=1, syncd=0
@2,938,562us: [+2 T:0x40bd6490 S:0x40bd5d64] OP - Processor_create_d> Adding DSP segment #3 to Link configuration: name='DDRALGHEAP', startAddress=0x8f9ffff8, sizeInBytes=0x8, shared=0, syncd=0
@2,938,751us: [+2 T:0x40bd6490 S:0x40bd5d64] OP - Processor_create_d> DODSPCTRL was=1; now=0
@2,938,908us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - LINKCFG_Object generated for PROC_setup(0x5f960) ...
@2,939,099us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - linkCfg->gppObject->
@2,939,212us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - name = "ARM9"
@2,939,369us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - maxMsgqs = 0x82
@2,939,509us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - maxChnlQueue = 0x10
@2,939,604us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - poolTableId = 0xffffffff
@2,939,794us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - numPools = 0x0
@3,275,421us: [+3 T:0x40bd6490 S:0x40bd5d04] OP -
@3,275,532us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - linkCfg->dspConfigs[0]->dspObject->
@3,275,630us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - name = "DM6467GEM"
@3,275,722us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - dspArch = 0x2
@3,275,809us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - loaderName = "COFF"
@3,275,896us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - autoStart = FALSE
@3,275,984us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - execName = "DEFAULT.OUT"
@3,276,072us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - doDspCtrl = DSP_BootMode_Boot_NoPwr
@3,276,166us: [+3 T:0x40bd6490 S:0x40bd5d04] the cvbs is 5,5
OP - resumeAddr = 0x8ff00020
@3,276,256us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - resetVector = 0x8ff00000
@3,276,343us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - resetCodeSize = 0x80
@3,276,430us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - maduSize = 0x1
@3,276,517us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - cpuFreq = 0x91050
@3,276,605us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - endian = 0x3
@3,276,692us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - wordSwap = 0x0
@3,276,779us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - memTableId = 0x0
@3,276,865us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - memEntries = 0x4
@3,276,953us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - linkDrvId = 0x0
@3,277,040us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - arg1 = 0xffffffff
@3,277,127us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - arg2 = 0xffffffff
@3,277,213us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - arg3 = 0x0
@3,277,299us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - arg4 = 0x0
@3,277,385us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - arg5 = 0xffffffff
@3,277,471us: [+3 T:0x40bd6490 S:0x40bd5d04] OP -
@3,277,550us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - linkCfg->dspConfigs[0]->linkDrvObjects->
@3,277,639us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - name = "SHMDRV"
@3,277,725us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - hshkPollCount = 0x5f5e100
@3,277,812us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - memEntry = 0x1
@3,278,050us: [+0 T:0x4001fcb0 S:0xbef35a44] CE - Engine_open> Enter('server_test2', 0x0, 0xbef35ab4)
@3,278,211us: [+0 T:0x4001fcb0 S:0xbef35a2c] OM - Memory_alloc> Enter(0x2c)
@3,278,325us: [+0 T:0x4001fcb0 S:0xbef35a2c] OM - Memory_alloc> return (0x62078)
@3,278,461us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - ipsTableId = 0x0
@3,278,565us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - numIpsEntries = 0x1
@3,278,657us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - poolTableId = 0x0
@3,278,746us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - numPools = 0x1
@3,278,834us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - dataTableId = 0x0
@3,278,923us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - numDataDrivers = 0x1
@3,279,010us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - mqtId = 0x0
@3,279,099us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - ringIoTableId = 0x0
@3,279,189us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - mplistTableId = 0x0
@3,279,276us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - mpcsTableId = 0x0
@3,279,362us: [+3 T:0x40bd6490 S:0x40bd5d04] OP -
@3,279,443tvp514x get 2 current std 0.
us: [+3 T:0x40bd6490 S:0x40bd5d0The status 3f is 82.
4] OP - linkCfg->dspConfigs[0]->The status is 82.
memTables[0][i].
@3,279,531us: PAL.
[+3 T:0x40bd6490 S:0x40bd5d04] OInput set to: 5, std : 1P - entry #0:
@3,279,618us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - entry = 0x0
@3,279,704us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - name = "DDR2"
@3,279,791us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - physAddr = 0x8fa00000
@3,279,879us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - dspVirtAddr = 0x8fa00000
@3,279,967us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - gppVirtAddr = 0xffffffff
@3,280,054us: [+3 T:0x40bd6490 S:0x40bvpif query std.
d5d04] OP - size = 0x400000
@tvp514x query std.
3,280,143us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - shared = TRUE
@3,280,231us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - syncd = FALSE
@3,280,316us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - entry #1:
@3,280,401us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - entry = 0x1
444.
555.
666
Checking video standard
@3,634,300us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - name = "DSPLINKMEM"
@3,634,426us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - physAddr = 0x8fe00000
@3,634,525us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - dspVirtAddr = 0x8fe00000
@3,634,616us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - gppVirtAddr = 0xffffffff
@3,634,706us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - size = 0x100000
@3,634,793us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - shared = TRUE
@3,634,881us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - syncd = FALSE
@3,634,967us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - entry #2:
@3,635,053us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - entry = 0x2
@3,635,141us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - name = "RESET_VECTOR"
@3,635,230us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - physAddr = 0x8ff00000
@3,635,319us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - dspVirtAddr = 0x8ff00000
@3,635,407us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - gppVirtAddr = 0xffffffff
@3,635,495us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - size = 0x80
@3,635,582us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - shared = TRUE
@3,635,667us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - syncd = FALSE
@3,635,755us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - entry #3:
@3,635,840us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - entry = 0x3
@3,635,926us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - name = "DDRALGHEAP"
@3,636,014us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - physAddr = 0x8f9ffff8
@3,636,104us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - dspVirtAddr = 0x8f9ffff8
@3,636,218us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - gppVirtAddr = 0xffffffff
@3,636,312us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - size = 0x8
@3,636,399us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - shared = FALSE
@3,636,488us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - syncd = FALSE
@3,636,575us: [+3 T:0x40bd6490 S:0x40bd5d04] OP -
@3,636,657us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - linkCfg->dspConfigs[0]->ipsTables[0][i].
@3,636,749us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - entry #0:
@3,636,834us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - name = "IPS"
@3,636,920us: [+3 T:0x40bd649tvp514x get 2 current std 0.
0 S:0x40bd5d04] OP - numIpsEveThe status 3f is 82.
nts = 0x20
@3,637,006us: [+3 T:The status is 82.
0x40bd6490 S:0x40bd5d04] OP - PAL.
memEntry = 0x1
@3,637,095us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - gppIntId = 0x2d
@3,637,185us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - dspIntId = 0x10
@3,637,270us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - dspIntVectorId = 0x4
@3,637,357us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - arg1 = 0x2faf080
@3,637,442us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - arg2 = 0x0
@3,637,530us: [+3 T:0x40bd6490 S:0x40bd5d04] OP -
@3,637,610us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - linkCfg->dspConfigs[0]->poolTables[0][i].
@3,637,699us: [+3 T:0x40bd6490 S:0x40bd5d04] OP -
@3,637,780us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - linkCfg->dspConfigs[0]->dataTables[0][i].
@3,637,954us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - entry #0:
@3,638,059us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - name = "ZCPYDATA"
@3,638,152us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - baseChnlId = 0x0
@3,638,241us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - numChannels = 0x10
@3,638,328us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - maxBufSize = 0x4000
@3,638,416us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - memEntry = 0x1
@3,638,502us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - poolId = 0x0
@3,638,588us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - queuePerChnl = 0x1
@3,638,674us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - ipsId = 0x0
@3,638,759us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - ipsEventNo = 0x2
@3,638,845us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - arg1 = 0x0
@3,638,932us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - arg2 = 0x0
@3,6tvp514x set std ****.
39,052us: [+3 T:0x40bd6490 S:0x4The set standard is c03e5b48,<NULL>
0bd5d04] OP -
@3,639,140us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - linkCfg->dspConfigs[0]->mqtObjects->
@3,639,231us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - name = "ZCPYMQT"
@3,639,317us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - s stream.
memEntry = 0x1
777.
@3,993,603us: [+2 T:0x41cb7490 S:0x41cb6a5c] ti.sdo.dmai - [Capture] Capture input set to Composite:6
get std ok.
Get res ok.
Get stream ok.
Get fmt.
@3,993,945us: [+2 T:0x41cb7490 S:0x41cb6b34] ti.sdo.dmai - [Capture] Video input connected size 720x576 pitch 720
Get width ok.
Crop ok.
Start streaming.
@4,013,237us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - maxMsgSize = 0xffffffff
@4,013,360us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - ipsId = 0x0
@4,013,544us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - ipsEventNo = 0x1
@4,013,832us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - arg1 = 0x0
@4,013,953us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - arg2 = 0x0
@4,014,047us: [+3 T:0x40bd6490 S:0x40bd5d04] OP -
@4,014,134us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - linkCfg->dspConfigs[0]->ringIoObjects->
@4,014,227us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - name = "RINGIOTABLE"
@4,014,319us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - memEntry = 0x1
@4,014,408us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - maxEntries = 0x40
@4,014,497us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - ipsId = 0x0
@4,014,583us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - ipsEventNo = 0x0
@4,014,671us: [+3 T:0x40bd6490 S:0x40bd5d04] OP -
@4,014,755us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - linkCfg->dspConfigs[0]->mplistObjects->
@4,014,845us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - name = "MPLISTTABLE"
@4,014,933us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - memEntry = 0x1
@4,015,021us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - maxEntries = 0x40
@4,015,109us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - ipsId = 0xffffffff
@4,015,196us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - ipsEventNo = 0xffffffff
@4,015,285us: [+3 T:0x40bd6490 S:0x40bd5d04] OP -
@4,015,369us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - linkCfg->dspConfigs[0]->mpcsObjects->
@4,015,459us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - name = "MPCS"
@4,015,547us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - memEntry = 0x1
@4,015,635us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - maxEntries = 0x100
@4,015,724us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - ipsId = 0xffffffff
@4,015,812us: [+3 T:0x40bd6490 S:0x40bd5d04] OP - ipsEventNo = 0xffffffff
@4,023,784us: [+2 T:0x40bd6490 S:0x40bd5d64] OP - Processor_create_d> Attaching to DSP PROC...
@4,029,590us: [+2 T:0x40bd6490 S:0x40bd5d64] OP - Processor_create_d> Opening MSGQ pool...
@4,030,079us: [+2 T:0x40bd6490 S:0x40bd5d64] OP - Processor_create_d> Loading server_test2.x64P on DSP (1 args)...
@4,108,701us: [+2 T:0x40bd6490 S:0x40bd5d64] OP - Processor_create_d> Starting DSP PROC...
@4,117,343us: [+2 T:0x40bd6490 S:0x40bd5d64] OP - Processor_create_d> Opening remote transport...
@4,118,205us: [+2 T:0x40bd6490 S:0x40bd5d64] OP - Processor_create_d> return (1)
@4,118,334us: [+0 T:0x40bd6490 S:0x40bd5d54] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x611d8]
@4,118,472us: [+0 T:0x424b7490 S:0x424b6bbc] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x611d8] status[0]
@4,118,585us: [+0 T:0x424b7490 S:0x424b6bdc] OP - doCmd> Exit (result=1)
@4,118,675us: [+0 T:0x424b7490 S:0x424b6bfc] OP - Processor_create> return (0x61f68)
@4,118,766us: [+0 T:0x424b7490 S:0x424b6c34] CE - rserverOpen('server_test2.x64P'): 0x60398 done.
@4,118,862us: [+0 T:0x424b7490 S:0x424b6c1c] OM - Memory_alloc> Enter(0x2a)
@4,118,960us: [+0 T:0x424b7490 S:0x424b6c1c] OM - Memory_alloc> return (0x62350)
@4,119,153us: [+0 T:0x424b7490 S:0x424b6c0c] OC - Comm_create> Enter(queueName='server_test2_1471_0', queue=0x61bc0, attrs=0x0)
@4,119,271us: [+0 T:0x424b7490 S:0x424b6bf4] OM - Memory_alloc> Enter(0x4)
@4,119,371us: [+0 T:0x424b7490 S:0x424b6bf4] OM - Memory_alloc> return (0x62380)
@4,120,052us: [+0 T:0x424b7490 S:0x424b6c0c] OC - Comm_create> return (0x62380)
@4,120,180us: [+0 T:0x424b7490 S:0x424b6c14] OM - Memory_free> Enter(0x62350, 0x2a)
@4,120,360us: [+0 T:0x424b7490 S:0x424b6c14] OM - Memory_free> return (0x1)
@4,120,474us: [+0 T:0x424b7490 S:0x424b6c14] OC - Comm_alloc> Enter(poolId=0x0, msg=0x61bcc, size=576)
@4,120,604us: [+0 T:0x424b7490 S:0x424b6c14] OC - Comm_alloc> msg=0x43434880, returning (0)
@4,120,707us: [+0 T:0x424b7490 S:0x424b6bfc] OC - Comm_locate> Enter(queueName='rmsq', queue=0x61bbc)
@4,121,145us: [+0 T:0x424b7490 S:0x424b6bfc] OC - Comm_locate> return (0)
@4,121,270us: [+0 T:0x424b7490 S:0x424b6c34] CE - checkServer(0x61bb0)
@4,121,413us: [+0 T:0x424b7490 S:0x424b6c04] OC - Comm_put> Enter(queue=0x0, msg=0x43434880)
@4,121,567us: [+0 T:0x424b7490 S:0x424b6c04] OC - Comm_put> return (0)
@4,121,709us: [+0 T:0x424b7490 S:0x424b6bfc] OC - Comm_get> Enter(queue=0x10000, msg=0x424b6ca0, timeout=-1)
@4,121,939us: [+0 T:0x424b7490 S:0x424b6bfc] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
@4,122,068us: [+0 T:0x424b7490 S:0x424b6c34] CE - rmsInit> RMS initialized(0x61bb0); CE_DEBUG on, setting DSP trace mask to *+01234567,GT_prefix=12345,GT_time=3
@4,122,184us: [+0 T:0x424b7490 S:0x424b6c0c] CE - Engine_setTrace> Enter(engine=0x61bb0, mask='*+01234567,GT_prefix=12345,GT_time=3')
@4,122,297us: [+1 T:0x424b7490 S:0x424b6c0c] CE - Engine_setTrace> Requesting DSP set trace ...
@4,122,387us: [+0 T:0x424b7490 S:0x424b6bdc] OC - Comm_put> Enter(queue=0x0, msg=0x43434880)
@4,122,548us: [+0 T:0x424b7490 S:0x424b6bdc] OC - Comm_put> return (0)
@4,122,652us: [+0 T:0x424b7490 S:0x424b6bd4] OC - Comm_get> Enter(queue=0x10000, msg=0x424b6c54, timeout=-1)
@4,458,585us: [+0 T:0x424b7490 S:0x424b6bd4] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
@4,458,703us: [+0 T:0x424b7490 S:0x424b6c0c] CE - Engine_setTrace> return(0)
@4,458,829us: [+0 T:0x424b7490 S:0x424b6b9c] OC - Comm_put> Enter(queue=0x0, msg=0x43434880)
@4,458,977us: [+0 T:0x424b7490 S:0x424b6b9c] OC - Comm_put> return (0)
@4,459,113us: [+0 T:0x424b7490 S:0x424b6b94] OC - Comm_get> Enter(queue=0x10000, msg=0x424b6c34, timeout=-1)
@4,459,270us: [+0 T:0x4001fcb0 S:0xbef35a44] CE - rserverOpen('server_test2.x64P'), count = 1
@4,459,390us: [+0 T:0x4001fcb0 S:0xbef35a44] CE - rserverOpen('server_test2.x64P'): 0x60398 done.
@4,459,499us: [+0 T:0x4001fcb0 S:0xbef35a2c] OM - Memory_alloc> Enter(0x2a)
@4,459,604us: [+0 T:0x4001fcb0 S:0xbef35a2c] OM - Memory_alloc> return (0x62350)
@4,459,721us: [+0 T:0x4001fcb0 S:0xbef35a1c] OC - Comm_create> Enter(queueName='server_test2_1471_1', queue=0x62088, attrs=0x0)
@4,459,828us: [+0 T:0x4001fcb0 S:0xbef35a04] OM - Memory_alloc> Enter(0x4)
@4,459,931us: [+0 T:0x4001fcb0 S:0xbef35a04] OM - Memory_alloc> return (0x62390)
@4,460,875us: [+0 T:0x4001fcb0 S:0xbef35a1c] OC - Comm_create> return (0x62390)
@4,461,012us: [+0 T:0x4001fcb0 S:0xbef35a24] OM - Memory_free> Enter(0x62350, 0x2a)
@4,461,127us: [+0 T:0x4001fcb0 S:0xbef35a24] OM - Memory_free> return (0x1)
@4,461,229us: [+0 T:0x4001fcb0 S:0xbef35a24] OC - Comm_alloc> Enter(poolId=0x0, msg=0x62094, size=576)
@4,461,394us: [+0 T:0x4001fcb0 S:0xbef35a24] OC - Comm_alloc> msg=0x43435880, returning (0)
@4,461,504us: [+0 T:0x4001fcb0 S:0xbef35a0c] OC - Comm_locate> Enter(queueName='rmsq', queue=0x62084)
@4,461,901us: [+0 T:0x424b7490 S:0x424b6b94] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
[DSP] @0x00000128:[T:0x00000000] ti.sdo.ce.examples.servers.server_test2 - main> Welcome to DSP server's main().
[DSP] @0,020,271tk: [+0 T:0x8fa23e6c S:0x8fa245e4] OG - Global_setSpecialTrace> enter(mask='*+01234567,GT_prefix=12345,GT_time=3')
[DSP] @0,020,344tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - Global_setSpecialTrace> This program was built with the following packages:
[DSP] @0,020,406tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.sdo.fc.global (/opt/dvsdk/dvsdk_3_10_00_19/framework_components_2_25_02_06/packages/ti/sdo/fc/global/) [1, 0, 0]
[DSP] @0,020,485tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.sdo.utils.trace (/opt/dvsdk/dvsdk_3_10_00_19/framework_components_2_25_02_06/packages/ti/sdo/utils/trace/) [1, 0, 0]
[DSP] @0,020,567tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.xdais (/opt/dvsdk/dvsdk_3_10_00_19/xdais_6_25_02_11/packages/ti/xdais/) [1, 2.0, 1]
[DSP] @0,020,636tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.rtdx (/opt/dvsdk/dvsdk_3_10_00_19/bios_5_41_00_06/packages/ti/rtdx/) [2, 0, 0, 5]
[DSP] @0,020,705tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.psl (/opt/dvsdk/dvsdk_3_10_00_19/bios_5_41_00_06/packages/ti/psl/) [5, 0, 0, 0]
[DSP] @0,020,773tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.pmi (/opt/dvsdk/dvsdk_3_10_00_19/bios_5_41_00_06/packages/ti/pmi/) [1, 0, 0, 0]
[DSP] @0,020,841tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.pscl (/opt/dvsdk/dvsdk_3_10_00_19/bios_5_41_00_06/packages/ti/pscl/) [1, 0, 0, 0]
[DSP] @0,020,910tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.bios (/opt/dvsdk/dvsdk_3_10_00_19/bios_5_41_00_06/packages/ti/bios/) [5, 2, 5, 19]
[DSP] @0,020,979tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.sdo.fc.dskt2 (/opt/dvsdk/dvsdk_3_10_00_19/framework_components_2_25_02_06/packages/ti/sdo/fc/dskt2/) [1, 0, 4]
[DSP] @0,021,059tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.sdo.fc.dman3 (/opt/dvsdk/dvsdk_3_10_00_19/framework_components_2_25_02_06/packages/ti/sdo/fc/dman3/) [1, 0, 4]
[DSP] @0,021,138tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.sdo.ce.global (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/global/) [1, 0, 0]
[DSP] @0,021,215tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.xdais.dm (/opt/dvsdk/dvsdk_3_10_00_19/xdais_6_25_02_11/packages/ti/xdais/dm/) [1, 0, 5]
[DSP] @0,021,286tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.sdo.ce.node (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/node/) [1, 0, 0]
[DSP] @0,021,362tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.sdo.ce.ipc.dsplink.dsp (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/ipc/dsplink/dsp/) [2, 0, 1]
[DSP] @0,021,446tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.sdo.ce.utils.xdm (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/utils/xdm/) [1, 0, 2]
[DSP] @0,021,525tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.sdo.fc.memutils (/opt/dvsdk/dvsdk_3_10_00_19/framework_components_2_25_02_06/packages/ti/sdo/fc/memutils/) [1, 0, 0]
[DSP] @0,021,606tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.sdo.fc.acpy3 (/opt/dvsdk/dvsdk_3_10_00_19/framework_components_2_25_02_06/packages/ti/sdo/fc/acpy3/) [1, 0, 4]
[DSP] @0,021,686tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package dsplink.dsp (/opt/dvsdk/dvsdk_3_10_00_19/dsplink_linux_1_64/dsplink/dsp/) [1, 4, 0]
[DSP] @0,021,754tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.bios.utils (/opt/dvsdk/dvsdk_3_10_00_19/biosutils_1_02_02/packages/ti/bios/utils/) [2, 0, 2, 02]
[DSP] @0,021,829tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.catalog.c6000 (/opt/dvsdk/dvsdk_3_10_00_19/xdctools_3_16_01_27/packages/ti/catalog/c6000/) [1, 0, 0, 0]
[DSP] @0,021,905tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.catalog (/opt/dvsdk/dvsdk_3_10_00_19/xdctools_3_16_01_27/packages/ti/catalog/) [1, 0, 0]
[DSP] @0,021,976tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.platforms.evmDM6467 (/opt/dvsdk/dvsdk_3_10_00_19/xdctools_3_16_01_27/packages/ti/platforms/evmDM6467/) [1, 0, 0, 0]
[DSP] @0,022,057tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - pack@4,464,257us: [+0 T:0x4001fcb0 S:0xbef35a0c] OC - Comm_locate> return (0)
@4,802,022us: [+0 T:0x424b7490 S:0x424b6b9c] OC - Comm_put> Enter(queue=0x0, msg=0x43434880)
@4,802,199us: [+0 T:0x424b7490 S:0x424b6b9c] OC - Comm_put> return (0)
@4,802,342us: [+0 T:0x424b7490 S:0x424b6b94] OC - Comm_get> Enter(queue=0x10000, msg=0x424b6c34, timeout=-1)
@4,802,493us: [+0 T:0x4001fcb0 S:0xbef35a44] CE - checkServer(0x62078)
@4,802,621us: [+0 T:0x4001fcb0 S:0xbef35a14] OC - Comm_put> Enter(queue=0x0, msg=0x43435880)
@4,802,777us: [+0 T:0x4001fcb0 S:0xbef35a14] OC - Comm_put> return (0)
@4,802,927us: [+0 T:0x4001fcb0 S:0xbef35a0c] OC - Comm_get> Enter(queue=0x10001, msg=0xbef35ab0, timeout=-1)
@4,803,178us: [+0 T:0x424b7490 S:0x424b6b94] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
age ti.sdo.ce.osal (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/osal/) [2, 0, 2]
[DSP] @0,022,133tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.sdo.ce.osal.bios (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/osal/bios/) [2, 0, 1]
[DSP] @0,022,212tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.sdo.ce.ipc (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/ipc/) [2, 0, 1]
[DSP] @0,022,287tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.sdo.ce.ipc.bios (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/ipc/bios/) [2, 0, 1]
[DSP] @0,022,366tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.sdo.ce.alg (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/alg/) [1, 0, 1]
[DSP] @0,022,441tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.sdo.ce (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/) [1, 0, 6]
[DSP] @0,022,513tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.sdo.ce.bioslog (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/bioslog/) [1, 0, 1]
[DSP] @0,022,591tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.sdo.ce.video1 (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/video1/) [1, 0, 2]
[DSP] @0,022,668tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.sdo.ce.examples.codecs.videnc1_copy (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/examples/ti/sdo/ce/examples/codecs/videnc1_copy/) [1, 0, 0]
[DSP] @0,022,762tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.sdo.ce.video2 (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/packages/ti/sdo/ce/video2/) [1, 0, 2]
[DSP] @0,022,839tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.sdo.ce.examples.codecs.viddec2_copy (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/examples/ti/sdo/ce/examples/codecs/viddec2_copy/) [1, 0, 0]
[DSP] @0,022,932tk: [+4 T:0x8fa23e6c S:0x8fa245e4] OG - package ti.sdo.ce.examples.servers.server_test2 (/opt/dvsdk/dvsdk_3_10_00_19/codec_engine_2_25_05_16/examples/ti/sdo/ce/examples/servers/server_test2/) [1, 0, 0]
[DSP] @0,023,026tk: [+0 T:0x8fa23e6c S:0x8fa245e4] OG - Global_setSpecialTrace> return
[DSP] @1,321,290tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
[DSP] @1,321,346tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - remote time = 0x0, trace buffer size = 4032
[DSP] @2,648,585tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
[DSP] @2,648,640tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - remote time = 0x0, trace buffer size = 4032
@4,804,388us: [+0 T:0x4001fcb0 S:0xbef35a0c] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
@5,140,401us: [+0 T:0x424b7490 S:0x424b6bcc] CE - Engine_fwriteTrace> returning count [6833]
@5,140,529us: [+0 T:0x424b7490 S:0x424b6c34] CE - Engine_open> return(400304)
@5,140,762us: [+0 T:0x424b7490 S:0x424b6b84] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_create> Enter (engine=0x61bb0, name='viddec2_copy', params=0x424b6dc0)
@5,140,905us: [+0 T:0x424b7490 S:0x424b6b54] CV - VISA_create(0x61bb0, 'viddec2_copy', 0x424b6dc0, 0x2340, 'ti.sdo.ce.video2.IVIDDEC2')
@5,141,017us: [+0 T:0x424b7490 S:0x424b6a4c] CV - VISA_create2(0x61bb0, 'viddec2_copy', 0x424b6dc0, 0x1c, 0x2340, 'ti.sdo.ce.video2.IVIDDEC2')
@5,141,137us: [+0 T:0x424b7490 S:0x424b6a34] OM - Memory_alloc> Enter(0x34)
@5,141,235us: [+0 T:0x424b7490 S:0x424b6a34] OM - Memory_alloc> return (0x62570)
@5,141,328us: [+0 T:0x424b7490 S:0x424b6a34] OM - Memory_alloc> Enter(0x4)
@5,141,423us: [+0 T:0x424b7490 S:0x424b6a34] OM - Memory_alloc> return (0x625a8)
@5,141,515us: [+0 T:0x424b7490 S:0x424b6a2c] OC - Comm_alloc> Enter(poolId=0x0, msg=0x625a8, size=2340)
@5,141,673us: [+0 T:0x424b7490 S:0x424b6a2c] OC - Comm_alloc> msg=0x43436880, returning (0)
@5,141,777us: [+0 T:0x424b7490 S:0x424b69d4] CE - Engine_createNode(0x61bb0, 'viddec2_copy', 2340, 0x424b6dc0, 0x1c, 0x424b6b48)
@5,141,883us: [+0 T:0x424b7490 S:0x424b69d4] CE - Engine> allocNode Enter(engine=0x61bb0, impId='viddec2_copy')
@5,141,979us: [+0 T:0x424b7490 S:0x424b69bc] OM - Memory_alloc> Enter(0x20)
@5,142,074us: [+0 T:0x424b7490 S:0x424b69bc] OM - Memory_alloc> return (0x625b8)
@5,142,262us: [+0 T:0x424b7490 S:0x424b69d4] CE - Engine> allocNode(). Calling (Comm_create(gppfromnode_1471_2, 0x625c0, NULL)
@5,142,377us: [+0 T:0x424b7490 S:0x424b69ac] OC - Comm_create> Enter(queueName='gppfromnode_1471_2', queue=0x625c0, attrs=0x0)
@5,142,481us: [+0 T:0x424b7490 S:0x424b6994] OM - Memory_alloc> Enter(0x4)
@5,142,577us: [+0 T:0x424b7490 S:0x424b6994] OM - Memory_alloc> return (0x625e0)
@5,143,272us: [+0 T:0x424b7490 S:0x424b69ac] OC - Comm_create> return (0x625e0)
@5,143,404us: [+0 T:0x424b7490 S:0x424b69b4] OC - Comm_put> Enter(queue=0x0, msg=0x43434880)
@5,143,554us: [+0 T:0x424b7490 S:0x424b69b4] OC - Comm_put> return (0)
@5,477,641us: [+0 T:0x424b7490 S:0x424b69ac] OC - Comm_get> Enter(queue=0x10000, msg=0x424b6a5c, timeout=-1)
@5,477,788us: [+0 T:0x424b7490 S:0x424b69ac] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
@5,477,967us: [+0 T:0x424b7490 S:0x424b69b4] OC - Comm_put> Enter(queue=0x0, msg=0x43434880)
@5,478,121us: [+0 T:0x424b7490 S:0x424b69b4] OC - Comm_put> return (0)
@5,478,263us: [+0 T:0x424b7490 S:0x424b69ac] OC - Comm_get> Enter(queue=0x10000, msg=0x424b6a5c, timeout=-1)
@5,478,411us: [+0 T:0x4001fcb0 S:0xbef359ac] OC - Comm_put> Enter(queue=0x0, msg=0x43435880)
@5,478,596us: [+0 T:0x4001fcb0 S:0xbef359ac] OC - Comm_put> return (0)
@5,478,737us: [+0 T:0x4001fcb0 S:0xbef359a4] OC - Comm_get> Enter(queue=0x10001, msg=0xbef35a44, timeout=-1)
@5,478,992us: [+0 T:0x424b7490 S:0x424b69ac] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
@5,479,113us: [+4 T:0x424b7490 S:0x424b69d4] CE - Engine_createNode> created node(stdIn=0x2, stdOut=0x10002, msgq=0x625e0, algName='viddec2_copy', rmsNode=0x8fa25120, algHandle=0x8fa25208)
@5,479,341us: [+0 T:0x4001fcb0 S:0xbef359a4] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
[DSP] @2,650,826tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - processRmsCmd(0x8fe048a8, 4056): cmd = 6
[DSP] @3,968,674tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 0
[DSP] @3,968,734tk: [+0 T:0x8fa23e6c S:0x8fa24514] OM - Memory_alloc> Enter(size=0x18)
[DSP] @3,968,785tk: [+0 T:0x8fa23e6c S:0x8fa24514] OM - Memory_alloc> return (0x8fa25120)
[DSP] @3,968,835tk: [+0 T:0x8fa23e6c S:0x8fa24514] OM - Memory_alloc> Enter(size=0xf)
[DSP] @3,968,881tk: [+0 T:0x8fa23e6c S:0x8fa24514] OM - Memory_alloc> return (0x8fa25138)
[DSP] @3,968,935tk: [+0 T:0x8fa23e6c S:0x8fa244d4] OM - Memory_alloc> Enter(size=0x20)
[DSP] @3,968,983tk: [+0 T:0x8fa23e6c S:0x8fa244d4] OM - Memory_alloc> return (0x8fa25148)
[DSP] @3,969,034tk: [+0 T:0x8fa23e6c S:0x8fa244a4] OM - Memory_alloc> Enter(size=0x24)
[DSP] @3,969,081tk: [+0 T:0x8fa23e6c S:0x8fa244a4] OM - Memory_alloc> return (0x8fa25168)
[DSP] @3,969,152tk: [+0 T:0x8fa23e6c S:0x8fa244bc] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_create> Enter (engine=0x0, name='viddec2_copy', params=0x8fe038c0)
[DSP] @3,969,234tk: [+0 T:0x8fa23e6c S:0x8fa24484] CV - VISA_create(0x0, 'viddec2_copy', 0x8fe038c0, 0x2340, 'ti.sdo.ce.video2.IVIDDEC2')
[DSP] @3,969,307tk: [+0 T:0x8fa23e6c S:0x8fa24364] CV - VISA_create2(0x0, 'viddec2_copy', 0x8fe038c0, 0x1c, 0x2340, 'ti.sdo.ce.video2.IVIDDEC2')
[DSP] @3,969,391tk: [+0 T:0x8fa23e6c S:0x8fa242fc] CE - Engine_open> Enter('local', 0x8fa2434c, 0x8fe038c0)
[DSP] @3,969,453tk: [+0 T:0x8fa23e6c S:0x8fa242dc] OM - Memory_alloc> Enter(size=0x2c)
[DSP] @3,969,501tk: [+0 T:0x8fa23e6c S:0x8fa242dc] OM - Memory_alloc> return (0x8fa251d8)
[DSP] @3,969,558tk: [+0 T:0x8fa23e6c S:0x8fa242fc] CE - Engine_open> return(-1885187624)
[DSP] @3,969,618tk: [+0 T:0x8fa23e6c S:0x8fa24344] OM - Memory_alloc> Enter(size=0x34)
[DSP] @3,969,665tk: [+0 T:0x8fa23e6c S:0x8fa24344] OM - Memory_alloc> return (0x8fa25208)
[DSP] @3,969,717tk: [+0 T:0x8fa23e6c S:0x8fa2431c] ti.sdo.ce.alg.Algorithm - Algorithm_create> Enter(fxns=0x8fa4d1cc, idma3Fxns=0x0, iresFxns=0x0, params=0x8fe038c0, attrs=0x8fa24468)
[DSP] @3,969,810tk: [+0 T:0x8fa23e6c S:0x8fa242fc] OM - Memory_alloc> Enter(size=0x10)
[DSP] @3,969,856tk: [+0 T:0x8fa23e6c S:0x8fa242fc] OM - Memory_alloc> return (0x8fa25240)
[DSP] @3,969,972tk: [+0 T:0x8fa23e6c S:0x8fa2431c] ti.sdo.ce.alg.Algorithm - Algorithm_create> return (0x8fa25240)
[DSP] @3,970,033tk: [+5 T:0x8fa23e6c S:0x8fa24364] CV - VISA_create> local codec created (name='viddec2_copy', handle=0x8fa25240)
[DSP] @3,970,100tk: [+0 T:0x8fa23e6c S:0x8fa244bc] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_create> return (0x8fa25208)
[DSP] @3,970,163tk: [+4 T:0x8fa23e6c S:0x8fa2447c] OT - Thread_create > name: "viddec2_copy#0", pri: -1, stack size: 10288, stack seg: 0
[DSP] @5,262,531tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 1
[DSP] @5,264,346tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - processRmsCmd(0x8fe048a8, 4056): cmd = 5
[DSP] @5,264,401tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - remote time = 0x0, trace buffer size = 4032
@5,482,045us: [+0 T:0x40bd6490 S:0x40bd5d64] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x611d8]
@5,816,895us: [+0 T:0x4001fcb0 S:0xbef359dc] CE - Engine_fwriteTrace> returning count [3041]
@5,817,061us: [+0 T:0x424b7490 S:0x424b693c] OC - Comm_put> Enter(queue=0x0, msg=0x43434880)
@5,817,222us: [+0 T:0x424b7490 S:0x424b693c] OC - Comm_put> return (0)
@5,817,364us: [+0 T:0x424b7490 S:0x424b6934] OC - Comm_get> Enter(queue=0x10000, msg=0x424b69d4, timeout=-1)
@5,817,511us: [+0 T:0x4001fcb0 S:0xbef35a44] CE - Engine_open> return(401528)
@5,818,042us: [+0 T:0x424b7490 S:0x424b6934] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
[DSP] @6,573,878tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
[DSP] @6,573,933tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - remote time = 0x0, trace buffer size = 4032
@5,818,377us: [+0 T:0x424b7490 S:0x424b696c] CE - Engine_fwriteTrace> returning count [197]
@5,818,483us: [+2 T:0x424b7490 S:0x424b69d4] CE - Engine_createNode> Returning 0x625b8
@5,818,595us: [+5 T:0x424b7490 S:0x424b6a4c] CV - VISA_create> remote codec created (name='viddec2_copy', localQueueID=0x10002, remoteQueueID=0x0002)
@5,818,706us: [+0 T:0x424b7490 S:0x424b6b84] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_create> return (0x62570)
@5,818,802us: [+2 T:0x424b7490 S:0x424b6bac] ti.sdo.dmai - [Vdec2] Video decoder instance created
@5,818,890us: [+0 T:0x424b7490 S:0x424b6b3c] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_control> Enter (handle=0x62570, id=1, dynParams=0x424b6da4 (size=0x1c), status=0x424b6c04 (size=0xb8)
@5,819,036us: [+4 T:0x424b7490 S:0x424b6afc] CV - VISA_getMaxMsgSize(0x62570): returning 0x1000
@5,819,174us: [+5 T:0x424b7490 S:0x424b6b04] CV - VISA_allocMsg> Allocating message for messageId=0x00020001
@5,819,277us: [+0 T:0x424b7490 S:0x424b6ae4] CV - VISA_call(visa=0x62570, msg=0x43436880): messageId=0x00020001, command=0x1
@5,819,382us: [+0 T:0x424b7490 S:0x424b6aac] OC - Comm_put> Enter(queue=0x2, msg=0x43436880)
@5,819,521us: [+0 T:0x424b7490 S:0x424b6aac] OC - Comm_put> return (0)
@5,819,623us: [+0 T:0x424b7490 S:0x424b6aa4] OC - Comm_get> Enter(queue=0x10002, msg=0x424b6b54, timeout=-1)
@5,819,850us: [+0 T:0x424b7490 S:0x424b6aa4] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
@5,819,976us: [+0 T:0x424b7490 S:0x424b6a34] OC - Comm_put> Enter(queue=0x0, msg=0x43434880)
@5,820,118us: [+0 T:0x424b7490 S:0x424b6a34] OC - Comm_put> return (0)
@5,820,242us: [+0 T:0x424b7490 S:0x424b6a2c] OC - Comm_get> Enter(queue=0x10000, msg=0x424b6acc, timeout=-1)
@5,820,469us: [+0 T:0x424b7490 S:0x424b6a2c] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
[DSP] @6,582,778tk: [+5 T:0x8fa25634 S:0x8fa27e2c] CN - NODE> 0x8fa25148(viddec2_copy#0) call(algHandle=0x8fa25208, msg=0x8fe05880); messageId=0x00020001
[DSP] @6,582,868tk: [+0 T:0x8fa25634 S:0x8fa27cb4] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_control> Enter (handle=0x8fa25208, id=1, dynParams=0x8fe058b4 (size=0x1c), status=0x8fe058d0 (size=0xb8)
[DSP] @6,582,972tk: [+5 T:0x8fa25634 S:0x8fa27c94] CV - VISA_enter(visa=0x8fa25208): algHandle = 0x8fa25240
[DSP] @6,583,033tk: [+0 T:0x8fa25634 S:0x8fa27c74] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(alg=0x8fa25240)
[DSP] @6,583,099tk: [+0 T:0x8fa25634 S:0x8fa27c74] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Exit
[DSP] @6,583,156tk: [+5 T:0x8fa25634 S:0x8fa27c94] CV - VISA_exit(visa=0x8fa25208): algHandle = 0x8fa25240
[DSP] @6,583,221tk: [+0 T:0x8fa25634 S:0x8fa27c74] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(alg=0x8fa25240)
[DSP] @6,583,283tk: [+0 T:0x8fa25634 S:0x8fa27c74] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Exit
[DSP] @6,583,338tk: [+0 T:0x8fa25634 S:0x8fa27cb4] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_control> Exit (handle=0x8fa25208, retVal=0x0)
[DSP] @6,583,405tk: [+5 T:0x8fa25634 S:0x8fa27e2c] CN - NODE> returned from call(algHandle=0x8fa25208, msg=0x8fe05880); messageId=0x00020001
[DSP] @6,585,085tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
[DSP] @6,585,141tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - remote time = 0x0, trace buffer size = 4032
@5,823,747us: [+0 T:0x40bd6490 S:0x40bd5d64] OP - getCmd_d> Enter (proc=0x40bd5dd8)
@6,155,887us: [+0 T:0x424b7490 S:0x424b6a64] CE - Engine_fwriteTrace> returning count [1476]
@6,156,003us: [+0 T:0x424b7490 S:0x424b6ae4] CV - VISA_call Completed: messageId=0x00020001, command=0x1, return(status=0)
@6,156,120us: [+5 T:0x424b7490 S:0x424b6aec] CV - VISA_freeMsg(0x62570, 0x43436880): Freeing message with messageId=0x00020001
@6,156,225us: [+0 T:0x424b7490 S:0x424b6b3c] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_control> Exit (handle=0x62570, retVal=0x0)
@6,156,323us: [+2 T:0x424b7490 S:0x424b6bac] ti.sdo.dmai - [Vdec2] Made XDM_SETPARAMS control call
@6,156,413us: [+0 T:0x424b7490 S:0x424b6a6c] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_control> Enter (handle=0x62570, id=5, dynParams=0x624fc (size=0x1c), status=0x424b6b24 (size=0xb8)
@6,156,523us: [+4 T:0x424b7490 S:0x424b6a2c] CV - VISA_getMaxMsgSize(0x62570): returning 0x1000
@6,156,618us: [+5 T:0x424b7490 S:0x424b6a34] CV - VISA_allocMsg> Allocating message for messageId=0x00020002
@6,156,714us: [+0 T:0x424b7490 S:0x424b6a14] CV - VISA_call(visa=0x62570, msg=0x43436880): messageId=0x00020002, command=0x1
@6,156,815us: [+0 T:0x424b7490 S:0x424b69dc] OC - Comm_put> Enter(queue=0x2, msg=0x43436880)
@6,156,957us: [+0 T:0x424b7490 S:0x424b69dc] OC - Comm_put> return (0)
@6,157,058us: [+0 T:0x424b7490 S:0x424b69d4] OC - Comm_get> Enter(queue=0x10002, msg=0x424b6a84, timeout=-1)
@6,157,331us: [+0 T:0x424b7490 S:0x424b69d4] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
@6,157,459us: [+0 T:0x424b7490 S:0x424b6964] OC - Comm_put> Enter(queue=0x0, msg=0x43434880)
@6,157,604us: [+0 T:0x424b7490 S:0x424b6964] OC - Comm_put> return (0)
@6,157,742us: [+0 T:0x424b7490 S:0x424b695c] OC - Comm_get> Enter(queue=0x10000, msg=0x424b69fc, timeout=-1)
@6,158,020us: [+0 T:0x424b7490 S:0x424b695c] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
[DSP] @7,887,699tk: [+5 T:0x8fa25634 S:0x8fa27e2c] CN - NODE> 0x8fa25148(viddec2_copy#0) call(algHandle=0x8fa25208, msg=0x8fe05880); messageId=0x00020002
[DSP] @7,887,782tk: [+0 T:0x8fa25634 S:0x8fa27cb4] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_control> Enter (handle=0x8fa25208, id=5, dynParams=0x8fe058b4 (size=0x1c), status=0x8fe058d0 (size=0xb8)
[DSP] @7,887,881tk: [+5 T:0x8fa25634 S:0x8fa27c94] CV - VISA_enter(visa=0x8fa25208): algHandle = 0x8fa25240
[DSP] @7,887,940tk: [+0 T:0x8fa25634 S:0x8fa27c74] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(alg=0x8fa25240)
[DSP] @7,888,001tk: [+0 T:0x8fa25634 S:0x8fa27c74] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Exit
[DSP] @7,888,052tk: [+5 T:0x8fa25634 S:0x8fa27c94] CV - VISA_exit(visa=0x8fa25208): algHandle = 0x8fa25240
[DSP] @7,888,112tk: [+0 T:0x8fa25634 S:0x8fa27c74] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(alg=0x8fa25240)
[DSP] @7,888,173tk: [+0 T:0x8fa25634 S:0x8fa27c74] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Exit
[DSP] @7,888,225tk: [+0 T:0x8fa25634 S:0x8fa27cb4] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_control> Exit (handle=0x8fa25208, retVal=0x0)
[DSP] @7,888,291tk: [+5 T:0x8fa25634 S:0x8fa27e2c] CN - NODE> returned from call(algHandle=0x8fa25208, msg=0x8fe05880); messageId=0x00020002
[DSP] @7,890,201tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
[DSP] @7,890,257tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - remote time = 0x0, trace buffer size = 4032
@6,159,450us: [+0 T:0x40bd6490 S:0x40bd5d44] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x611c0] timeout[0xffffffff]
@6,494,212us: [+0 T:0x424b7490 S:0x424b6994] CE - Engine_fwriteTrace> returning count [1476]
@6,494,326us: [+0 T:0x424b7490 S:0x424b6a14] CV - VISA_call Completed: messageId=0x00020002, command=0x1, return(status=0)
@6,494,443us: [+5 T:0x424b7490 S:0x424b6a1c] CV - VISA_freeMsg(0x62570, 0x43436880): Freeing message with messageId=0x00020002
@6,494,547us: [+0 T:0x424b7490 S:0x424b6a6c] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_control> Exit (handle=0x62570, retVal=0x0)
@6,494,646us: [+2 T:0x424b7490 S:0x424b6adc] ti.sdo.dmai - [Vdec2] Made XDM_GETBUFINFO control call
Vdec2_getOutBufSize(hVd2):1
@6,494,844us: [+2 T:0x424b7490 S:0x424b6bbc] ti.sdo.dmai - [Venc1] Creating encoder videnc1_copy for max 1280x720 bitrate 6000000 ratectrl 1
@6,494,977us: [+0 T:0x424b7490 S:0x424b6b94] ti.sdo.ce.video1.VIDENC1 - VIDENC1_create> Enter (engine=0x61bb0, name='videnc1_copy', params=0x4b0d4)
@6,495,088us: [+0 T:0x424b7490 S:0x424b6b64] CV - VISA_create(0x61bb0, 'videnc1_copy', 0x4b0d4, 0x520, 'ti.sdo.ce.video1.IVIDENC1')
@6,495,196us: [+0 T:0x424b7490 S:0x424b6a5c] CV - VISA_create2(0x61bb0, 'videnc1_copy', 0x4b0d4, 0x30, 0x520, 'ti.sdo.ce.video1.IVIDENC1')
@6,495,312us: [+0 T:0x424b7490 S:0x424b6a44] OM - Memory_alloc> Enter(0x34)
@6,495,409us: [+0 T:0x424b7490 S:0x424b6a44] OM - Memory_alloc> return (0x62738)
@6,495,501us: [+0 T:0x424b7490 S:0x424b6a44] OM - Memory_alloc> Enter(0x4)
@6,495,595us: [+0 T:0x424b7490 S:0x424b6a44] OM - Memory_alloc> return (0x62770)
@6,495,687us: [+0 T:0x424b7490 S:0x424b6a3c] OC - Comm_alloc> Enter(poolId=0x0, msg=0x62770, size=520)
@6,495,812us: [+0 T:0x424b7490 S:0x424b6a3c] OC - Comm_alloc> msg=0x43437880, returning (0)
@6,495,914us: [+0 T:0x424b7490 S:0x424b69e4] CE - Engine_createNode(0x61bb0, 'videnc1_copy', 520, 0x4b0d4, 0x30, 0x424b6b58)
@6,496,018us: [+0 T:0x424b7490 S:0x424b69e4] CE - Engine> allocNode Enter(engine=0x61bb0, impId='videnc1_copy')
@6,496,116us: [+0 T:0x424b7490 S:0x424b69cc] OM - Memory_alloc> Enter(0x20)
@6,496,210us: [+0 T:0x424b7490 S:0x424b69cc] OM - Memory_alloc> return (0x62780)
@6,496,318us: [+0 T:0x424b7490 S:0x424b69e4] CE - Engine> allocNode(). Calling (Comm_create(gppfromnode_1471_3, 0x62788, NULL)
@6,496,418us: [+0 T:0x424b7490 S:0x424b69bc] OC - Comm_create> Enter(queueName='gppfromnode_1471_3', queue=0x62788, attrs=0x0)
@6,496,514us: [+0 T:0x424b7490 S:0x424b69a4] OM - Memory_alloc> Enter(0x4)
@6,496,632us: [+0 T:0x424b7490 S:0x424b69a4] OM - Memory_alloc> return (0x627a8)
@6,497,326us: [+0 T:0x424b7490 S:0x424b69bc] OC - Comm_create> return (0x627a8)
@6,497,458us: [+0 T:0x424b7490 S:0x424b69c4] OC - Comm_put> Enter(queue=0x0, msg=0x43434880)
@6,497,606us: [+0 T:0x424b7490 S:0x424b69c4] OC - Comm_put> return (0)
@6,497,710us: [+0 T:0x424b7490 S:0x424b69bc] OC - Comm_get> Enter(queue=0x10000, msg=0x424b6a6c, timeout=-1)
@6,498,137us: [+0 T:0x424b7490 S:0x424b69bc] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
@6,498,265us: [+0 T:0x424b7490 S:0x424b69c4] OC - Comm_put> Enter(queue=0x0, msg=0x43434880)
@6,498,407us: [+0 T:0x424b7490 S:0x424b69c4] OC - Comm_put> return (0)
@6,498,546us: [+0 T:0x424b7490 S:0x424b69bc] OC - Comm_get> Enter(queue=0x10000, msg=0x424b6a6c, timeout=-1)
@6,498,743us: [+0 T:0x424b7490 S:0x424b69bc] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
@6,498,861us: [+4 T:0x424b7490 S:0x424b69e4] CE - Engine_createNode> created node(stdIn=0x3, stdOut=0x10003, msgq=0x627a8, algName='videnc1_copy', rmsNode=0x8fa27eb8, algHandle=0x8fa27f58)
@6,498,987us: [+0 T:0x424b7490 S:0x424b694c] OC - Comm_put> Enter(queue=0x0, msg=0x43434880)
@6,832,695us: [+0 T:0x424b7490 S:0x424b694c] OC - Comm_put> return (0)
@6,832,845us: [+0 T:0x424b7490 S:0x424b6944] OC - Comm_get> Enter(queue=0x10000, msg=0x424b69e4, timeout=-1)
@6,833,083us: [+0 T:0x424b7490 S:0x424b6944] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
[DSP] @9,205,056tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 0
[DSP] @9,205,116tk: [+0 T:0x8fa23e6c S:0x8fa24514] OM - Memory_alloc> Enter(size=0x18)
[DSP] @9,205,169tk: [+0 T:0x8fa23e6c S:0x8fa24514] OM - Memory_alloc> return (0x8fa27eb8)
[DSP] @9,205,220tk: [+0 T:0x8fa23e6c S:0x8fa24514] OM - Memory_alloc> Enter(size=0xf)
[DSP] @9,205,265tk: [+0 T:0x8fa23e6c S:0x8fa24514] OM - Memory_alloc> return (0x8fa27ed0)
[DSP] @9,205,317tk: [+0 T:0x8fa23e6c S:0x8fa244d4] OM - Memory_alloc> Enter(size=0x20)
[DSP] @9,205,363tk: [+0 T:0x8fa23e6c S:0x8fa244d4] OM - Memory_alloc> return (0x8fa27ee0)
[DSP] @9,205,416tk: [+0 T:0x8fa23e6c S:0x8fa244a4] OM - Memory_alloc> Enter(size=0x24)
[DSP] @9,205,463tk: [+0 T:0x8fa23e6c S:0x8fa244a4] OM - Memory_alloc> return (0x8fa27f00)
[DSP] @9,205,542tk: [+0 T:0x8fa23e6c S:0x8fa244bc] ti.sdo.ce.video1.VIDENC1 - VIDENC1_create> Enter (engine=0x0, name='videnc1_copy', params=0x8fe038c0)
[DSP] @9,205,616tk: [+0 T:0x8fa23e6c S:0x8fa24484] CV - VISA_create(0x0, 'videnc1_copy', 0x8fe038c0, 0x520, 'ti.sdo.ce.video1.IVIDENC1')
[DSP] @9,205,689tk: [+0 T:0x8fa23e6c S:0x8fa24364] CV - VISA_create2(0x0, 'videnc1_copy', 0x8fe038c0, 0x30, 0x520, 'ti.sdo.ce.video1.IVIDENC1')
[DSP] @9,205,782tk: [+0 T:0x8fa23e6c S:0x8fa24344] OM - Memory_alloc> Enter(size=0x34)
[DSP] @9,205,833tk: [+0 T:0x8fa23e6c S:0x8fa24344] OM - Memory_alloc> return (0x8fa27f58)
[DSP] @9,205,885tk: [+0 T:0x8fa23e6c S:0x8fa2431c] ti.sdo.ce.alg.Algorithm - Algorithm_create> Enter(fxns=0x8fa4d0cc, idma3Fxns=0x0, iresFxns=0x0, params=0x8fe038c0, attrs=0x8fa24468)
[DSP] @9,205,975tk: [+0 T:0x8fa23e6c S:0x8fa242fc] OM - Memory_alloc> Enter(size=0x10)
[DSP] @9,206,022tk: [+0 T:0x8fa23e6c S:0x8fa242fc] OM - Memory_alloc> return (0x8fa27f90)
[DSP] @9,206,113tk: [+0 T:0x8fa23e6c S:0x8fa2431c] ti.sdo.ce.alg.Algorithm - Algorithm_create> return (0x8fa27f90)
[DSP] @9,206,174tk: [+5 T:0x8fa23e6c S:0x8fa24364] CV - VISA_create> local codec created (name='videnc1_copy', handle=0x8fa27f90)
[DSP] @9,206,240tk: [+0 T:0x8fa23e6c S:0x8fa244bc] ti.sdo.ce.video1.VIDENC1 - VIDENC1_create> return (0x8fa27f58)
[DSP] @9,206,306tk: [+4 T:0x8fa23e6c S:0x8fa2447c] OT - Thread_create > name: "videnc1_copy#1", pri: -1, stack size: 10288, stack seg: 0
[DSP] @9,208,164tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 1
[DSP] @10,500,896tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
[DSP] @10,500,951tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - remote time = 0x0, trace buffer size = 4032
@6,835,616us: [+0 T:0x424b7490 S:0x424b697c] CE - Engine_fwriteTrace> returning count [2570]
@6,835,717us: [+2 T:0x424b7490 S:0x424b69e4] CE - Engine_createNode> Returning 0x62780
@6,835,826us: [+5 T:0x424b7490 S:0x424b6a5c] CV - VISA_create> remote codec created (name='videnc1_copy', localQueueID=0x10003, remoteQueueID=0x0003)
@6,835,937us: [+0 T:0x424b7490 S:0x424b6b94] ti.sdo.ce.video1.VIDENC1 - VIDENC1_create> return (0x62738)
@6,836,036us: [+2 T:0x424b7490 S:0x424b6bbc] ti.sdo.dmai - [Venc1] Setting dynParams size 1280x720 bitrate 6000000
@6,836,138us: [+0 T:0x424b7490 S:0x424b6b3c] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Enter (handle=0x62738, id=1, dynParams=0x4b104 (size=0x30), status=0x424b6c20 (size=0x9c)
@6,836,250us: [+4 T:0x424b7490 S:0x424b6afc] CV - VISA_getMaxMsgSize(0x62738): returning 0x1000
@6,836,343us: [+5 T:0x424b7490 S:0x424b6b04] CV - VISA_allocMsg> Allocating message for messageId=0x00030001
@6,836,439us: [+0 T:0x424b7490 S:0x424b6ae4] CV - VISA_call(visa=0x62738, msg=0x43437880): messageId=0x00030001, command=0x1
@7,171,370us: [+0 T:0x424b7490 S:0x424b6aac] OC - Comm_put> Enter(queue=0x3, msg=0x43437880)
@7,171,530us: [+0 T:0x424b7490 S:0x424b6aac] OC - Comm_put> return (0)
@7,171,635us: [+0 T:0x424b7490 S:0x424b6aa4] OC - Comm_get> Enter(queue=0x10003, msg=0x424b6b54, timeout=-1)
@7,171,899us: [+0 T:0x424b7490 S:0x424b6aa4] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
@7,172,030us: [+0 T:0x424b7490 S:0x424b6a34] OC - Comm_put> Enter(queue=0x0, msg=0x43434880)
@7,172,177us: [+0 T:0x424b7490 S:0x424b6a34] OC - Comm_put> return (0)
@7,172,314us: [+0 T:0x424b7490 S:0x424b6a2c] OC - Comm_get> Enter(queue=0x10000, msg=0x424b6acc, timeout=-1)
@7,172,511us: [+0 T:0x424b7490 S:0x424b6a2c] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
[DSP] @11,811,244tk: [+5 T:0x8fa28014 S:0x8fa2a80c] CN - NODE> 0x8fa27ee0(videnc1_copy#1) call(algHandle=0x8fa27f58, msg=0x8fe06880); messageId=0x00030001
[DSP] @11,811,338tk: [+0 T:0x8fa28014 S:0x8fa2a66c] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Enter (handle=0x8fa27f58, id=1, dynParams=0x8fe068b4 (size=0x30), status=0x8fe068e4 (size=0x9c)
[DSP] @11,811,439tk: [+5 T:0x8fa28014 S:0x8fa2a64c] CV - VISA_enter(visa=0x8fa27f58): algHandle = 0x8fa27f90
[DSP] @11,811,502tk: [+0 T:0x8fa28014 S:0x8fa2a62c] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(alg=0x8fa27f90)
[DSP] @11,811,568tk: [+0 T:0x8fa28014 S:0x8fa2a62c] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Exit
[DSP] @11,811,628tk: [+5 T:0x8fa28014 S:0x8fa2a64c] CV - VISA_exit(visa=0x8fa27f58): algHandle = 0x8fa27f90
[DSP] @11,811,690tk: [+0 T:0x8fa28014 S:0x8fa2a62c] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(alg=0x8fa27f90)
[DSP] @11,811,754tk: [+0 T:0x8fa28014 S:0x8fa2a62c] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Exit
[DSP] @11,811,807tk: [+0 T:0x8fa28014 S:0x8fa2a66c] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Exit (handle=0x8fa27f58, retVal=0x0)
[DSP] @11,811,877tk: [+5 T:0x8fa28014 S:0x8fa2a80c] CN - NODE> returned from call(algHandle=0x8fa27f58, msg=0x8fe06880); messageId=0x00030001
[DSP] @11,813,743tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
[DSP] @11,813,800tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - remote time = 0x0, trace buffer size = 4032
@7,174,000us: [+0 T:0x424b7490 S:0x424b6a64] CE - Engine_fwriteTrace> returning count [1488]
@7,174,127us: [+0 T:0x424b7490 S:0x424b6ae4] CV - VISA_call Completed: messageId=0x00030001, command=0x1, return(status=0)
@7,174,239us: [+5 T:0x424b7490 S:0x424b6aec] CV - VISA_freeMsg(0x62738, 0x43437880): Freeing message with messageId=0x00030001
@7,174,340us: [+0 T:0x424b7490 S:0x424b6b3c] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Exit (handle=0x62738, retVal=0x0)
@7,174,435us: [+2 T:0x424b7490 S:0x424b6bbc] ti.sdo.dmai - [Venc1] Made XDM_SETPARAMS control call
@7,174,524us: [+0 T:0x424b7490 S:0x424b6b3c] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Enter (handle=0x62738, id=5, dynParams=0x4b104 (size=0x30), status=0x424b6c20 (size=0x9c)
@7,174,632us: [+4 T:0x424b7490 S:0x424b6afc] CV - VISA_getMaxMsgSize(0x62738): returning 0x1000
@7,174,726us: [+5 T:0x424b7490 S:0x424b6b04] CV - VISA_allocMsg> Allocating message for messageId=0x00030002
@7,174,821us: [+0 T:0x424b7490 S:0x424b6ae4] CV - VISA_call(visa=0x62738, msg=0x43437880): messageId=0x00030002, command=0x1
@7,174,923us: [+0 T:0x424b7490 S:0x424b6aac] OC - Comm_put> Enter(queue=0x3, msg=0x43437880)
@7,175,060us: [+0 T:0x424b7490 S:0x424b6aac] OC - Comm_put> return (0)
@7,175,163us: [+0 T:0x424b7490 S:0x424b6aa4] OC - Comm_get> Enter(queue=0x10003, msg=0x424b6b54, timeout=-1)
@7,175,412us: [+0 T:0x424b7490 S:0x424b6aa4] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
@7,175,540us: [+0 T:0x424b7490 S:0x424b6a34] OC - Comm_put> Enter(queue=0x0, msg=0x43434880)
@7,510,143us: [+0 T:0x424b7490 S:0x424b6a34] OC - Comm_put> return (0)
@7,510,292us: [+0 T:0x424b7490 S:0x424b6a2c] OC - Comm_get> Enter(queue=0x10000, msg=0x424b6acc, timeout=-1)
@7,510,530us: [+0 T:0x424b7490 S:0x424b6a2c] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
[DSP] @11,824,895tk: [+5 T:0x8fa28014 S:0x8fa2a80c] CN - NODE> 0x8fa27ee0(videnc1_copy#1) call(algHandle=0x8fa27f58, msg=0x8fe06880); messageId=0x00030002
[DSP] @11,824,980tk: [+0 T:0x8fa28014 S:0x8fa2a66c] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Enter (handle=0x8fa27f58, id=5, dynParams=0x8fe068b4 (size=0x30), status=0x8fe068e4 (size=0x9c)
[DSP] @11,825,079tk: [+5 T:0x8fa28014 S:0x8fa2a64c] CV - VISA_enter(visa=0x8fa27f58): algHandle = 0x8fa27f90
[DSP] @11,825,138tk: [+0 T:0x8fa28014 S:0x8fa2a62c] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(alg=0x8fa27f90)
[DSP] @11,825,200tk: [+0 T:0x8fa28014 S:0x8fa2a62c] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Exit
[DSP] @11,825,252tk: [+5 T:0x8fa28014 S:0x8fa2a64c] CV - VISA_exit(visa=0x8fa27f58): algHandle = 0x8fa27f90
[DSP] @11,825,311tk: [+0 T:0x8fa28014 S:0x8fa2a62c] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(alg=0x8fa27f90)
[DSP] @11,825,372tk: [+0 T:0x8fa28014 S:0x8fa2a62c] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Exit
[DSP] @11,825,424tk: [+0 T:0x8fa28014 S:0x8fa2a66c] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Exit (handle=0x8fa27f58, retVal=0x0)
[DSP] @11,825,489tk: [+5 T:0x8fa28014 S:0x8fa2a80c] CN - NODE> returned from call(algHandle=0x8fa27f58, msg=0x8fe06880); messageId=0x00030002
[DSP] @13,120,711tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
[DSP] @13,120,767tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - remote time = 0x0, trace buffer size = 4032
@7,511,996us: [+0 T:0x424b7490 S:0x424b6a64] CE - Engine_fwriteTrace> returning count [1488]
@7,512,094us: [+0 T:0x424b7490 S:0x424b6ae4] CV - VISA_call Completed: messageId=0x00030002, command=0x1, return(status=0)
@7,512,204us: [+5 T:0x424b7490 S:0x424b6aec] CV - VISA_freeMsg(0x62738, 0x43437880): Freeing message with messageId=0x00030002
@7,512,305us: [+0 T:0x424b7490 S:0x424b6b3c] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Exit (handle=0x62738, retVal=0x0)
@7,512,417us: [+0 T:0x424b7490 S:0x424b6c54] OM - Memory_alloc> Enter(0x1)
@7,512,513us: [+0 T:0x424b7490 S:0x424b6c0c] OM - Memory_contigAlloc> Enter(size=1, align=-1, cached=FALSE, heap=FALSE)
@7,512,707us: [+4 T:0x424b7490 S:0x424b6c0c] OM - Memory_contigAlloc> CMEM_alloc(1) = 0x434a5000.
@7,512,875us: [+4 T:0x424b7490 S:0x424b6c0c] OM - Memory_contigAlloc> CMEM_getPhys(0x434a5000) = 0x8b662000.
@7,512,984us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> Enter(virtAddr=0x434a5000, size=1, physAddr=0x8b662000)
@7,513,088us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> creating new contigBuf object
@7,513,182us: [+0 T:0x424b7490 S:0x424b6bac] OM - Memory_alloc> Enter(0x10)
@7,513,292us: [+0 T:0x424b7490 S:0x424b6bac] OM - Memory_alloc> return (0x627f8)
@7,513,389us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> returning: cb->phys=0x8b662000, cb->size=1, cb->virt=0x434a5000
@7,513,489us: [+0 T:0x424b7490 S:0x424b6c0c] OM - Memory_contigAlloc> return (0x434a5000)
@7,513,578us: [+0 T:0x424b7490 S:0x424b6c54] OM - Memory_alloc> return (0x434a5000)
@7,513,667us: [+0 T:0x424b7490 S:0x424b6c34] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x434a5000, size=4)
@7,513,760us: [+1 T:0x424b7490 S:0x424b6c34] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x434a5000, size=4)
@7,513,853us: [+1 T:0x424b7490 S:0x424b6c34] OM - Memory__getPhysicalAddress> returning physAddr=0x0
@7,513,954us: [+1 T:0x424b7490 S:0x424b6c34] OM - Memory_getBufferPhysicalAddress> CMEM_getPhys(0x434a5000) = 0x8b662000.
@7,514,063us: [+1 T:0x424b7490 S:0x424b6bec] OM - Memory__addContigBuf> Enter(virtAddr=0x434a5000, size=4, physAddr=0x8b662000)
@7,848,855us: [+1 T:0x424b7490 S:0x424b6bec] OM - Memory__addContigBuf> case 2 (Sc=0x8b662000, Ec=0x8b662001, Ss=0x8b662000, Es=0x8b662004
@7,848,979us: [+1 T:0x424b7490 S:0x424b6bec] OM - Memory__addContigBuf> returning: cb->phys=0x8b662000, cb->size=4, cb->virt=0x434a5000
@7,849,083us: [+0 T:0x424b7490 S:0x424b6c34] OM - Memory_getBufferPhysicalAddress> return (0x8b662000)
@7,849,180us: [+2 T:0x424b7490 S:0x424b6c6c] ti.sdo.dmai - [Buffer] Alloc Buffer of size 1 at 0x434a5000 (0x8b662000 phys)
@7,849,288us: [+2 T:0x424b7490 S:0x424b6c6c] ti.sdo.dmai - [BufTab] Allocating BufTab for 8 buffers
@7,849,396us: [+0 T:0x424b7490 S:0x424b6c2c] OM - Memory_alloc> Enter(0x1)
@7,849,490us: [+0 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> Enter(size=1, align=-1, cached=FALSE, heap=FALSE)
@7,849,676us: [+4 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> CMEM_alloc(1) = 0x434bb000.
@7,849,819us: [+4 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> CMEM_getPhys(0x434bb000) = 0x8b64c000.
@7,849,927us: [+1 T:0x424b7490 S:0x424b6b9c] OM - Memory__addContigBuf> Enter(virtAddr=0x434bb000, size=1, physAddr=0x8b64c000)
@7,850,029us: [+1 T:0x424b7490 S:0x424b6b9c] OM - Memory__addContigBuf> creating new contigBuf object
@7,850,125us: [+0 T:0x424b7490 S:0x424b6b84] OM - Memory_alloc> Enter(0x10)
@7,850,220us: [+0 T:0x424b7490 S:0x424b6b84] OM - Memory_alloc> return (0x628c8)
@7,850,310us: [+1 T:0x424b7490 S:0x424b6b9c] OM - Memory__addContigBuf> returning: cb->phys=0x8b64c000, cb->size=1, cb->virt=0x434bb000
@7,850,407us: [+0 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> return (0x434bb000)
@7,850,496us: [+0 T:0x424b7490 S:0x424b6c2c] OM - Memory_alloc> return (0x434bb000)
@7,850,586us: [+0 T:0x424b7490 S:0x424b6c0c] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x434bb000, size=4)
@7,850,679us: [+1 T:0x424b7490 S:0x424b6c0c] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x434bb000, size=4)
@7,850,773us: [+1 T:0x424b7490 S:0x424b6c0c] OM - Memory__getPhysicalAddress> returning physAddr=0x0
@7,850,873us: [+1 T:0x424b7490 S:0x424b6c0c] OM - Memory_getBufferPhysicalAddress> CMEM_getPhys(0x434bb000) = 0x8b64c000.
@7,850,981us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> Enter(virtAddr=0x434bb000, size=4, physAddr=0x8b64c000)
@7,851,083us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> case 2 (Sc=0x8b64c000, Ec=0x8b64c001, Ss=0x8b64c000, Es=0x8b64c004
@7,851,184us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> returning: cb->phys=0x8b64c000, cb->size=4, cb->virt=0x434bb000
@7,851,282us: [+0 T:0x424b7490 S:0x424b6c0c] OM - Memory_getBufferPhysicalAddress> return (0x8b64c000)
@7,851,372us: [+2 T:0x424b7490 S:0x424b6c44] ti.sdo.dmai - [Buffer] Alloc Buffer of size 1 at 0x434bb000 (0x8b64c000 phys)
@7,851,479us: [+0 T:0x424b7490 S:0x424b6c2c] OM - Memory_alloc> Enter(0x1)
@7,851,597us: [+0 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> Enter(size=1, align=-1, cached=FALSE, heap=FALSE)
@7,851,793us: [+4 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> CMEM_alloc(1) = 0x434d1000.
@7,851,936us: [+4 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> CMEM_getPhys(0x434d1000) = 0x8b678000.
@7,852,042us: [+1 T:0x424b7490 S:0x424b6b9c] OM - Memory__addContigBuf> Enter(virtAddr=0x434d1000, size=1, physAddr=0x8b678000)
@7,852,149us: [+1 T:0x424b7490 S:0x424b6b9c] OM - Memory__addContigBuf> creating new contigBuf object
@7,852,239us: [+0 T:0x424b7490 S:0x424b6b84] OM - Memory_alloc> Enter(0x10)
@7,852,336us: [+0 T:0x424b7490 S:0x424b6b84] OM - Memory_alloc> return (0x62958)
@7,852,426us: [+1 T:0x424b7490 S:0x424b6b9c] OM - Memory__addContigBuf> returning: cb->phys=0x8b678000, cb->size=1, cb->virt=0x434d1000
@7,852,524us: [+0 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> return (0x434d1000)
@8,187,558us: [+0 T:0x424b7490 S:0x424b6c2c] OM - Memory_alloc> return (0x434d1000)
@8,187,669us: [+0 T:0x424b7490 S:0x424b6c0c] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x434d1000, size=4)
@8,187,768us: [+1 T:0x424b7490 S:0x424b6c0c] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x434d1000, size=4)
@8,187,925us: [+1 T:0x424b7490 S:0x424b6c0c] OM - Memory__getPhysicalAddress> returning physAddr=0x0
@8,188,049us: [+1 T:0x424b7490 S:0x424b6c0c] OM - Memory_getBufferPhysicalAddress> CMEM_getPhys(0x434d1000) = 0x8b678000.
@8,188,168us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> Enter(virtAddr=0x434d1000, size=4, physAddr=0x8b678000)
@8,188,272us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> case 2 (Sc=0x8b678000, Ec=0x8b678001, Ss=0x8b678000, Es=0x8b678004
@8,188,374us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> returning: cb->phys=0x8b678000, cb->size=4, cb->virt=0x434d1000
@8,188,470us: [+0 T:0x424b7490 S:0x424b6c0c] OM - Memory_getBufferPhysicalAddress> return (0x8b678000)
@8,188,562us: [+2 T:0x424b7490 S:0x424b6c44] ti.sdo.dmai - [Buffer] Alloc Buffer of size 1 at 0x434d1000 (0x8b678000 phys)
@8,188,671us: [+0 T:0x424b7490 S:0x424b6c2c] OM - Memory_alloc> Enter(0x1)
@8,188,768us: [+0 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> Enter(size=1, align=-1, cached=FALSE, heap=FALSE)
@8,188,961us: [+4 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> CMEM_alloc(1) = 0x43526000.
@8,189,106us: [+4 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> CMEM_getPhys(0x43526000) = 0x8b7cd000.
@8,189,213us: [+1 T:0x424b7490 S:0x424b6b9c] OM - Memory__addContigBuf> Enter(virtAddr=0x43526000, size=1, physAddr=0x8b7cd000)
@8,189,315us: [+1 T:0x424b7490 S:0x424b6b9c] OM - Memory__addContigBuf> creating new contigBuf object
@8,189,405us: [+0 T:0x424b7490 S:0x424b6b84] OM - Memory_alloc> Enter(0x10)
@8,189,499us: [+0 T:0x424b7490 S:0x424b6b84] OM - Memory_alloc> return (0x629e8)
@8,189,590us: [+1 T:0x424b7490 S:0x424b6b9c] OM - Memory__addContigBuf> returning: cb->phys=0x8b7cd000, cb->size=1, cb->virt=0x43526000
@8,189,688us: [+0 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> return (0x43526000)
@8,189,778us: [+0 T:0x424b7490 S:0x424b6c2c] OM - Memory_alloc> return (0x43526000)
@8,189,868us: [+0 T:0x424b7490 S:0x424b6c0c] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x43526000, size=4)
@8,189,961us: [+1 T:0x424b7490 S:0x424b6c0c] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x43526000, size=4)
@8,190,055us: [+1 T:0x424b7490 S:0x424b6c0c] OM - Memory__getPhysicalAddress> returning physAddr=0x0
@8,190,159us: [+1 T:0x424b7490 S:0x424b6c0c] OM - Memory_getBufferPhysicalAddress> CMEM_getPhys(0x43526000) = 0x8b7cd000.
@8,190,292us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> Enter(virtAddr=0x43526000, size=4, physAddr=0x8b7cd000)
@8,190,399us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> case 2 (Sc=0x8b7cd000, Ec=0x8b7cd001, Ss=0x8b7cd000, Es=0x8b7cd004
@8,190,499us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> returning: cb->phys=0x8b7cd000, cb->size=4, cb->virt=0x43526000
@8,190,596us: [+0 T:0x424b7490 S:0x424b6c0c] OM - Memory_getBufferPhysicalAddress> return (0x8b7cd000)
@8,190,688us: [+2 T:0x424b7490 S:0x424b6c44] ti.sdo.dmai - [Buffer] Alloc Buffer of size 1 at 0x43526000 (0x8b7cd000 phys)
@8,190,796us: [+0 T:0x424b7490 S:0x424b6c2c] OM - Memory_alloc> Enter(0x1)
@8,190,892us: [+0 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> Enter(size=1, align=-1, cached=FALSE, heap=FALSE)
@8,191,107us: [+4 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> CMEM_alloc(1) = 0x43597000.
@8,191,251us: [+4 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> CMEM_getPhys(0x43597000) = 0x8b5c2000.
@8,191,358us: [+1 T:0x424b7490 S:0x424b6b9c] OM - Memory__addContigBuf> Enter(virtAddr=0x43597000, size=1, physAddr=0x8b5c2000)
@8,526,291us: [+1 T:0x424b7490 S:0x424b6b9c] OM - Memory__addContigBuf> creating new contigBuf object
@8,526,404us: [+0 T:0x424b7490 S:0x424b6b84] OM - Memory_alloc> Enter(0x10)
@8,526,504us: [+0 T:0x424b7490 S:0x424b6b84] OM - Memory_alloc> return (0x62a78)
@8,526,596us: [+1 T:0x424b7490 S:0x424b6b9c] OM - Memory__addContigBuf> returning: cb->phys=0x8b5c2000, cb->size=1, cb->virt=0x43597000
@8,526,696us: [+0 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> return (0x43597000)
@8,526,787us: [+0 T:0x424b7490 S:0x424b6c2c] OM - Memory_alloc> return (0x43597000)
@8,526,875us: [+0 T:0x424b7490 S:0x424b6c0c] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x43597000, size=4)
@8,526,968us: [+1 T:0x424b7490 S:0x424b6c0c] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x43597000, size=4)
@8,527,062us: [+1 T:0x424b7490 S:0x424b6c0c] OM - Memory__getPhysicalAddress> returning physAddr=0x0
@8,527,168us: [+1 T:0x424b7490 S:0x424b6c0c] OM - Memory_getBufferPhysicalAddress> CMEM_getPhys(0x43597000) = 0x8b5c2000.
@8,527,279us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> Enter(virtAddr=0x43597000, size=4, physAddr=0x8b5c2000)
@8,527,382us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> case 2 (Sc=0x8b5c2000, Ec=0x8b5c2001, Ss=0x8b5c2000, Es=0x8b5c2004
@8,527,482us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> returning: cb->phys=0x8b5c2000, cb->size=4, cb->virt=0x43597000
@8,527,578us: [+0 T:0x424b7490 S:0x424b6c0c] OM - Memory_getBufferPhysicalAddress> return (0x8b5c2000)
@8,527,669us: [+2 T:0x424b7490 S:0x424b6c44] ti.sdo.dmai - [Buffer] Alloc Buffer of size 1 at 0x43597000 (0x8b5c2000 phys)
@8,527,777us: [+0 T:0x424b7490 S:0x424b6c2c] OM - Memory_alloc> Enter(0x1)
@8,527,935us: [+0 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> Enter(size=1, align=-1, cached=FALSE, heap=FALSE)
@8,528,147us: [+4 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> CMEM_alloc(1) = 0x43621000.
@8,528,290us: [+4 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> CMEM_getPhys(0x43621000) = 0x8b538000.
@8,528,396us: [+1 T:0x424b7490 S:0x424b6b9c] OM - Memory__addContigBuf> Enter(virtAddr=0x43621000, size=1, physAddr=0x8b538000)
@8,528,499us: [+1 T:0x424b7490 S:0x424b6b9c] OM - Memory__addContigBuf> creating new contigBuf object
@8,528,588us: [+0 T:0x424b7490 S:0x424b6b84] OM - Memory_alloc> Enter(0x10)
@8,528,682us: [+0 T:0x424b7490 S:0x424b6b84] OM - Memory_alloc> return (0x62b08)
@8,528,772us: [+1 T:0x424b7490 S:0x424b6b9c] OM - Memory__addContigBuf> returning: cb->phys=0x8b538000, cb->size=1, cb->virt=0x43621000
@8,528,869us: [+0 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> return (0x43621000)
@8,528,960us: [+0 T:0x424b7490 S:0x424b6c2c] OM - Memory_alloc> return (0x43621000)
@8,529,081us: [+0 T:0x424b7490 S:0x424b6c0c] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x43621000, size=4)
@8,529,178us: [+1 T:0x424b7490 S:0x424b6c0c] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x43621000, size=4)
@8,529,276us: [+1 T:0x424b7490 S:0x424b6c0c] OM - Memory__getPhysicalAddress> returning physAddr=0x0
@8,529,381us: [+1 T:0x424b7490 S:0x424b6c0c] OM - Memory_getBufferPhysicalAddress> CMEM_getPhys(0x43621000) = 0x8b538000.
@8,529,494us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> Enter(virtAddr=0x43621000, size=4, physAddr=0x8b538000)
@8,529,596us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> case 2 (Sc=0x8b538000, Ec=0x8b538001, Ss=0x8b538000, Es=0x8b538004
@8,529,694us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> returning: cb->phys=0x8b538000, cb->size=4, cb->virt=0x43621000
@8,529,791us: [+0 T:0x424b7490 S:0x424b6c0c] OM - Memory_getBufferPhysicalAddress> return (0x8b538000)
@8,529,881us: [+2 T:0x424b7490 S:0x424b6c44] ti.sdo.dmai - [Buffer] Alloc Buffer of size 1 at 0x43621000 (0x8b538000 phys)
@8,865,052us: [+0 T:0x424b7490 S:0x424b6c2c] OM - Memory_alloc> Enter(0x1)
@8,865,169us: [+0 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> Enter(size=1, align=-1, cached=FALSE, heap=FALSE)
@8,865,369us: [+4 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> CMEM_alloc(1) = 0x436ab000.
@8,865,512us: [+4 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> CMEM_getPhys(0x436ab000) = 0x8b4ae000.
@8,865,620us: [+1 T:0x424b7490 S:0x424b6b9c] OM - Memory__addContigBuf> Enter(virtAddr=0x436ab000, size=1, physAddr=0x8b4ae000)
@8,865,723us: [+1 T:0x424b7490 S:0x424b6b9c] OM - Memory__addContigBuf> creating new contigBuf object
@8,865,814us: [+0 T:0x424b7490 S:0x424b6b84] OM - Memory_alloc> Enter(0x10)
@8,865,909us: [+0 T:0x424b7490 S:0x424b6b84] OM - Memory_alloc> return (0x62b98)
@8,866,000us: [+1 T:0x424b7490 S:0x424b6b9c] OM - Memory__addContigBuf> returning: cb->phys=0x8b4ae000, cb->size=1, cb->virt=0x436ab000
@8,866,117us: [+0 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> return (0x436ab000)
@8,866,211us: [+0 T:0x424b7490 S:0x424b6c2c] OM - Memory_alloc> return (0x436ab000)
@8,866,301us: [+0 T:0x424b7490 S:0x424b6c0c] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x436ab000, size=4)
@8,866,394us: [+1 T:0x424b7490 S:0x424b6c0c] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x436ab000, size=4)
@8,866,490us: [+1 T:0x424b7490 S:0x424b6c0c] OM - Memory__getPhysicalAddress> returning physAddr=0x0
@8,866,593us: [+1 T:0x424b7490 S:0x424b6c0c] OM - Memory_getBufferPhysicalAddress> CMEM_getPhys(0x436ab000) = 0x8b4ae000.
@8,866,704us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> Enter(virtAddr=0x436ab000, size=4, physAddr=0x8b4ae000)
@8,866,806us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> case 2 (Sc=0x8b4ae000, Ec=0x8b4ae001, Ss=0x8b4ae000, Es=0x8b4ae004
@8,866,905us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> returning: cb->phys=0x8b4ae000, cb->size=4, cb->virt=0x436ab000
@8,867,001us: [+0 T:0x424b7490 S:0x424b6c0c] OM - Memory_getBufferPhysicalAddress> return (0x8b4ae000)
@8,867,093us: [+2 T:0x424b7490 S:0x424b6c44] ti.sdo.dmai - [Buffer] Alloc Buffer of size 1 at 0x436ab000 (0x8b4ae000 phys)
@8,867,203us: [+0 T:0x424b7490 S:0x424b6c2c] OM - Memory_alloc> Enter(0x1)
@8,867,296us: [+0 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> Enter(size=1, align=-1, cached=FALSE, heap=FALSE)
@8,867,492us: [+4 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> CMEM_alloc(1) = 0x43735000.
@8,867,637us: [+4 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> CMEM_getPhys(0x43735000) = 0x8b424000.
@8,867,770us: [+1 T:0x424b7490 S:0x424b6b9c] OM - Memory__addContigBuf> Enter(virtAddr=0x43735000, size=1, physAddr=0x8b424000)
@8,867,945us: [+1 T:0x424b7490 S:0x424b6b9c] OM - Memory__addContigBuf> creating new contigBuf object
@8,868,051us: [+0 T:0x424b7490 S:0x424b6b84] OM - Memory_alloc> Enter(0x10)
@8,868,153us: [+0 T:0x424b7490 S:0x424b6b84] OM - Memory_alloc> return (0x62c28)
@8,868,244us: [+1 T:0x424b7490 S:0x424b6b9c] OM - Memory__addContigBuf> returning: cb->phys=0x8b424000, cb->size=1, cb->virt=0x43735000
@8,868,341us: [+0 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> return (0x43735000)
@8,868,429us: [+0 T:0x424b7490 S:0x424b6c2c] OM - Memory_alloc> return (0x43735000)
@8,868,517us: [+0 T:0x424b7490 S:0x424b6c0c] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x43735000, size=4)
@8,868,611us: [+1 T:0x424b7490 S:0x424b6c0c] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x43735000,DavinciHD_vdce DavinciHD_vdce.2: invalid size
size=4)
@8,868,704us: [+1 T:0xs stream.
424b7490 S:0x424b6c0c] OM - Memostream enable.
ry__getPhysicalAddress> returning physAddr=0x0
@8,868,807us: [+1 T:0x424b7490 S:0x424b6c0c] OM - Memory_getBufferPhysicalAddress> CMEM_getPhys(0x43735000) = 0x8b424000.
@9,203,814us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> Enter(virtAddr=0x43735000, size=4, physAddr=0x8b424000)
@9,203,935us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> case 2 (Sc=0x8b424000, Ec=0x8b424001, Ss=0x8b424000, Es=0x8b424004
@9,204,039us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> returning: cb->phys=0x8b424000, cb->size=4, cb->virt=0x43735000
@9,204,141us: [+0 T:0x424b7490 S:0x424b6c0c] OM - Memory_getBufferPhysicalAddress> return (0x8b424000)
@9,204,235us: [+2 T:0x424b7490 S:0x424b6c44] ti.sdo.dmai - [Buffer] Alloc Buffer of size 1 at 0x43735000 (0x8b424000 phys)
@9,204,344us: [+0 T:0x424b7490 S:0x424b6c2c] OM - Memory_alloc> Enter(0x1)
@9,204,439us: [+0 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> Enter(size=1, align=-1, cached=FALSE, heap=FALSE)
@9,204,650us: [+4 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> CMEM_alloc(1) = 0x437bf000.
@9,204,795us: [+4 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> CMEM_getPhys(0x437bf000) = 0x8b39a000.
@9,204,900us: [+1 T:0x424b7490 S:0x424b6b9c] OM - Memory__addContigBuf> Enter(virtAddr=0x437bf000, size=1, physAddr=0x8b39a000)
@9,205,005us: [+1 T:0x424b7490 S:0x424b6b9c] OM - Memory__addContigBuf> creating new contigBuf object
@9,205,096us: [+0 T:0x424b7490 S:0x424b6b84] OM - Memory_alloc> Enter(0x10)
@9,205,195us: [+0 T:0x424b7490 S:0x424b6b84] OM - Memory_alloc> return (0x62cb8)
@9,205,287us: [+1 T:0x424b7490 S:0x424b6b9c] OM - Memory__addContigBuf> returning: cb->phys=0x8b39a000, cb->size=1, cb->virt=0x437bf000
@9,205,386us: [+0 T:0x424b7490 S:0x424b6be4] OM - Memory_contigAlloc> return (0x437bf000)
@9,205,475us: [+0 T:0x424b7490 S:0x424b6c2c] OM - Memory_alloc> return (0x437bf000)
@9,205,564us: [+0 T:0x424b7490 S:0x424b6c0c] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x437bf000, size=4)
@9,205,659us: [+1 T:0x424b7490 S:0x424b6c0c] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x437bf000, size=4)
@9,205,754us: [+1 T:0x424b7490 S:0x424b6c0c] OM - Memory__getPhysicalAddress> returning physAddr=0x0
@9,205,856us: [+1 T:0x424b7490 S:0x424b6c0c] OM - Memory_getBufferPhysicalAddress> CMEM_getPhys(0x437bf000) = 0x8b39a000.
@9,205,966us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> Enter(virtAddr=0x437bf000, size=4, physAddr=0x8b39a000)
@9,206,069us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> case 2 (Sc=0x8b39a000, Ec=0x8b39a001, Ss=0x8b39a000, Es=0x8b39a004
@9,206,190us: [+1 T:0x424b7490 S:0x424b6bc4] OM - Memory__addContigBuf> returning: cb->phys=0x8b39a000, cb->size=4, cb->virt=0x437bf000
@9,206,289us: [+0 T:0x424b7490 S:0x424b6c0c] OM - Memory_getBufferPhysicalAddress> return (0x8b39a000)
@9,206,381us: [+2 T:0x424b7490 S:0x424b6c44] ti.sdo.dmai - [Buffer] Alloc Buffer of size 1 at 0x437bf000 (0x8b39a000 phys)
srcDim.width: 0 dstDim.width:720
srcDim.height: 0 dstDim.height:576
srcDim.x: 0
srcDim.y: 0
srcDim.x: 0
srcDim.y: 0
srcDim.x: 0
srcDim.y: 0
srcDim.x: 0
srcDim.y: 0
@9,213,689us: [+7 T:0x41cb7490 S:0x41cb6c5c] ti.sdo.dmai - [Ccv] Failed VDCE_START
Error: Failed to execute color conversion job
@9,214,979us: [+2 T:0x413d6490 S:0x413d5ca4] ti.sdo.dmai - [Buffer] Free Buffer of size 829440 at 0x413d7000 (0x8b91f000 phys)
@9,215,115us: [+0 T:0x413d6490 S:0x413d5c84] OM - Memory_free> Enter(0x413d7000, 0xca800)
@9,215,215us: [+0 T:0x413d6490 S:0x413d5c4c] OM - Memory_contigFree> Enter(addr=1094545408, size=829440)
@9,215,312us: [+1 T:0x413d6490 S:0x413d5c24] OM - Memory__removeContigBuf> Enter(virtAddr=0x413d7000, size=829440)
@9,215,412us: [+1 T:0x413d6490 S:0x413d5c24] OM - Memory__removeContigBuf> removing cb->phys=0x8b91f000, cb->size=0xca800, cb->virt=0x413d7000
@9,215,515us: [+0 T:0x413d6490 S:0x413d5c04] OM - Memory_free> Enter(0x61810, 0x10)
@9,215,610us: [+0 T:0x413d6490 S:0x413d5c04] OM - Memory_free> return (0x1)
@9,216,147us: [+0 T:0x413d6490 S:0x413d5c4c] OM - Memory_contigFree> return (0x1)
@9,552,167us: [+0 T:0x413d6490 S:0x413d5c84] OM - Memory_free> return (0x1)
@9,552,290us: [+2 T:0x413d6490 S:0x413d5ca4] ti.sdo.dmai - [Buffer] Free Buffer of size 829440 at 0x424b8000 (0x8b83e000 phys)
@9,552,399us: [+0 T:0x413d6490 S:0x413d5c84] OM - Memory_free> Enter(0x424b8000, 0xca800)
@9,552,495us: [+0 T:0x413d6490 S:0x413d5c4c] OM - Memory_contigFree> Enter(addr=1112244224, size=829440)
@9,552,591us: [+1 T:0x413d6490 S:0x413d5c24] OM - Memory__removeContigBuf> Enter(virtAddr=0x424b8000, size=829440)
@9,552,690us: [+1 T:0x413d6490 S:0x413d5c24] OM - Memory__removeContigBuf> removing cb->phys=0x8b83e000, cb->size=0xca800, cb->virt=0x424b8000
@9,552,790us: [+0 T:0x413d6490 S:0x413d5c04] OM - Memory_free> Enter(0x61a38, 0x10)
@9,552,882us: [+0 T:0x413d6490 S:0x413d5c04] OM - Memory_free> return (0x1)
@9,553,414us: [+0 T:0x413d6490 S:0x413d5c4c] OM - Memory_contigFree> return (0x1)
@9,553,540us: [+0 T:0x413d6490 S:0x413d5c84] OM - Memory_free> return (0x1)
@9,553,644us: [+2 T:0x413d6490 S:0x413d5ca4] ti.sdo.dmai - [Buffer] Free Buffer of size 829440 at 0x42599000 (0x8b6cd000 phys)
@9,553,749us: [+0 T:0x413d6490 S:0x413d5c84] OM - Memory_free> Enter(0x42599000, 0xca800)
@9,553,843us: [+0 T:0x413d6490 S:0x413d5c4c] OM - Memory_contigFree> Enter(addr=1113165824, size=829440)
@9,553,937us: [+1 T:0x413d6490 S:0x413d5c24] OM - Memory__removeContigBuf> Enter(virtAddr=0x42599000, size=829440)
@9,554,033us: [+1 T:0x413d6490 S:0x413d5c24] OM - Memory__removeContigBuf> removing cb->phys=0x8b6cd000, cb->size=0xca800, cb->virt=0x42599000
@9,554,136us: [+0 T:0x413d6490 S:0x413d5c04] OM - Memory_free> Enter(0x61ac8, 0x10)
@9,554,229us: [+0 T:0x413d6490 S:0x413d5c04] OM - Memory_free> return (0x1)
@9,554,803us: [+0 T:0x413d6490 S:0x413d5c4c] OM - Memory_contigFree> return (0x1)
@9,554,957us: [+0 T:0x413d6490 S:0x413d5c84] OM - Memory_free> return (0x1)
@9,555,290us: [+2 T:0x424b7490 S:0x424b6c74] ti.sdo.dmai - [Buffer] Free Buffer of size 1 at 0x434a5000 (0x8b662000 phys)
@9,555,445us: [+0 T:0x424b7490 S:0x424b6c54] OM - Memory_free> Enter(0x434a5000, 0x1)
@9,555,553us: [+0 T:0x424b7490 S:0x424b6c1c] OM - Memory_contigFree> Enter(addr=1128943616, size=1)
@9,555,651us: [+1 T:0x424b7490 S:0x424b6bf4] OM - Memory__removeContigBuf> Enter(virtAddr=0x434a5000, size=1)
@9,555,750us: [+1 T:0x424b7490 S:0x424b6bf4] OM - Memory__removeContigBuf> ERROR: Failed to find matching cb.
@9,555,841us: [+7 T:0x424b7490 S:0x424b6c1c] OM - Memory_contigFree> Error: buffer (addr=1128943616, size=1) not found in translation cache
@9,555,940us: [+0 T:0x424b7490 S:0x424b6c1c] OM - Memory_contigFree> return (0x0)
@9,556,027us: [+0 T:0x424b7490 S:0x424b6c54] OM - Memory_free> return (0x0)
@9,556,120us: [+0 T:0x424b7490 S:0x424b6c74] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_delete> Enter (handle=0x62570)
@9,556,216us: [+0 T:0x424b7490 S:0x424b6c4c] CV - VISA_delete(0x62570)
@9,556,302us: [+5 T:0x424b7490 S:0x424b6c4c] CV - VISA_delete> deleting codec (localQueue=0x10002, remoteQueue=0x2)
@9,556,393us: [+0 T:0x424b7490 S:0x424b6c24] CE - Engine_ctrlNode(0x625b8, 0x625a8, 0x0)
@9,556,485us: [+0 T:0x424b7490 S:0x424b6c04] OC - Comm_put> Enter(queue=0x2, msg=0x43436880)
@9,556,623us: [+0 T:0x424b7490 S:0x424b6c04] OC - Comm_put> return (0)
@9,556,760us: [+0 T:0x424b7490 S:0x424b6bfc] OC - Comm_get> Enter(queue=0x10002, msg=0x625a8, timeout=-1)
@9,556,901us: [+2 T:0x41cb7490 S:0x41cb6c8c] ti.sdo.dmai - [Buffer] Free Buffer of size 829440 at 0x42699000 (0x857ca000 phys)
@9,557,035us: [+0 T:0x41cb7490 S:0x41cb6c6c] OM - Memory_free> Enter(0x42699000, 0xca800)
@9,557,143us: [+0 T:0x41cb7490 S:0x41cb6c34] OM - Memory_contigFree> Enter(addr=1114214400, size=829440)
@9,557,241us: [+1 T:0x41cb7490 S:0x41cb6c0c] OM - Memory__removeContigBuf> Enter(virtAddr=0x42699000, size=829440)
@9,890,872us: [+1 T:0x41cb7490 S:0x41cb6c0c] OM - Memory__removeContigBuf> removing cb->phys=0x857ca000, cb->size=0xca800, cb->virt=0x42699000
@9,890,998us: [+0 T:0x41cb7490 S:0x41cb6bec] OM - Memory_free> Enter(0x61c70, 0x10)
@9,891,097us: [+0 T:0x41cb7490 S:0x41cb6bec] OM - Memory_free> return (0x1)
@9,891,852us: [+0 T:0x41cb7490 S:0x41cb6c34] OM - Memory_contigFree> return (0x1)
@9,891,977us: [+0 T:0x41cb7490 S:0x41cb6c6c] OM - Memory_free> return (0x1)
@9,892,079us: [+2 T:0x41cb7490 S:0x41cb6c8c] ti.sdo.dmai - [Buffer] Free Buffer of size 829440 at 0x427fc000 (0x85667000 phys)
@9,892,185us: [+0 T:0x41cb7490 S:0x41cb6c6c] OM - Memory_free> Enter(0x427fc000, 0xca800)
@9,892,278us: [+0 T:0x41cb7490 S:0x41cb6c34] OM - Memory_contigFree> Enter(addr=1115668480, size=829440)
@9,892,370us: [+1 T:0x41cb7490 S:0x41cb6c0c] OM - Memory__removeContigBuf> Enter(virtAddr=0x427fc000, size=829440)
@9,892,467us: [+1 T:0x41cb7490 S:0x41cb6c0c] OM - Memory__removeContigBuf> removing cb->phys=0x85667000, cb->size=0xca800, cb->virt=0x427fc000
@9,892,565us: [+0 T:0x41cb7490 S:0x41cb6bec] OM - Memory_free> Enter(0x61de8, 0x10)
@9,892,655us: [+0 T:0x41cb7490 S:0x41cb6bec] OM - Memory_free> return (0x1)
@9,893,403us: [+0 T:0x41cb7490 S:0x41cb6c34] OM - Memory_contigFree> return (0x1)
@9,893,528us: [+0 T:0x41cb7490 S:0x41cb6c6c] OM - Memory_free> return (0x1)
@9,893,660us: [+2 T:0x41cb7490 S:0x41cb6c8c] ti.sdo.dmai - [Buffer] Free Buffer of size 829440 at 0x4295f000 (0x85504000 phys)
@9,893,765us: [+0 T:0x41cb7490 S:0x41cb6c6c] OM - Memory_free> Enter(0x4295f000, 0xca800)
@9,893,858us: [+0 T:0x41cb7490 S:0x41cb6c34] OM - Memory_contigFree> Enter(addr=1117122560, size=829440)
@9,893,951us: [+1 T:0x41cb7490 S:0x41cb6c0c] OM - Memory__removeContigBuf> Enter(virtAddr=0x4295f000, size=829440)
@9,894,048us: [+1 T:0x41cb7490 S:0x41cb6c0c] OM - Memory__removeContigBuf> removing cb->phys=0x85504000, cb->size=0xca800, cb->virt=0x4295f000
@9,894,148us: [+0 T:0x41cb7490 S:0x41cb6bec] OM - Memory_free> Enter(0x61e78, 0x10)
@9,894,241us: [+0 T:0x41cb7490 S:0x41cb6bec] OM - Memory_free> return (0x1)
@9,894,987us: [+0 T:0x41cb7490 S:0x41cb6c34] OM - Memory_contigFree> return (0x1)
@9,895,112us: [+0 T:0x41cb7490 S:0x41cb6c6c] OM - Memory_free> return (0x1)
@9,895,212us: [+2 T:0x41cb7490 S:0x41cb6c8c] ti.sdo.dmai - [Buffer] Free Buffer of size 829440 at 0x42ac2000 (0x853a1000 phys)
@9,895,315us: [+0 T:0x41cb7490 S:0x41cb6c6c] OM - Memory_free> Enter(0x42ac2000, 0xca800)
@9,895,409us: [+0 T:0x41cb7490 S:0x41cb6c34] OM - Memory_contigFree> Enter(addr=1118576640, size=829440)
@9,895,504us: [+1 T:0x41cb7490 S:0x41cb6c0c] OM - Memory__removeContigBuf> Enter(virtAddr=0x42ac2000, size=829440)
@9,895,600us: [+1 T:0x41cb7490 S:0x41cb6c0c] OM - Memory__removeContigBuf> removing cb->phys=0x853a1000, cb->size=0xca800, cb->virt=0x42ac2000
@9,895,698us: [+0 T:0x41cb7490 S:0x41cb6bec] OM - Memory_free> Enter(0x61f28, 0x10)
@9,895,789us: [+0 T:0x41cb7490 S:0x41cb6bec] OM - Memory_free> return (0x1)
@9,896,561us: [+0 T:0x41cb7490 S:0x41cb6c34] OM - Memory_contigFree> return (0x1)
@9,896,685us: [+0 T:0x41cb7490 S:0x41cb6c6c] OM - Memory_free> return (0x1)
@9,896,911us: [+0 T:0x424b7490 S:0x424b6bfc] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
@9,897,059us: [+0 T:0x424b7490 S:0x424b6c24] CE - Engine_ctrlNode> Returning 0x0
@9,897,167us: [+0 T:0x424b7490 S:0x424b6c04] CE - Engine_deleteNode(0x625b8)
@9,897,261us: [+0 T:0x424b7490 S:0x424b6be4] OC - Comm_put> Enter(queue=0x0, msg=0x43434880)
@9,897,401us: [+0 T:0x424b7490 S:0x424b6be4] OC - Comm_put> return (0)
@9,897,500us: [+0 T:0x424b7490 S:0x424b6bdc] OC - Comm_get> Enter(queue=0x10000, msg=0x424b6c5c, timeout=-1)
@9,897,642us: [+0 T:0x4001fcb0 S:0xbef35a84] CE - Engine_close(0x62078)
@9,897,768us: [+0 T:0x4001fcb0 S:0xbef359ec] OC - Comm_put> Enter(queue=0x0, msg=0x43435880)
@10,232,396us: [+0 T:0x424b7490 S:0x424b6bdc] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
@10,232,524us: [+5 T:0x424b7490 S:0x424b6c04] CE - Engine_deleteNode(0x625b8): algName = viddec2_copy, algHandle = 0x8fa25208, stack size = 10288, stack used = 703(7%)
@10,232,690us: [+0 T:0x424b7490 S:0x424b6bdc] OC - Comm_delete> Enter (comm=0x625e0)
@10,233,036us: [+0 T:0x424b7490 S:0x424b6bbc] OM - Memory_free> Enter(0x625e0, 0x4)
@10,233,170us: [+0 T:0x424b7490 S:0x424b6bbc] OM - Memory_free> return (0x1)
@10,233,267us: [+0 T:0x424b7490 S:0x424b6bfc] OC - Comm_delete> return
@10,233,357us: [+0 T:0x424b7490 S:0x424b6be4] OM - Memory_free> Enter(0x625b8, 0x20)
@10,233,449us: [+0 T:0x424b7490 S:0x424b6be4] OM - Memory_free> return (0x1)
@10,233,540us: [+0 T:0x424b7490 S:0x424b6c3c] OC - Comm_free> Enter (msg=0x43436880)
@10,233,657us: [+0 T:0x424b7490 S:0x424b6c3c] OC - Comm_free> return (0)
@10,233,751us: [+0 T:0x424b7490 S:0x424b6c2c] OM - Memory_free> Enter(0x625a8, 0x4)
@10,233,848us: [+0 T:0x424b7490 S:0x424b6c2c] OM - Memory_free> return (0x1)
@10,233,935us: [+0 T:0x424b7490 S:0x424b6c2c] OM - Memory_free> Enter(0x62570, 0x34)
@10,234,027us: [+0 T:0x424b7490 S:0x424b6c2c] OM - Memory_free> return (0x1)
@10,234,117us: [+0 T:0x424b7490 S:0x424b6c84] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_delete> return
@10,234,210us: [+0 T:0x424b7490 S:0x424b6c74] ti.sdo.ce.video1.VIDENC1 - VIDENC1_delete> Enter (handle=0x62738)
@10,234,305us: [+0 T:0x424b7490 S:0x424b6c4c] CV - VISA_delete(0x62738)
@10,234,394us: [+5 T:0x424b7490 S:0x424b6c4c] CV - VISA_delete> deleting codec (localQueue=0x10003, remoteQueue=0x3)
@10,234,489us: [+0 T:0x424b7490 S:0x424b6c24] CE - Engine_ctrlNode(0x62780, 0x62770, 0x0)
@10,234,581us: [+0 T:0x424b7490 S:0x424b6c04] OC - Comm_put> Enter(queue=0x3, msg=0x43437880)
@10,234,717us: [+0 T:0x424b7490 S:0x424b6c04] OC - Comm_put> return (0)
@10,234,851us: [+0 T:0x424b7490 S:0x424b6bfc] OC - Comm_get> Enter(queue=0x10003, msg=0x62770, timeout=-1)
@10,235,114us: [+0 T:0x424b7490 S:0x424b6bfc] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
@10,235,235us: [+0 T:0x424b7490 S:0x424b6c24] CE - Engine_ctrlNode> Returning 0x0
@10,235,328us: [+0 T:0x424b7490 S:0x424b6c04] CE - Engine_deleteNode(0x62780)
@10,235,419us: [+0 T:0x424b7490 S:0x424b6be4] OC - Comm_put> Enter(queue=0x0, msg=0x43434880)
@10,235,554us: [+0 T:0x424b7490 S:0x424b6be4] OC - Comm_put> return (0)
@10,235,654us: [+0 T:0x424b7490 S:0x424b6bdc] OC - Comm_get> Enter(queue=0x10000, msg=0x424b6c5c, timeout=-1)
@10,235,860us: [+0 T:0x4001fcb0 S:0xbef359ec] OC - Comm_put> return (0)
@10,236,230us: [+0 T:0x424b7490 S:0x424b6bdc] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
@10,236,356us: [+5 T:0x424b7490 S:0x424b6c04] CE - Engine_deleteNode(0x62780): algName = videnc1_copy, algHandle = 0x8fa27f58, stack size = 10288, stack used = 743(8%)
@10,236,476us: [+0 T:0x424b7490 S:0x424b6bdc] OC - Comm_delete> Enter (comm=0x627a8)
@10,236,815us: [+0 T:0x424b7490 S:0x424b6bbc] OM - Memory_free> Enter(0x627a8, 0x4)
@10,236,947us: [+0 T:0x424b7490 S:0x424b6bbc] OM - Memory_free> return (0x1)
@10,237,043us: [+0 T:0x424b7490 S:0x424b6bfc] OC - Comm_delete> return
@10,237,137us: [+0 T:0x424b7490 S:0x424b6be4] OM - Memory_free> Enter(0x62780, 0x20)
@10,237,230us: [+0 T:0x424b7490 S:0x424b6be4] OM - Memory_free> return (0x1)
@10,237,319us: [+0 T:0x424b7490 S:0x424b6c3c] OC - Comm_free> Enter (msg=0x43437880)
@10,237,437us: [+0 T:0x424b7490 S:0x424b6c3c] OC - Comm_free> return (0)
@10,237,533us: [+0 T:0x424b7490 S:0x424b6c2c] OM - Memory_free> Enter(0x62770, 0x4)
@10,237,628us: [+0 T:0x424b7490 S:0x424b6c2c] OM - Memory_free> return (0x1)
@10,237,714us: [+0 T:0x424b7490 S:0x424b6c2c] OM - Memory_free> Enter(0x62738, 0x34)
@10,237,806us: [+0 T:0x424b7490 S:0x424b6c2c] OM - Memory_free> return (0x1)
@10,237,984us: [+0 T:0x424b7490 S:0x424b6c84] ti.sdo.ce.video1.VIDENC1 - VIDENC1_delete> return
@10,573,968us: [+0 T:0x424b7490 S:0x424b6c74] CE - Engine_close(0x61bb0)
@10,574,110us: [+0 T:0x4001fcb0 S:0xbef359e4] OC - Comm_get> Enter(queue=0x10001, msg=0xbef35a84, timeout=-1)
@10,574,263us: [+0 T:0x4001fcb0 S:0xbef359e4] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
[DSP] @21,034,882tk: [+0 T:0x8fa25634 S:0x8fa27e2c] CN - NODE_EXECFXN(0x8fa25148): viddec2_copy#0 exiting per request ...
[DSP] @22,352,699tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 2
[DSP] @22,353,055tk: [+0 T:0x8fa23e6c S:0x8fa245e4] CN - NODE_delete(0x8fa25148): viddec2_copy#0
[DSP] @22,353,125tk: [+0 T:0x8fa23e6c S:0x8fa245c4] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_delete> Enter (handle=0x8fa25208)
[DSP] @22,353,188tk: [+0 T:0x8fa23e6c S:0x8fa24594] CV - VISA_delete(0x8fa25208)
[DSP] @22,353,237tk: [+5 T:0x8fa23e6c S:0x8fa24594] CV - VISA_delete> deleting codec (localQueue=0xffff, remoteQueue=0xffff)
[DSP] @22,353,299tk: [+5 T:0x8fa23e6c S:0x8fa24594] CV - VISA_delete> deleting codec 0x8fa25240
[DSP] @22,353,352tk: [+0 T:0x8fa23e6c S:0x8fa24574] ti.sdo.ce.alg.Algorithm - Algorithm_delete> Enter(alg=0x8fa25240)
[DSP] @22,353,436tk: [+0 T:0x8fa23e6c S:0x8fa24544] OM - Memory_free> Enter(addr=0x8fa25240, size=16)
[DSP] @22,353,493tk: [+0 T:0x8fa23e6c S:0x8fa24544] OM - Memory_free> return (0x1)
[DSP] @22,353,538tk: [+0 T:0x8fa23e6c S:0x8fa24574] ti.sdo.ce.alg.Algorithm - Algorithm_delete> Exit
[DSP] @22,353,590tk: [+0 T:0x8fa23e6c S:0x8fa24564] OM - Memory_free> Enter(addr=0x8fa25208, size=52)
[DSP] @22,353,645tk: [+0 T:0x8fa23e6c S:0x8fa24564] OM - Memory_free> return (0x1)
[DSP] @22,353,689tk: [+0 T:0x8fa23e6c S:0x8fa245c4] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_delete> return
[DSP] @22,353,744tk: [+0 T:0x8fa23e6c S:0x8fa245ac] OM - Memory_free> Enter(addr=0x8fa25168, size=36)
[DSP] @22,353,799tk: [+0 T:0x8fa23e6c S:0x8fa245ac] OM - Memory_free> return (0x1)
[DSP] @22,353,843tk: [+0 T:0x8fa23e6c S:0x8fa245b4] OM - Memory_free> Enter(addr=0x8fa25148, size=32)
[DSP] @22,353,898tk: [+0 T:0x8fa23e6c S:0x8fa245b4] OM - Memory_free> return (0x1)
[DSP] @22,353,944tk: [+0 T:0x8fa23e6c S:0x8fa245d4] OM - Memory_free> Enter(addr=0x8fa25138, size=15)
[DSP] @22,353,999tk: [+0 T:0x8fa23e6c S:0x8fa245d4] OM - Memory_free> return (0x1)
[DSP] @22,354,043tk: [+0 T:0x8fa23e6c S:0x8fa245d4] OM - Memory_free> Enter(addr=0x8fa25120, size=24)
[DSP] @22,354,098tk: [+0 T:0x8fa23e6c S:0x8fa245d4] OM - Memory_free> return (0x1)
[DSP] @23,657,204tk: [+0 T:0x8fa28014 S:0x8fa2a80c] CN - NODE_EXECFXN(0x8fa27ee0): videnc1_copy#1 exiting per request ...
[DSP] @23,660,402tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 2
[DSP] @23,660,748tk: [+0 T:0x8fa23e6c S:0x8fa245e4] CN - NODE_delete(0x8fa27ee0): videnc1_copy#1
[DSP] @23,660,811tk: [+0 T:0x8fa23e6c S:0x8fa245c4] ti.sdo.ce.video1.VIDENC1 - VIDENC1_delete> Enter (handle=0x8fa27f58)
[DSP] @23,660,873tk: [+0 T:0x8fa23e6c S:0x8fa24594] CV - VISA_delete(0x8fa27f58)
[DSP] @23,660,921tk: [+5 T:0x8fa23e6c S:0x8fa24594] CV - VISA_delete> deleting codec (localQueue=0xffff, remoteQueue=0xffff)
[DSP] @23,660,983tk: [+5 T:0x8fa23e6c S:0x8fa24594] CV - VISA_delete> deleting codec 0x8fa27f90
[DSP] @23,661,035tk: [+0 T:0x8fa23e6c S:0x8fa24574] ti.sdo.ce.alg.Algorithm - Algorithm_delete> Enter(alg=0x8fa27f90)
[DSP] @23,661,108tk: [+0 T:0x8fa23e6c S:0x8fa24544] OM - Memory_free> Enter(addr=0x8fa27f90, size=16)
[DSP] @23,661,164tk: [+0 T:0x8fa23e6c S:0x8fa24544] OM - Memory_free> return (0x1)
[DSP] @23,661,209tk: [+0 T:0x8fa23e6c S:0x8fa24574] ti.sdo.ce.alg.Algorithm - Algorithm_delete> Exit
[DSP] @23,661,260tk: [+0 T:0x8fa23e6c S:0x8fa24564] OM - Memory_free> Enter(addr=0x8fa27f58, size=52)
[DSP] @23,661,315tk: [+0 T:0x8fa23e6c S:0x8fa24564] OM - Memory_free> return (0x1)
[DSP] @23,661,360tk: [+0 T:0x8fa23e6c S:0x8fa245c4] ti.sdo.ce.video1.VIDENC1 - VIDENC1_delete> return
[DSP] @23,661,412tk: [+0 T:0x8fa23e6c S:0x8fa245ac] OM - Memory_free> Enter(addr=0x8fa27f00, size=36)
[DSP] @23,661,467tk: [+0 T:0x8fa23e6c S:0x8fa245ac] OM - Memory_free> return (0x1)
[DSP] @23,661,511tk: [+0 T:0x8fa23e6c S:0x8fa245b4] OM - Memory_free> Enter(addr=0x8fa27ee0, size=32)
[DSP] @23,661,619tk: [+0 T:0x8fa23e6c S:0x8fa245b4] OM - Memory_free> return (0x1)
[DSP] @23,661,664tk: [+0 T:0x8fa23e6c S:0x8fa245d4] OM - Memory_free> Enter(addr=0x8fa27ed0, size=15)
[DSP] @23,661,719tk: [+0 T:0x8fa23e6c S:0x8fa245d4] OM - Memory_free> return (0x1)
[DSP] @23,661,763tk: [+0 T:0x8fa23e6c S:0x8fa245d4] OM - Memory_free> Enter(addr=0x8fa27eb8, size=24)
[DSP] @23,661,818tk: [+0 T:0x8fa23e6c@10,913,419us: [+0 T:0x4001fcb0 S:0xbef359ec] OC - Comm_put> Enter(queue=0x0, msg=0x43435880)
@10,913,586us: [+0 T:0x4001fcb0 S:0xbef359ec] OC - Comm_put> return (0)
@10,913,838us: [+0 T:0x4001fcb0 S:0xbef359e4] OC - Comm_get> Enter(queue=0x10001, msg=0xbef35a84, timeout=-1)
@10,913,975us: [+0 T:0x4001fcb0 S:0xbef359e4] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
S:0x8fa245d4] OM - Memory_free> return (0x1)
[DSP] @23,661,897tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - processRmsCmd(0x8fe048a8, 4056): cmd = 5
[DSP] @23,661,952tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - remote time = 0x0, trace buffer size = 4032
[DSP] @26,282,474tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - processRmsCmd(0x8fe048a8, 4056): cmd = 5
[DSP] @26,282,529tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - remote time = 0x0, trace buffer size = 4032
@10,914,535us: [+0 T:0x4001fcb0 S:0xbef35a1c] CE - Engine_fwriteTrace> returning count [4740]
@10,914,680us: [+0 T:0x424b7490 S:0x424b6bdc] OC - Comm_put> Enter(queue=0x0, msg=0x43434880)
@10,914,830us: [+0 T:0x424b7490 S:0x424b6bdc] OC - Comm_put> return (0)
@10,914,968us: [+0 T:0x424b7490 S:0x424b6bd4] OC - Comm_get> Enter(queue=0x10000, msg=0x424b6c74, timeout=-1)
@10,915,204us: [+0 T:0x424b7490 S:0x424b6bd4] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
[DSP] @26,287,285tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
[DSP] @26,287,340tk: [+0 T:0x8fa23e6c S:0x8fa2462c] CR - remote time = 0x0, trace buffer size = 4032
@10,915,559us: [+0 T:0x424b7490 S:0x424b6c0c] CE - Engine_fwriteTrace> returning count [199]
@10,915,667us: [+0 T:0x424b7490 S:0x424b6c54] CS - Server_disconnectTrace('0x61bb0', 0x0)
@10,915,761us: [+0 T:0x424b7490 S:0x424b6c54] CS - Server_connectTrace> return(0)
@10,915,852us: [+0 T:0x424b7490 S:0x424b6c64] OC - Comm_free> Enter (msg=0x43434880)
@10,915,967us: [+0 T:0x424b7490 S:0x424b6c64] OC - Comm_free> return (0)
@10,916,063us: [+0 T:0x424b7490 S:0x424b6c54] OC - Comm_delete> Enter (comm=0x62380)
@10,916,400us: [+0 T:0x424b7490 S:0x424b6c34] OM - Memory_free> Enter(0x62380, 0x4)
@10,916,531us: [+0 T:0x424b7490 S:0x424b6c34] OM - Memory_free> return (0x1)
@10,916,631us: [+0 T:0x424b7490 S:0x424b6c74] OC - Comm_delete> return
@10,916,722us: [+0 T:0x424b7490 S:0x424b6c64] OC - Comm_release> Enter(queue=0x0)
@10,916,825us: [+0 T:0x424b7490 S:0x424b6c64] OC - Comm_release> return (0)
@10,916,920us: [+0 T:0x424b7490 S:0x424b6c74] CE - rserverClose(0x60398), count = 2
@10,917,009us: [+0 T:0x424b7490 S:0x424b6c74] CE - rserverClose(0x60398) done.
@10,917,100us: [+0 T:0x424b7490 S:0x424b6c74] OM - Memory_free> Enter(0x61bb0, 0x2c)
@10,917,194us: [+0 T:0x424b7490 S:0x424b6c74] OM - Memory_free> return (0x1)
@10,917,286us: [+2 T:0x424b7490 S:0x424b6c24] ti.sdo.dmai - [Buffer] Free Buffer of size 1 at 0x434bb000 (0x8b64c000 phys)
@10,917,384us: [+0 T:0x424b7490 S:0x424b6c04] OM - Memory_free> Enter(0x434bb000, 0x1)
@10,917,473us: [+0 T:0x424b7490 S:0x424b6bcc] OM - Memory_contigFree> Enter(addr=1129033728, size=1)
@11,251,417us: [+1 T:0x424b7490 S:0x424b6ba4] OM - Memory__removeContigBuf> Enter(virtAddr=0x434bb000, size=1)
@11,251,539us: [+1 T:0x424b7490 S:0x424b6ba4] OM - Memory__removeContigBuf> ERROR: Failed to find matching cb.
@11,251,634us: [+7 T:0x424b7490 S:0x424b6bcc] OM - Memory_contigFree> Error: buffer (addr=1129033728, size=1) not found in translation cache
@11,251,732us: [+0 T:0x424b7490 S:0x424b6bcc] OM - Memory_contigFree> return (0x0)
@11,251,818us: [+0 T:0x424b7490 S:0x424b6c04] OM - Memory_free> return (0x0)
@11,251,908us: [+2 T:0x424b7490 S:0x424b6c24] ti.sdo.dmai - [Buffer] Free Buffer of size 1 at 0x434d1000 (0x8b678000 phys)
@11,252,008us: [+0 T:0x424b7490 S:0x424b6c04] OM - Memory_free> Enter(0x434d1000, 0x1)
@11,252,102us: [+0 T:0x424b7490 S:0x424b6bcc] OM - Memory_contigFree> Enter(addr=1129123840, size=1)
@11,252,194us: [+1 T:0x424b7490 S:0x424b6ba4] OM - Memory__removeContigBuf> Enter(virtAddr=0x434d1000, size=1)
@11,252,289us: [+1 T:0x424b7490 S:0x424b6ba4] OM - Memory__removeContigBuf> ERROR: Failed to find matching cb.
@11,252,376us: [+7 T:0x424b7490 S:0x424b6bcc] OM - Memory_contigFree> Error: buffer (addr=1129123840, size=1) not found in translation cache
@11,252,470us: [+0 T:0x424b7490 S:0x424b6bcc] OM - Memory_contigFree> return (0x0)
@11,252,557us: [+0 T:0x424b7490 S:0x424b6c04] OM - Memory_free> return (0x0)
@11,252,649us: [+2 T:0x424b7490 S:0x424b6c24] ti.sdo.dmai - [Buffer] Free Buffer of size 1 at 0x43526000 (0x8b7cd000 phys)
@11,252,747us: [+0 T:0x424b7490 S:0x424b6c04] OM - Memory_free> Enter(0x43526000, 0x1)
@11,252,839us: [+0 T:0x424b7490 S:0x424b6bcc] OM - Memory_contigFree> Enter(addr=1129472000, size=1)
@11,252,931us: [+1 T:0x424b7490 S:0x424b6ba4] OM - Memory__removeContigBuf> Enter(virtAddr=0x43526000, size=1)
@11,253,023us: [+1 T:0x424b7490 S:0x424b6ba4] OM - Memory__removeContigBuf> ERROR: Failed to find matching cb.
@11,253,113us: [+7 T:0x424b7490 S:0x424b6bcc] OM - Memory_contigFree> Error: buffer (addr=1129472000, size=1) not found in translation cache
@11,253,210us: [+0 T:0x424b7490 S:0x424b6bcc] OM - Memory_contigFree> return (0x0)
@11,253,296us: [+0 T:0x424b7490 S:0x424b6c04] OM - Memory_free> return (0x0)
@11,253,387us: [+2 T:0x424b7490 S:0x424b6c24] ti.sdo.dmai - [Buffer] Free Buffer of size 1 at 0x43597000 (0x8b5c2000 phys)
@11,253,486us: [+0 T:0x424b7490 S:0x424b6c04] OM - Memory_free> Enter(0x43597000, 0x1)
@11,253,577us: [+0 T:0x424b7490 S:0x424b6bcc] OM - Memory_contigFree> Enter(addr=1129934848, size=1)
@11,253,670us: [+1 T:0x424b7490 S:0x424b6ba4] OM - Memory__removeContigBuf> Enter(virtAddr=0x43597000, size=1)
@11,253,762us: [+1 T:0x424b7490 S:0x424b6ba4] OM - Memory__removeContigBuf> ERROR: Failed to find matching cb.
@11,253,849us: [+7 T:0x424b7490 S:0x424b6bcc] OM - Memory_contigFree> Error: buffer (addr=1129934848, size=1) not found in translation cache
@11,253,945us: [+0 T:0x424b7490 S:0x424b6bcc] OM - Memory_contigFree> return (0x0)
@11,254,030us: [+0 T:0x424b7490 S:0x424b6c04] OM - Memory_free> return (0x0)
@11,254,151us: [+2 T:0x424b7490 S:0x424b6c24] ti.sdo.dmai - [Buffer] Free Buffer of size 1 at 0x43621000 (0x8b538000 phys)
@11,254,255us: [+0 T:0x424b7490 S:0x424b6c04] OM - Memory_free> Enter(0x43621000, 0x1)
@11,254,349us: [+0 T:0x424b7490 S:0x424b6bcc] OM - Memory_contigFree> Enter(addr=1130500096, size=1)
@11,254,442us: [+1 T:0x424b7490 S:0x424b6ba4] OM - Memory__removeContigBuf> Enter(virtAddr=0x43621000, size=1)
@11,254,534us: [+1 T:0x424b7490 S:0x424b6ba4] OM - Memory__removeContigBuf> ERROR: Failed to find matching cb.
@11,254,620us: [+7 T:0x424b7490 S:0x424b6bcc] OM - Memory_contigFree> Error: buffer (addr=1130500096, size=1) not found in translation cache
@11,254,715us: [+0 T:0x424b7490 S:0x424b6bcc] OM - Memory_contigFree> return (0x0)
@11,254,800us: [+0 T:0x424b7490 S:0x424b6c04] OM - Memory_free> return (0x0)
@11,590,165us: [+2 T:0x424b7490 S:0x424b6c24] ti.sdo.dmai - [Buffer] Free Buffer of size 1 at 0x436ab000 (0x8b4ae000 phys)
@11,590,287us: [+0 T:0x424b7490 S:0x424b6c04] OM - Memory_free> Enter(0x436ab000, 0x1)
@11,590,383us: [+0 T:0x424b7490 S:0x424b6bcc] OM - Memory_contigFree> Enter(addr=1131065344, size=1)
@11,590,478us: [+1 T:0x424b7490 S:0x424b6ba4] OM - Memory__removeContigBuf> Enter(virtAddr=0x436ab000, size=1)
@11,590,572us: [+1 T:0x424b7490 S:0x424b6ba4] OM - Memory__removeContigBuf> ERROR: Failed to find matching cb.
@11,590,661us: [+7 T:0x424b7490 S:0x424b6bcc] OM - Memory_contigFree> Error: buffer (addr=1131065344, size=1) not found in translation cache
@11,590,757us: [+0 T:0x424b7490 S:0x424b6bcc] OM - Memory_contigFree> return (0x0)
@11,590,845us: [+0 T:0x424b7490 S:0x424b6c04] OM - Memory_free> return (0x0)
@11,590,936us: [+2 T:0x424b7490 S:0x424b6c24] ti.sdo.dmai - [Buffer] Free Buffer of size 1 at 0x43735000 (0x8b424000 phys)
@11,591,035us: [+0 T:0x424b7490 S:0x424b6c04] OM - Memory_free> Enter(0x43735000, 0x1)
@11,591,129us: [+0 T:0x424b7490 S:0x424b6bcc] OM - Memory_contigFree> Enter(addr=1131630592, size=1)
@11,591,221us: [+1 T:0x424b7490 S:0x424b6ba4] OM - Memory__removeContigBuf> Enter(virtAddr=0x43735000, size=1)
@11,591,314us: [+1 T:0x424b7490 S:0x424b6ba4] OM - Memory__removeContigBuf> ERROR: Failed to find matching cb.
@11,591,401us: [+7 T:0x424b7490 S:0x424b6bcc] OM - Memory_contigFree> Error: buffer (addr=1131630592, size=1) not found in translation cache
@11,591,497us: [+0 T:0x424b7490 S:0x424b6bcc] OM - Memory_contigFree> return (0x0)
@11,591,583us: [+0 T:0x424b7490 S:0x424b6c04] OM - Memory_free> return (0x0)
@11,591,673us: [+2 T:0x424b7490 S:0x424b6c24] ti.sdo.dmai - [Buffer] Free Buffer of size 1 at 0x437bf000 (0x8b39a000 phys)
@11,591,773us: [+0 T:0x424b7490 S:0x424b6c04] OM - Memory_free> Enter(0x437bf000, 0x1)
@11,591,863us: [+0 T:0x424b7490 S:0x424b6bcc] OM - Memory_contigFree> Enter(addr=1132195840, size=1)
@11,591,954us: [+1 T:0x424b7490 S:0x424b6ba4] OM - Memory__removeContigBuf> Enter(virtAddr=0x437bf000, size=1)
@11,592,045us: [+1 T:0x424b7490 S:0x424b6ba4] OM - Memory__removeContigBuf> ERROR: Failed to find matching cb.
@11,592,135us: [+7 T:0x424b7490 S:0x424b6bcc] OM - Memory_contigFree> Error: buffer (addr=1132195840, size=1) not found in translation cache
@11,592,230us: [+0 T:0x424b7490 S:0x424b6bcc] OM - Memory_contigFree> return (0x0)
@11,592,314us: [+0 T:0x424b7490 S:0x424b6c04] OM - Memory_free> return (0x0)
@11,592,534us: [+0 T:0x4001fcb0 S:0xbef35a64] CS - Server_disconnectTrace('0x62078', 0x0)
@11,592,674us: [+0 T:0x4001fcb0 S:0xbef35a64] CS - Server_connectTrace> return(0)
@11,592,783us: [+0 T:0x4001fcb0 S:0xbef35a74] OC - Comm_free> Enter (msg=0x43435880)
@11,592,942us: [+0 T:0x4001fcb0 S:0xbef35a74] OC - Comm_free> return (0)
@11,593,047us: [+0 T:0x4001fcb0 S:0xbef35a64] OC - Comm_delete> Enter (comm=0x62390)
@11,593,396us: [+0 T:0x4001fcb0 S:0xbef35a44] OM - Memory_free> Enter(0x62390, 0x4)
@11,593,536us: [+0 T:0x4001fcb0 S:0xbef35a44] OM - Memory_free> return (0x1)
@11,593,640us: [+0 T:0x4001fcb0 S:0xbef35a84] OC - Comm_delete> return
@11,593,735us: [+0 T:0x4001fcb0 S:0xbef35a74] OC - Comm_release> Enter(queue=0x0)
@11,593,846us: [+0 T:0x4001fcb0 S:0xbef35a74] OC - Comm_release> return (0)
@11,593,949us: [+0 T:0x4001fcb0 S:0xbef35a84] CE - rserverClose(0x60398), count = 1
@11,594,047us: [+0 T:0x4001fcb0 S:0xbef35a6c] OP - Processor_delete> Enter(proc=0x61f68)
@11,594,147us: [+0 T:0x4001fcb0 S:0xbef35a4c] OP - doCmd> Enter (cmdId=2, proc=0x61f68)
@11,594,246us: [+0 T:0x4001fcb0 S:0xbef35a3c] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x611c0]
@11,594,369us: [+0 T:0x4001fcb0 S:0xbef35a4c] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x611c0]
@11,594,476us: [+0 T:0x4001fcb0 S:0xbef35a2c] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x611d8] timeout[0xffffffff]
@11,928,900us: [+0 T:0x40bd6490 S:0x40bd5d44] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x611c0] status[0]
@11,929,030us: [+0 T:0x40bd6490 S:0x40bd5d64] OP - getCmd_d> Exit (result=2)
@11,929,131us: [+0 T:0x40bd6490 S:0x40bd5d4c] OP - Processor_delete_d> Enter (proc=0x61f68)
@11,929,227us: [+2 T:0x40bd6490 S:0x40bd5d4c] OP - Processor_delete_d> Closing remote transport...
@11,929,647us: [+2 T:0x40bd6490 S:0x40bd5d4c] OP - Processor_delete_d> Stopping DSP...
@11,929,829us: [+2 T:0x40bd6490 S:0x40bd5d4c] OP - Processor_delete_d> Closing pool...
@11,930,222us: [+2 T:0x40bd6490 S:0x40bd5d4c] OP - Processor_delete_d> Detaching from DSP...
@11,933,698us: [+2 T:0x40bd6490 S:0x40bd5d4c] OP - Processor_delete_d> Destroying DSP... (object, that is)
@11,934,527us: [+0 T:0x40bd6490 S:0x40bd5d2c] OM - Memory_free> Enter(0x61f90, 0x0)
@11,934,662us: [+0 T:0x40bd6490 S:0x40bd5d2c] OM - Memory_free> return (0x1)
@11,934,760us: [+0 T:0x40bd6490 S:0x40bd5d64] OP - Processor_delete_d> return
@11,934,852us: [+0 T:0x40bd6490 S:0x40bd5d54] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x611d8]
@11,934,994us: [+0 T:0x4001fcb0 S:0xbef35a2c] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x611d8] status[0]
@11,935,113us: [+0 T:0x4001fcb0 S:0xbef35a4c] OP - doCmd> Exit (result=1)
@11,935,212us: [+1 T:0x4001fcb0 S:0xbef35a6c] OP - Processor_delete(0x61f68) freeing object ...
@11,935,310us: [+0 T:0x4001fcb0 S:0xbef35a4c] OM - Memory_free> Enter(0x61f68, 0x24)
@11,935,411us: [+0 T:0x4001fcb0 S:0xbef35a4c] OM - Memory_free> return (0x1)
@11,935,507us: [+0 T:0x4001fcb0 S:0xbef35a84] OP - Processor_delete> return.
@11,935,597us: [+0 T:0x4001fcb0 S:0xbef35a84] CE - rserverClose(0x60398) done.
@11,935,690us: [+0 T:0x4001fcb0 S:0xbef35a84] OM - Memory_free> Enter(0x62078, 0x2c)
@11,935,788us: [+0 T:0x4001fcb0 S:0xbef35a84] OM - Memory_free> return (0x1)
@11,936,189us: [+0 T:0x4001fcb0 S:0xbef35adc] OG - Global_exit> enter
@11,936,312us: [+2 T:0x4001fcb0 S:0xbef35adc] OG - Global_exit> calling function *0x229a0()...
@11,936,421us: [+2 T:0x4001fcb0 S:0xbef35adc] OG - Global_exit> calling function *0x24760()...
@11,936,523us: [+0 T:0x4001fcb0 S:0xbef35aac] OM - Memory_free> Enter(0x61420, 0x18)
@11,936,623us: [+0 T:0x4001fcb0 S:0xbef35aac] OM - Memory_free> return (0x1)
@11,936,718us: [+0 T:0x4001fcb0 S:0xbef35aac] OM - Memory_free> Enter(0x61440, 0x18)
@11,936,817us: [+0 T:0x4001fcb0 S:0xbef35aac] OM - Memory_free> return (0x1)
@11,936,911us: [+0 T:0x4001fcb0 S:0xbef35aac] OM - Memory_free> Enter(0x61460, 0x18)
@11,937,010us: [+0 T:0x4001fcb0 S:0xbef35aac] OM - Memory_free> return (0x1)
@11,937,109us: [+2 T:0x4001fcb0 S:0xbef35adc] OG - Global_exit> calling function *0x2f880()...
@11,937,238us: [+2 T:0x4001fcb0 S:0xbef35adc] OG - Global_exit> calling function *0x275a0()...
@11,937,344us: [+2 T:0x4001fcb0 S:0xbef35adc] OG - Global_exit> calling function *0x2cf2c()...
@11,937,446us: [+2 T:0x4001fcb0 S:0xbef35adc] OG - Global_exit> calling function *0x2abe0()...
@11,937,543us: [+0 T:0x4001fcb0 S:0xbef35aac] OP - doCmd> Enter (cmdId=3, proc=0x0)
@11,937,641us: [+0 T:0x4001fcb0 S:0xbef35a9c] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x611c0]
@11,937,738us: [+0 T:0x4001fcb0 S:0xbef35aac] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x611c0]
@11,937,834us: [+0 T:0x4001fcb0 S:0xbef35a8c] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x611d8] timeout[0xffffffff]
@11,938,079us: [+0 T:0x40bd6490 S:0x40bd5d64] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x611d8]
@11,938,195us: [+0 T:0x40bd6490 S:0x40bd5d64] OP - getCmd_d> Enter (proc=0x40bd5dd8)
@11,938,293us: [+0 T:0x40bd6490 S:0x40bd5d44] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x611c0] timeout[0xffffffff]
@11,938,392us: [+0 T:0x40bd6490 S:0x40bd5d44] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x611c0] status[0]
@11,938,485us: [+0 T:0x40bd6490 S:0x40bd5d64] OP - getCmd_d> Exit (result=3)
@12,273,169us: [+0 T:0x40bd6490 S:0x40bd5d54] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x611d8]
@12,273,329us: [+0 T:0x4001fcb0 S:0xbef35a8c] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x611d8] status[0]
@12,273,454us: [+0 T:0x4001fcb0 S:0xbef35aac] OP - doCmd> Exit (result=1)
@12,273,551us: [+0 T:0x4001fcb0 S:0xbef35ab4] OT - Thread_delete> Enter (task=0x61210)
@12,273,706us: [+4 T:0x4001fcb0 S:0xbef35ab4] OT - Thread_delete> pthread_cancel (0x0)
@12,274,216us: [+4 T:0x4001fcb0 S:0xbef35ab4] OT - Thread_delete> pthread_join (0x0)
@12,274,350us: [+0 T:0x4001fcb0 S:0xbef35a94] OM - Memory_free> Enter(0x61210, 0x64)
@12,274,462us: [+0 T:0x4001fcb0 S:0xbef35a94] OM - Memory_free> return (0x1)
@12,274,563us: [+0 T:0x4001fcb0 S:0xbef35acc] OT - Thread_delete> Exit (task=0x61210)
@12,274,662us: [+0 T:0x4001fcb0 S:0xbef35abc] ti.sdo.ce.osal.Sem - Entered Sem_delete> sem[0x611c0]
@12,274,783us: [+0 T:0x4001fcb0 S:0xbef35a9c] OM - Memory_free> Enter(0x611c0, 0x14)
@12,274,897us: [+0 T:0x4001fcb0 S:0xbef35a9c] OM - Memory_free> return (0x1)
@12,274,994us: [+0 T:0x4001fcb0 S:0xbef35acc] ti.sdo.ce.osal.Sem - Leaving Sem_delete>
@12,275,086us: [+0 T:0x4001fcb0 S:0xbef35abc] ti.sdo.ce.osal.Sem - Entered Sem_delete> sem[0x611d8]
@12,275,186us: [+0 T:0x4001fcb0 S:0xbef35a9c] OM - Memory_free> Enter(0x611d8, 0x14)
@12,275,286us: [+0 T:0x4001fcb0 S:0xbef35a9c] OM - Memory_free> return (0x1)
@12,275,382us: [+0 T:0x4001fcb0 S:0xbef35acc] ti.sdo.ce.osal.Sem - Leaving Sem_delete>
@12,275,473us: [+0 T:0x4001fcb0 S:0xbef35aac] OM - Memory_free> Enter(0x611f0, 0x18)
@12,275,572us: [+0 T:0x4001fcb0 S:0xbef35aac] OM - Memory_free> return (0x1)
@12,275,670us: [+2 T:0x4001fcb0 S:0xbef35adc] OG - Global_exit> calling function *0x2f390()...
@12,275,795us: [+2 T:0x4001fcb0 S:0xbef35adc] OG - Global_exit> calling function *0x2c9e0()...
@12,275,930us: [+0 T:0x4001fcb0 S:0xbef35abc] OM - Memory_free> Enter(0x61140, 0x18)
@12,276,037us: [+0 T:0x4001fcb0 S:0xbef35abc] OM - Memory_free> return (0x1)
@12,276,138us: [+2 T:0x4001fcb0 S:0xbef35adc] OG - Global_exit> calling function *0x2dbb4()...
@12,276,244us: [+0 T:0x4001fcb0 S:0xbef35aac] OM - Memory_free> Enter(0x610f0, 0x18)
@12,276,349us: [+0 T:0x4001fcb0 S:0xbef35aac] OM - Memory_free> return (0x1)
@12,276,453us: [+2 T:0x4001fcb0 S:0xbef35adc] OG - Global_exit> calling function *0x2fd2c()...
@12,276,553us: [+2 T:0x4001fcb0 S:0xbef35adc] OG - Global_exit> calling function *0x308e0()...
@12,276,653us: [+2 T:0x4001fcb0 S:0xbef35adc] OG - Global_exit> calling function *0x2fc8c()...
Thank you very much!