Multi Channel Video Input Port (MCVIP) Capture Demo, (c) Texas Instruments 2009
 
 USAGE: ./mcvip_test.out <videoSystem> <i2c Mode> <Cascade Mode> <Audio Mode>
 
 videoSystem = NTSC (default)  or PAL 
 i2c Mode    = WinVCC or DM6467 (default) 
 Cascade Mode    = C1 (default), C2, C3 or C4 
 Audio Mode    = audio 
 
 MCVIP: VPIF0 I2C ADDR = 0xb0
 MCVIP: VPIF0 I2C ADDR = 0xb0
 MCVIP: VPIF1 I2C ADDR = 0xb0
 MCVIP: VPIF1 I2C ADDR = 0xb0
@0,568,652us: [+4 T:0x400176e8 S:0xbefffbec] OG - Global_init> This program was built with the following packages:
@0,568,752us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package gnu.targets.rts470MV (/root/dvsdk_1_40_02_33/xdc_3_00_06/packages/gnu/targets/rts470MV/) [1,0,0,0,1203621000516]
@0,568,845us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package ti.xdais.dm (/root/dvsdk_1_40_02_33/xdais_6_10_01/packages/ti/xdais/dm/) [1,0,4,1210262746529]
@0,568,906us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package ti.xdais (/root/dvsdk_1_40_02_33/xdais_6_10_01/packages/ti/xdais/) [1,2,1,1210262742149]
@0,568,962us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package ti.sdo.utils.trace (/root/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/utils/trace/) [1,0,0,1220943366422]
@0,569,016us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package ti.sdo.ce.utils.xdm (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/utils/xdm/) [1,0,1,1220951322616]
@0,569,073us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package ti.sdo.fc.dman3 (/root/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/fc/dman3/) [1,0,3,1220943053023]
@0,569,126us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package ti.sdo.fc.acpy3 (/root/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/fc/acpy3/) [1,0,2,1220943014135]
@0,569,180us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package dsplink.gpp (/root/dvsdk_1_40_02_33/dsplink-davinci-v1.50-prebuilt/packages/dsplink/gpp/) [3,0,0]
@0,569,232us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package ti.sdo.linuxutils.cmem (/root/dvsdk_1_40_02_33/cmem_2_10/packages/ti/sdo/linuxutils/cmem/) [2,0,1,1204929560755]
@0,569,284us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package ti.catalog.c470 (/root/dvsdk_1_40_02_33/xdc_3_00_06/packages/ti/catalog/c470/) [1,0,1,0,1203561761475]
@0,569,336us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package ti.catalog.c6000 (/root/dvsdk_1_40_02_33/xdc_3_00_06/packages/ti/catalog/c6000/) [1,0,0,0,1203561781695]
@0,569,388us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package ti.platforms.evmDM6467 (/root/dvsdk_1_40_02_33/xdc_3_00_06/packages/ti/platforms/evmDM6467/) [1,0,0,0,1203562062227]
@0,569,439us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package ti.sdo.ce.osal (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/osal/) [2,0,2,1220951127739]
@0,569,491us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package ti.sdo.ce.ipc (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/ipc/) [2,0,1,1220951085787]
@0,569,543us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package ti.sdo.ce.osal.linux (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/osal/linux/) [2,0,1,1220951140072]
@0,569,595us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package ti.sdo.ce.ipc.dsplink (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/ipc/dsplink/) [2,0,1,1220951096818]
@0,569,648us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package ti.sdo.ce.alg (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/alg/) [1,0,1,1220950781938]
@0,569,699us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package ti.sdo.ce (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/) [1,0,6,1220950771473]
@0,569,751us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package ti.sdo.ce.video (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/video/) [1,0,3,1220951334122]
@0,569,802us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package viddec_copy (/root/armfs/changquan/my_PAN6/viddec_copy/) []
@0,569,853us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package videnc_copy (/root/armfs/changquan/my_PAN6/videnc_copy/) []
@0,569,903us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package ti.sdo.ce.speech (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/speech/) [1,0,2,1220951161404]
@0,630,342us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package ti.sdo.ce.speech1 (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/speech1/) [1,0,1,1220951167662]
@0,630,419us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package ti.sdo.ce.audio (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/audio/) [1,0,2,1220950788137]
@0,630,478us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package ti.sdo.ce.video1 (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/video1/) [1,0,2,1220951340734]
@0,630,533us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package ti.sdo.ce.video2 (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/video2/) [1,0,2,1220951347240]
@0,630,586us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package ti.sdo.dmai (/root/dvsdk_1_40_02_33/dmai_1_10_00_06/packages/ti/sdo/dmai/) [1,0,0,1221669198000]
@0,630,637us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package ti.sdo.ce.bioslog (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/bioslog/) [1,0,1,1220950800329]
@0,630,690us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package ti.sdo.ce.utils.trace (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/utils/trace/) [1,0,1,1220951317087]
@0,630,743us: [+4 T:0x400176e8 S:0xbefffbec] OG -     package alg_config (/root/armfs/changquan/my_PAN6/alg/src/alg_config/) []
@0,630,836us: [+0 T:0x400176e8 S:0xbefffbf4] OG - Global_atexit> enter (fxn=0x27f5c)
@0,630,912us: [+0 T:0x400176e8 S:0xbefffbf4] OG - Global_atexit> enter (fxn=0x273f4)
@0,631,007us: [+0 T:0x400176e8 S:0xbefffbc4] OM - Memory_alloc> Enter(0x18)
@0,631,077us: [+0 T:0x400176e8 S:0xbefffbc4] OM - Memory_alloc> return (0x6c0f0)
@0,631,137us: [+0 T:0x400176e8 S:0xbefffbe4] OG - Global_atexit> enter (fxn=0x25cdc)
@0,631,230us: [+0 T:0x400176e8 S:0xbefffbc4] OM - Memory_alloc> Enter(0x18)
@0,631,295us: [+0 T:0x400176e8 S:0xbefffbc4] OM - Memory_alloc> return (0x6c140)
@0,631,353us: [+0 T:0x400176e8 S:0xbefffbe4] OG - Global_atexit> enter (fxn=0x23928)
@0,631,464us: [+0 T:0x400176e8 S:0xbefffbec] OG - Global_atexit> enter (fxn=0x26a78)
@0,631,596us: [+0 T:0x400176e8 S:0xbefffbd4] ti.sdo.ce.osal.Sem - Sem_create> count: 0
@0,631,661us: [+0 T:0x400176e8 S:0xbefffbbc] OM - Memory_alloc> Enter(0x14)
@0,631,722us: [+0 T:0x400176e8 S:0xbefffbbc] OM - Memory_alloc> return (0x6c1c0)
@0,631,791us: [+0 T:0x400176e8 S:0xbefffbd4] ti.sdo.ce.osal.Sem - Leaving Sem_create> sem[0x6c1c0]
@0,631,852us: [+0 T:0x400176e8 S:0xbefffbd4] ti.sdo.ce.osal.Sem - Sem_create> count: 0
@0,631,906us: [+0 T:0x400176e8 S:0xbefffbbc] OM - Memory_alloc> Enter(0x14)
@0,631,963us: [+0 T:0x400176e8 S:0xbefffbbc] OM - Memory_alloc> return (0x6c1d8)
@0,632,017us: [+0 T:0x400176e8 S:0xbefffbd4] ti.sdo.ce.osal.Sem - Leaving Sem_create> sem[0x6c1d8]
@0,632,074us: [+0 T:0x400176e8 S:0xbefffbbc] OM - Memory_alloc> Enter(0x18)
@0,632,131us: [+0 T:0x400176e8 S:0xbefffbbc] OM - Memory_alloc> return (0x6c1f0)
@0,632,187us: [+0 T:0x400176e8 S:0xbefffbbc] OT - Thread_create> Enter (fxn=0x22a68, attrs=0x0)
@0,632,244us: [+0 T:0x400176e8 S:0xbefffba4] OM - Memory_alloc> Enter(0x64)
@0,632,300us: [+0 T:0x400176e8 S:0xbefffba4] OM - Memory_alloc> return (0x6c210)
@0,632,608us: [+0 T:0x400176e8 S:0xbefffbbc] OT - Thread_create> Exit (task=0x6c210)
@0,632,690us: [+0 T:0x400176e8 S:0xbefffbf4] OG - Global_atexit> enter (fxn=0x22628)
@0,632,767us: [+0 T:0x400176e8 S:0xbefffbf4] OG - Global_atexit> enter (fxn=0x2471c)
@0,632,849us: [+0 T:0x400176e8 S:0xbefffbdc] ti.sdo.ce.alg - ALG_init> Enter
@0,632,907us: [+0 T:0x400176e8 S:0xbefffbcc] OG - Global_atexit> enter (fxn=0x21b70)
@0,632,967us: [+0 T:0x400176e8 S:0xbefffbfc] ti.sdo.ce.alg - ALG_init> Exit
@0,633,019us: [+0 T:0x400176e8 S:0xbefffbf4] OG - Global_atexit> enter (fxn=0x21610)
@0,633,088us: [+0 T:0x400176e8 S:0xbefffbcc] OM - Memory_alloc> Enter(0x18)
@0,633,151us: [+0 T:0x400176e8 S:0xbefffbcc] OM - Memory_alloc> return (0x6c3a8)
@0,633,210us: [+0 T:0x400176e8 S:0xbefffbf4] OG - Global_atexit> enter (fxn=0x26bdc)
@0,633,411us: [+6 T:0x400176e8 S:0xbefffbf4] CE - Engine_init> CE debugging on (CE_DEBUG=3; allowed CE_DEBUG levels: 1=min, 2=good, 3=max)
@0,633,492us: [+0 T:0x400176e8 S:0xbefffbe4] OG - Global_atexit> enter (fxn=0x1e994)
@0,633,557us: [+0 T:0x400176e8 S:0xbefffbc4] OM - Memory_alloc> Enter(0x18)
@0,633,620us: [+0 T:0x400176e8 S:0xbefffbc4] OM - Memory_alloc> return (0x6c3e8)
@0,633,680us: [+0 T:0x400176e8 S:0xbefffbc4] OM - Memory_alloc> Enter(0x18)
@0,633,738us: [+0 T:0x400176e8 S:0xbefffbc4] OM - Memory_alloc> return (0x6c408)
@0,633,794us: [+0 T:0x400176e8 S:0xbefffbc4] OM - Memory_alloc> Enter(0x18)
@0,633,852us: [+0 T:0x400176e8 S:0xbefffbc4] OM - Memory_alloc> return (0x6c428)
@0,633,959us: [+0 T:0x400176e8 S:0xbefffbf4] CS - Server_init()
@0,634,020us: [+0 T:0x400176e8 S:0xbefffbf4] CS - Server_init> Global_useLinkArbiter = 0
@0,634,080us: [+0 T:0x400176e8 S:0xbefffbf4] OG - Global_atexit> enter (fxn=0x1d4e4)
@0,634,220us: [+0 T:0x400176e8 S:0xbefffc04] OG - Global_atexit> enter (fxn=0x1a584)
TraceUtil_start> note: CE_DEBUG env. var is set, so TraceUtil is not active (unset CE_DEBUG if you need TraceUtil)
@0,634,344us: [+0 T:0x400176e8 S:0xbefffba4] CE - Engine_open> Enter('videocopy', 0x0, 0x6bb74)
@0,634,417us: [+0 T:0x400176e8 S:0xbefffb8c] OM - Memory_alloc> Enter(0x2c)
@0,634,480us: [+0 T:0x400176e8 S:0xbefffb8c] OM - Memory_alloc> return (0x6c4a8)
@0,634,539us: [+0 T:0x400176e8 S:0xbefffba4] CE - rserverOpen('video_copy.x64P'), count = 0
@0,634,598us: [+0 T:0x400176e8 S:0xbefffb6c] OP - Processor_create> Enter(imageName='video_copy.x64P', linkCfg='(null)', attrs=0xbefffbf0)
@0,634,893us: [+1 T:0x40946b60 S:0x4094652c] OP - daemon> thread created.
@0,635,004us: [+0 T:0x40946b60 S:0x4094652c] OP - getCmd_d> Enter (proc=0x409465a4)
@0,635,074us: [+0 T:0x40946b60 S:0x4094650c] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x6c1c0] timeout[0xffffffff]
@0,640,220us: [+0 T:0x400176e8 S:0xbefffb54] OM - Memory_alloc> Enter(0x24)
@0,640,311us: [+0 T:0x400176e8 S:0xbefffb54] OM - Memory_alloc> return (0x6c4d8)
@0,640,374us: [+0 T:0x400176e8 S:0xbefffb4c] OP - doCmd> Enter (cmdId=1, proc=0x6c4d8)
@0,640,433us: [+0 T:0x400176e8 S:0xbefffb3c] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x6c1c0]
@0,640,523us: [+0 T:0x400176e8 S:0xbefffb4c] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x6c1c0]
@0,640,590us: [+0 T:0x400176e8 S:0xbefffb2c] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x6c1d8] timeout[0xffffffff]
@0,640,673us: [+0 T:0x40946b60 S:0x4094650c] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x6c1c0] status[0]
@0,640,749us: [+0 T:0x40946b60 S:0x4094652c] OP - getCmd_d> Exit (result=1)
@0,640,812us: [+0 T:0x40946b60 S:0x4094652c] OP - Processor_create_d> Enter(proc=0x6c4d8)
@0,640,875us: [+2 T:0x40946b60 S:0x4094652c] OP - Processor_create_d> Initializing DSP PROC...
@0,640,942us: [+2 T:0x40946b60 S:0x4094652c] OP - Processor_create_d> Using DspLink config data for entry #0 [server 'video_copy.x64P']
@0,641,009us: [+0 T:0x40946b60 S:0x40946514] OM - Memory_alloc> Enter(0xe0)
@0,641,083us: [+0 T:0x40946b60 S:0x40946514] OM - Memory_alloc> return (0x6c500)
@0,644,020us: [+2 T:0x40946b60 S:0x4094652c] OP - Processor_create_d> Adding DSP segment #0 to Link configuration: name='DDR2', startAddress=0x8fa00000, sizeInBytes=0x400000, shared=1
@0,644,117us: [+2 T:0x40946b60 S:0x4094652c] OP - Processor_create_d> Adding DSP segment #1 to Link configuration: name='DSPLINKMEM', startAddress=0x8fe00000, sizeInBytes=0x100000, shared=1
@0,644,197us: [+2 T:0x40946b60 S:0x4094652c] OP - Processor_create_d> Adding DSP segment #2 to Link configuration: name='RESET_VECTOR', startAddress=0x8ff00000, sizeInBytes=0x80, shared=0
@0,644,272us: [+2 T:0x40946b60 S:0x4094652c] OP - Processor_create_d> Adding DSP segment #3 to Link configuration: name='DDRALGHEAP', startAddress=0x88000000, sizeInBytes=0x7a00000, shared=0
@0,644,346us: [+2 T:0x40946b60 S:0x4094652c] OP - Processor_create_d> DOPOWERCONTROL was=0; now=0
@0,659,489us: [+2 T:0x40946b60 S:0x4094652c] OP - Processor_create_d> Attaching to DSP PROC...
@0,668,908us: [+2 T:0x40946b60 S:0x4094652c] OP - Processor_create_d> Opening MSGQ pool...
@1,127,415us: [+2 T:0x40946b60 S:0x4094652c] OP - Processor_create_d> Loading video_copy.x64P on DSP (1 args)...
@1,264,576us: [+2 T:0x40946b60 S:0x4094652c] OP - Processor_create_d> Starting DSP PROC...
@1,274,013us: [+2 T:0x40946b60 S:0x4094652c] OP - Processor_create_d> Opening remote transport...
@1,274,310us: [+2 T:0x40946b60 S:0x4094652c] OP - Processor_create_d> return (1)
@1,274,400us: [+0 T:0x40946b60 S:0x4094651c] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x6c1d8]
@1,274,496us: [+0 T:0x400176e8 S:0xbefffb2c] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x6c1d8] status[0]
@1,274,570us: [+0 T:0x400176e8 S:0xbefffb4c] OP - doCmd> Exit (result=1)
@1,274,627us: [+0 T:0x400176e8 S:0xbefffb6c] OP - Processor_create> return (0x6c4d8)
@1,274,684us: [+0 T:0x400176e8 S:0xbefffba4] CE - rserverOpen('video_copy.x64P'): 0x51b00 done.
@1,274,745us: [+0 T:0x400176e8 S:0xbefffb8c] OM - Memory_alloc> Enter(0x1d)
@1,274,806us: [+0 T:0x400176e8 S:0xbefffb8c] OM - Memory_alloc> return (0x6c6b8)
@1,274,875us: [+0 T:0x400176e8 S:0xbefffb7c] OC - Comm_create> Enter(queueName='videocopy_0', queue=0x6c4b8, attrs=0x0)
@1,274,936us: [+0 T:0x400176e8 S:0xbefffb64] OM - Memory_alloc> Enter(0x4)
@1,274,995us: [+0 T:0x400176e8 S:0xbefffb64] OM - Memory_alloc> return (0x6c6e0)
@1,275,219us: [+0 T:0x400176e8 S:0xbefffb7c] OC - Comm_create> return (0x6c6e0)
@1,275,301us: [+0 T:0x400176e8 S:0xbefffb7c] OM - Memory_free> Enter(0x6c6b8, 0x1d)
@1,275,386us: [+0 T:0x400176e8 S:0xbefffb7c] OM - Memory_free> return (0x1)
@1,275,449us: [+0 T:0x400176e8 S:0xbefffb84] OC - Comm_alloc> Enter(poolId=0x0, msg=0x6c4c4, size=576)
@1,275,550us: [+0 T:0x400176e8 S:0xbefffb84] OC - Comm_alloc> msg=0x41155880, returning (0)
@1,275,613us: [+0 T:0x400176e8 S:0xbefffb64] OC - Comm_locate> Enter(queueName='rmsq', queue=0x6c4b4)
@1,278,980us: [+0 T:0x400176e8 S:0xbefffb64] OC - Comm_locate> return (0)
@1,279,062us: [+0 T:0x400176e8 S:0xbefffba4] CE - checkServer(0x6c4a8)
@1,279,136us: [+0 T:0x400176e8 S:0xbefffb74] OC - Comm_put> Enter(queue=0x0, msg=0x41155880)
@1,279,231us: [+0 T:0x400176e8 S:0xbefffb74] OC - Comm_put> return (0)
@1,279,335us: [+0 T:0x400176e8 S:0xbefffb6c] OC - Comm_get> Enter(queue=0x10000, msg=0xbefffbe8, timeout=-1)
@1,279,414us: [+0 T:0x400176e8 S:0xbefffb6c] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
@1,279,482us: [+0 T:0x400176e8 S:0xbefffba4] CE - rmsInit> RMS initialized(0x6c4a8); CE_DEBUG on, setting DSP trace mask to *+01234567,GT_prefix=12345,GT_time=3
@1,279,549us: [+0 T:0x400176e8 S:0xbefffb74] CE - Engine_setTrace> Enter(engine=0x6c4a8, mask='*+01234567,GT_prefix=12345,GT_time=3')
@1,279,641us: [+1 T:0x400176e8 S:0xbefffb74] CE - Engine_setTrace> Requesting DSP set trace ...
@1,279,696us: [+0 T:0x400176e8 S:0xbefffb44] OC - Comm_put> Enter(queue=0x0, msg=0x41155880)
@1,279,778us: [+0 T:0x400176e8 S:0xbefffb44] OC - Comm_put> return (0)
@1,279,840us: [+0 T:0x400176e8 S:0xbefffb3c] OC - Comm_get> Enter(queue=0x10000, msg=0xbefffbac, timeout=-1)
@1,279,928us: [+0 T:0x40946b60 S:0x4094652c] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x6c1d8]
@1,280,042us: [+0 T:0x40946b60 S:0x4094652c] OP - getCmd_d> Enter (proc=0x409465a4)
@1,280,124us: [+0 T:0x40946b60 S:0x4094650c] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x6c1c0] timeout[0xffffffff]
@1,281,028us: [+0 T:0x400176e8 S:0xbefffb3c] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
@1,281,105us: [+0 T:0x400176e8 S:0xbefffb74] CE - Engine_setTrace> return(0)
@1,281,176us: [+0 T:0x400176e8 S:0xbefffb04] OC - Comm_put> Enter(queue=0x0, msg=0x41155880)
@1,281,264us: [+0 T:0x400176e8 S:0xbefffb04] OC - Comm_put> return (0)
@1,281,328us: [+0 T:0x400176e8 S:0xbefffafc] OC - Comm_get> Enter(queue=0x10000, msg=0xbefffb90, timeout=-1)
@1,281,439us: [+0 T:0x400176e8 S:0xbefffafc] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
[DSP] @0x000001f7:[T:0x00000000] servers.video_copy.evmDM6467 - main> Welcome to DSP server's main().
[DSP] @0,022,711tk: [+0 T:0x8fa4634c S:0x8fa472bc] OG - Global_setSpecialTrace> enter(mask='*+01234567,GT_prefix=12345,GT_time=3')
[DSP] @0,022,862tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG - Global_setSpecialTrace> This program was built with the following packages:
[DSP] @0,022,987tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package ti.targets.rts6000 (/root/dvsdk_1_40_02_33/xdc_3_00_06/packages/ti/targets/rts6000/) [1,0,0,0,1203621186754]
[DSP] @0,023,131tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package ti.sdo.fc.dman3 (/root/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/fc/dman3/) [1,0,3,1220943053023]
[DSP] @0,023,279tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package ti.xdais.dm (/root/dvsdk_1_40_02_33/xdais_6_10_01/packages/ti/xdais/dm/) [1,0,4,1210262746529]
[DSP] @0,023,417tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package ti.xdais (/root/dvsdk_1_40_02_33/xdais_6_10_01/packages/ti/xdais/) [1,2,1,1210262742149]
[DSP] @0,023,605tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package ti.sdo.ce.node (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/node/) [1,0,0,1220951122176]
[DSP] @0,023,775tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package ti.sdo.ce.ipc.dsplink.dsp (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/ipc/dsplink/dsp/) [2,0,1,1220951102696]
[DSP] @0,023,979tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package ti.sdo.utils.trace (/root/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/utils/trace/) [1,0,0,1220943366422]
[DSP] @0,024,134tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package ti.sdo.ce.utils.xdm (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/utils/xdm/) [1,0,1,1220951322616]
[DSP] @0,024,282tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package ti.sdo.fc.acpy3 (/root/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/fc/acpy3/) [1,0,2,1220943014135]
[DSP] @0,024,431tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package dsplink.dsp (/root/dvsdk_1_40_02_33/dsplink-davinci-v1.50-prebuilt/packages/dsplink/dsp/) [1,3,0]
[DSP] @0,024,569tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package ti.rtdx (/root/dvsdk_1_40_02_33/bios_5_32_01/packages/ti/rtdx/) [2,0,0,1,1194024051755]
[DSP] @0,024,702tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package ti.psl (/root/dvsdk_1_40_02_33/bios_5_32_01/packages/ti/psl/) [5,0,0,0,0]
[DSP] @0,024,828tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package ti.bios (/root/dvsdk_1_40_02_33/bios_5_32_01/packages/ti/bios/) [5,2,4,9,1193960176642]
[DSP] @0,024,963tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package ti.sdo.fc.dskt2 (/root/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/fc/dskt2/) [1,0,4,1220943095081]
[DSP] @0,025,110tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package ti.bios.utils (/root/dvsdk_1_40_02_33/biosutils_1_01_00/packages/ti/bios/utils/) [2,0,1,0,1191891830609]
[DSP] @0,025,252tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package ti.catalog.c6000 (/root/dvsdk_1_40_02_33/xdc_3_00_06/packages/ti/catalog/c6000/) [1,0,0,0,1203561781695]
[DSP] @0,025,394tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package ti.platforms.evmDM6467 (/root/dvsdk_1_40_02_33/bios_5_32_01/packages/ti/platforms/evmDM6467/) [1,0,0,0,1192229633217]
[DSP] @0,025,542tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package ti.sdo.ce.osal (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/osal/) [2,0,2,1220951127739]
[DSP] @0,025,685tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package ti.sdo.ce.ipc (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/ipc/) [2,0,1,1220951085787]
[DSP] @0,025,826tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package ti.sdo.ce.alg (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/alg/) [1,0,1,1220950781938]
[DSP] @0,025,967tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package ti.sdo.ce.osal.bios (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/osal/bios/) [2,0,1,1220951133740]
[DSP] @0,026,204tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package ti.sdo.ce.ipc.bios (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/@1,375,889us: [+0 T:0x400176e8 S:0xbefffb04] OC - Comm_put> Enter(queue=0x0, msg=0x41155880)
@1,375,996us: [+0 T:0x400176e8 S:0xbefffb04] OC - Comm_put> return (0)
@1,376,061us: [+0 T:0x400176e8 S:0xbefffafc] OC - Comm_get> Enter(queue=0x10000, msg=0xbefffb90, timeout=-1)
@1,376,181us: [+0 T:0x400176e8 S:0xbefffafc] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
ce/ipc/bios/) [2,0,1,1220951091097]
[DSP] @0,026,366tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package ti.sdo.ce (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/) [1,0,6,1220950771473]
[DSP] @0,026,504tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package ti.sdo.ce.bioslog (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/bioslog/) [1,0,1,1220950800329]
[DSP] @0,026,650tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package ti.sdo.ce.video (/root/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/video/) [1,0,3,1220951334122]
[DSP] @0,026,794tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package viddec_copy (/root/armfs/changquan/my_PAN6/viddec_copy/) []
[DSP] @0,026,913tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package videnc_copy (/root/armfs/changquan/my_PAN6/videnc_copy/) []
[DSP] @0,027,034tk: [+4 T:0x8fa4634c S:0x8fa472bc] OG -     package video_copy (/root/armfs/changquan/my_PAN6/video_copy/video_copy/) []
[DSP] @0,027,159tk: [+0 T:0x8fa4634c S:0x8fa472bc] OG - Global_setSpecialTrace> return
[DSP] @0,028,489tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
[DSP] @0,028,622tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - remote time = 0x0, trace buffer size = 4032
[DSP] @0,398,515tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
[DSP] @0,398,650tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - remote time = 0x0, trace buffer size = 4032
@1,377,336us: [+0 T:0x400176e8 S:0xbefffb34] CE - Engine_fwriteTrace> returning count [5618]
@1,377,408us: [+0 T:0x400176e8 S:0xbefffba4] CE - Engine_open> return(443560)

 ===========================
 MCVIP Main Menu (NTSC mode)
 ===========================

 00: Start - 2Ch D1 - Port A  (BT656 )
 01: Start - 2Ch D1 - Port B  (BT656 )
 02: Start - 4Ch D1           (BT656 )
 03: Start - 4Ch Half-D1      (BT656 )
 04: Start - 4Ch CIF          (BT656 )
 05: Start - 4Ch D1           (BT1120)
 06: Start - 4Ch Half-D1      (BT1120)
 07: Start - 8Ch Half-D1      (BT656 )
 08: Start - 8Ch CIF          (BT656 )
 09: Start - 4Ch Half-D1 + D1 (BT656 )
 0a: Start - 4Ch CIF     + D1 (BT656 )
 0b: Start - 8Ch CIF     + D1 (BT656 )
 0c: Start - 4Ch Half-D1 CIF progressive      (BT656 )
 0d: Start - 4Ch Half-D1 CIF progressive      (BT1120)
 0e: Start - 8Ch Half-D1 CIF progressive      (BT656 )
 10: Start - 2Ch D1 - Port A  (BT656 ) CROP
 11: Start - 2Ch D1 - Port B  (BT656 ) CROP
 12: Start - 4Ch D1           (BT656 ) CROP
 13: Start - 4Ch Half-D1      (BT656 ) CROP
 14: Start - 4Ch CIF          (BT656 ) CROP
 15: Start - 4Ch D1           (BT1120) CROP
 16: Start - 4Ch Half-D1      (BT1120) CROP
 17: Start - 8Ch Half-D1      (BT656 ) CROP
 18: Start - 8Ch CIF          (BT656 ) CROP
 19: Start - 4Ch Half-D1 + D1 (BT656 ) CROP
 1a: Start - 4Ch CIF     + D1 (BT656 ) CROP
 1b: Start - 8Ch CIF     + D1 (BT656 ) CROP
 1c: Start - 4Ch Half-D1 CIF progressive      (BT656 ) CROP
 1d: Start - 4Ch Half-D1 CIF progressive      (BT1120) CROP
 1e: Start - 8Ch Half-D1 CIF progressive      (BT656 ) CROP

 x: Exit
 
 Enter Choice : 
input: 02
 MCVIP: Starting. Please wait !!!
HL->Size 706560
numCh=4,frameWidth=736,frameHeight=480.
 ENCODE: Sending CMD
 ENCODE_tskCreate:frameWidth=736, frameHeight=480,pChInfo->bufSize=706560
 ENCODE: Allocating memory 706560x4
 ENCODE: Creating buffer
 ENCODE: Creating ALG_vidEnc 0
@3,485,487us: [+0 T:0x43f81b60 S:0x43f813ec] ti.sdo.ce.video.VIDENC - VIDENC_create> Enter (engine=0x6c4a8, name='videnc_copy', params=0x43f81508)
@3,485,600us: [+0 T:0x43f81b60 S:0x43f813bc] CV - VISA_create(0x6c4a8, 'videnc_copy', 0x43f81508, 0x828, 'ti.sdo.ce.video.IVIDENC')
@3,485,675us: [+0 T:0x43f81b60 S:0x43f812e4] CV - VISA_create2(0x6c4a8, 'videnc_copy', 0x43f81508, 0x2c, 0x828, 'ti.sdo.ce.video.IVIDENC')
@3,485,753us: [+0 T:0x43f81b60 S:0x43f812cc] OM - Memory_alloc> Enter(0x30)
@3,485,816us: [+0 T:0x43f81b60 S:0x43f812cc] OM - Memory_alloc> return (0x6c998)
@3,485,876us: [+0 T:0x43f81b60 S:0x43f81274] CE - Engine_createNode(0x6c4a8, 'videnc_copy', 828, 0x43f81508, 0x2c, 0x43f813e8)
@3,485,942us: [+0 T:0x43f81b60 S:0x43f8125c] OM - Memory_alloc> Enter(0x20)
@3,486,000us: [+0 T:0x43f81b60 S:0x43f8125c] OM - Memory_alloc> return (0x6c6b8)
@3,486,069us: [+0 T:0x43f81b60 S:0x43f8124c] OC - Comm_create> Enter(queueName='gppfromnode_1', queue=0x6c6c0, attrs=0x0)
@3,486,131us: [+0 T:0x43f81b60 S:0x43f81234] OM - Memory_alloc> Enter(0x4)
@3,486,191us: [+0 T:0x43f81b60 S:0x43f81234] OM - Memory_alloc> return (0x6c9d0)
@3,486,424us: [+0 T:0x43f81b60 S:0x43f8124c] OC - Comm_create> return (0x6c9d0)
@3,486,512us: [+0 T:0x43f81b60 S:0x43f81254] OC - Comm_put> Enter(queue=0x0, msg=0x41155880)
@3,486,607us: [+0 T:0x43f81b60 S:0x43f81254] OC - Comm_put> return (0)
@3,486,673us: [+0 T:0x43f81b60 S:0x43f8124c] OC - Comm_get> Enter(queue=0x10000, msg=0x43f812cc, timeout=-1)
@3,487,777us: [+0 T:0x43f81b60 S:0x43f8124c] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
@3,487,873us: [+0 T:0x43f81b60 S:0x43f81254] OC - Comm_put> Enter(queue=0x0, msg=0x41155880)
@3,487,967us: [+0 T:0x43f81b60 S:0x43f81254] OC - Comm_put> return (0)
@3,488,060us: [+0 T:0x43f81b60 S:0x43f8124c] OC - Comm_get> Enter(queue=0x10000, msg=0x43f812cc, timeout=-1)
@3,488,150us: [+0 T:0x43f81b60 S:0x43f8124c] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
@3,488,217us: [+4 T:0x43f81b60 S:0x43f81274] CE - Engine_createNode> created node(stdIn=0x2, stdOut=0x10001, msgq=0x6c9d0, algName='videnc_copy', rmsNode=0x8fa479f8, algHandle=0x8fa47ad8)
@3,488,298us: [+0 T:0x43f81b60 S:0x43f811d4] OC - Comm_put> Enter(queue=0x0, msg=0x41155880)
@3,488,384us: [+0 T:0x43f81b60 S:0x43f811d4] OC - Comm_put> return (0)
@3,488,444us: [+0 T:0x43f81b60 S:0x43f811cc] OC - Comm_get> Enter(queue=0x10000, msg=0x43f81260, timeout=-1)
@3,488,558us: [+0 T:0x43f81b60 S:0x43f811cc] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
[DSP] @8,643,087tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 0
[DSP] @8,643,229tk: [+0 T:0x8fa4634c S:0x8fa471f4] OM - Memory_alloc> Enter(size=0x18)
[DSP] @8,643,345tk: [+0 T:0x8fa4634c S:0x8fa471f4] OM - Memory_alloc> return (0x8fa479f8)
[DSP] @8,643,456tk: [+0 T:0x8fa4634c S:0x8fa471f4] OM - Memory_alloc> Enter(size=0xe)
[DSP] @8,643,565tk: [+0 T:0x8fa4634c S:0x8fa471f4] OM - Memory_alloc> return (0x8fa47a10)
[DSP] @8,643,684tk: [+0 T:0x8fa4634c S:0x8fa471bc] OM - Memory_alloc> Enter(size=0x20)
[DSP] @8,643,795tk: [+0 T:0x8fa4634c S:0x8fa471bc] OM - Memory_alloc> return (0x8fa47a20)
[DSP] @8,643,941tk: [+0 T:0x8fa4634c S:0x8fa4718c] OM - Memory_alloc> Enter(size=0x24)
[DSP] @8,644,054tk: [+0 T:0x8fa4634c S:0x8fa4718c] OM - Memory_alloc> return (0x8fa47a40)
[DSP] @8,644,200tk: [+0 T:0x8fa4634c S:0x8fa471a4] ti.sdo.ce.video.VIDENC - VIDENC_create> Enter (engine=0x0, name='videnc_copy', params=0x8fe038c0)
[DSP] @8,644,353tk: [+0 T:0x8fa4634c S:0x8fa4716c] CV - VISA_create(0x0, 'videnc_copy', 0x8fe038c0, 0x828, 'ti.sdo.ce.video.IVIDENC')
[DSP] @8,644,498tk: [+0 T:0x8fa4634c S:0x8fa4705c] CV - VISA_create2(0x0, 'videnc_copy', 0x8fe038c0, 0x2c, 0x828, 'ti.sdo.ce.video.IVIDENC')
[DSP] @8,644,658tk: [+0 T:0x8fa4634c S:0x8fa46ff4] CE - Engine_open> Enter('local', 0x8fa4704c, 0x33c)
[DSP] @8,644,782tk: [+0 T:0x8fa4634c S:0x8fa46fd4] OM - Memory_alloc> Enter(size=0x2c)
[DSP] @8,644,889tk: [+0 T:0x8fa4634c S:0x8fa46fd4] OM - Memory_alloc> return (0x8fa47aa8)
[DSP] @8,645,012tk: [+0 T:0x8fa4634c S:0x8fa46ff4] CE - Engine_open> return(-1885046104)
[DSP] @8,645,140tk: [+0 T:0x8fa4634c S:0x8fa4703c] OM - Memory_alloc> Enter(size=0x30)
[DSP] @8,645,249tk: [+0 T:0x8fa4634c S:0x8fa4703c] OM - Memory_alloc> return (0x8fa47ad8)
[DSP] @8,645,365tk: [+0 T:0x8fa4634c S:0x8fa47014] ti.sdo.ce.alg.Algorithm - Algorithm_create> Enter(fxns=0x8fa6f78c, idma3Fxns=0x8fa6f7c0, params=0x8fe038c0, attrs=0x8fa47160)
[DSP] @8,645,533tk: [+0 T:0x8fa4634c S:0x8fa46ff4] OM - Memory_alloc> Enter(size=0x10)
[DSP] @8,645,642tk: [+0 T:0x8fa4634c S:0x8fa46ff4] OM - Memory_alloc> return (0x8fa47b08)
[DSP] @8,645,824tk: [+0 T:0x8fa4634c S:0x8fa46f84] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_alloc(0x8fe038c0, 0x8fa47008, 0x8fa47e88)
[DSP] @8,646,014tk: [+0 T:0x8fa4634c S:0x8fa46f8c] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_initObj(0x88000250, 0x8fa47e88, 0x0, 0x8fe038c0)
[DSP] @8,646,234tk: [+0 T:0x8fa4634c S:0x8fa46fac] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_dmaGetChannels(0x88000250, 0x88000258)
[DSP] @8,646,408tk: [+0 T:0x8fa4634c S:0x8fa46fac] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_dmaGetChannels(0x88000250, 0x88000258)
[DSP] @8,646,649tk: [+0 T:0x8fa4634c S:0x8fa46fac] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_dmaInit(0x88000250, 0x88000258)
[DSP] @8,646,796tk: [+0 T:0x8fa4634c S:0x8fa47014] ti.sdo.ce.alg.Algorithm - Algorithm_create> return (0x8fa47b08)
[DSP] @8,646,922tk: [+0 T:0x8fa4634c S:0x8fa471a4] ti.sdo.ce.video.VIDENC - VIDENC_create> return (0x8fa47ad8)
[DSP] @8,647,053tk: [+4 T:0x8fa4634c S:0x8fa47164] OT - Thread_create > name: "videnc_copy#0", pri:  -1, stack size:  9216, stack seg: 0
[DSP] @8,648,418tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 1
[DSP] @8,650,033tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
[DSP] @8,650,166tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - remote time = 0x0, trace buffer size = 4032
@3,491,145us: [+0 T:0x43f81b60 S:0x43f81204] CE - Engine_fwriteTrace> returning count [3511]
@3,491,241us: [+2 T:0x43f81b60 S:0x43f81274] CE - Engine_createNode> Returning 0x6c6b8
@3,491,305us: [+0 T:0x43f81b60 S:0x43f812cc] OM - Memory_alloc> Enter(0x4)
@3,491,371us: [+0 T:0x43f81b60 S:0x43f812cc] OM - Memory_alloc> return (0x6c9e0)
@3,491,432us: [+0 T:0x43f81b60 S:0x43f812c4] OC - Comm_alloc> Enter(poolId=0x0, msg=0x6c9e0, size=828)
@3,491,516us: [+0 T:0x43f81b60 S:0x43f812c4] OC - Comm_alloc> msg=0x41156880, returning (0)
@3,491,591us: [+5 T:0x43f81b60 S:0x43f812e4] CV - VISA_create> remote codec created (name='videnc_copy', localQueueID=0x10001, remoteQueueID=0x0002)
@3,491,663us: [+0 T:0x43f81b60 S:0x43f813ec] ti.sdo.ce.video.VIDENC - VIDENC_create> return (0x6c998)
@3,491,725us: [+0 T:0x43f81b60 S:0x43f8139c] ti.sdo.ce.video.VIDENC - VIDENC_control> Enter (handle=0x6c998, id=1, params=0x43f81450, status=0x43f81478
@3,491,795us: [+5 T:0x43f81b60 S:0x43f8136c] CV - VISA_allocMsg> Allocating message for messageId=0x0002e5be
@3,491,857us: [+0 T:0x43f81b60 S:0x43f8134c] CV - VISA_call(visa=0x6c998, msg=0x41156880): messageId=0x0002e5be, command=0x1
@3,491,923us: [+0 T:0x43f81b60 S:0x43f8131c] OC - Comm_put> Enter(queue=0x2, msg=0x41156880)
@3,492,011us: [+0 T:0x43f81b60 S:0x43f8131c] OC - Comm_put> return (0)
@3,492,077us: [+0 T:0x43f81b60 S:0x43f81314] OC - Comm_get> Enter(queue=0x10001, msg=0x43f813ac, timeout=-1)
@3,493,338us: [+0 T:0x43f81b60 S:0x43f81314] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
@3,493,435us: [+0 T:0x43f81b60 S:0x43f8129c] OC - Comm_put> Enter(queue=0x0, msg=0x41155880)
@3,493,527us: [+0 T:0x43f81b60 S:0x43f8129c] OC - Comm_put> return (0)
@3,493,592us: [+0 T:0x43f81b60 S:0x43f81294] OC - Comm_get> Enter(queue=0x10000, msg=0x43f81328, timeout=-1)
@3,493,712us: [+0 T:0x43f81b60 S:0x43f81294] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
[DSP] @8,664,205tk: [+5 T:0x8fa47f34 S:0x8fa4a2f4] CN - NODE> 0x8fa47a20(videnc_copy#0) call(algHandle=0x8fa47ad8, msg=0x8fe04880); messageId=0x0002e5be
[DSP] @8,664,383tk: [+0 T:0x8fa47f34 S:0x8fa4a23c] ti.sdo.ce.video.VIDENC - VIDENC_control> Enter (handle=0x8fa47ad8, id=1, params=0x8fe048b4, status=0x8fe048dc
[DSP] @8,664,556tk: [+5 T:0x8fa47f34 S:0x8fa4a21c] CV - VISA_enter(visa=0x8fa47ad8): algHandle = 0x8fa47b08
[DSP] @8,664,684tk: [+0 T:0x8fa47f34 S:0x8fa4a1fc] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(handle=0x8fa47b08)
[DSP] @8,664,862tk: [+0 T:0x8fa47f34 S:0x8fa4a1cc] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_activate(0x88000250)
[DSP] @8,664,995tk: [+0 T:0x8fa47f34 S:0x8fa4a1fc] ti.sdo.ce.alg.Algorithm - Algorithm_activate> return
[DSP] @8,665,117tk: [+0 T:0x8fa47f34 S:0x8fa4a204] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_control(0x88000250, 0x1, 0x8fe048b4, 0x8fe048dc)
[DSP] @8,665,277tk: [+5 T:0x8fa47f34 S:0x8fa4a21c] CV - VISA_exit(visa=0x8fa47ad8): algHandle = 0x8fa47b08
[DSP] @8,665,406tk: [+0 T:0x8fa47f34 S:0x8fa4a1fc] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(handle=0x8fa47b08)
[DSP] @8,665,538tk: [+0 T:0x8fa47f34 S:0x8fa4a1fc] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> return
[DSP] @8,665,655tk: [+0 T:0x8fa47f34 S:0x8fa4a23c] ti.sdo.ce.video.VIDENC - VIDENC_control> Exit (handle=0x8fa47ad8, retVal=0x0)
[DSP] @8,665,796tk: [+5 T:0x8fa47f34 S:0x8fa4a2f4] CN - NODE> returned from call(algHandle=0x8fa47ad8, msg=0x8fe04880); messageId=0x0002e5be
[DSP] @8,670,121tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
[DSP] @8,670,256tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - remote time = 0x0, trace buffer size = 4032
@3,494,953us: [+0 T:0x43f81b60 S:0x43f812cc] CE - Engine_fwriteTrace> returning count [1732]
@3,495,016us: [+0 T:0x43f81b60 S:0x43f8134c] CV - VISA_call Completed: messageId=0x0002e5be, command=0x1, return(status=0)
@3,495,086us: [+5 T:0x43f81b60 S:0x43f8135c] CV - VISA_freeMsg(0x6c998, 0x41156880): Freeing message with messageId=0x0002e5be
@3,495,152us: [+0 T:0x43f81b60 S:0x43f8139c] ti.sdo.ce.video.VIDENC - VIDENC_control> Exit (handle=0x6c998, retVal=0x0)
@3,495,213us: [+0 T:0x43f81b60 S:0x43f8139c] ti.sdo.ce.video.VIDENC - VIDENC_control> Enter (handle=0x6c998, id=5, params=0x43f81450, status=0x43f81478
@3,495,301us: [+5 T:0x43f81b60 S:0x43f8136c] CV - VISA_allocMsg> Allocating message for messageId=0x0002e5bf
@3,495,364us: [+0 T:0x43f81b60 S:0x43f8134c] CV - VISA_call(visa=0x6c998, msg=0x41156880): messageId=0x0002e5bf, command=0x1
@3,495,431us: [+0 T:0x43f81b60 S:0x43f8131c] OC - Comm_put> Enter(queue=0x2, msg=0x41156880)
@3,495,518us: [+0 T:0x43f81b60 S:0x43f8131c] OC - Comm_put> return (0)
@3,495,581us: [+0 T:0x43f81b60 S:0x43f81314] OC - Comm_get> Enter(queue=0x10001, msg=0x43f813ac, timeout=-1)
@3,495,986us: [+0 T:0x43f81b60 S:0x43f81314] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
@3,496,070us: [+0 T:0x43f81b60 S:0x43f8129c] OC - Comm_put> Enter(queue=0x0, msg=0x41155880)
@3,496,160us: [+0 T:0x43f81b60 S:0x43f8129c] OC - Comm_put> return (0)
@3,496,222us: [+0 T:0x43f81b60 S:0x43f81294] OC - Comm_get> Enter(queue=0x10000, msg=0x43f81328, timeout=-1)
@3,496,339us: [+0 T:0x43f81b60 S:0x43f81294] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
[DSP] @8,677,901tk: [+5 T:0x8fa47f34 S:0x8fa4a2f4] CN - NODE> 0x8fa47a20(videnc_copy#0) call(algHandle=0x8fa47ad8, msg=0x8fe04880); messageId=0x0002e5bf
[DSP] @8,678,077tk: [+0 T:0x8fa47f34 S:0x8fa4a23c] ti.sdo.ce.video.VIDENC - VIDENC_control> Enter (handle=0x8fa47ad8, id=5, params=0x8fe048b4, status=0x8fe048dc
[DSP] @8,678,244tk: [+5 T:0x8fa47f34 S:0x8fa4a21c] CV - VISA_enter(visa=0x8fa47ad8): algHandle = 0x8fa47b08
[DSP] @8,678,371tk: [+0 T:0x8fa47f34 S:0x8fa4a1fc] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(handle=0x8fa47b08)
[DSP] @8,678,504tk: [+0 T:0x8fa47f34 S:0x8fa4a1fc] ti.sdo.ce.alg.Algorithm - Algorithm_activate> return
[DSP] @8,678,615tk: [+0 T:0x8fa47f34 S:0x8fa4a204] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_control(0x88000250, 0x5, 0x8fe048b4, 0x8fe048dc)
[DSP] @8,678,815tk: [+5 T:0x8fa47f34 S:0x8fa4a21c] CV - VISA_exit(visa=0x8fa47ad8): algHandle = 0x8fa47b08
[DSP] @8,678,944tk: [+0 T:0x8fa47f34 S:0x8fa4a1fc] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(handle=0x8fa47b08)
[DSP] @8,679,073tk: [+0 T:0x8fa47f34 S:0x8fa4a1fc] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> return
[DSP] @8,679,186tk: [+0 T:0x8fa47f34 S:0x8fa4a23c] ti.sdo.ce.video.VIDENC - VIDENC_control> Exit (handle=0x8fa47ad8, retVal=0x0)
[DSP] @8,679,323tk: [+5 T:0x8fa47f34 S:0x8fa4a2f4] CN - NODE> returned from call(algHandle=0x8fa47ad8, msg=0x8fe04880); messageId=0x0002e5bf
[DSP] @8,680,418tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
[DSP] @8,680,552tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - remote time = 0x0, trace buffer size = 4032
@3,739,299us: [+0 T:0x43f81b60 S:0x43f812cc] CE - Engine_fwriteTrace> returning count [1606]
@3,739,377us: [+0 T:0x43f81b60 S:0x43f8134c] CV - VISA_call Completed: messageId=0x0002e5bf, command=0x1, return(status=0)
@3,739,450us: [+5 T:0x43f81b60 S:0x43f8135c] CV - VISA_freeMsg(0x6c998, 0x41156880): Freeing message with messageId=0x0002e5bf
@3,739,516us: [+0 T:0x43f81b60 S:0x43f8139c] ti.sdo.ce.video.VIDENC - VIDENC_control> Exit (handle=0x6c998, retVal=0x0)
Create:ENCODE ID= 0,eHandle=444824
 ENCODE_tskCreate:frameWidth=736, frameHeight=480,pChInfo->bufSize=706560
 ENCODE: Allocating memory 706560x4
 ENCODE: Creating buffer
 ENCODE: Creating ALG_vidEnc 1
@3,739,918us: [+0 T:0x43f81b60 S:0x43f813ec] ti.sdo.ce.video.VIDENC - VIDENC_create> Enter (engine=0x6c4a8, name='videnc_copy', params=0x43f81508)
@3,739,994us: [+0 T:0x43f81b60 S:0x43f813bc] CV - VISA_create(0x6c4a8, 'videnc_copy', 0x43f81508, 0x828, 'ti.sdo.ce.video.IVIDENC')
@3,740,097us: [+0 T:0x43f81b60 S:0x43f812e4] CV - VISA_create2(0x6c4a8, 'videnc_copy', 0x43f81508, 0x2c, 0x828, 'ti.sdo.ce.video.IVIDENC')
@3,740,178us: [+0 T:0x43f81b60 S:0x43f812cc] OM - Memory_alloc> Enter(0x30)
@3,740,245us: [+0 T:0x43f81b60 S:0x43f812cc] OM - Memory_alloc> return (0x6ca20)
@3,740,305us: [+0 T:0x43f81b60 S:0x43f81274] CE - Engine_createNode(0x6c4a8, 'videnc_copy', 828, 0x43f81508, 0x2c, 0x43f813e8)
@3,740,371us: [+0 T:0x43f81b60 S:0x43f8125c] OM - Memory_alloc> Enter(0x20)
@3,740,431us: [+0 T:0x43f81b60 S:0x43f8125c] OM - Memory_alloc> return (0x6ca58)
@3,740,497us: [+0 T:0x43f81b60 S:0x43f8124c] OC - Comm_create> Enter(queueName='gppfromnode_2', queue=0x6ca60, attrs=0x0)
@3,740,558us: [+0 T:0x43f81b60 S:0x43f81234] OM - Memory_alloc> Enter(0x4)
@3,740,617us: [+0 T:0x43f81b60 S:0x43f81234] OM - Memory_alloc> return (0x6ca80)
@3,740,872us: [+0 T:0x43f81b60 S:0x43f8124c] OC - Comm_create> return (0x6ca80)
@3,740,961us: [+0 T:0x43f81b60 S:0x43f81254] OC - Comm_put> Enter(queue=0x0, msg=0x41155880)
@3,741,056us: [+0 T:0x43f81b60 S:0x43f81254] OC - Comm_put> return (0)
@3,741,123us: [+0 T:0x43f81b60 S:0x43f8124c] OC - Comm_get> Enter(queue=0x10000, msg=0x43f812cc, timeout=-1)
@3,742,051us: [+0 T:0x43f81b60 S:0x43f8124c] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
@3,742,141us: [+0 T:0x43f81b60 S:0x43f81254] OC - Comm_put> Enter(queue=0x0, msg=0x41155880)
@3,742,234us: [+0 T:0x43f81b60 S:0x43f81254] OC - Comm_put> return (0)
@3,742,331us: [+0 T:0x43f81b60 S:0x43f8124c] OC - Comm_get> Enter(queue=0x10000, msg=0x43f812cc, timeout=-1)
@3,742,417us: [+0 T:0x43f81b60 S:0x43f8124c] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
@3,742,485us: [+4 T:0x43f81b60 S:0x43f81274] CE - Engine_createNode> created node(stdIn=0x3, stdOut=0x10002, msgq=0x6ca80, algName='videnc_copy', rmsNode=0x8fa4a388, algHandle=0x8fa4a3f8)
@3,742,565us: [+0 T:0x43f81b60 S:0x43f811d4] OC - Comm_put> Enter(queue=0x0, msg=0x41155880)
@3,742,650us: [+0 T:0x43f81b60 S:0x43f811d4] OC - Comm_put> return (0)
@3,742,711us: [+0 T:0x43f81b60 S:0x43f811cc] OC - Comm_get> Enter(queue=0x10000, msg=0x43f81260, timeout=-1)
@3,742,823us: [+0 T:0x43f81b60 S:0x43f811cc] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
[DSP] @9,637,032tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 0
[DSP] @9,637,174tk: [+0 T:0x8fa4634c S:0x8fa471f4] OM - Memory_alloc> Enter(size=0x18)
[DSP] @9,637,291tk: [+0 T:0x8fa4634c S:0x8fa471f4] OM - Memory_alloc> return (0x8fa4a388)
[DSP] @9,637,404tk: [+0 T:0x8fa4634c S:0x8fa471f4] OM - Memory_alloc> Enter(size=0xe)
[DSP] @9,637,511tk: [+0 T:0x8fa4634c S:0x8fa471f4] OM - Memory_alloc> return (0x8fa4a3a0)
[DSP] @9,637,630tk: [+0 T:0x8fa4634c S:0x8fa471bc] OM - Memory_alloc> Enter(size=0x20)
[DSP] @9,637,741tk: [+0 T:0x8fa4634c S:0x8fa471bc] OM - Memory_alloc> return (0x8fa4a3b0)
[DSP] @9,637,855tk: [+0 T:0x8fa4634c S:0x8fa4718c] OM - Memory_alloc> Enter(size=0x24)
[DSP] @9,637,965tk: [+0 T:0x8fa4634c S:0x8fa4718c] OM - Memory_alloc> return (0x8fa4a3d0)
[DSP] @9,638,087tk: [+0 T:0x8fa4634c S:0x8fa471a4] ti.sdo.ce.video.VIDENC - VIDENC_create> Enter (engine=0x0, name='videnc_copy', params=0x8fe038c0)
[DSP] @9,638,234tk: [+0 T:0x8fa4634c S:0x8fa4716c] CV - VISA_create(0x0, 'videnc_copy', 0x8fe038c0, 0x828, 'ti.sdo.ce.video.IVIDENC')
[DSP] @9,638,382tk: [+0 T:0x8fa4634c S:0x8fa4705c] CV - VISA_create2(0x0, 'videnc_copy', 0x8fe038c0, 0x2c, 0x828, 'ti.sdo.ce.video.IVIDENC')
[DSP] @9,638,558tk: [+0 T:0x8fa4634c S:0x8fa4703c] OM - Memory_alloc> Enter(size=0x30)
[DSP] @9,638,663tk: [+0 T:0x8fa4634c S:0x8fa4703c] OM - Memory_alloc> return (0x8fa4a3f8)
[DSP] @9,638,775tk: [+0 T:0x8fa4634c S:0x8fa47014] ti.sdo.ce.alg.Algorithm - Algorithm_create> Enter(fxns=0x8fa6f78c, idma3Fxns=0x8fa6f7c0, params=0x8fe038c0, attrs=0x8fa47160)
[DSP] @9,638,941tk: [+0 T:0x8fa4634c S:0x8fa46ff4] OM - Memory_alloc> Enter(size=0x10)
[DSP] @9,639,046tk: [+0 T:0x8fa4634c S:0x8fa46ff4] OM - Memory_alloc> return (0x8fa4a428)
[DSP] @9,639,168tk: [+0 T:0x8fa4634c S:0x8fa46f84] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_alloc(0x8fe038c0, 0x8fa47008, 0x8fa4a438)
[DSP] @9,639,392tk: [+0 T:0x8fa4634c S:0x8fa46f8c] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_initObj(0x88000258, 0x8fa4a438, 0x0, 0x8fe038c0)
[DSP] @9,639,564tk: [+0 T:0x8fa4634c S:0x8fa46fac] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_dmaGetChannels(0x88000258, 0x88000620)
[DSP] @9,639,710tk: [+0 T:0x8fa4634c S:0x8fa46fac] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_dmaGetChannels(0x88000258, 0x88000620)
[DSP] @9,639,931tk: [+0 T:0x8fa4634c S:0x8fa46fac] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_dmaInit(0x88000258, 0x88000620)
[DSP] @9,640,076tk: [+0 T:0x8fa4634c S:0x8fa47014] ti.sdo.ce.alg.Algorithm - Algorithm_create> return (0x8fa4a428)
[DSP] @9,640,199tk: [+0 T:0x8fa4634c S:0x8fa471a4] ti.sdo.ce.video.VIDENC - VIDENC_create> return (0x8fa4a3f8)
[DSP] @9,640,326tk: [+4 T:0x8fa4634c S:0x8fa47164] OT - Thread_create > name: "videnc_copy#1", pri:  -1, stack size:  9216, stack seg: 0
[DSP] @9,641,655tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 1
[DSP] @9,643,269tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
[DSP] @9,643,400tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - remote time = 0x0, trace buffer size = 4032
@4,229,570us: [+0 T:0x43f81b60 S:0x43f81204] CE - Engine_fwriteTrace> returning count [3142]
@4,229,651us: [+2 T:0x43f81b60 S:0x43f81274] CE - Engine_createNode> Returning 0x6ca58
@4,229,713us: [+0 T:0x43f81b60 S:0x43f812cc] OM - Memory_alloc> Enter(0x4)
@4,229,778us: [+0 T:0x43f81b60 S:0x43f812cc] OM - Memory_alloc> return (0x6ca90)
@4,229,838us: [+0 T:0x43f81b60 S:0x43f812c4] OC - Comm_alloc> Enter(poolId=0x0, msg=0x6ca90, size=828)
@4,229,921us: [+0 T:0x43f81b60 S:0x43f812c4] OC - Comm_alloc> msg=0x41157880, returning (0)
@4,229,996us: [+5 T:0x43f81b60 S:0x43f812e4] CV - VISA_create> remote codec created (name='videnc_copy', localQueueID=0x10002, remoteQueueID=0x0003)
@4,230,114us: [+0 T:0x43f81b60 S:0x43f813ec] ti.sdo.ce.video.VIDENC - VIDENC_create> return (0x6ca20)
@4,230,181us: [+0 T:0x43f81b60 S:0x43f8139c] ti.sdo.ce.video.VIDENC - VIDENC_control> Enter (handle=0x6ca20, id=1, params=0x43f81450, status=0x43f81478
@4,230,252us: [+5 T:0x43f81b60 S:0x43f8136c] CV - VISA_allocMsg> Allocating message for messageId=0x0003f49b
@4,230,314us: [+0 T:0x43f81b60 S:0x43f8134c] CV - VISA_call(visa=0x6ca20, msg=0x41157880): messageId=0x0003f49b, command=0x1
@4,230,381us: [+0 T:0x43f81b60 S:0x43f8131c] OC - Comm_put> Enter(queue=0x3, msg=0x41157880)
@4,230,469us: [+0 T:0x43f81b60 S:0x43f8131c] OC - Comm_put> return (0)
@4,230,531us: [+0 T:0x43f81b60 S:0x43f81314] OC - Comm_get> Enter(queue=0x10002, msg=0x43f813ac, timeout=-1)
@4,231,019us: [+0 T:0x43f81b60 S:0x43f81314] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
@4,231,116us: [+0 T:0x43f81b60 S:0x43f8129c] OC - Comm_put> Enter(queue=0x0, msg=0x41155880)
@4,231,208us: [+0 T:0x43f81b60 S:0x43f8129c] OC - Comm_put> return (0)
@4,231,273us: [+0 T:0x43f81b60 S:0x43f81294] OC - Comm_get> Enter(queue=0x10000, msg=0x43f81328, timeout=-1)
@4,231,395us: [+0 T:0x43f81b60 S:0x43f81294] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
[DSP] @11,548,804tk: [+5 T:0x8fa4a4ac S:0x8fa4c86c] CN - NODE> 0x8fa4a3b0(videnc_copy#1) call(algHandle=0x8fa4a3f8, msg=0x8fe05880); messageId=0x0003f49b
[DSP] @11,548,997tk: [+0 T:0x8fa4a4ac S:0x8fa4c7b4] ti.sdo.ce.video.VIDENC - VIDENC_control> Enter (handle=0x8fa4a3f8, id=1, params=0x8fe058b4, status=0x8fe058dc
[DSP] @11,549,169tk: [+5 T:0x8fa4a4ac S:0x8fa4c794] CV - VISA_enter(visa=0x8fa4a3f8): algHandle = 0x8fa4a428
[DSP] @11,549,297tk: [+0 T:0x8fa4a4ac S:0x8fa4c774] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(handle=0x8fa4a428)
[DSP] @11,549,434tk: [+0 T:0x8fa4a4ac S:0x8fa4c744] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_deactivate(0x88000250)
[DSP] @11,549,565tk: [+0 T:0x8fa4a4ac S:0x8fa4c744] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_activate(0x88000258)
[DSP] @11,549,695tk: [+0 T:0x8fa4a4ac S:0x8fa4c774] ti.sdo.ce.alg.Algorithm - Algorithm_activate> return
[DSP] @11,549,808tk: [+0 T:0x8fa4a4ac S:0x8fa4c77c] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_control(0x88000258, 0x1, 0x8fe058b4, 0x8fe058dc)
[DSP] @11,549,969tk: [+5 T:0x8fa4a4ac S:0x8fa4c794] CV - VISA_exit(visa=0x8fa4a3f8): algHandle = 0x8fa4a428
[DSP] @11,550,095tk: [+0 T:0x8fa4a4ac S:0x8fa4c774] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(handle=0x8fa4a428)
[DSP] @11,550,224tk: [+0 T:0x8fa4a4ac S:0x8fa4c774] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> return
[DSP] @11,550,337tk: [+0 T:0x8fa4a4ac S:0x8fa4c7b4] ti.sdo.ce.video.VIDENC - VIDENC_control> Exit (handle=0x8fa4a3f8, retVal=0x0)
[DSP] @11,550,474tk: [+5 T:0x8fa4a4ac S:0x8fa4c86c] CN - NODE> returned from call(algHandle=0x8fa4a3f8, msg=0x8fe05880); messageId=0x0003f49b
[DSP] @11,551,692tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
[DSP] @11,551,827tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - remote time = 0x0, trace buffer size = 4032
@4,232,811us: [+0 T:0x43f81b60 S:0x43f812cc] CE - Engine_fwriteTrace> returning count [1875]
@4,232,884us: [+0 T:0x43f81b60 S:0x43f8134c] CV - VISA_call Completed: messageId=0x0003f49b, command=0x1, return(status=0)
@4,232,953us: [+5 T:0x43f81b60 S:0x43f8135c] CV - VISA_freeMsg(0x6ca20, 0x41157880): Freeing message with messageId=0x0003f49b
@4,233,021us: [+0 T:0x43f81b60 S:0x43f8139c] ti.sdo.ce.video.VIDENC - VIDENC_control> Exit (handle=0x6ca20, retVal=0x0)
@4,233,083us: [+0 T:0x43f81b60 S:0x43f8139c] ti.sdo.ce.video.VIDENC - VIDENC_control> Enter (handle=0x6ca20, id=5, params=0x43f81450, status=0x43f81478
@4,233,150us: [+5 T:0x43f81b60 S:0x43f8136c] CV - VISA_allocMsg> Allocating message for messageId=0x0003f49c
@4,233,211us: [+0 T:0x43f81b60 S:0x43f8134c] CV - VISA_call(visa=0x6ca20, msg=0x41157880): messageId=0x0003f49c, command=0x1
@4,233,277us: [+0 T:0x43f81b60 S:0x43f8131c] OC - Comm_put> Enter(queue=0x3, msg=0x41157880)
@4,233,365us: [+0 T:0x43f81b60 S:0x43f8131c] OC - Comm_put> return (0)
@4,233,428us: [+0 T:0x43f81b60 S:0x43f81314] OC - Comm_get> Enter(queue=0x10002, msg=0x43f813ac, timeout=-1)
@4,233,833us: [+0 T:0x43f81b60 S:0x43f81314] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
@4,233,923us: [+0 T:0x43f81b60 S:0x43f8129c] OC - Comm_put> Enter(queue=0x0, msg=0x41155880)
@4,234,014us: [+0 T:0x43f81b60 S:0x43f8129c] OC - Comm_put> return (0)
@4,234,079us: [+0 T:0x43f81b60 S:0x43f81294] OC - Comm_get> Enter(queue=0x10000, msg=0x43f81328, timeout=-1)
@4,234,210us: [+0 T:0x43f81b60 S:0x43f81294] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
[DSP] @11,560,116tk: [+5 T:0x8fa4a4ac S:0x8fa4c86c] CN - NODE> 0x8fa4a3b0(videnc_copy#1) call(algHandle=0x8fa4a3f8, msg=0x8fe05880); messageId=0x0003f49c
[DSP] @11,560,290tk: [+0 T:0x8fa4a4ac S:0x8fa4c7b4] ti.sdo.ce.video.VIDENC - VIDENC_control> Enter (handle=0x8fa4a3f8, id=5, params=0x8fe058b4, status=0x8fe058dc
[DSP] @11,560,455tk: [+5 T:0x8fa4a4ac S:0x8fa4c794] CV - VISA_enter(visa=0x8fa4a3f8): algHandle = 0x8fa4a428
[DSP] @11,560,619tk: [+0 T:0x8fa4a4ac S:0x8fa4c774] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(handle=0x8fa4a428)
[DSP] @11,560,754tk: [+0 T:0x8fa4a4ac S:0x8fa4c774] ti.sdo.ce.alg.Algorithm - Algorithm_activate> return
[DSP] @11,560,866tk: [+0 T:0x8fa4a4ac S:0x8fa4c77c] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_control(0x88000258, 0x5, 0x8fe058b4, 0x8fe058dc)
[DSP] @11,561,024tk: [+5 T:0x8fa4a4ac S:0x8fa4c794] CV - VISA_exit(visa=0x8fa4a3f8): algHandle = 0x8fa4a428
[DSP] @11,561,146tk: [+0 T:0x8fa4a4ac S:0x8fa4c774] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(handle=0x8fa4a428)
[DSP] @11,561,271tk: [+0 T:0x8fa4a4ac S:0x8fa4c774] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> return
[DSP] @11,561,382tk: [+0 T:0x8fa4a4ac S:0x8fa4c7b4] ti.sdo.ce.video.VIDENC - VIDENC_control> Exit (handle=0x8fa4a3f8, retVal=0x0)
[DSP] @11,561,518tk: [+5 T:0x8fa4a4ac S:0x8fa4c86c] CN - NODE> returned from call(algHandle=0x8fa4a3f8, msg=0x8fe05880); messageId=0x0003f49c
[DSP] @11,562,658tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
[DSP] @11,562,812tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - remote time = 0x0, trace buffer size = 4032
@4,718,818us: [+0 T:0x43f81b60 S:0x43f812cc] CE - Engine_fwriteTrace> returning count [1619]
@4,718,896us: [+0 T:0x43f81b60 S:0x43f8134c] CV - VISA_call Completed: messageId=0x0003f49c, command=0x1, return(status=0)
@4,718,967us: [+5 T:0x43f81b60 S:0x43f8135c] CV - VISA_freeMsg(0x6ca20, 0x41157880): Freeing message with messageId=0x0003f49c
@4,719,034us: [+0 T:0x43f81b60 S:0x43f8139c] ti.sdo.ce.video.VIDENC - VIDENC_control> Exit (handle=0x6ca20, retVal=0x0)
Create:ENCODE ID= 1,eHandle=444960
 ENCODE_tskCreate:frameWidth=736, frameHeight=480,pChInfo->bufSize=706560
 ENCODE: Allocating memory 706560x4
 ENCODE: Creating buffer
 ENCODE: Creating ALG_vidEnc 2
@4,719,456us: [+0 T:0x43f81b60 S:0x43f813ec] ti.sdo.ce.video.VIDENC - VIDENC_create> Enter (engine=0x6c4a8, name='videnc_copy', params=0x43f81508)
@4,719,533us: [+0 T:0x43f81b60 S:0x43f813bc] CV - VISA_create(0x6c4a8, 'videnc_copy', 0x43f81508, 0x828, 'ti.sdo.ce.video.IVIDENC')
@4,719,600us: [+0 T:0x43f81b60 S:0x43f812e4] CV - VISA_create2(0x6c4a8, 'videnc_copy', 0x43f81508, 0x2c, 0x828, 'ti.sdo.ce.video.IVIDENC')
@4,719,674us: [+0 T:0x43f81b60 S:0x43f812cc] OM - Memory_alloc> Enter(0x30)
@4,719,740us: [+0 T:0x43f81b60 S:0x43f812cc] OM - Memory_alloc> return (0x6cad0)
@4,719,799us: [+0 T:0x43f81b60 S:0x43f81274] CE - Engine_createNode(0x6c4a8, 'videnc_copy', 828, 0x43f81508, 0x2c, 0x43f813e8)
@4,719,865us: [+0 T:0x43f81b60 S:0x43f8125c] OM - Memory_alloc> Enter(0x20)
@4,719,925us: [+0 T:0x43f81b60 S:0x43f8125c] OM - Memory_alloc> return (0x6cb08)
@4,719,992us: [+0 T:0x43f81b60 S:0x43f8124c] OC - Comm_create> Enter(queueName='gppfromnode_3', queue=0x6cb10, attrs=0x0)
@4,720,090us: [+0 T:0x43f81b60 S:0x43f81234] OM - Memory_alloc> Enter(0x4)
@4,720,158us: [+0 T:0x43f81b60 S:0x43f81234] OM - Memory_alloc> return (0x6cb30)
@4,720,400us: [+0 T:0x43f81b60 S:0x43f8124c] OC - Comm_create> return (0x6cb30)
@4,720,488us: [+0 T:0x43f81b60 S:0x43f81254] OC - Comm_put> Enter(queue=0x0, msg=0x41155880)
@4,720,585us: [+0 T:0x43f81b60 S:0x43f81254] OC - Comm_put> return (0)
@4,720,649us: [+0 T:0x43f81b60 S:0x43f8124c] OC - Comm_get> Enter(queue=0x10000, msg=0x43f812cc, timeout=-1)
@4,721,933us: [+0 T:0x43f81b60 S:0x43f8124c] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
@4,722,022us: [+0 T:0x43f81b60 S:0x43f81254] OC - Comm_put> Enter(queue=0x0, msg=0x41155880)
@4,722,115us: [+0 T:0x43f81b60 S:0x43f81254] OC - Comm_put> return (0)
@4,722,212us: [+0 T:0x43f81b60 S:0x43f8124c] OC - Comm_get> Enter(queue=0x10000, msg=0x43f812cc, timeout=-1)
@4,722,296us: [+0 T:0x43f81b60 S:0x43f8124c] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
@4,722,363us: [+4 T:0x43f81b60 S:0x43f81274] CE - Engine_createNode> created node(stdIn=0x4, stdOut=0x10003, msgq=0x6cb30, algName='videnc_copy', rmsNode=0x8fa4c900, algHandle=0x8fa4c970)
@4,722,443us: [+0 T:0x43f81b60 S:0x43f811d4] OC - Comm_put> Enter(queue=0x0, msg=0x41155880)
@4,722,527us: [+0 T:0x43f81b60 S:0x43f811d4] OC - Comm_put> return (0)
@4,722,590us: [+0 T:0x43f81b60 S:0x43f811cc] OC - Comm_get> Enter(queue=0x10000, msg=0x43f81260, timeout=-1)
@4,722,704us: [+0 T:0x43f81b60 S:0x43f811cc] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
[DSP] @13,463,316tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 0
[DSP] @13,463,457tk: [+0 T:0x8fa4634c S:0x8fa471f4] OM - Memory_alloc> Enter(size=0x18)
[DSP] @13,463,573tk: [+0 T:0x8fa4634c S:0x8fa471f4] OM - Memory_alloc> return (0x8fa4c900)
[DSP] @13,463,685tk: [+0 T:0x8fa4634c S:0x8fa471f4] OM - Memory_alloc> Enter(size=0xe)
[DSP] @13,463,792tk: [+0 T:0x8fa4634c S:0x8fa471f4] OM - Memory_alloc> return (0x8fa4c918)
[DSP] @13,463,911tk: [+0 T:0x8fa4634c S:0x8fa471bc] OM - Memory_alloc> Enter(size=0x20)
[DSP] @13,464,023tk: [+0 T:0x8fa4634c S:0x8fa471bc] OM - Memory_alloc> return (0x8fa4c928)
[DSP] @13,464,138tk: [+0 T:0x8fa4634c S:0x8fa4718c] OM - Memory_alloc> Enter(size=0x24)
[DSP] @13,464,248tk: [+0 T:0x8fa4634c S:0x8fa4718c] OM - Memory_alloc> return (0x8fa4c948)
[DSP] @13,464,370tk: [+0 T:0x8fa4634c S:0x8fa471a4] ti.sdo.ce.video.VIDENC - VIDENC_create> Enter (engine=0x0, name='videnc_copy', params=0x8fe038c0)
[DSP] @13,464,516tk: [+0 T:0x8fa4634c S:0x8fa4716c] CV - VISA_create(0x0, 'videnc_copy', 0x8fe038c0, 0x828, 'ti.sdo.ce.video.IVIDENC')
[DSP] @13,464,665tk: [+0 T:0x8fa4634c S:0x8fa4705c] CV - VISA_create2(0x0, 'videnc_copy', 0x8fe038c0, 0x2c, 0x828, 'ti.sdo.ce.video.IVIDENC')
[DSP] @13,464,843tk: [+0 T:0x8fa4634c S:0x8fa4703c] OM - Memory_alloc> Enter(size=0x30)
[DSP] @13,464,952tk: [+0 T:0x8fa4634c S:0x8fa4703c] OM - Memory_alloc> return (0x8fa4c970)
[DSP] @13,465,066tk: [+0 T:0x8fa4634c S:0x8fa47014] ti.sdo.ce.alg.Algorithm - Algorithm_create> Enter(fxns=0x8fa6f78c, idma3Fxns=0x8fa6f7c0, params=0x8fe038c0, attrs=0x8fa47160)
[DSP] @13,465,236tk: [+0 T:0x8fa4634c S:0x8fa46ff4] OM - Memory_alloc> Enter(size=0x10)
[DSP] @13,465,345tk: [+0 T:0x8fa4634c S:0x8fa46ff4] OM - Memory_alloc> return (0x8fa4c9a0)
[DSP] @13,465,497tk: [+0 T:0x8fa4634c S:0x8fa46f84] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_alloc(0x8fe038c0, 0x8fa47008, 0x8fa4c9b0)
[DSP] @13,465,692tk: [+0 T:0x8fa4634c S:0x8fa46f8c] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_initObj(0x88000260, 0x8fa4c9b0, 0x0, 0x8fe038c0)
[DSP] @13,465,865tk: [+0 T:0x8fa4634c S:0x8fa46fac] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_dmaGetChannels(0x88000260, 0x88000620)
[DSP] @13,466,014tk: [+0 T:0x8fa4634c S:0x8fa46fac] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_dmaGetChannels(0x88000260, 0x88000620)
[DSP] @13,466,243tk: [+0 T:0x8fa4634c S:0x8fa46fac] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_dmaInit(0x88000260, 0x88000620)
[DSP] @13,466,393tk: [+0 T:0x8fa4634c S:0x8fa47014] ti.sdo.ce.alg.Algorithm - Algorithm_create> return (0x8fa4c9a0)
[DSP] @13,466,521tk: [+0 T:0x8fa4634c S:0x8fa471a4] ti.sdo.ce.video.VIDENC - VIDENC_create> return (0x8fa4c970)
[DSP] @13,466,651tk: [+4 T:0x8fa4634c S:0x8fa47164] OT - Thread_create > name: "videnc_copy#2", pri:  -1, stack size:  9216, stack seg: 0
[DSP] @13,469,301tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 1
[DSP] @13,470,904tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
[DSP] @13,471,037tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - remote time = 0x0, trace buffer size = 4032
@5,209,207us: [+0 T:0x43f81b60 S:0x43f81204] CE - Engine_fwriteTrace> returning count [3170]
@5,209,289us: [+2 T:0x43f81b60 S:0x43f81274] CE - Engine_createNode> Returning 0x6cb08
@5,209,352us: [+0 T:0x43f81b60 S:0x43f812cc] OM - Memory_alloc> Enter(0x4)
@5,209,417us: [+0 T:0x43f81b60 S:0x43f812cc] OM - Memory_alloc> return (0x6cb40)
@5,209,477us: [+0 T:0x43f81b60 S:0x43f812c4] OC - Comm_alloc> Enter(poolId=0x0, msg=0x6cb40, size=828)
@5,209,562us: [+0 T:0x43f81b60 S:0x43f812c4] OC - Comm_alloc> msg=0x41158880, returning (0)
@5,209,635us: [+5 T:0x43f81b60 S:0x43f812e4] CV - VISA_create> remote codec created (name='videnc_copy', localQueueID=0x10003, remoteQueueID=0x0004)
@5,209,706us: [+0 T:0x43f81b60 S:0x43f813ec] ti.sdo.ce.video.VIDENC - VIDENC_create> return (0x6cad0)
@5,209,768us: [+0 T:0x43f81b60 S:0x43f8139c] ti.sdo.ce.video.VIDENC - VIDENC_control> Enter (handle=0x6cad0, id=1, params=0x43f81450, status=0x43f81478
@5,209,838us: [+5 T:0x43f81b60 S:0x43f8136c] CV - VISA_allocMsg> Allocating message for messageId=0x0004ec9d
@5,209,902us: [+0 T:0x43f81b60 S:0x43f8134c] CV - VISA_call(visa=0x6cad0, msg=0x41158880): messageId=0x0004ec9d, command=0x1
@5,209,969us: [+0 T:0x43f81b60 S:0x43f8131c] OC - Comm_put> Enter(queue=0x4, msg=0x41158880)
@5,210,095us: [+0 T:0x43f81b60 S:0x43f8131c] OC - Comm_put> return (0)
@5,210,164us: [+0 T:0x43f81b60 S:0x43f81314] OC - Comm_get> Enter(queue=0x10003, msg=0x43f813ac, timeout=-1)
@5,211,417us: [+0 T:0x43f81b60 S:0x43f81314] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
@5,211,511us: [+0 T:0x43f81b60 S:0x43f8129c] OC - Comm_put> Enter(queue=0x0, msg=0x41155880)
@5,211,604us: [+0 T:0x43f81b60 S:0x43f8129c] OC - Comm_put> return (0)
@5,211,669us: [+0 T:0x43f81b60 S:0x43f81294] OC - Comm_get> Enter(queue=0x10000, msg=0x43f81328, timeout=-1)
@5,211,788us: [+0 T:0x43f81b60 S:0x43f81294] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
[DSP] @15,375,464tk: [+5 T:0x8fa4ca24 S:0x8fa4ede4] CN - NODE> 0x8fa4c928(videnc_copy#2) call(algHandle=0x8fa4c970, msg=0x8fe06880); messageId=0x0004ec9d
[DSP] @15,375,639tk: [+0 T:0x8fa4ca24 S:0x8fa4ed2c] ti.sdo.ce.video.VIDENC - VIDENC_control> Enter (handle=0x8fa4c970, id=1, params=0x8fe068b4, status=0x8fe068dc
[DSP] @15,375,809tk: [+5 T:0x8fa4ca24 S:0x8fa4ed0c] CV - VISA_enter(visa=0x8fa4c970): algHandle = 0x8fa4c9a0
[DSP] @15,375,935tk: [+0 T:0x8fa4ca24 S:0x8fa4ecec] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(handle=0x8fa4c9a0)
[DSP] @15,376,077tk: [+0 T:0x8fa4ca24 S:0x8fa4ecbc] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_deactivate(0x88000258)
[DSP] @15,376,212tk: [+0 T:0x8fa4ca24 S:0x8fa4ecbc] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_activate(0x88000260)
[DSP] @15,376,346tk: [+0 T:0x8fa4ca24 S:0x8fa4ecec] ti.sdo.ce.alg.Algorithm - Algorithm_activate> return
[DSP] @15,376,461tk: [+0 T:0x8fa4ca24 S:0x8fa4ecf4] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_control(0x88000260, 0x1, 0x8fe068b4, 0x8fe068dc)
[DSP] @15,376,625tk: [+5 T:0x8fa4ca24 S:0x8fa4ed0c] CV - VISA_exit(visa=0x8fa4c970): algHandle = 0x8fa4c9a0
[DSP] @15,376,753tk: [+0 T:0x8fa4ca24 S:0x8fa4ecec] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(handle=0x8fa4c9a0)
[DSP] @15,376,889tk: [+0 T:0x8fa4ca24 S:0x8fa4ecec] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> return
[DSP] @15,377,009tk: [+0 T:0x8fa4ca24 S:0x8fa4ed2c] ti.sdo.ce.video.VIDENC - VIDENC_control> Exit (handle=0x8fa4c970, retVal=0x0)
[DSP] @15,377,149tk: [+5 T:0x8fa4ca24 S:0x8fa4ede4] CN - NODE> returned from call(algHandle=0x8fa4c970, msg=0x8fe06880); messageId=0x0004ec9d
[DSP] @15,381,362tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
[DSP] @15,381,499tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - remote time = 0x0, trace buffer size = 4032
@5,213,144us: [+0 T:0x43f81b60 S:0x43f812cc] CE - Engine_fwriteTrace> returning count [1875]
@5,213,209us: [+0 T:0x43f81b60 S:0x43f8134c] CV - VISA_call Completed: messageId=0x0004ec9d, command=0x1, return(status=0)
@5,213,279us: [+5 T:0x43f81b60 S:0x43f8135c] CV - VISA_freeMsg(0x6cad0, 0x41158880): Freeing message with messageId=0x0004ec9d
@5,213,345us: [+0 T:0x43f81b60 S:0x43f8139c] ti.sdo.ce.video.VIDENC - VIDENC_control> Exit (handle=0x6cad0, retVal=0x0)
@5,213,405us: [+0 T:0x43f81b60 S:0x43f8139c] ti.sdo.ce.video.VIDENC - VIDENC_control> Enter (handle=0x6cad0, id=5, params=0x43f81450, status=0x43f81478
@5,213,471us: [+5 T:0x43f81b60 S:0x43f8136c] CV - VISA_allocMsg> Allocating message for messageId=0x0004ec9e
@5,213,532us: [+0 T:0x43f81b60 S:0x43f8134c] CV - VISA_call(visa=0x6cad0, msg=0x41158880): messageId=0x0004ec9e, command=0x1
@5,213,598us: [+0 T:0x43f81b60 S:0x43f8131c] OC - Comm_put> Enter(queue=0x4, msg=0x41158880)
@5,213,684us: [+0 T:0x43f81b60 S:0x43f8131c] OC - Comm_put> return (0)
@5,213,748us: [+0 T:0x43f81b60 S:0x43f81314] OC - Comm_get> Enter(queue=0x10003, msg=0x43f813ac, timeout=-1)
@5,214,150us: [+0 T:0x43f81b60 S:0x43f81314] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
@5,214,235us: [+0 T:0x43f81b60 S:0x43f8129c] OC - Comm_put> Enter(queue=0x0, msg=0x41155880)
@5,214,322us: [+0 T:0x43f81b60 S:0x43f8129c] OC - Comm_put> return (0)
@5,214,386us: [+0 T:0x43f81b60 S:0x43f81294] OC - Comm_get> Enter(queue=0x10000, msg=0x43f81328, timeout=-1)
@5,214,500us: [+0 T:0x43f81b60 S:0x43f81294] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
[DSP] @15,389,491tk: [+5 T:0x8fa4ca24 S:0x8fa4ede4] CN - NODE> 0x8fa4c928(videnc_copy#2) call(algHandle=0x8fa4c970, msg=0x8fe06880); messageId=0x0004ec9e
[DSP] @15,389,668tk: [+0 T:0x8fa4ca24 S:0x8fa4ed2c] ti.sdo.ce.video.VIDENC - VIDENC_control> Enter (handle=0x8fa4c970, id=5, params=0x8fe068b4, status=0x8fe068dc
[DSP] @15,389,833tk: [+5 T:0x8fa4ca24 S:0x8fa4ed0c] CV - VISA_enter(visa=0x8fa4c970): algHandle = 0x8fa4c9a0
[DSP] @15,389,960tk: [+0 T:0x8fa4ca24 S:0x8fa4ecec] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(handle=0x8fa4c9a0)
[DSP] @15,390,100tk: [+0 T:0x8fa4ca24 S:0x8fa4ecec] ti.sdo.ce.alg.Algorithm - Algorithm_activate> return
[DSP] @15,390,216tk: [+0 T:0x8fa4ca24 S:0x8fa4ecf4] ti.sdo.ce.examples.codecs.videnc_copy - VIDENCCOPY_TI_control(0x88000260, 0x5, 0x8fe068b4, 0x8fe068dc)
[DSP] @15,390,380tk: [+5 T:0x8fa4ca24 S:0x8fa4ed0c] CV - VISA_exit(visa=0x8fa4c970): algHandle = 0x8fa4c9a0
[DSP] @15,390,505tk: [+0 T:0x8fa4ca24 S:0x8fa4ecec] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(handle=0x8fa4c9a0)
[DSP] @15,390,637tk: [+0 T:0x8fa4ca24 S:0x8fa4ecec] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> return
[DSP] @15,390,755tk: [+0 T:0x8fa4ca24 S:0x8fa4ed2c] ti.sdo.ce.video.VIDENC - VIDENC_control> Exit (handle=0x8fa4c970, retVal=0x0)
[DSP] @15,390,893tk: [+5 T:0x8fa4ca24 S:0x8fa4ede4] CN - NODE> returned from call(algHandle=0x8fa4c970, msg=0x8fe06880); messageId=0x0004ec9e
[DSP] @15,391,986tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
[DSP] @15,392,123tk: [+0 T:0x8fa4634c S:0x8fa47304] CR - remote time = 0x0, trace buffer size = 4032
@5,456,442us: [+0 T:0x43f81b60 S:0x43f812cc] CE - Engine_fwriteTrace> returning count [1619]
@5,456,521us: [+0 T:0x43f81b60 S:0x43f8134c] CV - VISA_call Completed: messageId=0x0004ec9e, command=0x1, return(status=0)
@5,456,592us: [+5 T:0x43f81b60 S:0x43f8135c] CV - VISA_freeMsg(0x6cad0, 0x41158880): Freeing message with messageId=0x0004ec9e
@5,456,658us: [+0 T:0x43f81b60 S:0x43f8139c] ti.sdo.ce.video.VIDENC - VIDENC_control> Exit (handle=0x6cad0, retVal=0x0)
Create:ENCODE ID= 2,eHandle=445136
 ENCODE: Create DONE
 ENCODE: Sending ACK (0)
 ENCODE: Waiting MSG
 ENCODE: Received ACK
 MCVIP: Display init in progress
 MCVIP: Display init done !!!
 MCVIP: TVP5158 init in progress for stage 0
Patch is downloading...
Patch is downloaded.  Soft reset!
 MCVIP: TVP5158 init done !!!

 ==================
 MCVIP Running Menu
 ==================

 9: Switch D1 preview to CH8

 8: Switch D1 preview to CH7

 7: Switch D1 preview to CH6

 6: Switch D1 preview to CH5

 5: Switch D1 preview to CH4

 4: Switch D1 preview to CH3

 3: Switch D1 preview to CH2

 2: Switch D1 preview to CH1

 1: Save frame to file

 0: Stop and Exit
 

 t: channel preview test
 
 r: VPIF reset
 s: VPIF set sync mode
 u: VPIF set unsync mode
 p: VPIF print registers

 Enter Choice : 

 ==================
 MCVIP Running Menu
 ==================

 9: Switch D1 preview to CH8

 8: Switch D1 preview to CH7

 7: Switch D1 preview to CH6

 6: Switch D1 preview to CH5

 5: Switch D1 preview to CH4

 4: Switch D1 preview to CH3

 3: Switch D1 preview to CH2

 2: Switch D1 preview to CH1

 1: Save frame to file

 0: Stop and Exit
 

 t: channel preview test
 
 r: VPIF reset
 s: VPIF set sync mode
 u: VPIF set unsync mode
 p: VPIF print registers

 Enter Choice : Caputure: framesize=706560
 Capture Ch 0: BufId 0;Encode_bufVirtAddr=1140334592,bufsize=706560
@8,406,327us: [+0 T:0x419c4b60 S:0x419c43fc] ti.sdo.ce.video.VIDENC - VIDENC_process> Enter (handle=0x6c998, inBufs=0x419c44a8, outBufs=0x419c4498, inArgs=0x419c4484, outArgs=0x419c44b8)
@8,406,412us: [+5 T:0x419c4b60 S:0x419c4384] CV - VISA_allocMsg> Allocating message for messageId=0x0002e5c0
@8,406,481us: [+0 T:0x419c4b60 S:0x419c4354] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x419c5000, size=706560)
@8,406,544us: [+1 T:0x419c4b60 S:0x419c4354] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x419c5000, size=706560)
@8,406,603us: [+1 T:0x419c4b60 S:0x419c4354] OM - Memory__getPhysicalAddress> returning physAddr=0x0
@8,406,670us: [+1 T:0x419c4b60 S:0x419c4354] OM - Memory_getBufferPhysicalAddress> CMEM_getPhys(0x419c5000) = 0x87800000.
@8,406,742us: [+1 T:0x419c4b60 S:0x419c42fc] OM - Memory__addContigBuf> Enter(virtAddr=0x419c5000, size=706560, physAddr=0x87800000)
@8,406,809us: [+1 T:0x419c4b60 S:0x419c42fc] OM - Memory__addContigBuf> creating new contigBuf object
@8,406,863us: [+0 T:0x419c4b60 S:0x419c42e4] OM - Memory_alloc> Enter(0x10)
@8,406,924us: [+0 T:0x419c4b60 S:0x419c42e4] OM - Memory_alloc> return (0x6c738)
@8,406,982us: [+1 T:0x419c4b60 S:0x419c42fc] OM - Memory__addContigBuf> returning: cb->phys=0x87800000, cb->size=706560, cb->virt=0x419c5000
@8,407,045us: [+0 T:0x419c4b60 S:0x419c4354] OM - Memory_getBufferPhysicalAddress> return (0x87800000)
@8,407,104us: [+0 T:0x419c4b60 S:0x419c4354] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x43f82000, size=706560)
@8,407,164us: [+1 T:0x419c4b60 S:0x419c4354] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x43f82000, size=706560)
@8,407,224us: [+1 T:0x419c4b60 S:0x419c4354] OM - Memory__getPhysicalAddress> returning physAddr=0x0
@8,407,288us: [+1 T:0x419c4b60 S:0x419c4354] OM - Memory_getBufferPhysicalAddress> CMEM_getPhys(0x43f82000) = 0x882c8000.
@8,407,356us: [+1 T:0x419c4b60 S:0x419c42fc] OM - Memory__addContigBuf> Enter(virtAddr=0x43f82000, size=706560, physAddr=0x882c8000)
@8,407,422us: [+1 T:0x419c4b60 S:0x419c42fc] OM - Memory__addContigBuf> creating new contigBuf object
@8,407,476us: [+0 T:0x419c4b60 S:0x419c42e4] OM - Memory_alloc> Enter(0x10)
@8,407,536us: [+0 T:0x419c4b60 S:0x419c42e4] OM - Memory_alloc> return (0x6cb98)
@8,407,591us: [+1 T:0x419c4b60 S:0x419c42fc] OM - Memory__addContigBuf> returning: cb->phys=0x882c8000, cb->size=706560, cb->virt=0x43f82000
@8,407,654us: [+0 T:0x419c4b60 S:0x419c4354] OM - Memory_getBufferPhysicalAddress> return (0x882c8000)
@8,407,714us: [+0 T:0x419c4b60 S:0x419c4394] CV - VISA_call(visa=0x6c998, msg=0x41156880): messageId=0x0002e5c0, command=0x0
@8,407,784us: [+0 T:0x419c4b60 S:0x419c4364] OC - Comm_put> Enter(queue=0x2, msg=0x41156880)
@8,407,876us: [+0 T:0x419c4b60 S:0x419c4364] OC - Comm_put> return (0)
@8,407,940us: [+0 T:0x419c4b60 S:0x419c435c] OC - Comm_get> Enter(queue=0x10001, msg=0x419c4404, timeout=-1)