VIDENC1_process fails

Frank,

1. Have you tried comparing your app with what is provided as part of demo? The demo would be working fine and can act as a reference.

2. Can you please share the parameters set during create, synamic update and process call, we will review it.

regards

Yashwant

Hi All,

I am using DVSDK  3.10.00.16.

and My problem is the demo program blocked in

retVal = fxns->process(alg, inBufs, outBufs, inArgs, outArgs);

and here is part of my ouput:

@25,694,740us: [+0 T:0x4001fce0] CE - Engine_open> Enter('encode', 0x0, 0xbec58a6c)
@25,694,995us: [+0 T:0x4001fce0] OM - Memory_alloc> Enter(0x2c)
@25,695,193us: [+0 T:0x4001fce0] OM - Memory_alloc> return (0x13ae68)
@25,695,386us: [+3 T:0x4001fce0] CE - Engine_fwriteTrace(0x13ae68, '[DSP] ', 0x403095e8)
@25,695,566us: [+3 T:0x4001fce0] CE - Engine_fwriteTrace> Engine has no server
@25,695,719us: [+0 T:0x4001fce0] CE - Engine_open> return(1289832)
@25,706,665us: [+0 T:0x415ea490] ti.sdo.ce.video1.VIDENC1 - VIDENC1_process> Enter (handle=0xfa058, inBufs=0x415e9c50, outBufs=0x415e9c44, inArgs=0x415e9c34, outArgs=0x415e9bbc)
@25,707,028us: [+5 T:0x415ea490] CV - VISA_enter(visa=0xfa058): algHandle = 0xfa090
@25,707,269us: [+0 T:0x415ea490] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(alg=0xfa090)
@25,707,479us: [+0 T:0x415ea490] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0xc22c8] timeout[0xffffffff]
@25,707,741us: [+0 T:0x415ea490] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0xc22c8] status[0]
@25,708,315us: [+0 T:0x415ea490] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Exit

Any help would be greatly appreciated.

Thanks

Lei

I found the error within my programm. It were some uninitialized extendet inArg parameter. The parameter "insertUserData" and "lengthUserData" form IH264VENC_InArgs are marked reserved. But the process call fails if not initialized to 0.

Thanks

Frank

Hello Lei,

I currently have a similar problem using the JPEG encoder. My encoder hangs in the same 

retVal = fxns->process(alg, inBufs, outBufs, inArgs, outArgs);

call after encoding some hundred images. If I restart my test application the encoder again encodes some hundred images and hangs. But after that the encoder hangs immediately in the first call.

I will prepare a small code example and post it here soon.

I tried to send a XD_RESET control call but that did not help. Maybe there is a way to release the encoder but at the moment I have to restart my board.

Best Regards

Frank

Hi Frank,

I am using the dvsdk_demos_3_10_00_14 from dvsdk_3_10_00_16, the porgramm from TI should be OK.

Thanks

Hi Frank,

My board hangs on the first call, and occupies 90%+ resouce.

  PID  PPID USER     STAT   VSZ %MEM %CPU COMMAND
 2259  2258 root     S <  50004  99%  95% ./encode -v test.264 -y 1 -t 10 -b 1000000

I've no idea now.

Best Regards

Lei

This works in our setup. Can you please send your Rules.make file (if your compiling in DVSDK environment)?

Regards

Sajesh

Here is my Rules.make, and I compile everything in DVSDK enviroment.

# Define target platform.
PLATFORM=dm365

# The installation directory of the DVSDK.
DVSDK_INSTALL_DIR=/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16

# For backwards compatibility
DVEVM_INSTALL_DIR=$(DVSDK_INSTALL_DIR)

# Where DSP/BIOS is installed.
BIOS_INSTALL_DIR=$(DVSDK_INSTALL_DIR)/bios_5_41_00_06

# Where the DSPBIOS Utils package is installed.
BIOSUTILS_INSTALL_DIR=$(DVSDK_INSTALL_DIR)/biosutils_1_02_02

# Where the Codec Engine package is installed.
CE_INSTALL_DIR=$(DVSDK_INSTALL_DIR)/codec_engine_2_25_01_06

# Where the DSP Link package is installed.
LINK_INSTALL_DIR=$(DVSDK_INSTALL_DIR)/dsplink_linux_1_64

ifeq ($(PLATFORM),dm355)
# Where the DM355 codecs are installed.
    CODEC_INSTALL_DIR=$(DVSDK_INSTALL_DIR)/dm355_codecs_03_10_00_00
endif

ifeq ($(PLATFORM),dm365)
# Where the DM365 codecs are installed.
    CODEC_INSTALL_DIR=$(DVSDK_INSTALL_DIR)/dm365_codecs_03_10_00_05
endif

# Where the DM365MM module is installed.
DM365MMAP_INSTALL_DIR=$(DVSDK_INSTALL_DIR)/dm365mm_01_00_03

ifeq ($(PLATFORM),dm6467)
# Where the cs2dm6467 codec server package is installed.
CODEC_INSTALL_DIR=./cs2dm6467_1_00_00_08
endif

# Where DMAI package is installed.
DMAI_INSTALL_DIR=$(DVSDK_INSTALL_DIR)/dmai_2_10_00_10

# Where the DVSDK demos are installed
DEMO_INSTALL_DIR=$(DVSDK_INSTALL_DIR)/dvsdk_demos_3_10_00_14

# Where the DVTB package is installed.
DVTB_INSTALL_DIR=$(DVSDK_INSTALL_DIR)/dvtb_4_20_04_4

# Where the EDMA3 LLD package is installed.
EDMA3_LLD_INSTALL_DIR=$(DVSDK_INSTALL_DIR)/edma3_lld_01_11_00_03

# Where the Framework Components package is installed.
FC_INSTALL_DIR=$(DVSDK_INSTALL_DIR)/framework_components_2_25_01_05

# Where the MFC Linux Utils package is installed.
LINUXUTILS_INSTALL_DIR=$(DVSDK_INSTALL_DIR)/linuxutils_2_25_01_06
CMEM_INSTALL_DIR=$(LINUXUTILS_INSTALL_DIR)

# Where the XDAIS package is installed.
XDAIS_INSTALL_DIR=$(DVSDK_INSTALL_DIR)/xdais_6_25_01_08

# Where the RTSC tools package is installed.
XDC_INSTALL_DIR=$(DVSDK_INSTALL_DIR)/xdctools_3_16_01_27

# Where the Code Gen is installed.
CODEGEN_INSTALL_DIR=$(DVSDK_INSTALL_DIR)/cgt6x_6_1_12

# The directory that points to your kernel source directory.
LINUXKERNEL_INSTALL_DIR=$(DVSDK_INSTALL_DIR)/../../git

# Where temporary Linux headers and libs are installed.
LINUXLIBS_INSTALL_DIR=$(DVSDK_INSTALL_DIR)/../../linuxlibs-2009.11-armv5te

# The prefix to be added before the GNU compiler tools (optionally including # path), i.e. "arm_v5t_le-" or "/opt/bin/arm_v5t_le-".
CSTOOL_DIR=/root/CodeSourcery/Sourcery_G++_Lite
CSTOOL_PREFIX=$(CSTOOL_DIR)/bin/arm-none-linux-gnueabi-
#CSTOOL_PREFIX=arm-none-linux-gnueabi-

MVTOOL_DIR=$(CSTOOL_DIR)
MVTOOL_PREFIX=$(CSTOOL_PREFIX)

# Where to copy the resulting executables
EXEC_DIR=./install/$(PLATFORM)

Best Regards

Lei

Lei,

The Rules.make looks OK to me.

Can you please try running the demo from overlay image provided in DVSDK release http://software-dl.ti.com/dsps/dsps_public_sw/sdo_sb/targetcontent/dvsdk/DVSDK_3_10/latest//exports/dvsdk_3_10_00_16_overlay_dm365.tar.gz?

Regards

Sajesh

Hi Sajesh,

I have tried the overlay images, and got the same result.

When the encode program running, I can see it consumes MIPS and memory but never encodes even one frame.

Thanks

Lei

OK. Have you tried dmai application (video_encode_io1)? It should also fail in your case. Are you able to encode in any other resolution?

Please send the complete log with CE_DEBUG=2.

Regards

Sajesh

Hi Sajesh,

Thank you for your reply.

I tried dmai application (video_encode_io1), and here is the log:

root@arago:/mnt/nfs# ./loadmodules.sh
CMEMK module: built on Jun 10 2010 at 10:47:16
  Reference Linux version 2.6.32
  File /home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/linuxutils_2_25_01_06/packages/ti/sdo/linuxutils/cmem/src/module/cmemk.c
allocated heap buffer 0xc7000000 of size 0x293000
cmemk initialized
IRQK module: built on Jun 10 2010 at 10:47:24
  Reference Linux version 2.6.32
  File /home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/linuxutils_2_25_01_06/packages/ti/sdo/linuxutils/irq/src/module/irqk.c
irqk initialized
EDMAK module: built on Jun 10 2010 at 10:47:22
  Reference Linux version 2.6.32
  File /home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/linuxutils_2_25_01_06/packages/ti/sdo/linuxutils/edma/src/module/edmak.c
root@arago:/mnt/nfs# export CE_DEBUG=2
root@arago:/mnt/nfs# ./video_encode_io1_dm365.x470MV -c h264enc -i colorful_toys
_cif_5frms_420p.yuv -r 352x288 -o cif.264
Starting application...
@0,799,335us: [+4 T:0x4001f060] OG - Global_init> This program was built with the following packages:
@0,799,919us: [+4 T:0x4001f060] OG -     package gnu.targets.arm.rtsv5T (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/xdctools_3_16_01_27/packages/gnu/targets/arm/rtsv5T/) [1, 0, 0, 0]
@0,800,215us: [+4 T:0x4001f060] OG -     package ti.sdo.linuxutils.edma (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/linuxutils_2_25_01_06/packages/ti/sdo/linuxutils/edma/) [1, 0, 0]
@0,800,396us: [+4 T:0x4001f060] OG -     package ti.sdo.linuxutils.cmem (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/linuxutils_2_25_01_06/packages/ti/sdo/linuxutils/cmem/) [2, 2, 0]
@0,800,563us: [+4 T:0x4001f060] OG -     package ti.sdo.fc.ires (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/framework_components_2_25_01_05/packages/ti/sdo/fc/ires/) [1, 0, 0]
@0,800,723us: [+4 T:0x4001f060] OG -     package ti.xdais (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/xdais_6_25_01_08/packages/ti/xdais/) [1, 2.0, 1]
@0,800,871us: [+4 T:0x4001f060] OG -     package ti.sdo.fc.global (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/framework_components_2_25_01_05/packages/ti/sdo/fc/global/) [1, 0, 0]
@0,801,024us: [+4 T:0x4001f060] OG -     package ti.sdo.fc.memutils (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/framework_components_2_25_01_05/packages/ti/sdo/fc/memutils/) [1, 0, 0]
@0,801,288us: [+4 T:0x4001f060] OG -     package ti.sdo.fc.ires.addrspace (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/framework_components_2_25_01_05/packages/ti/sdo/fc/ires/addrspace/) [1, 0, 0]
@0,801,459us: [+4 T:0x4001f060] OG -     package ti.sdo.fc.utils (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/framework_components_2_25_01_05/packages/ti/sdo/fc/utils/) [1, 0, 2]
@0,801,615us: [+4 T:0x4001f060] OG -     package ti.sdo.linuxutils.vicp (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/linuxutils_2_25_01_06/packages/ti/sdo/linuxutils/vicp/) [1, 0, 0]
@0,801,764us: [+4 T:0x4001f060] OG -     package ti.sdo.fc.ires.hdvicp (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/framework_components_2_25_01_05/packages/ti/sdo/fc/ires/hdvicp/) [1, 0, 1]
@0,802,092us: [+4 T:0x4001f060] OG -     package ti.sdo.utils.trace (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/framework_components_2_25_01_05/packages/ti/sdo/utils/trace/) [1, 0, 0]
@0,802,293us: [+4 T:0x4001f060] OG -     package ti.sdo.fc.hdvicpsync (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/framework_components_2_25_01_05/packages/ti/sdo/fc/hdvicpsync/) [1, 0, 0]
@0,802,457us: [+4 T:0x4001f060] OG -     package ti.sdo.codecs.h264enc (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/dm365_codecs_03_10_00_05/packages/ti/sdo/codecs/h264enc/) [1, 0, 0]
@0,802,615us: [+4 T:0x4001f060] OG -     package ti.sdo.codecs.mpeg4enc_hdvicp (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/dm365_codecs_03_10_00_05/packages/ti/sdo/codecs/mpeg4enc_hdvicp/) [1, 0, 0]
@0,802,875us: [+4 T:0x4001f060] OG -     package ti.sdo.codecs.mpeg2enc (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/dm365_codecs_03_10_00_05/packages/ti/sdo/codecs/mpeg2enc/) [1, 0, 0]
@0,803,054us: [+4 T:0x4001f060] OG -     package ti.sdo.fc.ires.nullresource (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/framework_components_2_25_01_05/packages/ti/sdo/fc/ires/nullresource/) [1, 0, 0]
@0,803,226us: [+4 T:0x4001f060] OG -     package ti.sdo.fc.rman (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/framework_components_2_25_01_05/packages/ti/sdo/fc/rman/) [2, 0, 0]
@0,803,381us: [+4 T:0x4001f060] OG -     package ti.sdo.fc.edma3 (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/framework_components_2_25_01_05/packages/ti/sdo/fc/edma3/) [3, 0, 0]
@0,803,533us: [+4 T:0x4001f060] OG -     package ti.sdo.fc.ires.edma3chan (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/framework_components_2_25_01_05/packages/ti/sdo/fc/ires/edma3chan/) [1, 0, 0]
@0,803,686us: [+4 T:0x4001f060] OG -     package ti.sdo.fc.ires.grouputils (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/framework_components_2_25_01_05/packages/ti/sdo/fc/ires/grouputils/) [1, 0, 0]
@0,803,840us: [+4 T:0x4001f060] OG -     package ti.sdo.fc.ires.vicp (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/framework_components_2_25_01_05/packages/ti/sdo/fc/ires/vicp/) [1, 0, 1]
@1,283,553us: [+4 T:0x4001f060] OG -     package ti.sdo.fc.vicpsync (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/framework_components_2_25_01_05/packages/ti/sdo/fc/vicpsync/) [1, 0, 0]
@1,283,778us: [+4 T:0x4001f060] OG -     package ti.sdo.codecs.mpeg4enc (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/dm365_codecs_03_10_00_05/packages/ti/sdo/codecs/mpeg4enc/) [1, 0, 0]
@1,283,951us: [+4 T:0x4001f060] OG -     package ti.sdo.ce.global (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/codec_engine_2_25_01_06/packages/ti/sdo/ce/global/) [1, 0, 0]
@1,284,111us: [+4 T:0x4001f060] OG -     package ti.xdais.dm (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/xdais_6_25_01_08/packages/ti/xdais/dm/) [1, 0, 5]
@1,284,260us: [+4 T:0x4001f060] OG -     package ti.sdo.ce.utils.xdm (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/codec_engine_2_25_01_06/packages/ti/sdo/ce/utils/xdm/) [1, 0, 2]
@1,284,413us: [+4 T:0x4001f060] OG -     package ti.sdo.ce.node (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/codec_engine_2_25_01_06/packages/ti/sdo/ce/node/) [1, 0, 0]
@1,284,559us: [+4 T:0x4001f060] OG -     package ti.sdo.fc.dman3 (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/framework_components_2_25_01_05/packages/ti/sdo/fc/dman3/) [1, 0, 4]
@1,284,757us: [+4 T:0x4001f060] OG -     package ti.sdo.fc.acpy3 (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/framework_components_2_25_01_05/packages/ti/sdo/fc/acpy3/) [1, 0, 4]
@1,284,923us: [+4 T:0x4001f060] OG -     package gnu.targets (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/xdctools_3_16_01_27/packages/gnu/targets/) [1, 0, 1]
@1,285,072us: [+4 T:0x4001f060] OG -     package gnu.targets.arm (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/xdctools_3_16_01_27/packages/gnu/targets/arm/) [1, 0, 0, 0]
@1,285,226us: [+4 T:0x4001f060] OG -     package ti.catalog.arm (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/xdctools_3_16_01_27/packages/ti/catalog/arm/) [1, 0, 1, 0]
@1,285,375us: [+4 T:0x4001f060] OG -     package ti.catalog (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/xdctools_3_16_01_27/packages/ti/catalog/) [1, 0, 0]
@1,285,520us: [+4 T:0x4001f060] OG -     package ti.platforms.evmDM365 (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/xdctools_3_16_01_27/packages/ti/platforms/evmDM365/) [1, 0, 0]
@1,285,667us: [+4 T:0x4001f060] OG -     package ti.sdo.ce.osal (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/codec_engine_2_25_01_06/packages/ti/sdo/ce/osal/) [2, 0, 2]
@1,285,815us: [+4 T:0x4001f060] OG -     package ti.sdo.ce.osal.linux (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/codec_engine_2_25_01_06/packages/ti/sdo/ce/osal/linux/) [2, 0, 1]
@1,285,965us: [+4 T:0x4001f060] OG -     package ti.sdo.ce.ipc (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/codec_engine_2_25_01_06/packages/ti/sdo/ce/ipc/) [2, 0, 1]
@1,286,157us: [+4 T:0x4001f060] OG -     package ti.sdo.ce.ipc.linux (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/codec_engine_2_25_01_06/packages/ti/sdo/ce/ipc/linux/) [2, 0, 1]
@1,286,321us: [+4 T:0x4001f060] OG -     package ti.sdo.ce.alg (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/codec_engine_2_25_01_06/packages/ti/sdo/ce/alg/) [1, 0, 1]
@1,286,470us: [+4 T:0x4001f060] OG -     package ti.sdo.ce (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/codec_engine_2_25_01_06/packages/ti/sdo/ce/) [1, 0, 6]
@1,286,617us: [+4 T:0x4001f060] OG -     package ti.sdo.ce.speech (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/codec_engine_2_25_01_06/packages/ti/sdo/ce/speech/) [1, 0, 2]
@1,286,766us: [+4 T:0x4001f060] OG -     package ti.sdo.ce.speech1 (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/codec_engine_2_25_01_06/packages/ti/sdo/ce/speech1/) [1, 0, 1]
@1,286,920us: [+4 T:0x4001f060] OG -     package ti.sdo.ce.audio (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/codec_engine_2_25_01_06/packages/ti/sdo/ce/audio/) [1, 0, 2]
@1,619,084us: [+4 T:0x4001f060] OG -     package ti.sdo.ce.audio1 (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/codec_engine_2_25_01_06/packages/ti/sdo/ce/audio1/) [1, 0, 1]
@1,619,309us: [+4 T:0x4001f060] OG -     package ti.sdo.ce.video (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/codec_engine_2_25_01_06/packages/ti/sdo/ce/video/) [1, 0, 3]
@1,619,479us: [+4 T:0x4001f060] OG -     package ti.sdo.ce.video1 (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/codec_engine_2_25_01_06/packages/ti/sdo/ce/video1/) [1, 0, 2]
@1,619,630us: [+4 T:0x4001f060] OG -     package ti.sdo.ce.video2 (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/codec_engine_2_25_01_06/packages/ti/sdo/ce/video2/) [1, 0, 2]
@1,619,778us: [+4 T:0x4001f060] OG -     package ti.sdo.ce.image (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/codec_engine_2_25_01_06/packages/ti/sdo/ce/image/) [1, 0, 3]
@1,619,928us: [+4 T:0x4001f060] OG -     package ti.sdo.ce.image1 (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/codec_engine_2_25_01_06/packages/ti/sdo/ce/image1/) [1, 0, 1]
@1,620,075us: [+4 T:0x4001f060] OG -     package ittiam.extensions.audio1 (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/dm365_codecs_03_10_00_05/packages/ittiam/extensions/audio1/) []
@1,620,273us: [+4 T:0x4001f060] OG -     package ti.sdo.dmai (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/dmai_2_10_00_10/packages/ti/sdo/dmai/) [1, 0, 0]
@1,620,439us: [+4 T:0x4001f060] OG -     package ti.sdo.codecs.h264enc.ce (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/dm365_codecs_03_10_00_05/packages/ti/sdo/codecs/h264enc/ce/) [1, 0, 0]
@1,620,599us: [+4 T:0x4001f060] OG -     package ti.sdo.codecs.mpeg4enc.ce (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/dm365_codecs_03_10_00_05/packages/ti/sdo/codecs/mpeg4enc/ce/) [1, 0, 0]
@1,620,752us: [+4 T:0x4001f060] OG -     package ti.sdo.codecs.mpeg4enc_hdvicp.ce (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/dm365_codecs_03_10_00_05/packages/ti/sdo/codecs/mpeg4enc_hdvicp/ce/) [1, 0, 0]
@1,620,908us: [+4 T:0x4001f060] OG -     package ti.sdo.codecs.mpeg2enc.ce (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/dm365_codecs_03_10_00_05/packages/ti/sdo/codecs/mpeg2enc/ce/) [1, 0, 0]
@1,621,059us: [+4 T:0x4001f060] OG -     package video_encode_io1_dm365_config (/home/xulei/Downloads/dvsdk/dvsdk_3_10_00_16/dmai_2_10_00_10/packages/ti/sdo/dmai/apps/video_encode_io1/linux/video_encode_io1_dm365_config/) []
@1,666,497us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - LockMP_create> key: 0x524d414e count: 1024280 attrs: 0xfa6f4
@1,667,207us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_create> lock[0x10b200]
@1,667,484us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@1,667,835us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@1,668,044us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@1,668,244us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@1,668,581us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@1,668,849us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@1,708,904us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - LockMP_create> key: 0x4956444a count: 68072 attrs: 0xfa118
@1,709,380us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_create> lock[0x10b228]
@1,709,584us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b228]
@1,709,769us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b228]
@1,710,538us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b228]
@1,710,815us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b228]
@1,711,143us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@1,711,374us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@1,711,555us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@1,711,738us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@1,712,140us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - LockMP_create> key: 0x50434958 count: 1024280 attrs: 0x10acc4
@1,712,505us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_create> lock[0x10b250]
@1,712,706us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b250]
@1,712,899us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b250]
@1,713,497us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b250]
@1,713,767us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b250]
@1,713,970us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b250]
@2,046,211us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b250]
@2,046,875us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b250]
@2,047,151us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b250]
@2,047,435us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@2,047,652us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@2,047,906us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@2,048,122us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@2,048,309us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@2,048,505us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@2,048,674us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@2,048,921us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@2,049,108us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@2,049,296us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@2,049,464us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@2,049,645us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@2,049,829us: [+0 T:0x4001f060] ti.sdo.ce.osal.Sem - Sem_create> count: 1
@2,050,057us: [+0 T:0x4001f060] ti.sdo.ce.osal.Sem - Leaving Sem_create> sem[0x10b278]
@2,050,310us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@2,050,548us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@2,050,769us: [+0 T:0x4001f060] ti.sdo.ce.alg - ALG_init> Enter
@2,051,109us: [+0 T:0x4001f060] ti.sdo.ce.alg - ALG_init> Exit
@2,051,439us: [+6 T:0x4001f060] CE - Engine_init> CE debugging on (CE_DEBUG=2; allowed CE_DEBUG levels: 1=min, 2=good, 3=max)
@2,051,819us: [+0 T:0x4001f060] CS - Server_init()
@2,052,198us: [+0 T:0x4001f060] CS - Server_init> Global_useLinkArbiter = 0
@2,089,880us: [+0 T:0x4001f060] CE - Engine_open> Enter('encode', 0x0, 0xbed11964)
@2,090,160us: [+0 T:0x4001f060] CE - Engine_open> return(1095384)
@2,090,480us: [+2 T:0x4001f060] ti.sdo.dmai - [Venc1] Creating encoder h264enc for max 352x288 bitrate 2000000 ratectrl 4
@2,090,739us: [+0 T:0x4001f060] ti.sdo.ce.video1.VIDENC1 - VIDENC1_create> Enter (engine=0x10b6d8, name='h264enc', params=0xbed11a34)
@2,090,952us: [+0 T:0x4001f060] CV - VISA_create(0x10b6d8, 'h264enc', 0xbed11a34, 0x520, 'ti.sdo.ce.video1.IVIDENC1')
@2,091,136us: [+0 T:0x4001f060] CV - VISA_create2(0x10b6d8, 'h264enc', 0xbed11a34, 0x30, 0x520, 'ti.sdo.ce.video1.IVIDENC1')
@2,091,349us: [+0 T:0x4001f060] ti.sdo.ce.alg.Algorithm - Algorithm_create> Enter (fxns=0xfb338, idma3Fxns=0x0, iresFxns=0xfb3c4, params=0xbed11a34, attrs=0xbed11814)
@2,091,557us: [+0 T:0x4001f060] ti.sdo.ce.alg - ALG_create> Enter (scratchId=1, fxns=0xfb338, parentAlg=0x0, params=0xbed11a34)
@2,091,991us: [+2 T:0x4001f060] ti.sdo.ce.alg - ALG_create> algNumAlloc 12 memory recs
@2,129,818us: [+2 T:0x4001f060] ti.sdo.ce.alg - ALG_create> algAlloc returned numRecs=12
@2,130,057us: [+4 T:0x4001f060] ti.sdo.ce.alg - ALG_create>  Memory requested memTab[0]: size=0x5b0, align=0x80, space=0x11, attrs=0x1
@2,130,249us: [+4 T:0x4001f060] ti.sdo.ce.alg - ALG_create>  Memory requested memTab[1]: size=0x1760, align=0x80, space=0x11, attrs=0x1
@2,130,418us: [+4 T:0x4001f060] ti.sdo.ce.alg - ALG_create>  Memory requested memTab[2]: size=0x1760, align=0x80, space=0x11, attrs=0x1
@2,130,628us: [+4 T:0x4001f060] ti.sdo.ce.alg - ALG_create>  Memory requested memTab[3]: size=0x5000, align=0x80, space=0x11, attrs=0x0
@2,130,798us: [+4 T:0x4001f060] ti.sdo.ce.alg - ALG_create>  Memory requested memTab[4]: size=0x800, align=0x80, space=0x11, attrs=0x1
@2,130,956us: [+4 T:0x4001f060] ti.sdo.ce.alg - ALG_create>  Memory requested memTab[5]: size=0x67980, align=0x80, space=0x11, attrs=0x1
@2,131,115us: [+4 T:0x4001f060] ti.sdo.ce.alg - ALG_create>  Memory requested memTab[6]: size=0x380, align=0x80, space=0x11, attrs=0x0
@2,131,271us: [+4 T:0x4001f060] ti.sdo.ce.alg - ALG_create>  Memory requested memTab[7]: size=0xbf38, align=0x80, space=0x11, attrs=0x1
@2,131,424us: [+4 T:0x4001f060] ti.sdo.ce.alg - ALG_create>  Memory requested memTab[8]: size=0xeb80, align=0x80, space=0x11, attrs=0x1
@2,131,576us: [+4 T:0x4001f060] ti.sdo.ce.alg - ALG_create>  Memory requested memTab[9]: size=0x4000, align=0x80, space=0x11, attrs=0x1
@2,131,729us: [+4 T:0x4001f060] ti.sdo.ce.alg - ALG_create>  Memory requested memTab[10]: size=0x10000, align=0x80, space=0x11, attrs=0x1
@2,463,557us: [+4 T:0x4001f060] ti.sdo.ce.alg - ALG_create>  Memory requested memTab[11]: size=0x180, align=0x80, space=0x11, attrs=0x1
@2,464,042us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(1456) = 0x402ff000.
@2,464,293us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x402ff000) = 0x87fff000.
@2,464,697us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(5984) = 0x40300000.
@2,464,979us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x40300000) = 0x875fa000.
@2,465,416us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(5984) = 0x40302000.
@2,465,648us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x40302000) = 0x875f8000.
@2,466,085us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(20480) = 0x40304000.
@2,466,405us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x40304000) = 0x87ff4000.
@2,466,975us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(2048) = 0x40309000.
@2,467,209us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x40309000) = 0x87ffe000.
@2,467,732us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(424320) = 0x4030a000.
@2,467,998us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x4030a000) = 0x86dba000.
@2,471,792us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(896) = 0x40389000.
@2,472,196us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x40389000) = 0x87ff9000.
@2,472,630us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(48952) = 0x4038a000.
@2,472,903us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x4038a000) = 0x875dc000.
@2,473,755us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(60288) = 0x40396000.
@2,474,026us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x40396000) = 0x87fe2000.
@2,474,990us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(16384) = 0x403a5000.
@2,475,233us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403a5000) = 0x87ffa000.
@2,475,768us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(65536) = 0x403a9000.
@2,476,092us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403a9000) = 0x87fa8000.
@2,477,020us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(384) = 0x403b9000.
@2,477,307us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403b9000) = 0x87ff3000.
@2,549,899us: [+4 T:0x4001f060] ti.sdo.ce.alg - ALG_create> algInit returned IALG_EOK
@2,550,219us: [+0 T:0x4001f060] ti.sdo.ce.alg - ALG_create> Exit (algHandle=0x402ff000)
@2,550,709us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(624) = 0x403ba000.
@2,551,045us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403ba000) = 0x87ff2000.
@2,551,439us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@2,551,682us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@2,552,027us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@2,552,312us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@2,552,497us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@2,552,681us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@2,553,099us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - LockMP_create> key: 0x33414d46 count: 716 attrs: 0xfa118
@2,553,495us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_create> lock[0x10b8e0]
@2,553,755us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b8e0]
@2,553,952us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b8e0]
@2,556,071us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b8e0]
@2,556,390us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b8e0]
@2,557,373us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@2,557,638us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@2,558,113us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(146) = 0x403cc000.
@2,558,353us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cc000) = 0x87fe1000.
@2,558,751us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@2,559,007us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@2,559,254us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@2,559,448us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@2,559,635us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@2,559,819us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@2,559,982us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@2,560,157us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@2,892,418us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@2,892,677us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@2,893,090us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403cd000.
@2,893,382us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cd000) = 0x87fe0000.
@2,893,788us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@2,894,040us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@2,894,222us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@2,894,405us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@2,894,584us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@2,894,829us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@2,894,995us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@2,895,177us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@2,895,625us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@2,895,869us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@2,896,329us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403ce000.
@2,896,568us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403ce000) = 0x87fb8000.
@2,896,956us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@2,897,202us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@2,897,380us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@2,897,630us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@2,897,818us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@2,898,007us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@2,898,176us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@2,898,353us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@2,898,878us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@2,899,128us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@2,899,539us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(74) = 0x403cf000.
@2,899,783us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cf000) = 0x87644000.
@2,900,218us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@2,900,475us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@2,900,659us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@2,900,848us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@2,901,027us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@2,901,215us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@2,901,421us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@2,901,652us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@2,902,263us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@2,902,525us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@2,902,941us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403d0000.
@2,903,227us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403d0000) = 0x87643000.
@2,903,626us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@2,903,882us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@3,236,010us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@3,236,266us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@3,236,461us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@3,236,646us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@3,236,809us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@3,236,989us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@3,237,479us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@3,237,726us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@3,238,141us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403d1000.
@3,238,378us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403d1000) = 0x87642000.
@3,238,825us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@3,239,083us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@3,239,272us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@3,239,460us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@3,239,638us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@3,239,827us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@3,240,045us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@3,240,244us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@3,240,696us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@3,240,945us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@3,241,398us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403d2000.
@3,241,640us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403d2000) = 0x87641000.
@3,242,683us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@3,243,007us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@3,243,195us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@3,243,385us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@3,243,570us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@3,243,758us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@3,243,918us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@3,244,141us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@3,244,607us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@3,244,858us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@3,245,282us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403d3000.
@3,245,563us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403d3000) = 0x875e8000.
@3,245,969us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@3,246,216us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@3,246,395us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@3,246,578us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@3,246,758us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@3,246,997us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@3,247,168us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@3,247,346us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@3,580,181us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@3,580,439us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@3,580,865us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403d4000.
@3,581,156us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403d4000) = 0x875db000.
@3,581,567us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@3,581,817us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@3,582,183us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@3,582,389us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@3,582,628us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@3,582,823us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@3,582,985us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@3,583,161us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@3,583,613us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@3,583,904us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@3,584,335us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403d5000.
@3,584,576us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403d5000) = 0x86e72000.
@3,584,972us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@3,585,267us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@3,585,496us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@3,585,697us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@3,585,876us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@3,586,061us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@3,586,230us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@3,586,410us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@3,586,894us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@3,587,143us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@3,587,572us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403d6000.
@3,587,811us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403d6000) = 0x86e71000.
@3,588,258us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@3,588,516us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@3,588,696us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@3,588,880us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@3,589,058us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@3,589,252us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@3,589,465us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@3,589,655us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@3,590,110us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@3,590,357us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@3,590,832us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403d7000.
@3,591,073us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403d7000) = 0x86e70000.
@3,591,478us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@3,591,735us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@3,923,794us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@3,924,043us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@3,924,242us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@3,924,431us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@3,924,595us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@3,924,776us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@3,925,275us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@3,925,527us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@3,925,949us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403d8000.
@3,926,187us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403d8000) = 0x86e6f000.
@3,926,633us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@3,926,895us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@3,927,077us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@3,927,269us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@3,927,450us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@3,927,632us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@3,927,841us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@3,928,031us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@3,928,501us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@3,928,744us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@3,929,211us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403d9000.
@3,929,458us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403d9000) = 0x86e6e000.
@3,929,855us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@3,930,114us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@3,930,295us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@3,930,529us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@3,930,725us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@3,930,916us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@3,931,079us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@3,931,266us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@3,931,724us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@3,932,166us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@3,932,627us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403da000.
@3,932,871us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403da000) = 0x86e6d000.
@3,933,265us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@3,933,573us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@3,933,756us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@3,933,945us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@3,934,127us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@3,934,310us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@3,934,470us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@3,934,650us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@4,268,017us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@4,268,282us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@4,268,716us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403db000.
@4,269,003us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403db000) = 0x86e6c000.
@4,269,455us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@4,269,717us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@4,269,903us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@4,270,083us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@4,270,309us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@4,270,513us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@4,270,675us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@4,270,849us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@4,271,305us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@4,271,551us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@4,272,187us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403dc000.
@4,272,443us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403dc000) = 0x86e6b000.
@4,272,851us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@4,273,149us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@4,273,339us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@4,273,525us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@4,273,707us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@4,273,890us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@4,274,052us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@4,274,236us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@4,274,728us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@4,274,975us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@4,275,416us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403dd000.
@4,275,661us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403dd000) = 0x86e6a000.
@4,276,108us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@4,276,365us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@4,276,545us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@4,276,727us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@4,276,907us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@4,277,092us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@4,277,307us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@4,277,504us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@4,277,961us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@4,278,205us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@4,278,687us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403de000.
@4,278,931us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403de000) = 0x86e69000.
@4,279,333us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@4,279,587us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@4,611,615us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@4,611,992us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@4,612,215us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@4,612,413us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@4,612,578us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@4,612,756us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@4,613,266us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@4,613,517us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@4,613,950us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403df000.
@4,614,235us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403df000) = 0x86e68000.
@4,614,652us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@4,614,910us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@4,615,092us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@4,615,282us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@4,615,463us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@4,615,703us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@4,615,873us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@4,616,056us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@4,616,521us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@4,616,769us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@4,617,246us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403e0000.
@4,617,490us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403e0000) = 0x86e67000.
@4,617,893us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@4,618,146us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@4,618,365us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@4,618,568us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@4,618,750us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@4,618,937us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@4,619,097us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@4,619,280us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@4,619,803us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@4,620,051us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@4,620,495us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403e1000.
@4,620,740us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403e1000) = 0x86e66000.
@4,621,184us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@4,621,442us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@4,621,624us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@4,621,811us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@4,622,170us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@4,622,381us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@4,622,593us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@4,622,784us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@4,955,808us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@4,956,068us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@4,956,514us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403e2000.
@4,956,806us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403e2000) = 0x86e65000.
@4,957,221us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@4,957,478us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@4,957,657us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@4,957,849us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@4,958,025us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@4,958,274us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@4,958,442us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@4,958,618us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@4,959,077us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@4,959,334us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@4,959,827us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403e3000.
@4,960,077us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403e3000) = 0x86e64000.
@4,960,486us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@4,960,738us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@4,960,965us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@4,961,161us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@4,961,346us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@4,961,537us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@4,961,698us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@4,962,024us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@4,962,573us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@4,962,820us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@4,963,263us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403e4000.
@4,963,507us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403e4000) = 0x86e63000.
@4,963,957us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@4,964,213us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@4,964,391us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@4,964,574us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@4,964,760us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@4,964,946us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@4,965,166us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@4,965,348us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@4,965,807us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@4,966,051us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@4,966,544us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403e5000.
@4,966,791us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403e5000) = 0x86e62000.
@4,967,196us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@4,967,451us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@5,299,408us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@5,299,652us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@5,299,845us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@5,300,038us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@5,300,205us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@5,300,382us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@5,300,883us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@5,301,132us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@5,301,578us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403e6000.
@5,301,824us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403e6000) = 0x86e61000.
@5,302,454us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@5,302,712us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@5,302,886us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@5,303,072us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@5,303,254us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@5,303,488us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@5,303,659us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@5,303,888us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@5,304,359us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@5,304,604us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@5,305,092us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403e7000.
@5,305,348us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403e7000) = 0x86e60000.
@5,305,754us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@5,306,010us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@5,306,244us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@5,306,437us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@5,306,617us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@5,306,804us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@5,306,962us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@5,307,139us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@5,307,638us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@5,307,893us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@5,308,341us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403e8000.
@5,308,581us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403e8000) = 0x86e5f000.
@5,309,030us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@5,309,295us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@5,309,473us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@5,309,657us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@5,309,838us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@5,310,024us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@5,310,190us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@5,310,412us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@5,643,609us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@5,643,871us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@5,644,315us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403e9000.
@5,644,603us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403e9000) = 0x86e5e000.
@5,645,018us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@5,645,277us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@5,645,456us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@5,645,644us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@5,645,823us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@5,646,064us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@5,646,237us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@5,646,421us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@5,646,917us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@5,647,166us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@5,647,662us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(62) = 0x403ea000.
@5,647,910us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403ea000) = 0x86e5d000.
@5,648,321us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@5,648,579us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@5,648,815us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@5,649,003us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@5,649,191us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@5,649,377us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@5,649,535us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@5,649,713us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@5,650,268us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@5,650,520us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@5,650,972us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(98) = 0x403eb000.
@5,651,213us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403eb000) = 0x86e5c000.
@5,651,664us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@5,652,064us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@5,652,279us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@5,652,473us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@5,652,652us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@5,652,888us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@5,653,061us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@5,653,248us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@5,653,752us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@5,654,000us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@5,654,542us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(62) = 0x403ec000.
@5,654,793us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403ec000) = 0x86e5b000.
@5,655,211us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@5,655,466us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@5,988,581us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@5,988,825us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@5,989,021us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@5,989,223us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@5,989,388us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@5,989,566us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@5,990,125us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@5,990,377us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@5,990,833us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(62) = 0x403ed000.
@5,991,076us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403ed000) = 0x86e5a000.
@5,991,553us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@5,991,812us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@5,992,185us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@5,992,386us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@5,992,612us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@5,992,810us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@5,992,975us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@5,993,155us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@5,993,626us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@5,993,919us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@5,994,426us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(56) = 0x403ee000.
@5,994,679us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403ee000) = 0x86e59000.
@5,995,091us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@5,995,392us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@5,995,577us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@5,995,765us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@5,995,946us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@5,996,132us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@5,996,292us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@5,996,469us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@5,997,017us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@5,997,270us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@5,997,718us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(62) = 0x403ef000.
@5,997,964us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403ef000) = 0x86e58000.
@5,998,423us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@5,998,677us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@5,998,853us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@5,999,037us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@5,999,221us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@5,999,406us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@5,999,627us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@5,999,808us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@6,332,707us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(344) = 0x403cb000.
@6,332,965us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@6,333,498us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(296) = 0x403f0000.
@6,333,793us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403f0000) = 0x86e57000.
@6,334,648us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@6,334,929us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@6,335,160us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@6,335,361us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@6,335,539us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@6,335,726us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@6,335,888us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@6,336,059us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@6,336,473us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x403cb000.
@6,336,718us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@6,337,158us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(28) = 0x40433000.
@6,337,394us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x40433000) = 0x86e56000.
@6,338,343us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@6,338,618us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@6,338,804us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@6,338,990us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@6,339,221us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@6,339,433us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@6,339,603us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@6,339,784us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@6,340,139us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(20) = 0x403cb000.
@6,340,378us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x403cb000) = 0x87ff1000.
@6,340,866us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(24) = 0x40636000.
@6,341,116us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x40636000) = 0x86e55000.
@6,341,717us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@6,342,166us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@6,342,369us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x10b200]
@6,342,559us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x10b200]
@6,343,237us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x10b200]
@6,343,527us: [+0 T:0x4001f060] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x10b200]
@6,343,872us: [+0 T:0x4001f060] ti.sdo.ce.alg.Algorithm - Algorithm_create> return (0x10b8b0)
@6,344,079us: [+5 T:0x4001f060] CV - VISA_create> local codec created (name='h264enc', handle=0x10b8b0)
@6,344,258us: [+0 T:0x4001f060] ti.sdo.ce.video1.VIDENC1 - VIDENC1_create> return (0x10b878)
@6,344,411us: [+2 T:0x4001f060] ti.sdo.dmai - [Venc1] Setting dynParams size 352x288 bitrate 2000000
@6,344,564us: [+0 T:0x4001f060] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Enter (handle=0x10b878, id=1, dynParams=0xbed11a04 (size=0x30), status=0xbed118e0 (size=0x9c)
@6,344,781us: [+5 T:0x4001f060] CV - VISA_enter(visa=0x10b878): algHandle = 0x10b8b0
@6,345,005us: [+0 T:0x4001f060] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(alg=0x10b8b0)
@6,345,273us: [+0 T:0x4001f060] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Exit
@6,678,025us: [+5 T:0x4001f060] CV - VISA_exit(visa=0x10b878): algHandle = 0x10b8b0
@6,678,251us: [+0 T:0x4001f060] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(alg=0x10b8b0)
@6,678,502us: [+0 T:0x4001f060] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Exit
@6,678,669us: [+0 T:0x4001f060] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Exit (handle=0x10b878, retVal=0x0)
@6,678,830us: [+2 T:0x4001f060] ti.sdo.dmai - [Venc1] Made XDM_SETPARAMS control call
@6,679,018us: [+0 T:0x4001f060] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Enter (handle=0x10b878, id=5, dynParams=0xbed11a04 (size=0x30), status=0xbed118e0 (size=0x9c)
@6,679,219us: [+5 T:0x4001f060] CV - VISA_enter(visa=0x10b878): algHandle = 0x10b8b0
@6,679,376us: [+0 T:0x4001f060] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(alg=0x10b8b0)
@6,679,602us: [+0 T:0x4001f060] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Exit
@6,679,782us: [+5 T:0x4001f060] CV - VISA_exit(visa=0x10b878): algHandle = 0x10b8b0
@6,679,942us: [+0 T:0x4001f060] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(alg=0x10b8b0)
@6,680,167us: [+0 T:0x4001f060] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Exit
@6,680,377us: [+0 T:0x4001f060] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Exit (handle=0x10b878, retVal=0x0)
@6,680,712us: [+2 T:0x4001f060] ti.sdo.dmai - [BufTab] Allocating BufTab for 1 buffers
@6,681,186us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(152064) = 0x40638000.
@6,681,455us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x40638000) = 0x86e73000.
@6,681,722us: [+2 T:0x4001f060] ti.sdo.dmai - [Buffer] Alloc Buffer of size 152064 at 0x40638000 (0x86e73000 phys)
@6,682,323us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_alloc(152064) = 0x40663000.
@6,682,589us: [+4 T:0x4001f060] OM - Memory_contigAlloc> CMEM_getPhys(0x40663000) = 0x875fc000.
@6,682,818us: [+2 T:0x4001f060] ti.sdo.dmai - [Buffer] Alloc Buffer of size 152064 at 0x40663000 (0x875fc000 phys)

 Frame 0: Read 420P frame size 152064 (352x288) from file
@6,905,143us: [+0 T:0x4001f060] ti.sdo.ce.video1.VIDENC1 - VIDENC1_process> Enter (handle=0x10b878, inBufs=0xbed118a0, outBufs=0xbed11894, inArgs=0xbed11884, outArgs=0xbed1180c)
@6,905,416us: [+5 T:0x4001f060] CV - VISA_enter(visa=0x10b878): algHandle = 0x10b8b0
@6,905,592us: [+0 T:0x4001f060] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(alg=0x10b8b0)
@6,905,831us: [+0 T:0x4001f060] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Exit

at this moment, the top reports:

Mem: 41576K used, 8760K free, 0K shrd, 588K buff, 7856K cached
CPU:  99% usr   0% sys   0% nic   0% idle   0% io   0% irq   0% sirq
Load average: 1.24 0.79 0.31 2/30 2173
  PID  PPID USER     STAT   VSZ %MEM %CPU COMMAND
 2170  2146 root     R     8028  16%  99% ./video_encode_io1_dm365.x470MV -c h264enc -i colorful_toys_cif_5frms_420p.yuv -r 3
 2173  2171 root     R     3056   6%   1% top
 2146     1 root     S     3056   6%   0% -sh
 2171  2135 root     S     3056   6%   0% -sh
 2140     1 root     S     2928   6%   0% /sbin/syslogd -n -C64 -m 20
 2135     1 root     S     2868   6%   0% /usr/sbin/telnetd
 2142     1 root     S     2864   6%   0% /sbin/klogd -n
 2145     1 root     S     1912   4%   0% /sbin/getty 38400 tty1
  989     1 root     S <   1900   4%   0% /sbin/udevd -d
    1     0 root     S     1652   3%   0% init [5]  
 1943     2 root     SWN      0   0%   0% [jffs2_gcd_mtd5]
  971     2 root     SW       0   0%   0% [flush-1:0]
    6     2 root     SW       0   0%   0% [khelper]
  145     2 root     SW       0   0%   0% [sync_supers]
    2     0 root     SW       0   0%   0% [kthreadd]
    3     2 root     SW       0   0%   0% [ksoftirqd/0]
    4     2 root     SW       0   0%   0% [watchdog/0]
    5     2 root     SW       0   0%   0% [events/0]
   10     2 root     SW       0   0%   0% [async/mgr]
  147     2 root     SW       0   0%   0% [bdi-default]
  149     2 root     SW       0   0%   0% [kblockd/0]
  163     2 root     SW       0   0%   0% [khubd]
  166     2 root     SW       0   0%   0% [kseriod]
  201     2 root     SW       0   0%   0% [rpciod/0]
  210     2 root     SW       0   0%   0% [khungtaskd]
  211     2 root     SW       0   0%   0% [kswapd0]
  259     2 root     SW       0   0%   0% [aio/0]
  268     2 root     SW       0   0%   0% [nfsiod]
  275     2 root     SW       0   0%   0% [crypto/0]
  891     2 root     SW       0   0%   0% [mtdblockd]

Thanks again,

Lei

Hi Sajesh,

I found this case is similar with mine.

http://e2e.ti.com/support/embedded/f/356/p/46739/164945.aspx

Yashwant replys:

"

Last week, Beta 2 of DVSDK 3.1 was released.

 http://software-dl.ti.com/dsps/dsps_public_sw/sdo_sb/targetcontent/dvsdk/DVSDK_3_10/latest/index_FDS.html

Please downlaod it and use. It has demo for H.264, Mpeg4 codec. I believe the problem you are facing was a bug is edma driver which was solved quite some time back. The above release has the updated kernel.

This also has Mpeg2 upto DMAI layer, but not available in demo. But it should be helpful.

"

And I notice that the date of the overlay image is Apr 14 2010.

Best Regards,

Lei

Lei,

That issue was fixed in build 16 (Beta 2). Have not you updated Linux kernel?

Regards

Sajesh

Sajesh,

I am using a 2.6.32-rc2 kernel downloaded from here:

http://software-dl.ti.com/dsps/dsps_public_sw/sdo_sb/targetcontent/dvsdk/DVSDK_3_10/latest/index_FDS.html

uname -a
Linux arago 2.6.32-rc2-davinci1 #28 PREEMPT Thu Jun 17 12:17:20 CST 2010 armv5tejl unknown

I changed some deivce driver configs from dm365-default.config to fit my custom board.

And I have tested the capture and display part with the encode demo, not send captured data to the video thread, I can see the captured video in the monitor.

Thanks,

Lei

Sajesh,

I test dmai application (video_encode_io1) again with prebuild images, the result is same. I think maybe it's not a software problem.

This is my environment:

Kernel:

http://arago-project.org/files/releases/davinci-psp_3.x.0.0-r35/images/dm365-evm/uImage-dm365-evm.bin

modules:

http://software-dl.ti.com/dsps/dsps_public_sw/sdo_sb/targetcontent/dvsdk/DVSDK_3_10/latest//exports/dvsdk_3_10_00_16_overlay_dm365.tar.gz

command:

./loadmodules.sh

./video_encode_io1_dm365.x470MV -c h264enc -i colorful_toys_cif_5frms_420p.yuv -r 352x288 -o cif.264

Every component comes from TI without any change.

It's there something I missed?

Best Regards,

Lei

Lei,

All components required to bring up the evm and run the demo is available from the DVSDK download page i.e. http://www.sanb.design.ti.com/tisb_releases/DVSDK_3_10_00/3_10_00_16/index_FDS.html. I would download all components (including u-boot and uImage) from download page and try it.

Regards

Sajesh

Sajesh,

Thanks for your reply again.

I can not access your download page, and I will wait for your reply.

Lei

Lei,

The correct URL is http://software-dl.ti.com/dsps/dsps_public_sw/sdo_sb/targetcontent/dvsdk/DVSDK_3_10/latest/index_FDS.html.

Regards

Sajesh

Sajesh,

How about your test, if the DVSDK 3.10 still can not work, I will try DVSDK 2.0 instead.

Regards

Lei

hi, Sajesh

have you tried with DVSDK 2.0 yet ?