DM6467启动问题到dsp时提示DSP-side failure code等

Heng Lee

Other Parts Discussed in Thread: TVP5158

您好，

我们公司设计了一款基于TI的TMS320DM6467TZUT1平台的编解码器，视频ADC选择的是TVP5158，我们在调试板子的时候，10块demo都出现了启动问题，即板子启动到dsp时提示dsp失败，出现如DSP-side failure code.和DSP-side component was not initialized.等信息，详细的log文档如下。但奇怪的是，板子有的时候启动能成功启动到dsp，有的时候则出错。

请给与帮助和支持，谢谢。

Chip initialization passed!
TI UBL Version: 1.30
Booting Catalog Boot Loader
BootMode = NAND
Starting NAND Copy...
Valid MagicNum found.
DONE
Jumping to entry point at 0x81080000.

U-Boot 1.2.0 (Apr 27 2011 - 16:06:31)

I2C:   ready
DRAM: 256 MB
NAND: 512 MiB
In:    serial
Out:   serial
Err:   serial
ARM Clock :- 297MHz
DDR Clock :- 198MHz
Hit any key to stop autoboot: 0

Loading from NAND 512MiB 3,3V 8-bit, offset 0x200000
   Image Name:   Linux-2.6.10_mvl401-davinci_evm-
   Image Type:   ARM Linux Kernel Image (uncompressed)
   Data Size:    1309808 Bytes = 1.2 MB
   Load Address: 80008000
   Entry Point: 80008000
## Booting image at 80700000 ...
   Image Name:   Linux-2.6.10_mvl401-davinci_evm-
   Image Type:   ARM Linux Kernel Image (uncompressed)
   Data Size:    1309808 Bytes = 1.2 MB
   Load Address: 80008000
   Entry Point: 80008000
   Verifying Checksum ... OK
OK

Starting kernel ...

Uncompressing Linux......................................................................................... done, booting the kernel.
Linux version 2.6.10_mvl401-davinci_evm-PSP_01_30_00_070 (xn@dvm) (gcc version 3.4.3 (MontaVista 3.4.3-25.0.104.0600975 2006-07-06)) #125 Wed Apr 20 14:25:13 CST 2011
CPU: ARM926EJ-Sid(wb) [41069265] revision 5 (ARMv5TEJ)
CPU0: D VIVT write-back cache
CPU0: I cache: 16384 bytes, associativity 4, 32 byte lines, 128 sets
CPU0: D cache: 8192 bytes, associativity 4, 32 byte lines, 64 sets
Machine: DaVinci DM6467 EVM
Memory policy: ECC disabled, Data cache writeback
DM6467
Built 1 zonelists
Kernel command line: mem=80M console=ttyS0,115200n8 root=/dev/mtdblock2 rootfstype=jffs2 noinitrd rw eth=00:00:5b:ee:11:11 ip=192.168.0.144:192.168.0.1:192.168.0.1:255.255.255.0:DM6467 mtdparts=nand_davinci.0:2M(boot),4M(kernel),42M(rootfs),-(data)
TI DaVinci EMAC: Kernel Boot params Eth address: 00:00:5b:ee:11:11
PID hash table entries: 512 (order: 9, 8192 bytes)
Console: colour dummy device 80x30
Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
Memory: 80MB = 80MB total
Memory: 78080KB available (2232K code, 445K data, 160K init)
Mount-cache hash table entries: 512 (order: 0, 4096 bytes)
CPU: Testing write buffer coherency: ok
spawn_desched_task(00000000)
desched cpu_callback 3/00000000
ksoftirqd started up.
desched cpu_callback 2/00000000
desched thread 0 started up.
NET: Registered protocol family 16
DaVinci: 48 gpio irqs
Registering platform device 'serial8250.0'. Parent at platform
Registering platform device 'musb_hdrc'. Parent at platform
Registering platform device 'nand_davinci.0'. Parent at platform
DaVinci I2C DEBUG: 15:17:51 Apr 14 2011
Registering platform device 'i2c'. Parent at platform
SCSI subsystem initialized
usbcore: registered new driver usbfs
usbcore: registered new driver hub
musb_hdrc: version 2.2a/db-0.4.8 [cppi-dma] [host] [debug=0]
musb_hdrc: ConfigData=0x06 (UTMI-8, dyn FIFOs, SoftConn)
musb_hdrc: MHDRC RTL version 1.500
musb_hdrc: USB Host mode controller at c5800000 using DMA, IRQ 13
musb_hdrc musb_hdrc: new USB bus registered, assigned bus number 1
hub 1-0:1.0: USB hub found
hub 1-0:1.0: 1 port detected
JFFS2 version 2.2. (NAND) (C) 2001-2003 Red Hat, Inc.
Registering platform device 'DavinciHD_vdce.2'. Parent at platform
Registering platform device 'relay_ctrl_dvs'. Parent at platform
Registering platform device 'tlp121'. Parent at platform
Major:253 Minor:0
chdrv: Driver initialized.
Serial: 8250/16550 driver $Revision: 1.90 $ 2 ports, IRQ sharing disabled
Registering platform device 'serial8250'. Parent at platform
ttyS0 at MMIO 0x1c20000 (irq = 40) is a ST16654
ttyS1 at MMIO 0x1c20400 (irq = 41) is a ST16650V2
io scheduler noop registered
io scheduler anticipatory registered
RAMDISK driver initialized: 1 RAM disks of 8192K size 1024 blocksize
Registering platform device 'ti_davinci_emac'. Parent at platform
TI DaVinci EMAC: MAC address is 00:08:28:18:28:55
TI DaVinci EMAC Linux version updated 5.0
TI DaVinci EMAC: Installed 1 instances.
netconsole: not configured, aborting
i2c /dev entries driver
Linux video capture interface: v1.00
Registering platform device 'vpif capture.1'. Parent at platform
elevator: using anticipatory as default io scheduler
nand_davinci nand_davinci.0: Using 1-bit hardware ECC
NAND device: Manufacturer ID: 0xec, Chip ID: 0xdc (Samsung NAND 512MiB 3,3V 8-bit)
Scanning device for bad blocks
Bad eraseblock 194 at 0x01840000
Bad eraseblock 383 at 0x02fe0000
Bad eraseblock 611 at 0x04c60000
Bad eraseblock 730 at 0x05b40000
Bad eraseblock 908 at 0x07180000
Bad eraseblock 1170 at 0x09240000
Bad eraseblock 1184 at 0x09400000
Bad eraseblock 1263 at 0x09de0000
Bad eraseblock 1482 at 0x0b940000
Bad eraseblock 1813 at 0x0e2a0000
Bad eraseblock 2014 at 0x0fbc0000
Bad eraseblock 2350 at 0x125c0000
Bad eraseblock 2399 at 0x12be0000
Bad eraseblock 2713 at 0x15320000
Bad eraseblock 2799 at 0x15de0000
Bad eraseblock 3102 at 0x183c0000
Bad eraseblock 3474 at 0x1b240000
Bad eraseblock 3488 at 0x1b400000
Bad eraseblock 3566 at 0x1bdc0000
Bad eraseblock 3687 at 0x1cce0000
4 cmdlinepart partitions found on MTD device nand_davinci.0
Creating 4 MTD partitions on "nand_davinci.0":
0x00000000-0x00200000 : "boot"
0x00200000-0x00600000 : "kernel"
0x00600000-0x03000000 : "rootfs"
0x03000000-0x20000000 : "data"
nand_davinci nand_davinci.0: hardware revision: 2.2
Initializing USB Mass Storage driver...
usbcore: registered new driver usb-storage
USB Mass Storage support registered.
usbcore: registered new driver usbhid
drivers/usb/input/hid-core.c: v2.0:USB HID core driver
mice: PS/2 mouse device common for all mice
NET: Registered protocol family 2
IP: routing cache hash table of 512 buckets, 4Kbytes
TCP: Hash tables configured (established 8192 bind 16384)
NET: Registered protocol family 1
NET: Registered protocol family 17
IP-Config: Complete:
      device=eth0, addr=192.168.0.144, mask=255.255.255.0, gw=192.168.0.1,
     host=DM6467, domain=, nis-domain=(none),
     bootserver=192.168.0.1, rootserver=192.168.0.1, rootpath=
Empty flash at 0x0085a004 ends at 0x0085a800
Empty flash at 0x0085c310 ends at 0x0085c800
Empty flash at 0x0085e574 ends at 0x0085e800
Empty flash at 0x00fa8920 ends at 0x00fa9000
Empty flash at 0x012a406c ends at 0x012a4800
Empty flash at 0x0136c9cc ends at 0x0136d000
Empty flash at 0x0136f118 ends at 0x0136f800
Empty flash at 0x01371890 ends at 0x01372000
Empty flash at 0x01374830 ends at 0x01375000
Empty flash at 0x01377024 ends at 0x01377800
Empty flash at 0x0137986c ends at 0x0137a000
Empty flash at 0x0137c13c ends at 0x0137c800
Empty flash at 0x0137e86c ends at 0x0137f000
Empty flash at 0x017a1008 ends at 0x017a1800
Empty flash at 0x017a3864 ends at 0x017a4000
Empty flash at 0x017a613c ends at 0x017a6800
Empty flash at 0x017a85f0 ends at 0x017a8800
Empty flash at 0x017ac0dc ends at 0x017ac800
Empty flash at 0x017af134 ends at 0x017af800
Empty flash at 0x017b2130 ends at 0x017b2800
Empty flash at 0x017b50e0 ends at 0x017b5800
Empty flash at 0x017b8acc ends at 0x017b9000
VFS: Mounted root (jffs2 filesystem).
Freeing init memory: 160K
INIT: version 2.85 booting
jffs2_get_inode_nodes(): Data CRC failed on node at 0x0085dc28: Read 0xbbd22e14, calculated 0xc0d03052
jffs2_get_inode_nodes(): Data CRC failed on node at 0x0085b9c4: Read 0xbbd22e14, calculated 0xccd43609
jffs2_get_inode_nodes(): CRC failed on node at 0x017a5fc8: Read 0xffffffff, calculated 0x53c5d532
jffs2_get_inode_nodes(): Data CRC failed on node at 0x017a3708: Read 0x56103bd2, calculated 0x351e3a73
jffs2_get_inode_nodes(): Data CRC failed on node at 0x017a0eb4: Read 0x0c5051d3, calculated 0xb24a8632
jffs2_get_inode_nodes(): Data CRC failed on node at 0x0137e714: Read 0x3d36b888, calculated 0x5d4a955e
jffs2_get_inode_nodes(): CRC failed on node at 0x0137bfc8: Read 0xffffffff, calculated 0x53c5d532
jffs2_get_inode_nodes(): Data CRC failed on node at 0x01379714: Read 0xa6bc027e, calculated 0x145513f1
jffs2_get_inode_nodes(): Data CRC failed on node at 0x01376ecc: Read 0xdc7f3f1e, calculated 0xb60999a6
jffs2_get_inode_nodes(): Data CRC failed on node at 0x013746c0: Read 0x9e4e312d, calculated 0xcc2ed600
jffs2_get_inode_nodes(): Data CRC failed on node at 0x01371724: Read 0x7e771017, calculated 0x8e4a00a8
jffs2_get_inode_nodes(): Data CRC failed on node at 0x0136ef98: Read 0x22fb8b83, calculated 0x51e1fb4f
0
jffs2_get_inode_nodes(): CRC failed on node at 0x00fa87c8: Read 0xffffffff, calculated 0x818c2d80
Mounting a tmpfs over /dev...done.
Creating initial device nodes...done.
0
Starting mounting local filesystems: mount none on /var/run type tmpfs (rw)
none on /tmp type tmpfs (rw)
Setting up IP spoofing protection: rp_filter.
Disable TCP/IP Explicit Congestion Notification: done.
Starting network interfaces: done.
Starting hotplug subsystem:
   pci
   pci      [success]
   usb
   usb      [success]
   isapnp
   isapnp   [success]
   ide
   ide      [success]
   input
   input    [success]
   scsi
   scsi     [success]
done.
Starting portmap daemon: portmap.
INIT: Entering runlevel: 3
Starting internet superserver: inetd.
Starting mcenc......ioremap_nocache(0x88000000, 83886080)=0xc5980000
allocated heap buffer 0xc5980000 of size 0xeac000
cmem initialized 4 pools between 0x88000000 and 0x8d000000
dsplinkk: no version for "struct_module" found: kernel tainted.
dsplinkk: module license 'DSP/BIOS(TM) LINK' taints kernel.
DSPLINK Module (1.50) created on Date: Dec 23 2010 Time: 09:55:36
I2C: Module install successful, device major num = 251
DMA: Module install successful, device major num = 250
TVP5158: Module install successful
DRV: Module install successful
DRV: Module built on Sep 19 2010 17:00:06
adapter = c0273b28
Registering Audio Devices. Total communication peripherals             (ASP) : 1
CONFIGURING TVP5158
tvp5158_write_value: C0 00
tvp5158_write_value: C1 88
tvp5158_write_value: C2 88
tvp5158_write_value: C3 68
tvp5158_write_value: C4 01
tvp5158_write_value: C5 00
tvp5158_write_value: C6 00
tvp5158_write_value: C7 00
tvp5158_write_value: C8 00
Codec with ID = 0 on ASP = 0 is initialized.Using minor number : 3
0: video_mode = 2, bitrate = 1000000
1: video_mode = 2, bitrate = 1000000
2: video_mode = 2, bitrate = 1500000
3: video_mode = 2, bitrate = 1500000
4: video_mode = 2, bitrate = 2000000
5: video_mode = 2, bitrate = 2000000
6: video_mode = 2, bitrate = 3000000
7: video_mode = 2, bitrate = 3000000
CE Runtime Initialization Done
jffs2_get_inode_nodes(): Data CRC failed on node at 0x017b827c: Read 0x0bdf83fb, calculated 0xf0e2d95b
jffs2_get_inode_nodes(): Data CRC failed on node at 0x017b4668: Read 0x7856fd5d, calculated 0x993fd763
jffs2_get_inode_nodes(): Data CRC failed on node at 0x017b16b8: Read 0x7856fd5d, calculated 0xde7967ec
jffs2_get_inode_nodes(): Data CRC failed on node at 0x017ae6bc: Read 0x7856fd5d, calculated 0x90002bf1
jffs2_get_inode_nodes(): Data CRC failed on node at 0x017ab664: Read 0x7856fd5d, calculated 0xc8ef3117
jffs2_get_inode_nodes(): Data CRC failed on node at 0x017a7c24: Read 0x7701c666, calculated 0x55981d6f
jffs2_get_inode_nodes(): Data CRC failed on node at 0x0136c49c: Read 0x52487205, calculated 0x2bcd629a
jffs2_get_inode_nodes(): Data CRC failed on node at 0x012a3b3c: Read 0x52487205, calculated 0xc1cf4608
DSP-side configuration mismatch/failure
0              -> success
Positive value -> DSP-side failure code.
(Uint32) -1    -> DSP-side component was not initialized.

    DRV configuration status    [0xffffffff]
    IPS configuration status    [0xffffffff]
    POOL configuration status   [0xffffffff]
    MPCS configuration status   [0xffffffff]
    MPLIST configuration status [0xffffffff]
    MQT configuration status    [0xffffffff]
    RINGIO configuration status [0xffffffff]
Enter 'q' to quit: @0x000863d4:[T:0x00004002] OP - Processor_create_d> Loading and starting DSP server 'dm6467_combos.x64P' FAILED, status=[0x80008052] (look for error code 'DSP_EBASE + 0x52' in dsplink*/packages/dsplink/gpp/inc/errbase.h)
@0x00086567:[T:0x00004002] OP - Processor_delete_d> Closing remote transport FAILED, status=0x80008000.
@0x000865fd:[T:0x00004002] OP - Processor_delete_d> Stopping DSP FAILED, status=0x80008000
@0x000889ea:[T:0x00008003] CE - rserverOpen: can't start 'dm6467_combos.x64P'; Processor_create failed
Fail in Engine Open
==== Audio encoder thread ====

14 年多前

0 Feng Dong 14 年多前

TI__Expert 8140 points

需要codec engine的log.

0 Heng Lee 14 年多前

Prodigy 30 points

这是coder engine log

##################################################################################

# Fail, CE_DEBUG=1

root@DM6467:/opt/video_copy# CE_DEBUG=1 ./video_copy.x470MV in.dat /dev/null

App-> Application started.

@0,290,093us: [+6 T:0x00004000] CE - Engine_init> CE debugging on (CE_DEBUG=1; allowed CE_DEBUG levels: 1=min, 2=good, 3=max)

DSP-side configuration mismatch/failure

0 -> success

Positive value -> DSP-side failure code.

(Uint32) -1 -> DSP-side component was not initialized.

DRV configuration status [0xffffffff]

IPS configuration status [0xffffffff]

POOL configuration status [0xffffffff]

MPCS configuration status [0xffffffff]

MPLIST configuration status [0xffffffff]

MQT configuration status [0xffffffff]

RINGIO configuration status [0xffffffff]

@11,316,898us: [+7 T:0x00004002] OP - Processor_create_d> Loading and starting DSP server 'video_copy.x64P' FAILED, status=[0x80008052] (look for error code 'DSP_EBASE + 0x52' in dsplink*/packages/dsplink/gpp/inc/errbase.h)

@11,317,215us: [+6 T:0x00004002] OP - Processor_delete_d> Closing remote transport FAILED, status=0x80008000.

@11,317,394us: [+6 T:0x00004002] OP - Processor_delete_d> Stopping DSP FAILED, status=0x80008000

@11,331,544us: [+6 T:0x00004000] CE - rserverOpen: can't start 'video_copy.x64P'; Processor_create failed

CEapp-> ERROR: can't open engine video_copy

App-> Application FAILED.

##################################################################################

# Fail, CE_DEBUG=2

root@DM6467:/opt/video_copy# CE_DEBUG=2 ./video_copy.x470MV in.dat /dev/null

App-> Application started.

@0,278,339us: [+4 T:0x00004000] OG - Global_init> This program was built with the following packages:

@0,278,645us: [+4 T:0x00004000] OG - package gnu.targets.rts470MV (/opt/dvsdk_1_40_02_33/xdc_3_00_06/packages/gnu/targets/rts470MV/) [1,0,0,0,1203621000516]

@0,278,785us: [+4 T:0x00004000] OG - package ti.xdais.dm (/opt/dvsdk_1_40_02_33/xdais_6_10_01/packages/ti/xdais/dm/) [1,0,4,1210262746529]

@0,278,909us: [+4 T:0x00004000] OG - package ti.xdais (/opt/dvsdk_1_40_02_33/xdais_6_10_01/packages/ti/xdais/) [1,2,1,1210262742149]

@0,279,050us: [+4 T:0x00004000] OG - package ti.sdo.utils.trace (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/utils/trace/) [1,0,0,1220943366422]

@0,279,185us: [+4 T:0x00004000] OG - package ti.sdo.ce.utils.xdm (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/utils/xdm/) [1,0,1,1220951322616]

@0,279,316us: [+4 T:0x00004000] OG - package ti.sdo.fc.dman3 (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/fc/dman3/) [1,0,3,1220943053023]

@0,279,442us: [+4 T:0x00004000] OG - package ti.sdo.fc.acpy3 (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/fc/acpy3/) [1,0,2,1220943014135]

@0,279,571us: [+4 T:0x00004000] OG - package dsplink.gpp (/opt/dvsdk_1_40_02_33/dsplink-davinci-v1.50-prebuilt/packages/dsplink/gpp/) [3,0,0]

@0,279,689us: [+4 T:0x00004000] OG - package ti.sdo.linuxutils.cmem (/opt/dvsdk_1_40_02_33/cmem_2_10/packages/ti/sdo/linuxutils/cmem/) [2,0,1,1204929560755]

@0,279,814us: [+4 T:0x00004000] OG - package ti.catalog.c470 (/opt/dvsdk_1_40_02_33/xdc_3_00_06/packages/ti/catalog/c470/) [1,0,1,0,1203561761475]

@0,279,934us: [+4 T:0x00004000] OG - package ti.catalog.c6000 (/opt/dvsdk_1_40_02_33/xdc_3_00_06/packages/ti/catalog/c6000/) [1,0,0,0,1203561781695]

@0,280,467us: [+4 T:0x00004000] OG - package ti.platforms.evmDM6467 (/opt/dvsdk_1_40_02_33/bios_5_32_01/packages/ti/platforms/evmDM6467/) [1,0,0,0,1192229633217]

@0,280,647us: [+4 T:0x00004000] OG - package ti.sdo.ce.osal (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/osal/) [2,0,2,1220951127739]

@0,280,784us: [+4 T:0x00004000] OG - package ti.sdo.ce.ipc (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/ipc/) [2,0,1,1220951085787]

@0,280,909us: [+4 T:0x00004000] OG - package ti.sdo.ce.alg (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/alg/) [1,0,1,1220950781938]

@0,281,031us: [+4 T:0x00004000] OG - package ti.sdo.ce.osal.linux (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/osal/linux/) [2,0,1,1220951140072]

@0,281,159us: [+4 T:0x00004000] OG - package ti.sdo.ce.ipc.dsplink (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/ipc/dsplink/) [2,0,1,1220951096818]

@0,281,286us: [+4 T:0x00004000] OG - package ti.sdo.ce (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/) [1,0,6,1220950771473]

@0,281,403us: [+4 T:0x00004000] OG - package ti.sdo.ce.video (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/video/) [1,0,3,1220951334122]

@0,281,525us: [+4 T:0x00004000] OG - package ti.codecs.viddec_copy (/home/xn/nfs/opt/video_copy/ti/codecs/viddec_copy/) []

@0,281,634us: [+4 T:0x00004000] OG - package ti.codecs.videnc_copy (/home/xn/nfs/opt/video_copy/ti/codecs/videnc_copy/) []

@0,281,742us: [+4 T:0x00004000] OG - package ti.sdo.ce.bioslog (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/bioslog/) [1,0,1,1220950800329]

@0,281,867us: [+4 T:0x00004000] OG - package ti.sdo.ce.utils.trace (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/utils/trace/) [1,0,1,1220951317087]

@0,281,995us: [+4 T:0x00004000] OG - package ti.apps.video_copy (/home/xn/nfs/opt/video_copy/ti/apps/video_copy/) []

@0,282,551us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Sem_create> count: 0

@0,282,701us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Leaving Sem_create> sem[0x3f508]

@0,282,813us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Sem_create> count: 0

@0,282,912us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Leaving Sem_create> sem[0x3f520]

@0,283,021us: [+0 T:0x00004000] OT - Thread_create> Enter (fxn=0x14464, attrs=0x0)

@0,482,349us: [+0 T:0x00004000] OT - Thread_create> Exit (task=0x3f558)

@0,482,527us: [+0 T:0x00004000] ti.sdo.ce.alg - ALG_init> Enter

@0,482,635us: [+0 T:0x00004000] ti.sdo.ce.alg - ALG_init> Exit

@0,482,814us: [+6 T:0x00004000] CE - Engine_init> CE debugging on (CE_DEBUG=2; allowed CE_DEBUG levels: 1=min, 2=good, 3=max)

@0,483,013us: [+0 T:0x00004000] CS - Server_init()

@0,483,108us: [+0 T:0x00004000] CS - Server_init> Global_useLinkArbiter = 0

@0,483,336us: [+0 T:0x00004000] CE - Engine_open> Enter('video_copy', 0x0, 0xbefffd64)

@0,483,503us: [+0 T:0x00004000] CE - rserverOpen('video_copy.x64P'), count = 0

@0,483,636us: [+0 T:0x00004000] OP - Processor_create> Enter(imageName='video_copy.x64P', linkCfg='(null)', attrs=0xbefffd68)

@0,483,829us: [+0 T:0x00004000] OP - doCmd> Enter (cmdId=1, proc=0x417c0)

@0,483,942us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x3f508]

@0,484,064us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x3f508]

@0,484,171us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x3f520] timeout[0xffffffff]

@0,484,492us: [+1 T:0x00004002] OP - daemon> thread created.

@0,484,611us: [+0 T:0x00004002] OP - getCmd_d> Enter (proc=0xbe7ffaec)

@0,484,710us: [+0 T:0x00004002] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x3f508] timeout[0xffffffff]

@0,484,822us: [+0 T:0x00004002] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x3f508] status[0]

@0,484,925us: [+0 T:0x00004002] OP - getCmd_d> Exit (result=1)

@0,485,031us: [+0 T:0x00004002] OP - Processor_create_d> Enter(proc=0x417c0)

@0,485,128us: [+2 T:0x00004002] OP - Processor_create_d> Initializing DSP PROC...

@0,485,232us: [+2 T:0x00004002] OP - Processor_create_d> Using DspLink config data for entry #0 [server 'video_copy.x64P']

@0,485,386us: [+2 T:0x00004002] OP - Processor_create_d> Adding DSP segment #0 to Link configuration: name='DDR2', startAddress=0x8fa00000, sizeInBytes=0x400000, shared=1

@0,485,548us: [+2 T:0x00004002] OP - Processor_create_d> Adding DSP segment #1 to Link configuration: name='DSPLINKMEM', startAddress=0x8fe00000, sizeInBytes=0x100000, shared=1

@0,485,700us: [+2 T:0x00004002] OP - Processor_create_d> Adding DSP segment #2 to Link configuration: name='RESET_VECTOR', startAddress=0x8ff00000, sizeInBytes=0x80, shared=0

@0,485,845us: [+2 T:0x00004002] OP - Processor_create_d> Adding DSP segment #3 to Link configuration: name='DDRALGHEAP', startAddress=0x88000000, sizeInBytes=0x7a00000, shared=0

@0,485,993us: [+2 T:0x00004002] OP - Processor_create_d> DOPOWERCONTROL was=0; now=0

@0,487,780us: [+2 T:0x00004002] OP - Processor_create_d> Attaching to DSP PROC...

@0,495,440us: [+2 T:0x00004002] OP - Processor_create_d> Opening MSGQ pool...

@0,495,829us: [+2 T:0x00004002] OP - Processor_create_d> Loading video_copy.x64P on DSP (1 args)...

@0,516,647us: [+2 T:0x00004002] OP - Processor_create_d> Starting DSP PROC...

DSP-side configuration mismatch/failure

0 -> success

Positive value -> DSP-side failure code.

(Uint32) -1 -> DSP-side component was not initialized.

DRV configuration status [0xffffffff]

IPS configuration status [0xffffffff]

POOL configuration status [0xffffffff]

MPCS configuration status [0xffffffff]

MPLIST configuration status [0xffffffff]

MQT configuration status [0xffffffff]

RINGIO configuration status [0xffffffff]

@11,513,027us: [+7 T:0x00004002] OP - Processor_create_d> Loading and starting DSP server 'video_copy.x64P' FAILED, status=[0x80008052] (look for error code 'DSP_EBASE + 0x52' in dsplink*/packages/dsplink/gpp/inc/errbase.h)

@11,513,259us: [+0 T:0x00004002] OP - Processor_delete_d> Enter (proc=0x417c0)

@11,513,389us: [+2 T:0x00004002] OP - Processor_delete_d> Closing remote transport...

@11,513,546us: [+6 T:0x00004002] OP - Processor_delete_d> Closing remote transport FAILED, status=0x80008000.

@11,513,674us: [+2 T:0x00004002] OP - Processor_delete_d> Stopping DSP...

@11,513,790us: [+6 T:0x00004002] OP - Processor_delete_d> Stopping DSP FAILED, status=0x80008000

@11,513,902us: [+2 T:0x00004002] OP - Processor_delete_d> Closing pool...

@11,514,256us: [+2 T:0x00004002] OP - Processor_delete_d> Detaching from DSP...

@11,527,505us: [+2 T:0x00004002] OP - Processor_delete_d> Destroying DSP... (object, that is)

@11,528,270us: [+0 T:0x00004002] OP - Processor_delete_d> return

@11,528,411us: [+2 T:0x00004002] OP - Processor_create_d> return (0)

@11,528,514us: [+0 T:0x00004002] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x3f520]

@11,528,666us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x3f520] status[0]

@11,528,791us: [+0 T:0x00004000] OP - doCmd> Exit (result=2)

@11,528,883us: [+0 T:0x00004000] OP - Processor_delete> Enter(proc=0x417c0)

@11,528,979us: [+1 T:0x00004000] OP - Processor_delete(0x417c0) freeing object ...

@11,529,079us: [+0 T:0x00004000] OP - Processor_delete> return.

@11,529,166us: [+6 T:0x00004000] CE - rserverOpen: can't start 'video_copy.x64P'; Processor_create failed

@11,529,273us: [+0 T:0x00004000] CE - rserverOpen('video_copy.x64P'): 0x0 done.

@11,529,366us: [+0 T:0x00004000] CE - Engine_close(0x41790)

@11,529,460us: [+0 T:0x00004000] CE - Engine_open> return(0)

CEapp-> ERROR: can't open engine video_copy

App-> Application FAILED.

@11,529,922us: [+0 T:0x00004000] OP - doCmd> Enter (cmdId=3, proc=0x0)

@11,530,105us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x3f508]

@11,530,218us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x3f508]

@11,530,317us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x3f520] timeout[0xffffffff]

@11,530,449us: [+0 T:0x00004002] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x3f520]

@11,530,560us: [+0 T:0x00004002] OP - getCmd_d> Enter (proc=0xbe7ffaec)

@11,530,655us: [+0 T:0x00004002] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x3f508] timeout[0xffffffff]

@11,530,761us: [+0 T:0x00004002] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x3f508] status[0]

@11,530,863us: [+0 T:0x00004002] OP - getCmd_d> Exit (result=3)

@11,530,948us: [+0 T:0x00004002] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x3f520]

@11,531,092us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x3f520] status[0]

@11,531,213us: [+0 T:0x00004000] OP - doCmd> Exit (result=1)

@11,531,304us: [+0 T:0x00004000] OT - Thread_delete> Enter (task=0x3f558)

@11,531,442us: [+4 T:0x00004000] OT - Thread_delete> pthread_cancel (0x0)

@11,531,766us: [+4 T:0x00004000] OT - Thread_delete> pthread_join (0x0)

@11,531,896us: [+0 T:0x00004000] OT - Thread_delete> Exit (task=0x3f558)

@11,531,997us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Entered Sem_delete> sem[0x3f508]

@11,532,123us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Leaving Sem_delete>

@11,532,219us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Entered Sem_delete> sem[0x3f520]

@11,532,324us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Leaving Sem_delete>

#################################################################################

# Fail, CE_DEBUG=3

root@DM6467:/opt/video_copy# CE_DEBUG=3 ./video_copy.x470MV in.dat /dev/null

App-> Application started.

@0,668,472us: [+4 T:0x00004000 S:0xbefffd64] OG - Global_init> This program was built with the following packages:

@0,668,919us: [+4 T:0x00004000 S:0xbefffd64] OG - package gnu.targets.rts470MV (/opt/dvsdk_1_40_02_33/xdc_3_00_06/packages/gnu/targets/rts470MV/) [1,0,0,0,1203621000516]

@0,669,155us: [+4 T:0x00004000 S:0xbefffd64] OG - package ti.xdais.dm (/opt/dvsdk_1_40_02_33/xdais_6_10_01/packages/ti/xdais/dm/) [1,0,4,1210262746529]

@0,669,321us: [+4 T:0x00004000 S:0xbefffd64] OG - package ti.xdais (/opt/dvsdk_1_40_02_33/xdais_6_10_01/packages/ti/xdais/) [1,2,1,1210262742149]

@0,669,540us: [+4 T:0x00004000 S:0xbefffd64] OG - package ti.sdo.utils.trace (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/utils/trace/) [1,0,0,1220943366422]

@0,669,713us: [+4 T:0x00004000 S:0xbefffd64] OG - package ti.sdo.ce.utils.xdm (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/utils/xdm/) [1,0,1,1220951322616]

@0,669,867us: [+4 T:0x00004000 S:0xbefffd64] OG - package ti.sdo.fc.dman3 (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/fc/dman3/) [1,0,3,1220943053023]

@0,670,402us: [+4 T:0x00004000 S:0xbefffd64] OG - package ti.sdo.fc.acpy3 (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/fc/acpy3/) [1,0,2,1220943014135]

@0,670,588us: [+4 T:0x00004000 S:0xbefffd64] OG - package dsplink.gpp (/opt/dvsdk_1_40_02_33/dsplink-davinci-v1.50-prebuilt/packages/dsplink/gpp/) [3,0,0]

@0,670,824us: [+4 T:0x00004000 S:0xbefffd64] OG - package ti.sdo.linuxutils.cmem (/opt/dvsdk_1_40_02_33/cmem_2_10/packages/ti/sdo/linuxutils/cmem/) [2,0,1,1204929560755]

@0,670,984us: [+4 T:0x00004000 S:0xbefffd64] OG - package ti.catalog.c470 (/opt/dvsdk_1_40_02_33/xdc_3_00_06/packages/ti/catalog/c470/) [1,0,1,0,1203561761475]

@0,671,133us: [+4 T:0x00004000 S:0xbefffd64] OG - package ti.catalog.c6000 (/opt/dvsdk_1_40_02_33/xdc_3_00_06/packages/ti/catalog/c6000/) [1,0,0,0,1203561781695]

@0,671,278us: [+4 T:0x00004000 S:0xbefffd64] OG - package ti.platforms.evmDM6467 (/opt/dvsdk_1_40_02_33/bios_5_32_01/packages/ti/platforms/evmDM6467/) [1,0,0,0,1192229633217]

@0,671,430us: [+4 T:0x00004000 S:0xbefffd64] OG - package ti.sdo.ce.osal (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/osal/) [2,0,2,1220951127739]

@0,671,577us: [+4 T:0x00004000 S:0xbefffd64] OG - package ti.sdo.ce.ipc (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/ipc/) [2,0,1,1220951085787]

@0,671,786us: [+4 T:0x00004000 S:0xbefffd64] OG - package ti.sdo.ce.alg (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/alg/) [1,0,1,1220950781938]

@0,671,939us: [+4 T:0x00004000 S:0xbefffd64] OG - package ti.sdo.ce.osal.linux (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/osal/linux/) [2,0,1,1220951140072]

@0,672,096us: [+4 T:0x00004000 S:0xbefffd64] OG - package ti.sdo.ce.ipc.dsplink (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/ipc/dsplink/) [2,0,1,1220951096818]

@0,672,252us: [+4 T:0x00004000 S:0xbefffd64] OG - package ti.sdo.ce (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/) [1,0,6,1220950771473]

@0,672,413us: [+4 T:0x00004000 S:0xbefffd64] OG - package ti.sdo.ce.video (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/video/) [1,0,3,1220951334122]

@0,672,568us: [+4 T:0x00004000 S:0xbefffd64] OG - package ti.codecs.viddec_copy (/home/xn/nfs/opt/video_copy/ti/codecs/viddec_copy/) []

@0,672,702us: [+4 T:0x00004000 S:0xbefffd64] OG - package ti.codecs.videnc_copy (/home/xn/nfs/opt/video_copy/ti/codecs/videnc_copy/) []

@0,672,834us: [+4 T:0x00004000 S:0xbefffd64] OG - package ti.sdo.ce.bioslog (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/bioslog/) [1,0,1,1220950800329]

@0,672,982us: [+4 T:0x00004000 S:0xbefffd64] OG - package ti.sdo.ce.utils.trace (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/utils/trace/) [1,0,1,1220951317087]

@0,673,135us: [+4 T:0x00004000 S:0xbefffd64] OG - package ti.apps.video_copy (/home/xn/nfs/opt/video_copy/ti/apps/video_copy/) []

@0,673,333us: [+0 T:0x00004000 S:0xbefffd6c] OG - Global_atexit> enter (fxn=0x19958)

@0,871,841us: [+0 T:0x00004000 S:0xbefffd6c] OG - Global_atexit> enter (fxn=0x18df0)

@0,872,061us: [+0 T:0x00004000 S:0xbefffd3c] OM - Memory_alloc> Enter(0x18)

@0,872,226us: [+0 T:0x00004000 S:0xbefffd3c] OM - Memory_alloc> return (0x3f438)

@0,872,515us: [+0 T:0x00004000 S:0xbefffd5c] OG - Global_atexit> enter (fxn=0x176d8)

@0,872,731us: [+0 T:0x00004000 S:0xbefffd3c] OM - Memory_alloc> Enter(0x18)

@0,872,868us: [+0 T:0x00004000 S:0xbefffd3c] OM - Memory_alloc> return (0x3f488)

@0,872,989us: [+0 T:0x00004000 S:0xbefffd5c] OG - Global_atexit> enter (fxn=0x15324)

@0,873,156us: [+0 T:0x00004000 S:0xbefffd64] OG - Global_atexit> enter (fxn=0x18474)

@0,873,335us: [+0 T:0x00004000 S:0xbefffd4c] ti.sdo.ce.osal.Sem - Sem_create> count: 0

@0,873,488us: [+0 T:0x00004000 S:0xbefffd34] OM - Memory_alloc> Enter(0x14)

@0,873,613us: [+0 T:0x00004000 S:0xbefffd34] OM - Memory_alloc> return (0x3f508)

@0,873,748us: [+0 T:0x00004000 S:0xbefffd4c] ti.sdo.ce.osal.Sem - Leaving Sem_create> sem[0x3f508]

@0,873,878us: [+0 T:0x00004000 S:0xbefffd4c] ti.sdo.ce.osal.Sem - Sem_create> count: 0

@0,873,991us: [+0 T:0x00004000 S:0xbefffd34] OM - Memory_alloc> Enter(0x14)

@0,874,108us: [+0 T:0x00004000 S:0xbefffd34] OM - Memory_alloc> return (0x3f520)

@0,874,222us: [+0 T:0x00004000 S:0xbefffd4c] ti.sdo.ce.osal.Sem - Leaving Sem_create> sem[0x3f520]

@0,874,344us: [+0 T:0x00004000 S:0xbefffd34] OM - Memory_alloc> Enter(0x18)

@0,874,458us: [+0 T:0x00004000 S:0xbefffd34] OM - Memory_alloc> return (0x3f538)

@0,874,574us: [+0 T:0x00004000 S:0xbefffd34] OT - Thread_create> Enter (fxn=0x14464, attrs=0x0)

@0,874,693us: [+0 T:0x00004000 S:0xbefffd1c] OM - Memory_alloc> Enter(0x64)

@0,874,806us: [+0 T:0x00004000 S:0xbefffd1c] OM - Memory_alloc> return (0x3f558)

@0,875,575us: [+0 T:0x00004000 S:0xbefffd34] OT - Thread_create> Exit (task=0x3f558)

@0,875,732us: [+0 T:0x00004000 S:0xbefffd6c] OG - Global_atexit> enter (fxn=0x14024)

@0,875,880us: [+0 T:0x00004000 S:0xbefffd6c] OG - Global_atexit> enter (fxn=0x16118)

@0,876,037us: [+0 T:0x00004000 S:0xbefffd54] ti.sdo.ce.alg - ALG_init> Enter

@0,876,160us: [+0 T:0x00004000 S:0xbefffd44] OG - Global_atexit> enter (fxn=0x1a320)

@0,876,286us: [+0 T:0x00004000 S:0xbefffd74] ti.sdo.ce.alg - ALG_init> Exit

@0,876,398us: [+0 T:0x00004000 S:0xbefffd6c] OG - Global_atexit> enter (fxn=0x19dc0)

@0,876,532us: [+0 T:0x00004000 S:0xbefffd44] OM - Memory_alloc> Enter(0x18)

@0,876,659us: [+0 T:0x00004000 S:0xbefffd44] OM - Memory_alloc> return (0x41690)

@0,876,799us: [+0 T:0x00004000 S:0xbefffd6c] OG - Global_atexit> enter (fxn=0x185d8)

@0,876,990us: [+6 T:0x00004000 S:0xbefffd6c] CE - Engine_init> CE debugging on (CE_DEBUG=3; allowed CE_DEBUG levels: 1=min, 2=good, 3=max)

@0,877,150us: [+0 T:0x00004000 S:0xbefffd5c] OG - Global_atexit> enter (fxn=0x11064)

@0,877,277us: [+0 T:0x00004000 S:0xbefffd3c] OM - Memory_alloc> Enter(0x18)

@0,877,399us: [+0 T:0x00004000 S:0xbefffd3c] OM - Memory_alloc> return (0x416d0)

@0,877,520us: [+0 T:0x00004000 S:0xbefffd3c] OM - Memory_alloc> Enter(0x18)

@0,877,638us: [+0 T:0x00004000 S:0xbefffd3c] OM - Memory_alloc> return (0x416f0)

@0,877,754us: [+0 T:0x00004000 S:0xbefffd3c] OM - Memory_alloc> Enter(0x18)

@0,877,874us: [+0 T:0x00004000 S:0xbefffd3c] OM - Memory_alloc> return (0x41710)

@0,878,038us: [+0 T:0x00004000 S:0xbefffd6c] CS - Server_init()

@0,878,157us: [+0 T:0x00004000 S:0xbefffd6c] CS - Server_init> Global_useLinkArbiter = 0

@0,878,281us: [+0 T:0x00004000 S:0xbefffd6c] OG - Global_atexit> enter (fxn=0xfbb4)

@0,878,476us: [+0 T:0x00004000 S:0xbefffd7c] OG - Global_atexit> enter (fxn=0xcc54)

@0,878,670us: [+0 T:0x00004000 S:0xbefffd1c] CE - Engine_open> Enter('video_copy', 0x0, 0xbefffd64)

@0,878,843us: [+0 T:0x00004000 S:0xbefffd04] OM - Memory_alloc> Enter(0x2c)

@0,878,971us: [+0 T:0x00004000 S:0xbefffd04] OM - Memory_alloc> return (0x41790)

@0,879,096us: [+0 T:0x00004000 S:0xbefffd1c] CE - rserverOpen('video_copy.x64P'), count = 0

@1,077,878us: [+0 T:0x00004000 S:0xbefffce4] OP - Processor_create> Enter(imageName='video_copy.x64P', linkCfg='(null)', attrs=0xbefffd68)

@1,078,100us: [+0 T:0x00004000 S:0xbefffccc] OM - Memory_alloc> Enter(0x24)

@1,078,244us: [+0 T:0x00004000 S:0xbefffccc] OM - Memory_alloc> return (0x41848)

@1,078,372us: [+0 T:0x00004000 S:0xbefffcc4] OP - doCmd> Enter (cmdId=1, proc=0x41848)

@1,078,497us: [+0 T:0x00004000 S:0xbefffcb4] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x3f508]

@1,078,640us: [+0 T:0x00004000 S:0xbefffcc4] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x3f508]

@1,078,772us: [+0 T:0x00004000 S:0xbefffca4] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x3f520] timeout[0xffffffff]

@1,080,050us: [+1 T:0x00004002 S:0xbe7ffa74] OP - daemon> thread created.

@1,080,195us: [+0 T:0x00004002 S:0xbe7ffa74] OP - getCmd_d> Enter (proc=0xbe7ffaec)

@1,080,320us: [+0 T:0x00004002 S:0xbe7ffa54] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x3f508] timeout[0xffffffff]

@1,080,458us: [+0 T:0x00004002 S:0xbe7ffa54] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x3f508] status[0]

@1,080,588us: [+0 T:0x00004002 S:0xbe7ffa74] OP - getCmd_d> Exit (result=1)

@1,080,702us: [+0 T:0x00004002 S:0xbe7ffa74] OP - Processor_create_d> Enter(proc=0x41848)

@1,080,822us: [+2 T:0x00004002 S:0xbe7ffa74] OP - Processor_create_d> Initializing DSP PROC...

@1,080,949us: [+2 T:0x00004002 S:0xbe7ffa74] OP - Processor_create_d> Using DspLink config data for entry #0 [server 'video_copy.x64P']

@1,081,097us: [+0 T:0x00004002 S:0xbe7ffa5c] OM - Memory_alloc> Enter(0xe0)

@1,081,247us: [+0 T:0x00004002 S:0xbe7ffa5c] OM - Memory_alloc> return (0x41870)

@1,081,396us: [+2 T:0x00004002 S:0xbe7ffa74] OP - Processor_create_d> Adding DSP segment #0 to Link configuration: name='DDR2', startAddress=0x8fa00000, sizeInBytes=0x400000, shared=1

@1,081,583us: [+2 T:0x00004002 S:0xbe7ffa74] OP - Processor_create_d> Adding DSP segment #1 to Link configuration: name='DSPLINKMEM', startAddress=0x8fe00000, sizeInBytes=0x100000, shared=1

@1,081,756us: [+2 T:0x00004002 S:0xbe7ffa74] OP - Processor_create_d> Adding DSP segment #2 to Link configuration: name='RESET_VECTOR', startAddress=0x8ff00000, sizeInBytes=0x80, shared=0

@1,081,928us: [+2 T:0x00004002 S:0xbe7ffa74] OP - Processor_create_d> Adding DSP segment #3 to Link configuration: name='DDRALGHEAP', startAddress=0x88000000, sizeInBytes=0x7a00000, shared=0

@1,082,102us: [+2 T:0x00004002 S:0xbe7ffa74] OP - Processor_create_d> DOPOWERCONTROL was=0; now=0

@1,083,903us: [+2 T:0x00004002 S:0xbe7ffa74] OP - Processor_create_d> Attaching to DSP PROC...

@1,091,615us: [+2 T:0x00004002 S:0xbe7ffa74] OP - Processor_create_d> Opening MSGQ pool...

@1,092,033us: [+2 T:0x00004002 S:0xbe7ffa74] OP - Processor_create_d> Loading video_copy.x64P on DSP (1 args)...

@1,112,848us: [+2 T:0x00004002 S:0xbe7ffa74] OP - Processor_create_d> Starting DSP PROC...

DSP-side configuration mismatch/failure

0 -> success

Positive value -> DSP-side failure code.

(Uint32) -1 -> DSP-side component was not initialized.

DRV configuration status [0xffffffff]

IPS configuration status [0xffffffff]

POOL configuration status [0xffffffff]

MPCS configuration status [0xffffffff]

MPLIST configuration status [0xffffffff]

MQT configuration status [0xffffffff]

RINGIO configuration status [0xffffffff]

@12,117,889us: [+7 T:0x00004002 S:0xbe7ffa74] OP - Processor_create_d> Loading and starting DSP server 'video_copy.x64P' FAILED, status=[0x80008052] (look for error code 'DSP_EBASE + 0x52' in dsplink*/packages/dsplink/gpp/inc/errbase.h)

@12,118,150us: [+0 T:0x00004002 S:0xbe7ffa54] OP - Processor_delete_d> Enter (proc=0x41848)

@12,118,307us: [+2 T:0x00004002 S:0xbe7ffa54] OP - Processor_delete_d> Closing remote transport...

@12,118,490us: [+6 T:0x00004002 S:0xbe7ffa54] OP - Processor_delete_d> Closing remote transport FAILED, status=0x80008000.

@12,118,645us: [+2 T:0x00004002 S:0xbe7ffa54] OP - Processor_delete_d> Stopping DSP...

@12,118,789us: [+6 T:0x00004002 S:0xbe7ffa54] OP - Processor_delete_d> Stopping DSP FAILED, status=0x80008000

@12,118,929us: [+2 T:0x00004002 S:0xbe7ffa54] OP - Processor_delete_d> Closing pool...

@12,119,311us: [+2 T:0x00004002 S:0xbe7ffa54] OP - Processor_delete_d> Detaching from DSP...

@12,132,582us: [+2 T:0x00004002 S:0xbe7ffa54] OP - Processor_delete_d> Destroying DSP... (object, that is)

@12,133,400us: [+0 T:0x00004002 S:0xbe7ffa2c] OM - Memory_free> Enter(0x41870, 0x0)

@12,133,565us: [+0 T:0x00004002 S:0xbe7ffa2c] OM - Memory_free> return (0x1)

@12,133,691us: [+0 T:0x00004002 S:0xbe7ffa74] OP - Processor_delete_d> return

@12,133,805us: [+2 T:0x00004002 S:0xbe7ffa74] OP - Processor_create_d> return (0)

@12,133,924us: [+0 T:0x00004002 S:0xbe7ffa64] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x3f520]

@12,134,102us: [+0 T:0x00004000 S:0xbefffca4] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x3f520] status[0]

@12,134,251us: [+0 T:0x00004000 S:0xbefffcc4] OP - doCmd> Exit (result=2)

@12,134,367us: [+0 T:0x00004000 S:0xbefffcd4] OP - Processor_delete> Enter(proc=0x41848)

@12,134,487us: [+1 T:0x00004000 S:0xbefffcd4] OP - Processor_delete(0x41848) freeing object ...

@12,134,606us: [+0 T:0x00004000 S:0xbefffcac] OM - Memory_free> Enter(0x41848, 0x24)

@12,134,727us: [+0 T:0x00004000 S:0xbefffcac] OM - Memory_free> return (0x1)

@12,134,839us: [+0 T:0x00004000 S:0xbefffce4] OP - Processor_delete> return.

@12,134,973us: [+6 T:0x00004000 S:0xbefffd1c] CE - rserverOpen: can't start 'video_copy.x64P'; Processor_create failed

@12,135,107us: [+0 T:0x00004000 S:0xbefffd1c] CE - rserverOpen('video_copy.x64P'): 0x0 done.

@12,135,227us: [+0 T:0x00004000 S:0xbefffcfc] CE - Engine_close(0x41790)

@12,135,344us: [+0 T:0x00004000 S:0xbefffcf4] OM - Memory_free> Enter(0x41790, 0x2c)

@12,135,467us: [+0 T:0x00004000 S:0xbefffcf4] OM - Memory_free> return (0x1)

@12,135,582us: [+0 T:0x00004000 S:0xbefffd1c] CE - Engine_open> return(0)

CEapp-> ERROR: can't open engine video_copy

App-> Application FAILED.

@12,136,003us: [+0 T:0x00004000 S:0xbefffdb4] OG - Global_exit> enter

@12,136,128us: [+2 T:0x00004000 S:0xbefffdb4] OG - Global_exit> calling function *0xcc54()...

@12,136,271us: [+2 T:0x00004000 S:0xbefffdb4] OG - Global_exit> calling function *0xfbb4()...

@12,136,402us: [+2 T:0x00004000 S:0xbefffdb4] OG - Global_exit> calling function *0x11064()...

@12,136,544us: [+0 T:0x00004000 S:0xbefffd7c] OM - Memory_free> Enter(0x416d0, 0x18)

@12,136,697us: [+0 T:0x00004000 S:0xbefffd7c] OM - Memory_free> return (0x1)

@12,136,813us: [+0 T:0x00004000 S:0xbefffd7c] OM - Memory_free> Enter(0x416f0, 0x18)

@12,136,930us: [+0 T:0x00004000 S:0xbefffd7c] OM - Memory_free> return (0x1)

@12,137,042us: [+0 T:0x00004000 S:0xbefffd7c] OM - Memory_free> Enter(0x41710, 0x18)

@12,137,162us: [+0 T:0x00004000 S:0xbefffd7c] OM - Memory_free> return (0x1)

@12,137,275us: [+2 T:0x00004000 S:0xbefffdb4] OG - Global_exit> calling function *0x185d8()...

@12,137,397us: [+2 T:0x00004000 S:0xbefffdb4] OG - Global_exit> calling function *0x19dc0()...

@12,137,521us: [+2 T:0x00004000 S:0xbefffdb4] OG - Global_exit> calling function *0x1a320()...

@12,137,644us: [+2 T:0x00004000 S:0xbefffdb4] OG - Global_exit> calling function *0x16118()...

@12,137,765us: [+2 T:0x00004000 S:0xbefffdb4] OG - Global_exit> calling function *0x14024()...

@12,137,887us: [+0 T:0x00004000 S:0xbefffd7c] OP - doCmd> Enter (cmdId=3, proc=0x0)

@12,138,002us: [+0 T:0x00004000 S:0xbefffd6c] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x3f508]

@12,138,127us: [+0 T:0x00004000 S:0xbefffd7c] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x3f508]

@12,138,270us: [+0 T:0x00004000 S:0xbefffd5c] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x3f520] timeout[0xffffffff]

@12,138,433us: [+0 T:0x00004002 S:0xbe7ffa74] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x3f520]

@12,138,571us: [+0 T:0x00004002 S:0xbe7ffa74] OP - getCmd_d> Enter (proc=0xbe7ffaec)

@12,337,680us: [+0 T:0x00004002 S:0xbe7ffa54] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x3f508] timeout[0xffffffff]

@12,337,841us: [+0 T:0x00004002 S:0xbe7ffa54] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x3f508] status[0]

@12,337,977us: [+0 T:0x00004002 S:0xbe7ffa74] OP - getCmd_d> Exit (result=3)

@12,338,092us: [+0 T:0x00004002 S:0xbe7ffa64] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x3f520]

@12,338,238us: [+0 T:0x00004002 S:0xbe7ffa74] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x3f520]

@12,338,473us: [+0 T:0x00004000 S:0xbefffd5c] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x3f520] status[0]

@12,338,633us: [+0 T:0x00004000 S:0xbefffd7c] OP - doCmd> Exit (result=1)

@12,338,750us: [+0 T:0x00004000 S:0xbefffd84] OT - Thread_delete> Enter (task=0x3f558)

@12,338,901us: [+4 T:0x00004000 S:0xbefffd84] OT - Thread_delete> pthread_cancel (0x3)

@12,339,047us: [+4 T:0x00004000 S:0xbefffd84] OT - Thread_delete> pthread_join (0x0)

@12,339,173us: [+0 T:0x00004000 S:0xbefffd5c] OM - Memory_free> Enter(0x3f558, 0x64)

@12,339,317us: [+0 T:0x00004000 S:0xbefffd5c] OM - Memory_free> return (0x1)

@12,339,436us: [+0 T:0x00004000 S:0xbefffd9c] OT - Thread_delete> Exit (task=0x3f558)

@12,339,555us: [+0 T:0x00004000 S:0xbefffd8c] ti.sdo.ce.osal.Sem - Entered Sem_delete> sem[0x3f508]

@12,339,697us: [+0 T:0x00004000 S:0xbefffd64] OM - Memory_free> Enter(0x3f508, 0x14)

@12,339,829us: [+0 T:0x00004000 S:0xbefffd64] OM - Memory_free> return (0x1)

@12,339,945us: [+0 T:0x00004000 S:0xbefffd9c] ti.sdo.ce.osal.Sem - Leaving Sem_delete>

@12,344,071us: [+0 T:0x00004000 S:0xbefffd8c] ti.sdo.ce.osal.Sem - Entered Sem_delete> sem[0x3f520]

@12,344,222us: [+0 T:0x00004000 S:0xbefffd64] OM - Memory_free> Enter(0x3f520, 0x14)

@12,344,378us: [+0 T:0x00004000 S:0xbefffd64] OM - Memory_free> return (0x1)

@12,344,496us: [+0 T:0x00004000 S:0xbefffd9c] ti.sdo.ce.osal.Sem - Leaving Sem_delete>

@12,344,610us: [+0 T:0x00004000 S:0xbefffd74] OM - Memory_free> Enter(0x3f538, 0x18)

@12,344,732us: [+0 T:0x00004000 S:0xbefffd74] OM - Memory_free> return (0x1)

@12,344,847us: [+2 T:0x00004000 S:0xbefffdb4] OG - Global_exit> calling function *0x18474()...

@12,345,118us: [+2 T:0x00004000 S:0xbefffdb4] OG - Global_exit> calling function *0x15324()...

@12,345,272us: [+0 T:0x00004000 S:0xbefffd8c] OM - Memory_free> Enter(0x3f488, 0x18)

@12,345,402us: [+0 T:0x00004000 S:0xbefffd8c] OM - Memory_free> return (0x1)

@12,345,521us: [+2 T:0x00004000 S:0xbefffdb4] OG - Global_exit> calling function *0x176d8()...

@12,345,660us: [+0 T:0x00004000 S:0xbefffd74] OM - Memory_free> Enter(0x3f438, 0x18)

@12,345,789us: [+0 T:0x00004000 S:0xbefffd74] OM - Memory_free> return (0x1)

@12,345,905us: [+2 T:0x00004000 S:0xbefffdb4] OG - Global_exit> calling function *0x18df0()...

@12,346,055us: [+2 T:0x00004000 S:0xbefffdb4] OG - Global_exit> calling function *0x19958()...

@12,346,178us: [+2 T:0x00004000 S:0xbefffdb4] OG - Global_exit> calling function *0x187fc()...

###################################################################################################

# Success, CE_DEBUG=1

root@DM6467:/opt/video_copy# CE_DEBUG=1 ./video_copy.x470MV in.dat /dev/null

App-> Application started.

@0,039,804us: [+6 T:0x00004000] CE - Engine_init> CE debugging on (CE_DEBUG=1; allowed CE_DEBUG levels: 1=min, 2=good, 3=max)

[DSP] @0x00000252:[T:0x00000000] servers.video_copy.evmDM6467 - main> Welcome to DSP server's main().

TraceUtil_start> note: CE_DEBUG env. var is set, so TraceUtil is not active (unset CE_DEBUG if you need TraceUtil)

CEapp-> Allocating contiguous buffer for 'input data' of size 1024...

CEapp-> Allocating contiguous buffer for 'encoded data' of size 1024...

CEapp-> Allocating contiguous buffer for 'output data' of size 1024...

App-> Processing frame 0...

App-> Processing frame 1...

App-> Processing frame 2...

App-> Processing frame 3...

App-> Finished encoding and decoding 4 frames

App-> Application finished successfully.

###################################################################################################

# Success, CE_DEBUG=2

root@DM6467:/opt/video_copy# CE_DEBUG=2 ./video_copy.x470MV in.dat /dev/null

App-> Application started.

@0,178,358us: [+4 T:0x00004000] OG - Global_init> This program was built with the following packages:

@0,178,662us: [+4 T:0x00004000] OG - package gnu.targets.rts470MV (/opt/dvsdk_1_40_02_33/xdc_3_00_06/packages/gnu/targets/rts470MV/) [1,0,0,0,1203621000516]

@0,178,805us: [+4 T:0x00004000] OG - package ti.xdais.dm (/opt/dvsdk_1_40_02_33/xdais_6_10_01/packages/ti/xdais/dm/) [1,0,4,1210262746529]

@0,178,928us: [+4 T:0x00004000] OG - package ti.xdais (/opt/dvsdk_1_40_02_33/xdais_6_10_01/packages/ti/xdais/) [1,2,1,1210262742149]

@0,179,066us: [+4 T:0x00004000] OG - package ti.sdo.utils.trace (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/utils/trace/) [1,0,0,1220943366422]

@0,179,202us: [+4 T:0x00004000] OG - package ti.sdo.ce.utils.xdm (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/utils/xdm/) [1,0,1,1220951322616]

@0,179,332us: [+4 T:0x00004000] OG - package ti.sdo.fc.dman3 (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/fc/dman3/) [1,0,3,1220943053023]

@0,179,461us: [+4 T:0x00004000] OG - package ti.sdo.fc.acpy3 (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/fc/acpy3/) [1,0,2,1220943014135]

@0,179,588us: [+4 T:0x00004000] OG - package dsplink.gpp (/opt/dvsdk_1_40_02_33/dsplink-davinci-v1.50-prebuilt/packages/dsplink/gpp/) [3,0,0]

@0,179,707us: [+4 T:0x00004000] OG - package ti.sdo.linuxutils.cmem (/opt/dvsdk_1_40_02_33/cmem_2_10/packages/ti/sdo/linuxutils/cmem/) [2,0,1,1204929560755]

@0,179,833us: [+4 T:0x00004000] OG - package ti.catalog.c470 (/opt/dvsdk_1_40_02_33/xdc_3_00_06/packages/ti/catalog/c470/) [1,0,1,0,1203561761475]

@0,179,954us: [+4 T:0x00004000] OG - package ti.catalog.c6000 (/opt/dvsdk_1_40_02_33/xdc_3_00_06/packages/ti/catalog/c6000/) [1,0,0,0,1203561781695]

@0,180,479us: [+4 T:0x00004000] OG - package ti.platforms.evmDM6467 (/opt/dvsdk_1_40_02_33/bios_5_32_01/packages/ti/platforms/evmDM6467/) [1,0,0,0,1192229633217]

@0,180,660us: [+4 T:0x00004000] OG - package ti.sdo.ce.osal (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/osal/) [2,0,2,1220951127739]

@0,180,797us: [+4 T:0x00004000] OG - package ti.sdo.ce.ipc (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/ipc/) [2,0,1,1220951085787]

@0,180,923us: [+4 T:0x00004000] OG - package ti.sdo.ce.alg (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/alg/) [1,0,1,1220950781938]

@0,181,047us: [+4 T:0x00004000] OG - package ti.sdo.ce.osal.linux (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/osal/linux/) [2,0,1,1220951140072]

@0,181,177us: [+4 T:0x00004000] OG - package ti.sdo.ce.ipc.dsplink (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/ipc/dsplink/) [2,0,1,1220951096818]

@0,181,307us: [+4 T:0x00004000] OG - package ti.sdo.ce (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/) [1,0,6,1220950771473]

@0,181,426us: [+4 T:0x00004000] OG - package ti.sdo.ce.video (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/video/) [1,0,3,1220951334122]

@0,181,549us: [+4 T:0x00004000] OG - package ti.codecs.viddec_copy (/home/xn/nfs/opt/video_copy/ti/codecs/viddec_copy/) []

@0,181,659us: [+4 T:0x00004000] OG - package ti.codecs.videnc_copy (/home/xn/nfs/opt/video_copy/ti/codecs/videnc_copy/) []

@0,181,768us: [+4 T:0x00004000] OG - package ti.sdo.ce.bioslog (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/bioslog/) [1,0,1,1220950800329]

@0,181,893us: [+4 T:0x00004000] OG - package ti.sdo.ce.utils.trace (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/utils/trace/) [1,0,1,1220951317087]

@0,182,021us: [+4 T:0x00004000] OG - package ti.apps.video_copy (/home/xn/nfs/opt/video_copy/ti/apps/video_copy/) []

@0,182,576us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Sem_create> count: 0

@0,182,725us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Leaving Sem_create> sem[0x3f508]

@0,182,839us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Sem_create> count: 0

@0,182,939us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Leaving Sem_create> sem[0x3f520]

@0,183,050us: [+0 T:0x00004000] OT - Thread_create> Enter (fxn=0x14464, attrs=0x0)

@0,382,366us: [+0 T:0x00004000] OT - Thread_create> Exit (task=0x3f558)

@0,382,547us: [+0 T:0x00004000] ti.sdo.ce.alg - ALG_init> Enter

@0,382,655us: [+0 T:0x00004000] ti.sdo.ce.alg - ALG_init> Exit

@0,382,832us: [+6 T:0x00004000] CE - Engine_init> CE debugging on (CE_DEBUG=2; allowed CE_DEBUG levels: 1=min, 2=good, 3=max)

@0,383,030us: [+0 T:0x00004000] CS - Server_init()

@0,383,127us: [+0 T:0x00004000] CS - Server_init> Global_useLinkArbiter = 0

@0,383,362us: [+0 T:0x00004000] CE - Engine_open> Enter('video_copy', 0x0, 0xbefffd64)

@0,383,528us: [+0 T:0x00004000] CE - rserverOpen('video_copy.x64P'), count = 0

@0,383,662us: [+0 T:0x00004000] OP - Processor_create> Enter(imageName='video_copy.x64P', linkCfg='(null)', attrs=0xbefffd68)

@0,383,856us: [+0 T:0x00004000] OP - doCmd> Enter (cmdId=1, proc=0x417c0)

@0,383,972us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x3f508]

@0,384,092us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x3f508]

@0,384,201us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x3f520] timeout[0xffffffff]

@0,384,525us: [+1 T:0x00004002] OP - daemon> thread created.

@0,384,645us: [+0 T:0x00004002] OP - getCmd_d> Enter (proc=0xbe7ffaec)

@0,384,746us: [+0 T:0x00004002] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x3f508] timeout[0xffffffff]

@0,384,858us: [+0 T:0x00004002] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x3f508] status[0]

@0,384,961us: [+0 T:0x00004002] OP - getCmd_d> Exit (result=1)

@0,385,073us: [+0 T:0x00004002] OP - Processor_create_d> Enter(proc=0x417c0)

@0,385,170us: [+2 T:0x00004002] OP - Processor_create_d> Initializing DSP PROC...

@0,385,271us: [+2 T:0x00004002] OP - Processor_create_d> Using DspLink config data for entry #0 [server 'video_copy.x64P']

@0,385,423us: [+2 T:0x00004002] OP - Processor_create_d> Adding DSP segment #0 to Link configuration: name='DDR2', startAddress=0x8fa00000, sizeInBytes=0x400000, shared=1

@0,385,585us: [+2 T:0x00004002] OP - Processor_create_d> Adding DSP segment #1 to Link configuration: name='DSPLINKMEM', startAddress=0x8fe00000, sizeInBytes=0x100000, shared=1

@0,385,736us: [+2 T:0x00004002] OP - Processor_create_d> Adding DSP segment #2 to Link configuration: name='RESET_VECTOR', startAddress=0x8ff00000, sizeInBytes=0x80, shared=0

@0,385,883us: [+2 T:0x00004002] OP - Processor_create_d> Adding DSP segment #3 to Link configuration: name='DDRALGHEAP', startAddress=0x88000000, sizeInBytes=0x7a00000, shared=0

@0,386,029us: [+2 T:0x00004002] OP - Processor_create_d> DOPOWERCONTROL was=0; now=0

@0,387,817us: [+2 T:0x00004002] OP - Processor_create_d> Attaching to DSP PROC...

@0,395,481us: [+2 T:0x00004002] OP - Processor_create_d> Opening MSGQ pool...

@0,395,875us: [+2 T:0x00004002] OP - Processor_create_d> Loading video_copy.x64P on DSP (1 args)...

@0,417,071us: [+2 T:0x00004002] OP - Processor_create_d> Starting DSP PROC...

@0,426,600us: [+2 T:0x00004002] OP - Processor_create_d> Opening remote transport...

@0,427,006us: [+2 T:0x00004002] OP - Processor_create_d> return (1)

@0,427,136us: [+0 T:0x00004002] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x3f520]

@0,427,297us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x3f520] status[0]

@0,427,422us: [+0 T:0x00004000] OP - doCmd> Exit (result=1)

@0,427,511us: [+0 T:0x00004000] OP - Processor_create> return (0x417c0)

@0,427,604us: [+0 T:0x00004000] CE - rserverOpen('video_copy.x64P'): 0x3e60c done.

@0,431,881us: [+0 T:0x00004000] CE - checkServer(0x41790)

@0,432,146us: [+0 T:0x00004000] CE - rmsInit> RMS initialized(0x41790); CE_DEBUG on, setting DSP trace mask to *+01234567,CR=67,ti.sdo.fc.dman3-2,ti.sdo.fc.dskt2-2,GT_prefix=1235,GT_time=3

@0,432,325us: [+0 T:0x00004000] CE - Engine_setTrace> Enter(engine=0x41790, mask='*+01234567,CR=67,ti.sdo.fc.dman3-2,ti.sdo.fc.dskt2-2,GT_prefix=1235,GT_time=3')

@0,432,483us: [+1 T:0x00004000] CE - Engine_setTrace> Requesting DSP set trace ...

@0,432,641us: [+0 T:0x00004002] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x3f520]

@0,432,764us: [+0 T:0x00004002] OP - getCmd_d> Enter (proc=0xbe7ffaec)

@0,432,864us: [+0 T:0x00004002] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x3f508] timeout[0xffffffff]

@0,437,653us: [+0 T:0x00004000] CE - Engine_setTrace> return(0)

[DSP] @0x00000251:[T:0x00000000] servers.video_copy.evmDM6467 - main> Welcome to DSP server's main().

[DSP] @0,023,802tk: [+0 T:0x8fa4578c] OG - Global_setSpecialTrace> enter(mask='*+01234567,CR=67,ti.sdo.fc.dman3-2,ti.sdo.fc.dskt2-2,GT_prefix=1235,GT_time=3')

[DSP] @0,024,528tk: [+4 T:0x8fa4578c] OG - Global_setSpecialTrace> This program was built with the following packages:

[DSP] @0,025,012tk: [+4 T:0x8fa4578c] OG - package ti.targets.rts6000 (/opt/dvsdk_1_40_02_33/xdc_3_00_06/packages/ti/targets/rts6000/) [1,0,0,0,1203621186754]

[DSP] @0,025,661tk: [+4 T:0x8fa4578c] OG - package ti.sdo.fc.dman3 (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/fc/dman3/) [1,0,3,1220943053023]

[DSP] @0,026,338tk: [+4 T:0x8fa4578c] OG - package ti.rtdx (/opt/dvsdk_1_40_02_33/bios_5_32_01/packages/ti/rtdx/) [2,0,0,1,1194024051755]

[DSP] @0,026,926tk: [+4 T:0x8fa4578c] OG - package ti.psl (/opt/dvsdk_1_40_02_33/bios_5_32_01/packages/ti/psl/) [5,0,0,0,0]

[DSP] @0,027,446tk: [+4 T:0x8fa4578c] OG - package ti.bios (/opt/dvsdk_1_40_02_33/bios_5_32_01/packages/ti/bios/) [5,2,4,9,1193960176642]

[DSP] @0,028,013tk: [+4 T:0x8fa4578c] OG - package ti.sdo.fc.dskt2 (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/fc/dskt2/) [1,0,4,1220943095081]

[DSP] @0,028,688tk: [+4 T:0x8fa4578c] OG - package ti.xdais.dm (/opt/dvsdk_1_40_02_33/xdais_6_10_01/packages/ti/xdais/dm/) [1,0,4,1210262746529]

[DSP] @0,029,298tk: [+4 T:0x8fa4578c] OG - package ti.xdais (/opt/dvsdk_1_40_02_33/xdais_6_10_01/packages/ti/xdais/) [1,2,1,1210262742149]

[DSP] @0,029,870tk: [+4 T:0x8fa4578c] OG - package ti.sdo.ce.node (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/node/) [1,0,0,1220951122176]

[DSP] @0,030,508tk: [+4 T:0x8fa4578c] OG - package ti.sdo.ce.ipc.dsplink.dsp (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/ipc/dsplink/dsp/) [2,0,1,1220951102696]

[DSP] @0,031,247tk: [+4 T:0x8fa4578c] OG - package ti.sdo.utils.trace (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/utils/trace/) [1,0,0,1220943366422]

[DSP] @0,031,947tk: [+4 T:0x8fa4578c] OG - package ti.sdo.ce.utils.xdm (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/utils/xdm/) [1,0,1,1220951322616]

[DSP] @0,032,624tk: [+4 T:0x8fa4578c] OG - package ti.sdo.fc.acpy3 (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/fc/acpy3/) [1,0,2,1220943014135]

[DSP] @0,033,301tk: [+4 T:0x8fa4578c] OG - package dsplink.dsp (/opt/dvsdk_1_40_02_33/dsplink-davinci-v1.50-prebuilt/packages/dsplink/dsp/) [1,3,0]

[DSP] @0,033,927tk: [+4 T:0x8fa4578c] OG - package ti.bios.utils (/opt/dvsdk_1_40_02_33/biosutils_1_01_00/packages/ti/bios/utils/) [2,0,1,0,1191891830609]

[DSP] @0,034,558tk: [+4 T:0x8fa4578c] OG - package ti.catalog.c6000 (/opt/dvsdk_1_40_02_33/xdc_3_00_06/packages/ti/catalog/c6000/) [1,0,0,0,1203561781695]

[DSP] @0,035,188tk: [+4 T:0x8fa4578c] OG - package ti.platforms.evmDM6467 (/opt/dvsdk_1_40_02_33/bios_5_32_01/packages/ti/platforms/evmDM6467/) [1,0,0,0,1192229633217]

[DSP] @0,035,882tk: [+4 T:0x8fa4578c] OG - package ti.sdo.ce.osal (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/osal/) [2,0,2,1220951127739]

[DSP] @0,036,524tk: [+4 T:0x8fa4578c] OG - package ti.sdo.ce.ipc (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/ipc/) [2,0,1,1220951085787]

[DSP] @0,037,158tk: [+4 T:0x8fa4578c] OG - package ti.sdo.ce.alg (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/alg/) [1,0,1,1220950781938]

[DSP] @0,037,790tk: [+4 T:0x8fa4578c] OG - package ti.sdo.ce.osal.bios (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/osal/bios/) [2,0,1,1220951133740]

[DSP] @0,038,485tk: [+4 T:0x8fa4578c] OG - package ti.sdo.ce.ipc.bios (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/ipc/bios/) [2,0,1,1220951091097]

[DSP] @0,039,156tk: [+4 T:0x8fa4578c] OG - package ti.sdo.ce (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/) [1,0,6,1220950771473]

[DSP] @0,039,761tk: [+4 T:0x8fa4578c] OG - package ti.sdo.ce.bioslog (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/bioslog/) [1,0,1,1220950800329]

[DSP] @0,040,423tk: [+4 T:0x8fa4578c] OG - package ti.sdo.ce.video (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/video/) [1,0,3,1220951334122]

[DSP] @0,041,089tk: [+4 T:0x8fa4578c] OG - package ti.codecs.viddec_copy (/home/xn/nfs/opt/video_copy/ti/codecs/viddec_copy/) []

[DSP] @0,041,624tk: [+4 T:0x8fa4578c] OG - package ti.codecs.videnc_copy (/home/xn/nfs/opt/video_copy/ti/codecs/videnc_copy/) []

[DSP] @0,042,160tk: [+4 T:0x8fa4578c] OG - package ti.servers.video_copy (/home/xn/nfs/opt/video_copy/ti/servers/video_copy/) []

[DSP] @0,042,694tk: [+0 T:0x8fa4578c] OG - Global_setSpecialTrace> return

@0,846,134us: [+0 T:0x00004000] CE - Engine_fwriteTrace> returning count [4882]

@0,846,247us: [+0 T:0x00004000] CE - Engine_open> return(268176)

TraceUtil_start> note: CE_DEBUG env. var is set, so TraceUtil is not active (unset CE_DEBUG if you need TraceUtil)

@0,846,471us: [+0 T:0x00004000] ti.sdo.ce.video.VIDENC - VIDENC_create> Enter (engine=0x41790, name='videnc_copy', params=0x0)

@0,846,632us: [+0 T:0x00004000] CV - VISA_create(0x41790, 'videnc_copy', 0x0, 0x828, 'ti.sdo.ce.video.IVIDENC')

@0,846,759us: [+0 T:0x00004000] CV - VISA_create2(0x41790, 'videnc_copy', 0x0, 0x0, 0x828, 'ti.sdo.ce.video.IVIDENC')

@0,846,902us: [+0 T:0x00004000] CE - Engine_createNode(0x41790, 'videnc_copy', 828, 0x0, 0x0, 0xbefffd68)

@0,850,162us: [+4 T:0x00004000] CE - Engine_createNode> created node(stdIn=0x2, stdOut=0x10001, msgq=0x41aa0, algName='videnc_copy', rmsNode=0x8fa46678, algHandle=0x8fa46758)

[DSP] @1,643,545tk: [+0 T:0x8fa4578c] OM - Memory_alloc> Enter(size=0x18)

[DSP] @1,643,909tk: [+0 T:0x8fa4578c] OM - Memory_alloc> return (0x8fa46678)

[DSP] @1,644,250tk: [+0 T:0x8fa4578c] OM - Memory_alloc> Enter(size=0xe)

[DSP] @1,644,566tk: [+0 T:0x8fa4578c] OM - Memory_alloc> return (0x8fa46690)

[DSP] @1,644,911tk: [+0 T:0x8fa4578c] OM - Memory_alloc> Enter(size=0x20)

[DSP] @1,645,233tk: [+0 T:0x8fa4578c] OM - Memory_alloc> return (0x8fa466a0)

[DSP] @1,645,569tk: [+0 T:0x8fa4578c] OM - Memory_alloc> Enter(size=0x24)

[DSP] @1,645,889tk: [+0 T:0x8fa4578c] OM - Memory_alloc> return (0x8fa466c0)

[DSP] @1,646,283tk: [+0 T:0x8fa4578c] ti.sdo.ce.video.VIDENC - VIDENC_create> Enter (engine=0x0, name='videnc_copy', params=0x0)

[DSP] @1,646,832tk: [+0 T:0x8fa4578c] CV - VISA_create(0x0, 'videnc_copy', 0x0, 0x828, 'ti.sdo.ce.video.IVIDENC')

[DSP] @1,647,331tk: [+0 T:0x8fa4578c] CV - VISA_create2(0x0, 'videnc_copy', 0x0, 0x0, 0x828, 'ti.sdo.ce.video.IVIDENC')

[DSP] @1,647,868tk: [+0 T:0x8fa4578c] CE - Engine_open> Enter('local', 0x8fa45c8c, 0x33c)

[DSP] @1,648,265tk: [+0 T:0x8fa4578c] OM - Memory_alloc> Enter(size=0x2c)

[DSP] @1,648,630tk: [+0 T:0x8fa4578c] OM - Memory_alloc> return (0x8fa46728)

[DSP] @1,648,978tk: [+0 T:0x8fa4578c] CE - Engine_open> return(-1885051096)

[DSP] @1,649,327tk: [+0 T:0x8fa4578c] OM - Memory_alloc> Enter(size=0x30)

[DSP] @1,649,653tk: [+0 T:0x8fa4578c] OM - Memory_alloc> return (0x8fa46758)

[DSP] @1,649,990tk: [+0 T:0x8fa4578c] ti.sdo.ce.alg.Algorithm - Algorithm_create> Enter(fxns=0x8fa6eb74, idma3Fxns=0x0, params=0x0, attrs=0x8fa45da0)

[DSP] @1,650,616tk: [+0 T:0x8fa4578c] OM - Memory_alloc> Enter(size=0x10)

[DSP] @1,650,956tk: [+0 T:0x8fa4578c] OM - Memory_alloc> return (0x8fa46788)

[DSP] @1,651,373tk: [+0 T:0x8fa4578c] ti.codecs.videnc_copy - VIDENCCOPY_TI_alloc(0x0, 0x8fa45c48, 0x8fa46b08)

[DSP] @1,651,896tk: [+0 T:0x8fa4578c] ti.codecs.videnc_copy - VIDENCCOPY_TI_initObj(0x88000250, 0x8fa46b08, 0x0, 0x0)

[DSP] @1,652,410tk: [+0 T:0x8fa4578c] ti.sdo.ce.alg.Algorithm - Algorithm_create> return (0x8fa46788)

[DSP] @1,652,838tk: [+0 T:0x8fa4578c] ti.sdo.ce.video.VIDENC - VIDENC_create> return (0x8fa46758)

[DSP] @1,653,291tk: [+4 T:0x8fa4578c] OT - Thread_create > name: "videnc_copy#0", pri: -1, stack size: 9216, stack seg: 0

@0,856,130us: [+0 T:0x00004000] CE - Engine_fwriteTrace> returning count [2288]

@0,856,241us: [+2 T:0x00004000] CE - Engine_createNode> Returning 0x419e0

@0,856,390us: [+5 T:0x00004000] CV - VISA_create> remote codec created (name='videnc_copy', localQueueID=0x10001, remoteQueueID=0x0002)

@0,856,534us: [+0 T:0x00004000] ti.sdo.ce.video.VIDENC - VIDENC_create> return (0x41a68)

@0,856,662us: [+0 T:0x00004000] ti.sdo.ce.video.VIDDEC - VIDDEC_create> Enter (engine=0x41790, name='viddec_copy', params=0x0)

@1,054,931us: [+0 T:0x00004000] CV - VISA_create(0x41790, 'viddec_copy', 0x0, 0x836, 'ti.sdo.ce.video.IVIDDEC')

@1,055,069us: [+0 T:0x00004000] CV - VISA_create2(0x41790, 'viddec_copy', 0x0, 0x0, 0x836, 'ti.sdo.ce.video.IVIDDEC')

@1,055,220us: [+0 T:0x00004000] CE - Engine_createNode(0x41790, 'viddec_copy', 836, 0x0, 0x0, 0xbefffd68)

@1,058,048us: [+4 T:0x00004000] CE - Engine_createNode> created node(stdIn=0x3, stdOut=0x10002, msgq=0x41b50, algName='viddec_copy', rmsNode=0x8fa48ff8, algHandle=0x8fa49090)

[DSP] @2,457,249tk: [+0 T:0x8fa4578c] OM - Memory_alloc> Enter(size=0x18)

[DSP] @2,457,585tk: [+0 T:0x8fa4578c] OM - Memory_alloc> return (0x8fa48ff8)

[DSP] @2,457,921tk: [+0 T:0x8fa4578c] OM - Memory_alloc> Enter(size=0xe)

[DSP] @2,458,237tk: [+0 T:0x8fa4578c] OM - Memory_alloc> return (0x8fa49010)

[DSP] @2,458,602tk: [+0 T:0x8fa4578c] OM - Memory_alloc> Enter(size=0x20)

[DSP] @2,458,923tk: [+0 T:0x8fa4578c] OM - Memory_alloc> return (0x8fa49020)

[DSP] @2,459,259tk: [+0 T:0x8fa4578c] OM - Memory_alloc> Enter(size=0x24)

[DSP] @2,459,579tk: [+0 T:0x8fa4578c] OM - Memory_alloc> return (0x8fa49040)

[DSP] @2,459,958tk: [+0 T:0x8fa4578c] ti.sdo.ce.video.VIDDEC - VIDDEC_create> Enter (engine=0x0, name='viddec_copy', params=0x0)

[DSP] @2,460,493tk: [+0 T:0x8fa4578c] CV - VISA_create(0x0, 'viddec_copy', 0x0, 0x836, 'ti.sdo.ce.video.IVIDDEC')

[DSP] @2,461,038tk: [+0 T:0x8fa4578c] CV - VISA_create2(0x0, 'viddec_copy', 0x0, 0x0, 0x836, 'ti.sdo.ce.video.IVIDDEC')

[DSP] @2,461,587tk: [+0 T:0x8fa4578c] OM - Memory_alloc> Enter(size=0x30)

[DSP] @2,461,914tk: [+0 T:0x8fa4578c] OM - Memory_alloc> return (0x8fa49090)

[DSP] @2,462,251tk: [+0 T:0x8fa4578c] ti.sdo.ce.alg.Algorithm - Algorithm_create> Enter(fxns=0x8fa6eb1c, idma3Fxns=0x0, params=0x0, attrs=0x8fa45da0)

[DSP] @2,462,875tk: [+0 T:0x8fa4578c] OM - Memory_alloc> Enter(size=0x10)

[DSP] @2,463,216tk: [+0 T:0x8fa4578c] OM - Memory_alloc> return (0x8fa490c0)

[DSP] @2,463,598tk: [+0 T:0x8fa4578c] ti.codecs.viddec_copy - VIDDECCOPY_TI_alloc(0x0, 0x8fa490d0, 0x0)

[DSP] @2,464,080tk: [+0 T:0x8fa4578c] ti.codecs.viddec_copy - VIDDECCOPY_TI_initObj(0x88000258, 0x0, 0x0, 0x8fa76cd0)

[DSP] @2,464,594tk: [+0 T:0x8fa4578c] ti.sdo.ce.alg.Algorithm - Algorithm_create> return (0x8fa490c0)

[DSP] @2,465,021tk: [+0 T:0x8fa4578c] ti.sdo.ce.video.VIDDEC - VIDDEC_create> return (0x8fa49090)

[DSP] @2,465,478tk: [+4 T:0x8fa4578c] OT - Thread_create > name: "viddec_copy#1", pri: -1, stack size: 9216, stack seg: 0

@1,063,285us: [+0 T:0x00004000] CE - Engine_fwriteTrace> returning count [1964]

@1,063,399us: [+2 T:0x00004000] CE - Engine_createNode> Returning 0x41b28

@1,063,546us: [+5 T:0x00004000] CV - VISA_create> remote codec created (name='viddec_copy', localQueueID=0x10002, remoteQueueID=0x0003)

@1,063,692us: [+0 T:0x00004000] ti.sdo.ce.video.VIDDEC - VIDDEC_create> return (0x41af0)

CEapp-> Allocating contiguous buffer for 'input data' of size 1024...

@1,063,948us: [+4 T:0x00004000] OM - Memory_contigAlloc> CMEM_alloc(1024) = 0x4001c000.

@1,064,077us: [+4 T:0x00004000] OM - Memory_contigAlloc> CMEM_getPhys(0x4001c000) = 0x87fff000.

CEapp-> Allocating contiguous buffer for 'encoded data' of size 1024...

@1,064,323us: [+4 T:0x00004000] OM - Memory_contigAlloc> CMEM_alloc(1024) = 0x4001d000.

@1,064,452us: [+4 T:0x00004000] OM - Memory_contigAlloc> CMEM_getPhys(0x4001d000) = 0x87ffe000.

CEapp-> Allocating contiguous buffer for 'output data' of size 1024...

@1,064,704us: [+4 T:0x00004000] OM - Memory_contigAlloc> CMEM_alloc(1024) = 0x401fe000.

@1,064,834us: [+4 T:0x00004000] OM - Memory_contigAlloc> CMEM_getPhys(0x401fe000) = 0x87ffd000.

@1,064,984us: [+0 T:0x00004000] ti.sdo.ce.video.VIDENC - VIDENC_control> Enter (handle=0x41a68, id=0, params=0xbefffd80, status=0xbefffcf0

@1,065,161us: [+5 T:0x00004000] CV - VISA_allocMsg> Allocating message for messageId=0x00020008

@1,065,281us: [+0 T:0x00004000] CV - VISA_call(visa=0x41a68, msg=0x4018f880): messageId=0x00020008, command=0x1

[DSP] @2,495,631tk: [+5 T:0x8fa46ba4] CN - NODE> 0x8fa466a0(videnc_copy#0) call(algHandle=0x8fa46758, msg=0x8fe04880); messageId=0x00020008

[DSP] @2,496,249tk: [+0 T:0x8fa46ba4] ti.sdo.ce.video.VIDENC - VIDENC_control> Enter (handle=0x8fa46758, id=0, params=0x8fe048b4, status=0x8fe048dc

[DSP] @2,496,900tk: [+5 T:0x8fa46ba4] CV - VISA_enter(visa=0x8fa46758): algHandle = 0x8fa46788

[DSP] @2,497,317tk: [+0 T:0x8fa46ba4] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(handle=0x8fa46788)

[DSP] @2,497,805tk: [+0 T:0x8fa46ba4] ti.codecs.videnc_copy - VIDENCCOPY_TI_activate(0x88000250)

[DSP] @2,498,214tk: [+0 T:0x8fa46ba4] ti.sdo.ce.alg.Algorithm - Algorithm_activate> return

[DSP] @2,498,602tk: [+0 T:0x8fa46ba4] ti.codecs.videnc_copy - VIDENCCOPY_TI_control(0x88000250, 0x0, 0x8fe048b4, 0x8fe048dc)

[DSP] @2,499,150tk: [+5 T:0x8fa46ba4] CV - VISA_exit(visa=0x8fa46758): algHandle = 0x8fa46788

[DSP] @2,499,562tk: [+0 T:0x8fa46ba4] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(handle=0x8fa46788)

[DSP] @2,500,045tk: [+0 T:0x8fa46ba4] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> return

[DSP] @2,500,443tk: [+0 T:0x8fa46ba4] ti.sdo.ce.video.VIDENC - VIDENC_control> Exit (handle=0x8fa46758, retVal=0x0)

[DSP] @2,500,934tk: [+5 T:0x8fa46ba4] CN - NODE> returned from call(algHandle=0x8fa46758, msg=0x8fe04880); messageId=0x00020008

@1,268,977us: [+0 T:0x00004000] CE - Engine_fwriteTrace> returning count [1347]

@1,269,087us: [+0 T:0x00004000] CV - VISA_call Completed: messageId=0x00020008, command=0x1, return(status=0)

@1,269,206us: [+5 T:0x00004000] CV - VISA_freeMsg(0x41a68, 0x4018f880): Freeing message with messageId=0x00020008

@1,269,342us: [+0 T:0x00004000] ti.sdo.ce.video.VIDENC - VIDENC_control> Exit (handle=0x41a68, retVal=0x0)

@1,269,456us: [+0 T:0x00004000] ti.sdo.ce.video.VIDDEC - VIDDEC_control> Enter (handle=0x41af0, id=0, params=0xbefffda8, status=0xbefffc48

@1,269,590us: [+5 T:0x00004000] CV - VISA_allocMsg> Allocating message for messageId=0x00030013

@1,269,697us: [+0 T:0x00004000] CV - VISA_call(visa=0x41af0, msg=0x40190880): messageId=0x00030013, command=0x1

[DSP] @3,294,121tk: [+5 T:0x8fa4916c] CN - NODE> 0x8fa49020(viddec_copy#1) call(algHandle=0x8fa49090, msg=0x8fe05880); messageId=0x00030013

[DSP] @3,294,738tk: [+0 T:0x8fa4916c] ti.sdo.ce.video.VIDDEC - VIDDEC_control> Enter (handle=0x8fa49090, id=0, params=0x8fe058b4, status=0x8fe058c4

[DSP] @3,295,367tk: [+5 T:0x8fa4916c] CV - VISA_enter(visa=0x8fa49090): algHandle = 0x8fa490c0

[DSP] @3,295,786tk: [+0 T:0x8fa4916c] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(handle=0x8fa490c0)

[DSP] @3,296,272tk: [+0 T:0x8fa4916c] ti.codecs.videnc_copy - VIDENCCOPY_TI_deactivate(0x88000250)

[DSP] @3,296,685tk: [+0 T:0x8fa4916c] ti.sdo.ce.alg.Algorithm - Algorithm_activate> return

[DSP] @3,297,069tk: [+0 T:0x8fa4916c] ti.codecs.viddec_copy - VIDDECCOPY_TI_control(0x88000258, 0x8fe058b4, 0xffffffff, 0x1)

[DSP] @3,297,610tk: [+5 T:0x8fa4916c] CV - VISA_exit(visa=0x8fa49090): algHandle = 0x8fa490c0

[DSP] @3,298,021tk: [+0 T:0x8fa4916c] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(handle=0x8fa490c0)

[DSP] @3,298,505tk: [+0 T:0x8fa4916c] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> return

[DSP] @3,298,895tk: [+0 T:0x8fa4916c] ti.sdo.ce.video.VIDDEC - VIDDEC_control> Exit (handle=0x8fa49090, retVal=0x0)

[DSP] @3,299,382tk: [+5 T:0x8fa4916c] CN - NODE> returned from call(algHandle=0x8fa49090, msg=0x8fe05880); messageId=0x00030013

@1,274,874us: [+0 T:0x00004000] CE - Engine_fwriteTrace> returning count [1349]

@1,274,983us: [+0 T:0x00004000] CV - VISA_call Completed: messageId=0x00030013, command=0x1, return(status=0)

@1,275,102us: [+5 T:0x00004000] CV - VISA_freeMsg(0x41af0, 0x40190880): Freeing message with messageId=0x00030013

@1,275,218us: [+0 T:0x00004000] ti.sdo.ce.video.VIDDEC - VIDDEC_control> Exit (handle=0x41af0, retVal=0x0)

@1,275,330us: [+5 T:0x00004000] OM - Memory_dumpKnownContigBufsList> following buffers were translated/registered:

@1,275,436us: [+5 T:0x00004000] OM - [ virt: 0x401fe000, size: 00001024, phys: 0x87ffd000 ]

@1,473,690us: [+5 T:0x00004000] OM - [ virt: 0x4001d000, size: 00001024, phys: 0x87ffe000 ]

@1,473,815us: [+5 T:0x00004000] OM - [ virt: 0x4001c000, size: 00001024, phys: 0x87fff000 ]

App-> Processing frame 0...

@1,474,147us: [+0 T:0x00004000] ti.sdo.ce.video.VIDENC - VIDENC_process> Enter (handle=0x41a68, inBufs=0xbefffdb0, outBufs=0xbefffda0, inArgs=0xbefffbfc, outArgs=0xbefffd00)

@1,474,313us: [+5 T:0x00004000] CV - VISA_allocMsg> Allocating message for messageId=0x00020009

@1,474,431us: [+0 T:0x00004000] CV - VISA_call(visa=0x41a68, msg=0x4018f880): messageId=0x00020009, command=0x0

[DSP] @4,093,871tk: [+5 T:0x8fa46ba4] CN - NODE> 0x8fa466a0(videnc_copy#0) call(algHandle=0x8fa46758, msg=0x8fe04880); messageId=0x00020009

[DSP] @4,094,504tk: [+0 T:0x8fa46ba4] OM - Memory_cacheInv> Enter(addr=0x87fff000, sizeInBytes=1024)

[DSP] @4,094,933tk: [+0 T:0x8fa46ba4] OM - Memory_cacheInv> return

[DSP] @4,095,221tk: [+0 T:0x8fa46ba4] OM - Memory_cacheInv> Enter(addr=0x87ffe000, sizeInBytes=1024)

[DSP] @4,095,648tk: [+0 T:0x8fa46ba4] OM - Memory_cacheInv> return

[DSP] @4,095,940tk: [+0 T:0x8fa46ba4] ti.sdo.ce.video.VIDENC - VIDENC_process> Enter (handle=0x8fa46758, inBufs=0x8fa48f74, outBufs=0x8fa48f80, inArgs=0x8fe049b8, outArgs=0x8fe049bc)

[DSP] @4,096,739tk: [+5 T:0x8fa46ba4] CV - VISA_enter(visa=0x8fa46758): algHandle = 0x8fa46788

[DSP] @4,097,158tk: [+0 T:0x8fa46ba4] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(handle=0x8fa46788)

[DSP] @4,097,615tk: [+0 T:0x8fa46ba4] ti.codecs.videnc_copy - VIDENCCOPY_TI_activate(0x88000250)

[DSP] @4,098,024tk: [+0 T:0x8fa46ba4] ti.sdo.ce.alg.Algorithm - Algorithm_activate> return

[DSP] @4,098,410tk: [+0 T:0x8fa46ba4] ti.codecs.videnc_copy - VIDENCCOPY_TI_process(0x88000250, 0x8fa48f74, 0x8fa48f80, 0x8fe049b8, 0x8fe049bc)

[DSP] @4,099,087tk: [+2 T:0x8fa46ba4] ti.codecs.videnc_copy - VIDENCCOPY_TI_process> memcpy (0x87ffe000, 0x87fff000, 1024)

[DSP] @4,099,666tk: [+5 T:0x8fa46ba4] CV - VISA_exit(visa=0x8fa46758): algHandle = 0x8fa46788

[DSP] @4,100,079tk: [+0 T:0x8fa46ba4] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(handle=0x8fa46788)

[DSP] @4,100,543tk: [+0 T:0x8fa46ba4] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> return

[DSP] @4,100,934tk: [+0 T:0x8fa46ba4] ti.sdo.ce.video.VIDENC - VIDENC_process> Exit (handle=0x8fa46758, retVal=0x0)

[DSP] @4,101,450tk: [+0 T:0x8fa46ba4] OM - Memory_cacheWb> Enter(addr=0x87ffe000, sizeInBytes=1024)

[DSP] @4,101,875tk: [+0 T:0x8fa46ba4] OM - Memory_cacheWb> return

[DSP] @4,102,163tk: [+5 T:0x8fa46ba4] CN - NODE> returned from call(algHandle=0x8fa46758, msg=0x8fe04880); messageId=0x00020009

@1,482,111us: [+0 T:0x00004000] CE - Engine_fwriteTrace> returning count [2026]

@1,482,222us: [+0 T:0x00004000] CV - VISA_call Completed: messageId=0x00020009, command=0x0, return(status=0)

@1,482,348us: [+5 T:0x00004000] CV - VISA_freeMsg(0x41a68, 0x4018f880): Freeing message with messageId=0x00020009

@1,482,467us: [+0 T:0x00004000] ti.sdo.ce.video.VIDENC - VIDENC_process> Exit (handle=0x41a68, retVal=0x0)

@1,482,580us: [+0 T:0x00004000] ti.sdo.ce.video.VIDDEC - VIDDEC_process> Enter (handle=0x41af0, inBufs=0xbefffdb0, outBufs=0xbefffda0, inArgs=0xbefffd90, outArgs=0xbefffcf0)

@1,482,733us: [+5 T:0x00004000] CV - VISA_allocMsg> Allocating message for messageId=0x00030014

@1,482,843us: [+0 T:0x00004000] CV - VISA_call(visa=0x41af0, msg=0x40190880): messageId=0x00030014, command=0x0

[DSP] @4,126,720tk: [+5 T:0x8fa4916c] CN - NODE> 0x8fa49020(viddec_copy#1) call(algHandle=0x8fa49090, msg=0x8fe05880); messageId=0x00030014

[DSP] @4,127,336tk: [+0 T:0x8fa4916c] OM - Memory_cacheInv> Enter(addr=0x87ffe000, sizeInBytes=1024)

[DSP] @4,127,768tk: [+0 T:0x8fa4916c] OM - Memory_cacheInv> return

[DSP] @4,128,058tk: [+0 T:0x8fa4916c] OM - Memory_cacheInv> Enter(addr=0x87ffd000, sizeInBytes=1024)

[DSP] @4,128,486tk: [+0 T:0x8fa4916c] OM - Memory_cacheInv> return

[DSP] @4,128,779tk: [+0 T:0x8fa4916c] ti.sdo.ce.video.VIDDEC - VIDDEC_process> Enter (handle=0x8fa49090, inBufs=0x8fa4b53c, outBufs=0x8fa4b548, inArgs=0x8fe059b8, outArgs=0x8fe059c4)

[DSP] @4,129,571tk: [+5 T:0x8fa4916c] CV - VISA_enter(visa=0x8fa49090): algHandle = 0x8fa490c0

[DSP] @4,129,990tk: [+0 T:0x8fa4916c] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(handle=0x8fa490c0)

[DSP] @4,130,446tk: [+0 T:0x8fa4916c] ti.codecs.videnc_copy - VIDENCCOPY_TI_deactivate(0x88000250)

[DSP] @4,130,859tk: [+0 T:0x8fa4916c] ti.sdo.ce.alg.Algorithm - Algorithm_activate> return

[DSP] @4,131,270tk: [+0 T:0x8fa4916c] ti.codecs.viddec_copy - VIDDECCOPY_TI_process(0x88000258, 0x8fa4b548, 0x8fe059c4, 0x1, 0x1)

[DSP] @4,131,861tk: [+2 T:0x8fa4916c] ti.codecs.viddec_copy - VIDDECCOPY_TI_process> Processed 1024 bytes.

[DSP] @4,132,304tk: [+5 T:0x8fa4916c] CV - VISA_exit(visa=0x8fa49090): algHandle = 0x8fa490c0

[DSP] @4,132,716tk: [+0 T:0x8fa4916c] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(handle=0x8fa490c0)

[DSP] @4,133,178tk: [+0 T:0x8fa4916c] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> return

[DSP] @4,133,587tk: [+0 T:0x8fa4916c] ti.sdo.ce.video.VIDDEC - VIDDEC_process> Exit (handle=0x8fa49090, retVal=0x0)

[DSP] @4,134,078tk: [+0 T:0x8fa4916c] OM - Memory_cacheWb> Enter(addr=0x87ffd000, sizeInBytes=1024)

[DSP] @4,134,505tk: [+0 T:0x8fa4916c] OM - Memory_cacheWb> return

[DSP] @4,134,792tk: [+5 T:0x8fa4916c] CN - NODE> returned from call(algHandle=0x8fa49090, msg=0x8fe05880); messageId=0x00030014

@1,689,686us: [+0 T:0x00004000] CE - Engine_fwriteTrace> returning count [1998]

@1,689,799us: [+0 T:0x00004000] CV - VISA_call Completed: messageId=0x00030014, command=0x0, return(status=0)

@1,689,924us: [+5 T:0x00004000] CV - VISA_freeMsg(0x41af0, 0x40190880): Freeing message with messageId=0x00030014

@1,690,088us: [+0 T:0x00004000] ti.sdo.ce.video.VIDDEC - VIDDEC_process> Exit (handle=0x41af0, retVal=0x0)

App-> Processing frame 1...

@1,690,449us: [+0 T:0x00004000] ti.sdo.ce.video.VIDENC - VIDENC_process> Enter (handle=0x41a68, inBufs=0xbefffdb0, outBufs=0xbefffda0, inArgs=0xbefffbfc, outArgs=0xbefffd00)

@1,690,617us: [+5 T:0x00004000] CV - VISA_allocMsg> Allocating message for messageId=0x0002000a

@1,690,734us: [+0 T:0x00004000] CV - VISA_call(visa=0x41a68, msg=0x4018f880): messageId=0x0002000a, command=0x0

[DSP] @4,938,823tk: [+5 T:0x8fa46ba4] CN - NODE> 0x8fa466a0(videnc_copy#0) call(algHandle=0x8fa46758, msg=0x8fe04880); messageId=0x0002000a

[DSP] @4,939,431tk: [+0 T:0x8fa46ba4] OM - Memory_cacheInv> Enter(addr=0x87fff000, sizeInBytes=1024)

[DSP] @4,939,860tk: [+0 T:0x8fa46ba4] OM - Memory_cacheInv> return

[DSP] @4,940,150tk: [+0 T:0x8fa46ba4] OM - Memory_cacheInv> Enter(addr=0x87ffe000, sizeInBytes=1024)

[DSP] @4,940,581tk: [+0 T:0x8fa46ba4] OM - Memory_cacheInv> return

[DSP] @4,940,873tk: [+0 T:0x8fa46ba4] ti.sdo.ce.video.VIDENC - VIDENC_process> Enter (handle=0x8fa46758, inBufs=0x8fa48f74, outBufs=0x8fa48f80, inArgs=0x8fe049b8, outArgs=0x8fe049bc)

[DSP] @4,941,667tk: [+5 T:0x8fa46ba4] CV - VISA_enter(visa=0x8fa46758): algHandle = 0x8fa46788

[DSP] @4,942,084tk: [+0 T:0x8fa46ba4] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(handle=0x8fa46788)

[DSP] @4,942,542tk: [+0 T:0x8fa46ba4] ti.codecs.videnc_copy - VIDENCCOPY_TI_activate(0x88000250)

[DSP] @4,942,951tk: [+0 T:0x8fa46ba4] ti.sdo.ce.alg.Algorithm - Algorithm_activate> return

[DSP] @4,943,358tk: [+0 T:0x8fa46ba4] ti.codecs.videnc_copy - VIDENCCOPY_TI_process(0x88000250, 0x8fa48f74, 0x8fa48f80, 0x8fe049b8, 0x8fe049bc)

[DSP] @4,944,017tk: [+2 T:0x8fa46ba4] ti.codecs.videnc_copy - VIDENCCOPY_TI_process> memcpy (0x87ffe000, 0x87fff000, 1024)

[DSP] @4,944,593tk: [+5 T:0x8fa46ba4] CV - VISA_exit(visa=0x8fa46758): algHandle = 0x8fa46788

[DSP] @4,945,008tk: [+0 T:0x8fa46ba4] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(handle=0x8fa46788)

[DSP] @4,945,470tk: [+0 T:0x8fa46ba4] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> return

[DSP] @4,945,883tk: [+0 T:0x8fa46ba4] ti.sdo.ce.video.VIDENC - VIDENC_process> Exit (handle=0x8fa46758, retVal=0x0)

[DSP] @4,946,373tk: [+0 T:0x8fa46ba4] OM - Memory_cacheWb> Enter(addr=0x87ffe000, sizeInBytes=1024)

[DSP] @4,946,799tk: [+0 T:0x8fa46ba4] OM - Memory_cacheWb> return

[DSP] @4,947,087tk: [+5 T:0x8fa46ba4] CN - NODE> returned from call(algHandle=0x8fa46758, msg=0x8fe04880); messageId=0x0002000a

@1,896,229us: [+0 T:0x00004000] CE - Engine_fwriteTrace> returning count [2026]

@1,896,344us: [+0 T:0x00004000] CV - VISA_call Completed: messageId=0x0002000a, command=0x0, return(status=0)

@1,896,470us: [+5 T:0x00004000] CV - VISA_freeMsg(0x41a68, 0x4018f880): Freeing message with messageId=0x0002000a

@1,896,589us: [+0 T:0x00004000] ti.sdo.ce.video.VIDENC - VIDENC_process> Exit (handle=0x41a68, retVal=0x0)

@1,896,701us: [+0 T:0x00004000] ti.sdo.ce.video.VIDDEC - VIDDEC_process> Enter (handle=0x41af0, inBufs=0xbefffdb0, outBufs=0xbefffda0, inArgs=0xbefffd90, outArgs=0xbefffcf0)

@1,896,849us: [+5 T:0x00004000] CV - VISA_allocMsg> Allocating message for messageId=0x00030015

@1,896,961us: [+0 T:0x00004000] CV - VISA_call(visa=0x41af0, msg=0x40190880): messageId=0x00030015, command=0x0

[DSP] @5,744,377tk: [+5 T:0x8fa4916c] CN - NODE> 0x8fa49020(viddec_copy#1) call(algHandle=0x8fa49090, msg=0x8fe05880); messageId=0x00030015

[DSP] @5,744,992tk: [+0 T:0x8fa4916c] OM - Memory_cacheInv> Enter(addr=0x87ffe000, sizeInBytes=1024)

[DSP] @5,745,428tk: [+0 T:0x8fa4916c] OM - Memory_cacheInv> return

[DSP] @5,745,719tk: [+0 T:0x8fa4916c] OM - Memory_cacheInv> Enter(addr=0x87ffd000, sizeInBytes=1024)

[DSP] @5,746,147tk: [+0 T:0x8fa4916c] OM - Memory_cacheInv> return

[DSP] @5,746,465tk: [+0 T:0x8fa4916c] ti.sdo.ce.video.VIDDEC - VIDDEC_process> Enter (handle=0x8fa49090, inBufs=0x8fa4b53c, outBufs=0x8fa4b548, inArgs=0x8fe059b8, outArgs=0x8fe059c4)

[DSP] @5,747,230tk: [+5 T:0x8fa4916c] CV - VISA_enter(visa=0x8fa49090): algHandle = 0x8fa490c0

[DSP] @5,747,648tk: [+0 T:0x8fa4916c] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(handle=0x8fa490c0)

[DSP] @5,748,105tk: [+0 T:0x8fa4916c] ti.codecs.videnc_copy - VIDENCCOPY_TI_deactivate(0x88000250)

[DSP] @5,748,539tk: [+0 T:0x8fa4916c] ti.sdo.ce.alg.Algorithm - Algorithm_activate> return

[DSP] @5,748,923tk: [+0 T:0x8fa4916c] ti.codecs.viddec_copy - VIDDECCOPY_TI_process(0x88000258, 0x8fa4b548, 0x8fe059c4, 0x1, 0x1)

[DSP] @5,749,511tk: [+2 T:0x8fa4916c] ti.codecs.viddec_copy - VIDDECCOPY_TI_process> Processed 1024 bytes.

[DSP] @5,749,954tk: [+5 T:0x8fa4916c] CV - VISA_exit(visa=0x8fa49090): algHandle = 0x8fa490c0

[DSP] @5,750,367tk: [+0 T:0x8fa4916c] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(handle=0x8fa490c0)

[DSP] @5,750,852tk: [+0 T:0x8fa4916c] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> return

[DSP] @5,751,242tk: [+0 T:0x8fa4916c] ti.sdo.ce.video.VIDDEC - VIDDEC_process> Exit (handle=0x8fa49090, retVal=0x0)

[DSP] @5,751,732tk: [+0 T:0x8fa4916c] OM - Memory_cacheWb> Enter(addr=0x87ffd000, sizeInBytes=1024)

[DSP] @5,752,157tk: [+0 T:0x8fa4916c] OM - Memory_cacheWb> return

[DSP] @5,752,444tk: [+5 T:0x8fa4916c] CN - NODE> returned from call(algHandle=0x8fa49090, msg=0x8fe05880); messageId=0x00030015

@1,904,493us: [+0 T:0x00004000] CE - Engine_fwriteTrace> returning count [1998]

@1,904,604us: [+0 T:0x00004000] CV - VISA_call Completed: messageId=0x00030015, command=0x0, return(status=0)

@1,904,729us: [+5 T:0x00004000] CV - VISA_freeMsg(0x41af0, 0x40190880): Freeing message with messageId=0x00030015

@1,904,846us: [+0 T:0x00004000] ti.sdo.ce.video.VIDDEC - VIDDEC_process> Exit (handle=0x41af0, retVal=0x0)

App-> Processing frame 2...

@1,905,067us: [+0 T:0x00004000] ti.sdo.ce.video.VIDENC - VIDENC_process> Enter (handle=0x41a68, inBufs=0xbefffdb0, outBufs=0xbefffda0, inArgs=0xbefffbfc, outArgs=0xbefffd00)

@1,905,222us: [+5 T:0x00004000] CV - VISA_allocMsg> Allocating message for messageId=0x0002000b

@1,905,333us: [+0 T:0x00004000] CV - VISA_call(visa=0x41a68, msg=0x4018f880): messageId=0x0002000b, command=0x0

[DSP] @5,777,082tk: [+5 T:0x8fa46ba4] CN - NODE> 0x8fa466a0(videnc_copy#0) call(algHandle=0x8fa46758, msg=0x8fe04880); messageId=0x0002000b

[DSP] @5,777,704tk: [+0 T:0x8fa46ba4] OM - Memory_cacheInv> Enter(addr=0x87fff000, sizeInBytes=1024)

[DSP] @5,778,132tk: [+0 T:0x8fa46ba4] OM - Memory_cacheInv> return

[DSP] @5,778,421tk: [+0 T:0x8fa46ba4] OM - Memory_cacheInv> Enter(addr=0x87ffe000, sizeInBytes=1024)

[DSP] @5,778,883tk: [+0 T:0x8fa46ba4] OM - Memory_cacheInv> return

[DSP] @5,779,178tk: [+0 T:0x8fa46ba4] ti.sdo.ce.video.VIDENC - VIDENC_process> Enter (handle=0x8fa46758, inBufs=0x8fa48f74, outBufs=0x8fa48f80, inArgs=0x8fe049b8, outArgs=0x8fe049bc)

[DSP] @5,779,940tk: [+5 T:0x8fa46ba4] CV - VISA_enter(visa=0x8fa46758): algHandle = 0x8fa46788

[DSP] @5,780,356tk: [+0 T:0x8fa46ba4] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(handle=0x8fa46788)

[DSP] @5,780,816tk: [+0 T:0x8fa46ba4] ti.codecs.videnc_copy - VIDENCCOPY_TI_activate(0x88000250)

[DSP] @5,781,247tk: [+0 T:0x8fa46ba4] ti.sdo.ce.alg.Algorithm - Algorithm_activate> return

[DSP] @5,781,633tk: [+0 T:0x8fa46ba4] ti.codecs.videnc_copy - VIDENCCOPY_TI_process(0x88000250, 0x8fa48f74, 0x8fa48f80, 0x8fe049b8, 0x8fe049bc)

[DSP] @5,782,287tk: [+2 T:0x8fa46ba4] ti.codecs.videnc_copy - VIDENCCOPY_TI_process> memcpy (0x87ffe000, 0x87fff000, 1024)

[DSP] @5,782,864tk: [+5 T:0x8fa46ba4] CV - VISA_exit(visa=0x8fa46758): algHandle = 0x8fa46788

[DSP] @5,783,278tk: [+0 T:0x8fa46ba4] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(handle=0x8fa46788)

[DSP] @5,783,765tk: [+0 T:0x8fa46ba4] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> return

[DSP] @5,784,156tk: [+0 T:0x8fa46ba4] ti.sdo.ce.video.VIDENC - VIDENC_process> Exit (handle=0x8fa46758, retVal=0x0)

[DSP] @5,784,646tk: [+0 T:0x8fa46ba4] OM - Memory_cacheWb> Enter(addr=0x87ffe000, sizeInBytes=1024)

[DSP] @5,785,071tk: [+0 T:0x8fa46ba4] OM - Memory_cacheWb> return

[DSP] @5,785,359tk: [+5 T:0x8fa46ba4] CN - NODE> returned from call(algHandle=0x8fa46758, msg=0x8fe04880); messageId=0x0002000b

@2,111,421us: [+0 T:0x00004000] CE - Engine_fwriteTrace> returning count [2026]

@2,111,534us: [+0 T:0x00004000] CV - VISA_call Completed: messageId=0x0002000b, command=0x0, return(status=0)

@2,111,657us: [+5 T:0x00004000] CV - VISA_freeMsg(0x41a68, 0x4018f880): Freeing message with messageId=0x0002000b

@2,111,797us: [+0 T:0x00004000] ti.sdo.ce.video.VIDENC - VIDENC_process> Exit (handle=0x41a68, retVal=0x0)

@2,111,912us: [+0 T:0x00004000] ti.sdo.ce.video.VIDDEC - VIDDEC_process> Enter (handle=0x41af0, inBufs=0xbefffdb0, outBufs=0xbefffda0, inArgs=0xbefffd90, outArgs=0xbefffcf0)

@2,112,064us: [+5 T:0x00004000] CV - VISA_allocMsg> Allocating message for messageId=0x00030016

@2,112,177us: [+0 T:0x00004000] CV - VISA_call(visa=0x41af0, msg=0x40190880): messageId=0x00030016, command=0x0

[DSP] @6,585,074tk: [+5 T:0x8fa4916c] CN - NODE> 0x8fa49020(viddec_copy#1) call(algHandle=0x8fa49090, msg=0x8fe05880); messageId=0x00030016

[DSP] @6,585,690tk: [+0 T:0x8fa4916c] OM - Memory_cacheInv> Enter(addr=0x87ffe000, sizeInBytes=1024)

[DSP] @6,586,122tk: [+0 T:0x8fa4916c] OM - Memory_cacheInv> return

[DSP] @6,586,435tk: [+0 T:0x8fa4916c] OM - Memory_cacheInv> Enter(addr=0x87ffd000, sizeInBytes=1024)

[DSP] @6,586,866tk: [+0 T:0x8fa4916c] OM - Memory_cacheInv> return

[DSP] @6,587,159tk: [+0 T:0x8fa4916c] ti.sdo.ce.video.VIDDEC - VIDDEC_process> Enter (handle=0x8fa49090, inBufs=0x8fa4b53c, outBufs=0x8fa4b548, inArgs=0x8fe059b8, outArgs=0x8fe059c4)

[DSP] @6,587,925tk: [+5 T:0x8fa4916c] CV - VISA_enter(visa=0x8fa49090): algHandle = 0x8fa490c0

[DSP] @6,588,343tk: [+0 T:0x8fa4916c] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(handle=0x8fa490c0)

[DSP] @6,588,825tk: [+0 T:0x8fa4916c] ti.codecs.videnc_copy - VIDENCCOPY_TI_deactivate(0x88000250)

[DSP] @6,589,251tk: [+0 T:0x8fa4916c] ti.sdo.ce.alg.Algorithm - Algorithm_activate> return

[DSP] @6,589,633tk: [+0 T:0x8fa4916c] ti.codecs.viddec_copy - VIDDECCOPY_TI_process(0x88000258, 0x8fa4b548, 0x8fe059c4, 0x1, 0x1)

[DSP] @6,590,220tk: [+2 T:0x8fa4916c] ti.codecs.viddec_copy - VIDDECCOPY_TI_process> Processed 1024 bytes.

[DSP] @6,590,662tk: [+5 T:0x8fa4916c] CV - VISA_exit(visa=0x8fa49090): algHandle = 0x8fa490c0

[DSP] @6,591,102tk: [+0 T:0x8fa4916c] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(handle=0x8fa490c0)

[DSP] @6,591,563tk: [+0 T:0x8fa4916c] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> return

[DSP] @6,591,952tk: [+0 T:0x8fa4916c] ti.sdo.ce.video.VIDDEC - VIDDEC_process> Exit (handle=0x8fa49090, retVal=0x0)

[DSP] @6,592,442tk: [+0 T:0x8fa4916c] OM - Memory_cacheWb> Enter(addr=0x87ffd000, sizeInBytes=1024)

[DSP] @6,592,868tk: [+0 T:0x8fa4916c] OM - Memory_cacheWb> return

[DSP] @6,593,179tk: [+5 T:0x8fa4916c] CN - NODE> returned from call(algHandle=0x8fa49090, msg=0x8fe05880); messageId=0x00030016

@2,317,678us: [+0 T:0x00004000] CE - Engine_fwriteTrace> returning count [1998]

@2,317,811us: [+0 T:0x00004000] CV - VISA_call Completed: messageId=0x00030016, command=0x0, return(status=0)

@2,317,941us: [+5 T:0x00004000] CV - VISA_freeMsg(0x41af0, 0x40190880): Freeing message with messageId=0x00030016

@2,318,059us: [+0 T:0x00004000] ti.sdo.ce.video.VIDDEC - VIDDEC_process> Exit (handle=0x41af0, retVal=0x0)

App-> Processing frame 3...

@2,318,283us: [+0 T:0x00004000] ti.sdo.ce.video.VIDENC - VIDENC_process> Enter (handle=0x41a68, inBufs=0xbefffdb0, outBufs=0xbefffda0, inArgs=0xbefffbfc, outArgs=0xbefffd00)

@2,318,440us: [+5 T:0x00004000] CV - VISA_allocMsg> Allocating message for messageId=0x0002000c

@2,318,553us: [+0 T:0x00004000] CV - VISA_call(visa=0x41a68, msg=0x4018f880): messageId=0x0002000c, command=0x0

[DSP] @7,391,226tk: [+5 T:0x8fa46ba4] CN - NODE> 0x8fa466a0(videnc_copy#0) call(algHandle=0x8fa46758, msg=0x8fe04880); messageId=0x0002000c

[DSP] @7,391,858tk: [+0 T:0x8fa46ba4] OM - Memory_cacheInv> Enter(addr=0x87fff000, sizeInBytes=1024)

[DSP] @7,392,287tk: [+0 T:0x8fa46ba4] OM - Memory_cacheInv> return

[DSP] @7,392,576tk: [+0 T:0x8fa46ba4] OM - Memory_cacheInv> Enter(addr=0x87ffe000, sizeInBytes=1024)

[DSP] @7,393,002tk: [+0 T:0x8fa46ba4] OM - Memory_cacheInv> return

[DSP] @7,393,294tk: [+0 T:0x8fa46ba4] ti.sdo.ce.video.VIDENC - VIDENC_process> Enter (handle=0x8fa46758, inBufs=0x8fa48f74, outBufs=0x8fa48f80, inArgs=0x8fe049b8, outArgs=0x8fe049bc)

[DSP] @7,394,091tk: [+5 T:0x8fa46ba4] CV - VISA_enter(visa=0x8fa46758): algHandle = 0x8fa46788

[DSP] @7,394,509tk: [+0 T:0x8fa46ba4] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(handle=0x8fa46788)

[DSP] @7,394,967tk: [+0 T:0x8fa46ba4] ti.codecs.videnc_copy - VIDENCCOPY_TI_activate(0x88000250)

[DSP] @7,395,376tk: [+0 T:0x8fa46ba4] ti.sdo.ce.alg.Algorithm - Algorithm_activate> return

[DSP] @7,395,760tk: [+0 T:0x8fa46ba4] ti.codecs.videnc_copy - VIDENCCOPY_TI_process(0x88000250, 0x8fa48f74, 0x8fa48f80, 0x8fe049b8, 0x8fe049bc)

[DSP] @7,396,434tk: [+2 T:0x8fa46ba4] ti.codecs.videnc_copy - VIDENCCOPY_TI_process> memcpy (0x87ffe000, 0x87fff000, 1024)

[DSP] @7,397,011tk: [+5 T:0x8fa46ba4] CV - VISA_exit(visa=0x8fa46758): algHandle = 0x8fa46788

[DSP] @7,397,424tk: [+0 T:0x8fa46ba4] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(handle=0x8fa46788)

[DSP] @7,397,886tk: [+0 T:0x8fa46ba4] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> return

[DSP] @7,398,303tk: [+0 T:0x8fa46ba4] ti.sdo.ce.video.VIDENC - VIDENC_process> Exit (handle=0x8fa46758, retVal=0x0)

[DSP] @7,398,794tk: [+0 T:0x8fa46ba4] OM - Memory_cacheWb> Enter(addr=0x87ffe000, sizeInBytes=1024)

[DSP] @7,399,220tk: [+0 T:0x8fa46ba4] OM - Memory_cacheWb> return

[DSP] @7,399,508tk: [+5 T:0x8fa46ba4] CN - NODE> returned from call(algHandle=0x8fa46758, msg=0x8fe04880); messageId=0x0002000c

@2,326,191us: [+0 T:0x00004000] CE - Engine_fwriteTrace> returning count [2026]

@2,326,303us: [+0 T:0x00004000] CV - VISA_call Completed: messageId=0x0002000c, command=0x0, return(status=0)

@2,326,429us: [+5 T:0x00004000] CV - VISA_freeMsg(0x41a68, 0x4018f880): Freeing message with messageId=0x0002000c

@2,326,549us: [+0 T:0x00004000] ti.sdo.ce.video.VIDENC - VIDENC_process> Exit (handle=0x41a68, retVal=0x0)

@2,326,659us: [+0 T:0x00004000] ti.sdo.ce.video.VIDDEC - VIDDEC_process> Enter (handle=0x41af0, inBufs=0xbefffdb0, outBufs=0xbefffda0, inArgs=0xbefffd90, outArgs=0xbefffcf0)

@2,525,570us: [+5 T:0x00004000] CV - VISA_allocMsg> Allocating message for messageId=0x00030017

@2,525,708us: [+0 T:0x00004000] CV - VISA_call(visa=0x41af0, msg=0x40190880): messageId=0x00030017, command=0x0

[DSP] @8,200,438tk: [+5 T:0x8fa4916c] CN - NODE> 0x8fa49020(viddec_copy#1) call(algHandle=0x8fa49090, msg=0x8fe05880); messageId=0x00030017

[DSP] @8,201,054tk: [+0 T:0x8fa4916c] OM - Memory_cacheInv> Enter(addr=0x87ffe000, sizeInBytes=1024)

[DSP] @8,201,505tk: [+0 T:0x8fa4916c] OM - Memory_cacheInv> return

[DSP] @8,201,796tk: [+0 T:0x8fa4916c] OM - Memory_cacheInv> Enter(addr=0x87ffd000, sizeInBytes=1024)

[DSP] @8,202,224tk: [+0 T:0x8fa4916c] OM - Memory_cacheInv> return

[DSP] @8,202,518tk: [+0 T:0x8fa4916c] ti.sdo.ce.video.VIDDEC - VIDDEC_process> Enter (handle=0x8fa49090, inBufs=0x8fa4b53c, outBufs=0x8fa4b548, inArgs=0x8fe059b8, outArgs=0x8fe059c4)

[DSP] @8,203,304tk: [+5 T:0x8fa4916c] CV - VISA_enter(visa=0x8fa49090): algHandle = 0x8fa490c0

[DSP] @8,203,763tk: [+0 T:0x8fa4916c] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(handle=0x8fa490c0)

[DSP] @8,204,247tk: [+0 T:0x8fa4916c] ti.codecs.videnc_copy - VIDENCCOPY_TI_deactivate(0x88000250)

[DSP] @8,204,687tk: [+0 T:0x8fa4916c] ti.sdo.ce.alg.Algorithm - Algorithm_activate> return

[DSP] @8,205,092tk: [+0 T:0x8fa4916c] ti.codecs.viddec_copy - VIDDECCOPY_TI_process(0x88000258, 0x8fa4b548, 0x8fe059c4, 0x1, 0x1)

[DSP] @8,205,735tk: [+2 T:0x8fa4916c] ti.codecs.viddec_copy - VIDDECCOPY_TI_process> Processed 1024 bytes.

[DSP] @8,206,207tk: [+5 T:0x8fa4916c] CV - VISA_exit(visa=0x8fa49090): algHandle = 0x8fa490c0

[DSP] @8,206,644tk: [+0 T:0x8fa4916c] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(handle=0x8fa490c0)

[DSP] @8,207,133tk: [+0 T:0x8fa4916c] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> return

[DSP] @8,207,544tk: [+0 T:0x8fa4916c] ti.sdo.ce.video.VIDDEC - VIDDEC_process> Exit (handle=0x8fa49090, retVal=0x0)

[DSP] @8,208,084tk: [+0 T:0x8fa4916c] OM - Memory_cacheWb> Enter(addr=0x87ffd000, sizeInBytes=1024)

[DSP] @8,208,535tk: [+0 T:0x8fa4916c] OM - Memory_cacheWb> return

[DSP] @8,208,839tk: [+5 T:0x8fa4916c] CN - NODE> returned from call(algHandle=0x8fa49090, msg=0x8fe05880); messageId=0x00030017

@2,533,348us: [+0 T:0x00004000] CE - Engine_fwriteTrace> returning count [1998]

@2,533,458us: [+0 T:0x00004000] CV - VISA_call Completed: messageId=0x00030017, command=0x0, return(status=0)

@2,533,581us: [+5 T:0x00004000] CV - VISA_freeMsg(0x41af0, 0x40190880): Freeing message with messageId=0x00030017

@2,533,699us: [+0 T:0x00004000] ti.sdo.ce.video.VIDDEC - VIDDEC_process> Exit (handle=0x41af0, retVal=0x0)

App-> Finished encoding and decoding 4 frames

@2,534,218us: [+0 T:0x00004000] ti.sdo.ce.video.VIDENC - VIDENC_delete> Enter (handle=0x41a68)

@2,534,351us: [+0 T:0x00004000] CV - VISA_delete(0x41a68)

@2,534,441us: [+5 T:0x00004000] CV - VISA_delete> deleting codec (localQueue=0x10001, remoteQueue=0x2)

@2,534,660us: [+0 T:0x00004000] CE - Engine_deleteNode(0x419e0)

@2,536,871us: [+5 T:0x00004000] CE - Engine_deleteNode(0x419e0): algName = videnc_copy, algHandle = 0x8fa46758, stack size = 9216, stack used = 795(9%)

@2,537,268us: [+0 T:0x00004000] ti.sdo.ce.video.VIDENC - VIDENC_delete> return

@2,537,397us: [+0 T:0x00004000] ti.sdo.ce.video.VIDDEC - VIDDEC_delete> Enter (handle=0x41af0)

@2,537,506us: [+0 T:0x00004000] CV - VISA_delete(0x41af0)

@2,537,592us: [+5 T:0x00004000] CV - VISA_delete> deleting codec (localQueue=0x10002, remoteQueue=0x3)

@2,537,807us: [+0 T:0x00004000] CE - Engine_deleteNode(0x41b28)

@2,539,983us: [+5 T:0x00004000] CE - Engine_deleteNode(0x41b28): algName = viddec_copy, algHandle = 0x8fa49090, stack size = 9216, stack used = 787(9%)

@2,540,409us: [+0 T:0x00004000] ti.sdo.ce.video.VIDDEC - VIDDEC_delete> return

@2,540,558us: [+0 T:0x00004000] CE - Engine_close(0x41790)

[DSP] @8,234,542tk: [+0 T:0x8fa46ba4] CN - NODE_EXECFXN(0x8fa466a0): videnc_copy#0 exiting per request ...

[DSP] @8,235,677tk: [+0 T:0x8fa4578c] CN - NODE_delete(0x8fa466a0): videnc_copy#0

[DSP] @8,236,111tk: [+0 T:0x8fa4578c] ti.sdo.ce.video.VIDENC - VIDENC_delete> Enter (handle=0x8fa46758)

[DSP] @8,236,570tk: [+0 T:0x8fa4578c] CV - VISA_delete(0x8fa46758)

[DSP] @8,236,910tk: [+5 T:0x8fa4578c] CV - VISA_delete> deleting codec (localQueue=0xffff, remoteQueue=0xffff)

[DSP] @8,237,410tk: [+0 T:0x8fa4578c] ti.sdo.ce.alg.Algorithm - Algorithm_delete> Enter(handle=0x8fa46788)

[DSP] @8,237,926tk: [+0 T:0x8fa4578c] OM - Memory_free> Enter(addr=0x8fa46788, size=16)

[DSP] @8,238,359tk: [+0 T:0x8fa4578c] OM - Memory_free> return (0x1)

[DSP] @8,238,684tk: [+0 T:0x8fa4578c] ti.sdo.ce.alg.Algorithm - Algorithm_delete> return

[DSP] @8,239,085tk: [+0 T:0x8fa4578c] OM - Memory_free> Enter(addr=0x8fa46758, size=48)

[DSP] @8,239,490tk: [+0 T:0x8fa4578c] OM - Memory_free> return (0x1)

[DSP] @8,239,812tk: [+0 T:0x8fa4578c] ti.sdo.ce.video.VIDENC - VIDENC_delete> return

[DSP] @8,240,193tk: [+0 T:0x8fa4578c] OM - Memory_free> Enter(addr=0x8fa466c0, size=36)

[DSP] @8,240,619tk: [+0 T:0x8fa4578c] OM - Memory_free> return (0x1)

[DSP] @8,240,941tk: [+0 T:0x8fa4578c] OM - Memory_free> Enter(addr=0x8fa466a0, size=32)

[DSP] @8,241,342tk: [+0 T:0x8fa4578c] OM - Memory_free> return (0x1)

[DSP] @8,241,664tk: [+0 T:0x8fa4578c] OM - Memory_free> Enter(addr=0x8fa46690, size=14)

[DSP] @8,242,068tk: [+0 T:0x8fa4578c] OM - Memory_free> return (0x1)

[DSP] @8,242,385tk: [+0 T:0x8fa4578c] OM - Memory_free> Enter(addr=0x8fa46678, size=24)

[DSP] @8,242,788tk: [+0 T:0x8fa4578c] OM - Memory_free> return (0x1)

[DSP] @8,246,844tk: [+0 T:0x8fa4916c] CN - NODE_EXECFXN(0x8fa49020): viddec_copy#1 exiting per request ...

[DSP] @8,247,959tk: [+0 T:0x8fa4578c] CN - NODE_delete(0x8fa49020): viddec_copy#1

[DSP] @8,248,373tk: [+0 T:0x8fa4578c] ti.sdo.ce.video.VIDDEC - VIDDEC_delete> Enter (handle=0x8fa49090)

[DSP] @8,248,835tk: [+0 T:0x8fa4578c] CV - VISA_delete(0x8fa49090)

[DSP] @8,249,174tk: [+5 T:0x8fa4578c] CV - VISA_delete> deleting codec (localQueue=0xffff, remoteQueue=0xffff)

[DSP] @8,249,676tk: [+0 T:0x8fa4578c] ti.sdo.ce.alg.Algorithm - Algorithm_delete> Enter(handle=0x8fa490c0)

[DSP] @8,250,201tk: [+0 T:0x8fa4578c] OM - Memory_free> Enter(addr=0x8fa490c0, size=16)

[DSP] @8,250,612tk: [+0 T:0x8fa4578c] OM - Memory_free> return (0x1)

[DSP] @8,250,937tk: [+0 T:0x8fa4578c] ti.sdo.ce.alg.Algorithm - Algorithm_delete> return

[DSP] @8,251,338tk: [+0 T:0x8fa4578c] OM - Memory_free> Enter(addr=0x8fa49090, size=48)

[DSP] @8,251,746tk: [+0 T:0x8fa4578c] OM - Memory_free> return (0x1)

[DSP] @8,252,069tk: [+0 T:0x8fa4578c] ti.sdo.ce.video.VIDDEC - VIDDEC_delete> return

[DSP] @8,252,477tk: [+0 T:0x8fa4578c] OM - Memory_free> Enter(addr=0x8fa49040, size=36)

[DSP] @8,252,885tk: [+0 T:0x8fa4578c] OM - Memory_free> return (0x1)

[DSP] @8,253,204tk: [+0 T:0x8fa4578c] OM - Memory_free> Enter(addr=0x8fa49020, size=32)

[DSP] @8,253,597tk: [+0 T:0x8fa4578c] OM - Memory_free> return (0x1)

[DSP] @8,253,901tk: [+0 T:0x8fa4578c] OM - Memory_free> Enter(addr=0x8fa49010, size=14)

[DSP] @8,254,283tk: [+0 T:0x8fa4578c] OM - Memory_free> return (0x1)

[DSP] @8,254,603tk: [+0 T:0x8fa4578c] OM - Memory_free> Enter(addr=0x8fa48ff8, size=24)

[DSP] @8,254,988tk: [+0 T:0x8fa4578c] OM - Memory_free> return (0x1)

@2,747,752us: [+0 T:0x00004000] CE - Engine_fwriteTrace> returning count [3388]

@2,748,088us: [+0 T:0x00004000] CE - rserverClose(0x3e60c), count = 1

@2,748,233us: [+0 T:0x00004000] OP - Processor_delete> Enter(proc=0x417c0)

@2,748,339us: [+0 T:0x00004000] OP - doCmd> Enter (cmdId=2, proc=0x417c0)

@2,748,434us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x3f508]

@2,748,554us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x3f508]

@2,748,658us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x3f520] timeout[0xffffffff]

@2,748,811us: [+0 T:0x00004002] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x3f508] status[0]

@2,748,933us: [+0 T:0x00004002] OP - getCmd_d> Exit (result=2)

@2,947,599us: [+0 T:0x00004002] OP - Processor_delete_d> Enter (proc=0x417c0)

@2,947,715us: [+2 T:0x00004002] OP - Processor_delete_d> Closing remote transport...

@2,948,009us: [+2 T:0x00004002] OP - Processor_delete_d> Stopping DSP...

@2,948,287us: [+2 T:0x00004002] OP - Processor_delete_d> Closing pool...

@2,948,660us: [+2 T:0x00004002] OP - Processor_delete_d> Detaching from DSP...

@2,957,911us: [+2 T:0x00004002] OP - Processor_delete_d> Destroying DSP... (object, that is)

@2,958,692us: [+0 T:0x00004002] OP - Processor_delete_d> return

@2,958,819us: [+0 T:0x00004002] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x3f520]

@2,958,950us: [+0 T:0x00004002] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x3f520]

@2,959,127us: [+0 T:0x00004002] OP - getCmd_d> Enter (proc=0xbe7ffaec)

@2,959,306us: [+0 T:0x00004002] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x3f508] timeout[0xffffffff]

@2,959,477us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x3f520] status[0]

@2,959,601us: [+0 T:0x00004000] OP - doCmd> Exit (result=1)

@2,959,688us: [+1 T:0x00004000] OP - Processor_delete(0x417c0) freeing object ...

@2,959,791us: [+0 T:0x00004000] OP - Processor_delete> return.

@2,959,876us: [+0 T:0x00004000] CE - rserverClose(0x3e60c) done.

App-> Application finished successfully.

@2,960,198us: [+0 T:0x00004000] OP - doCmd> Enter (cmdId=3, proc=0x0)

@2,960,316us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x3f508]

@2,960,443us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x3f508]

@2,960,549us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x3f520] timeout[0xffffffff]

@2,960,702us: [+0 T:0x00004002] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x3f508] status[0]

@2,960,824us: [+0 T:0x00004002] OP - getCmd_d> Exit (result=3)

@2,960,942us: [+0 T:0x00004002] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x3f520]

@2,961,060us: [+0 T:0x00004002] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x3f520]

@2,965,313us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x3f520] status[0]

@2,965,456us: [+0 T:0x00004000] OP - doCmd> Exit (result=1)

@2,965,548us: [+0 T:0x00004000] OT - Thread_delete> Enter (task=0x3f558)

@2,965,669us: [+4 T:0x00004000] OT - Thread_delete> pthread_cancel (0x3)

@2,965,794us: [+4 T:0x00004000] OT - Thread_delete> pthread_join (0x0)

@2,965,919us: [+0 T:0x00004000] OT - Thread_delete> Exit (task=0x3f558)

@2,966,019us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Entered Sem_delete> sem[0x3f508]

@2,966,144us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Leaving Sem_delete>

@2,966,240us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Entered Sem_delete> sem[0x3f520]

@2,966,343us: [+0 T:0x00004000] ti.sdo.ce.osal.Sem - Leaving Sem_delete>

0 Feng Dong 14 年多前

TI__Expert 8140 points

Problem: PROC_start failed with configuration mismatch/failure

Symptom: PROC_start timed out and/or printed configuration mismatch failure information of the type:

DSP-side configuration mismatch/failure

0 -> success

Positive value -> DSP-side failure code.

(Uint32) -1 -> DSP-side component was not initialized.

DRV configuration status [0xffffffff]

IPS configuration status [0xffffffff]

POOL configuration status [0xffffffff]

MPCS configuration status [0xffffffff]

MPLIST configuration status [0xffffffff]

MQT configuration status [0xffffffff]

DATA configuration status [0xffffffff]

RingIO configuration status [0xffffffff]

Explanation: This failure indicates that DSPLink failed while attempting to start the DSP running. DSPLink internally performs handshake within the drivers on the GPP and DSP. It verifies that each component on the DSP has been correctly initialized, as required by the GPP.

The above print indicates the status of initialization of each DSPLink component on the DSP-side.

For example, the above prints indicate that none of the DSPLink components were initialized. 0xffffffff means that the value is -1. This indicates that the specific DSP-side component with this value was not initialized. When this appears for all components, it indicates that none of the components were initialized. This implies that there was some generic issue in running the DSP.

Possible cause 1: DSP has not been loaded correctly, and hence is not executing correctly to reach the point where the driver completes the handshake. In case of a custom board, check if the board setup/configuration has been done correctly.

Possible cause 2: There is a configuration mismatch in memory regions between the GPP and DSP, resulting in the GPP looking for handshake at one location, and the DSP looking at another location. Check the DSP-side and GPP-side configuration files.

Possible cause 3: User has not reserved the required amount of memory from Linux kernel space. On Linux, check the bootcmd for mem parameter as specified at Changing DSPLink memory map wiki page

Possible cause 4: There is some other issue on the DSP-side resulting in the execution not reaching the driver handshake. This could also be an application integration issue, and can only be identified by debugging the DSP-side by connecting Code Composer Studio (CCS).

0 Heng Lee 14 年多前回复 Feng Dong

Prodigy 30 points

这些资料我之前就已经看过了，应该不是这些原因啊。这都是网络上的，我都可以搜索到的。

0 Feng Dong 14 年多前回复 Heng Lee

TI__Expert 8140 points

这里已经说了如果以上三个不是的话,请提供CCS连接是DSP的详细信息,所有寄存器的值

另外也要查电源部分,特别是core供电,

0 Feng Dong 14 年多前回复 Feng Dong

TI__Expert 8140 points

这个问题怎么样了?

处理器

处理器论坛

DM6467启动问题到dsp时提示DSP-side failure code等

处理器

处理器论坛

DM6467启动问题 到dsp时提示DSP-side failure code等

DM6467启动问题到dsp时提示DSP-side failure code等