This thread has been locked.

If you have a related question, please click the "Ask a related question" button in the top right corner. The newly created question will be automatically linked to this question.

DM385: DM385 Boot Time Optimazation

Part Number: DM385

Hello All,

I am using DM385 based custom board with IPNC 3.5 rdk. I am booting from nand ubifs.

1. I want to reduce boot time for loading firmware from host to M3 (m3vpss,m3video). it took around 2.2 seconds .I have attached my boot log with time stamp.

2. How to remove below log message. I can't find the printf statement

[8.520974 0.070999]  [m3vpss ] {SWOSD} edma3Handle->tccVal = 36
[8.524280 0.003306]  [m3vpss ] {SWOSD} edma3Handle->chId   = 36
[8.532919 0.008639]  [m3vpss ] {SWOSD} edma3Handle->tccVal = 37
[8.535377 0.002458]  [m3vpss ] {SWOSD} edma3Handle->chId   = 37
[8.538833 0.003456]  [m3vpss ] {SWOSD} edma3Handle->tccVal = 38
[8.544242 0.005409]  [m3vpss ] {SWOSD} edma3Handle->chId   = 38
[8.547694 0.003452]  [m3vpss ] {SWOSD} edma3Handle->tccVal = 39
[8.553242 0.005548]  [m3vpss ] {SWOSD} edma3Handle->chId   = 39
[8.555574 0.002332]  [m3vpss ] {SWOSD} edma3Handle->tccVal = 40
[8.560970 0.005396]  [m3vpss ] {SWOSD} edma3Handle->chId   = 40

Regards,

RAJ M

[0.000000 0.000000] 
[0.000115 0.000115] U-Boot 2010.06 (Apr 05 2017 - 12:24:30) DM385_IPNC_3.50.00
[0.002714 0.002599] 
[0.004921 0.002207] NAND device: Manufacturer ID: 0x01, Chip ID: 0xf1 (Spansion/AMD NAND 128MiB 3,3V 8-bit)
[0.012753 0.007832] 
[0.014940 0.002187] 
[0.015007 0.000067] 
[0.015063 0.000056] NAND read: device 0 offset 0x20000, size 0x1e000
[0.222977 0.207914]  122880 bytes read: OK
[0.228982 0.006005] 
[0.229048 0.000066] U-Boot 2010.06 (Apr 05 2017 - 12:24:59) DM385_IPNC_3.50.00
[0.240970 0.011922] 
[0.241031 0.000061] NAND device: Manufacturer ID: 0x01, Chip ID: 0xf1 (Spansion/AMD NAND 128MiB 3,3V 8-bit)
[0.248620 0.007589] 
[0.666005 0.417385] 
[0.668930 0.002925] Data (writethrough) Cache is ON
[0.671960 0.003030] 
[0.671995 0.000035] Loading from NAND 128MiB 3,3V 8-bit, offset 0x280000
[0.676587 0.004592]    Image Name:   Linux-2.6.37_DM385_CARDVR_3.50.0
[0.681247 0.004660]    Image Type:   ARM Linux Kernel Image (uncompressed)
[0.687037 0.005790]    Data Size:    1763452 Bytes = 1.7 MiB
[0.690300 0.003263]    Load Address: 80008000
[0.693173 0.002873]    Entry Point:  80008000
[1.569985 0.876812] ## Booting kernel from Legacy Image at 80007fc0 ...
[1.573470 0.003485]    Image Name:   Linux-2.6.37_DM385_CARDVR_3.50.0
[1.579170 0.005700]    Image Type:   ARM Linux Kernel Image (uncompressed)
[1.583918 0.004748]    Data Size:    1763452 Bytes = 1.7 MiB
[1.587235 0.003317]    Load Address: 80008000
[1.590066 0.002831]    Entry Point:  80008000
[1.592933 0.002867] OK
[1.593074 0.000141] OK
[1.593210 0.000136] 
[1.593257 0.000047] Starting kernel ...
[1.594128 0.000871] 
[1.597979 0.003851] Uncompressing Linux... done, booting the kernel.
[2.025965 0.427986] Trying to install type control for IRQ375
[2.029267 0.003302] Trying to set irq flags for IRQ375
[2.032409 0.003142] omap_voltage_domain_lookup: Voltage driver init not yet happened.Faulting!
[2.674976 0.642567] omap_voltage_late_init: Voltage driver support not added
[2.937989 0.263013] INIT: version 2.86 booting
[3.137985 0.199996] First Runlevel
[3.536974 0.398989] SysLink version : 2.20.02.20
[3.537776 0.000802] SysLink module created on Date:Apr  5 2017 Time:12:30:42
[3.543594 0.005818] Trace enabled
[3.545133 0.001539] Trace SetFailureReason enabled
[3.612661 0.067528] /dev/mem opened.
[4.530002 0.917341] 
[4.530106 0.000104]  [host] Loaded file ./firmware/ipnc_rdk_fw_m3vpss.xem3 on slave procId 1.
[4.633977 0.103871] Finish Runlevel
[5.052010 0.418033] 
[5.052100 0.000090]  [host] Loaded file ./firmware/ipnc_rdk_fw_m3video.xem3 on slave procId 0.
[5.169972 0.117872]  [m3video] SYSTEM  : Frequency <ORG> - 200000000, <NEW> - 200000000
[5.175038 0.005066]  [m3vpss ] SYSTEM  : Frequency <ORG> - 200000000, <NEW> - 200000000
[5.185920 0.010882]  [m3vpss ] UTILS: CPU KHz = 400000 Khz ***
[5.188307 0.002387]  [m3vpss ]  91: MEM: Shared Region 2: Base = 0xb1000000, Length = 0x0d900000 (217 MB) 
[5.197032 0.008725]  [m3vpss ]  91: MEM: Shared Region 1: Base = 0x8b800000, Length = 0x01e00000 (30 MB) 
[5.203625 0.006593]  [m3vpss ] Received character 's'
[6.183980 0.980355]  [m3video] UTILS: CPU KHz = 400000 Khz ***
[6.186163 0.002183]  [m3video]  1091: MEM: Shared Region 2: Base = 0xb1000000, Length = 0x0d900000 (217 MB) 
[6.194100 0.007937]  [m3video]  1092: MEM: Shared Region 1: Base = 0x8b800000, Length = 0x01e00000 (30 MB) 
[6.200565 0.006465]  [m3video] Received character 's'
[6.218985 0.018420] 
[6.221108 0.002043] 
[6.226926 0.005818] message_receive_thread creation success for Module ID=12
[6.230471 0.003545] Set amixer Start...!!!
[6.268987 0.038516] Error: SemWait: Invalid Semaphore handler
[6.278960 0.009973] Error: SemRelease: Invalid Semaphore handler
[6.282251 0.003291] ./bin/ipnc_rdk_mcfw.out   VNF_QUALITY CARDVRSINGLESTREAM MIPIDISPLAY_CH0 LCD AVI_RECORD_TRUE MJPEG_STREAM_FALSE LIVE_STREAM_FALSE TRIPLE_H264 TI2A AEWB HIGH_SPEED1 H264 HIGH_SPEEFHD &
[6.504970 0.222719]  [m3vpss ] === I2C0/2 Clk is active ===
[6.727975 0.223005]  [m3vpss ] AR0330 Camera Sensor Configuration Start...!!!
[6.730619 0.002644]  [m3vpss ]  
[6.970973 0.240354] Set amixer Done...!!!
[6.980957 0.009984]  [m3vpss ] AR0330 Camera Sensor Configuration Done...!!!
[6.984648 0.003691]  [m3vpss ]  
[7.104987 0.120339]  [m3vpss ] Received character 't'
[7.139991 0.035004]  [m3video] Received character 't'
[7.343003 0.203012] 
[7.343092 0.000089]  [host] Application Start Completed
[7.738008 0.394916] 
[7.738097 0.000089]  [host]  DEBUG : cPtr = CAMERA:1
[7.743916 0.005819] 742: PB_LOGS: Entered playbackTsk_main()
[7.747164 0.003248] 574: PB_LOGS: Entered videoSubmit_main()
[7.806981 0.059817] 
[7.807074 0.000093]  [host] cOverlay : 1
[7.807817 0.000743] 
[7.807886 0.000069]  [host]  IN MultiCh_cardvr_demo Config file read success
[7.840970 0.033084] 
[7.841068 0.000098]  [host]   :::###### FullHD Decoder Selected to create configuration... ######## 
[7.847675 0.006607] 
[7.847738 0.000063]  [host]   :::###### ########################################################### 
[7.856338 0.008600] 
[7.856407 0.000069]  [host]   ::: In Usecase width = (1920)...
[7.861082 0.004675] 
[7.861150 0.000068]  [host]   ::: In Usecase height = (1080)...
[7.868193 0.007043] 
[7.868272 0.000079]  [host]   ::: In Usecase width(D1) = (720)...
[7.870858 0.002586] 
[7.870921 0.000063]  [host]   ::: In Usecase height(D1) = (480)...
[7.875740 0.004819] 
[7.875805 0.000065]  [host]   ::: In Usecase width for 720p = (1280)...
[7.881785 0.005980] 
[7.881852 0.000067]  [host]   ::: In Usecase height 720p = (720)...
[7.887761 0.005909] 
[7.887830 0.000069]  [host]   ::: In DEC Create FullHD...
[7.892016 0.004186] 
[7.892084 0.000068]  [host]   : Checking width in Decoder (CH0) FullHD side = 1920
[7.898166 0.006082] 
[7.898218 0.000052]  [host]   : Checking Height in Decoder(CH0) FullHD side = 1080
[7.903281 0.005063] 
[7.903346 0.000065]  [host]   : Checking width in Decoder (CH1) side = 720
[7.909160 0.005814] 
[7.909235 0.000075]  [host]   : Checking Height in Decoder(CH1) side = 480
[7.915018 0.005783] 
[7.915087 0.000069]  [host]   : Checking width in Decoder (CH2) for D1 side = 1280
[7.920153 0.005066] 
[7.920221 0.000068]  [host]   : Checking Height in Decoder(CH2) for D1 side = 720
[8.131992 0.211771]  [m3vpss ] DCC server task running 
[8.194977 0.062985] Starting udev
[8.237450 0.003036] 
[8.239933 0.002483] message_receive_thread creation success for Module ID=2
[8.354978 0.115045]  [m3vpss ] TVP5158 Video Decoder Configuration Start...!!!
[8.358637 0.003659]  [m3vpss ]  TVP5158: 0x58: NO Patch downloaded, using ROM firmware.
[8.398961 0.040324]  [m3vpss ] TVP5158 Video Decoder Configuration Done...!!!
[8.520974 0.070999]  [m3vpss ] {SWOSD} edma3Handle->tccVal = 36 
[8.524280 0.003306]  [m3vpss ] {SWOSD} edma3Handle->chId   = 36 
[8.532919 0.008639]  [m3vpss ] {SWOSD} edma3Handle->tccVal = 37 
[8.535377 0.002458]  [m3vpss ] {SWOSD} edma3Handle->chId   = 37 
[8.538833 0.003456]  [m3vpss ] {SWOSD} edma3Handle->tccVal = 38 
[8.544242 0.005409]  [m3vpss ] {SWOSD} edma3Handle->chId   = 38 
[8.547694 0.003452]  [m3vpss ] {SWOSD} edma3Handle->tccVal = 39 
[8.553242 0.005548]  [m3vpss ] {SWOSD} edma3Handle->chId   = 39 
[8.555574 0.002332]  [m3vpss ] {SWOSD} edma3Handle->tccVal = 40 
[8.560970 0.005396]  [m3vpss ] {SWOSD} edma3Handle->chId   = 40 
[8.983978 0.357988] Caching udev devnodes
[9.868941 0.010679] 
[9.869068 0.000127]  [host] USECASE SETUP DONE
[9.870059 0.000991] 
[9.870122 0.000063]  [host] #################  : In iSetOSD() 
[9.872423 0.002301] 
[9.872485 0.000062]  [host] #################  : In iSetOSD() 
[9.877925 0.005440] 
[9.877998 0.000073]  [host] #################  : In iSetOSD() 
[9.881436 0.003438] 
[9.881501 0.000065]  [host] #################  : In iSetOSD() 
[9.884975 0.003474]  [m3vpss ]   : width is 720 and height is 480 : dataFormat 1
[9.889934 0.004959]  [m3vpss ]   : pitch 0 is 1440 : pitch 1 is 0 : pitch 2 is 0
[9.895856 0.005922]  [m3vpss ] DisplayId = 4,DataFormat = 1
[9.902295 0.006439]  [m3vpss ]  : DisplayLink_drvSwitchCh(665) Display channel no is 0
[9.906279 0.003984] WIS_STREAMER_VER = 0.00.01
[9.909144 0.002865] /opt/dvr/bin/wis-streamer
[9.912013 0.002869] camera1
[9.912322 0.000309] ###########IsSilence = 0 ################
[9.914641 0.002319] ###########streamingMode = 0 ################
[9.920070 0.005429] ###########audioType = 0 ################
[9.923452 0.003382] ###########svcEnable = 0 ################
[9.926835 0.003383] In fn share_memory_init
[9.929591 0.002756] 
[9.929659 0.000068]  [host]   : MSG_CMD_GET_MEM case 
[9.931812 0.002153] 
[9.931873 0.000061]  [host]    : total mem size  is 59703296
[9.937200 0.005327]  
[9.937302 0.000102]  [host] Active channel : 4 
[9.940243 0.002941]  [m3vpss ]  : DisplayLink_drvSwitchCh(665) Display channel no is 4
[9.945398 0.005155] Sending discover...
[9.948046 0.002648] Initializing...
[9.948626 0.000580] 
[9.948689 0.000063]  [host] ##############Deciding encoder channel#################
[9.954725 0.006036] 
[9.954785 0.000060]  [host] ENC_DISABLE
[9.957524 0.002739] 
[9.957587 0.000063]  [host] 
[9.958915 0.001328] Disable Channel: 1
[9.959579 0.000664]  [host] 
[9.961916 0.002337] Disable Channel: 2
[9.962587 0.000671]  [host] 
[9.964917 0.002330] Disable Channel: 3...done initializing
[10.099988 0.117777]  [m3vpss ]  4936: CAMERA: Fields = 1 (fps = 0), Total Resets = 0 (Avg 0 ms per reset)
[10.135999 0.036011]   : the sample rate is 8000
[10.137123 0.001124]  : IN Fn (Audio_captureCreate) AudioSampling Rate=8000
[10.142690 0.005567] 
[10.142759 0.000069]  [host] 
[10.146039 0.003280]  Audio capture task created [m3vpss ]  5062: CAPTURE: Fields = 0 (fps = 0), Total Resets = 0 (Avg 0 ms per reset)
[10.246986 0.100947] 
[10.247086 0.000100]  [host] AUDIO : period size = 500 frames, dir = 0
[10.250659 0.003573] 
[10.250726 0.000067]  [host] AUDIO : period time = 62500 us, dir = 0
[10.321640 0.000672] 
[10.324801 0.003109] 
[10.799401 0.004477] INIT: Entering runlevel: 5
[10.852952 0.053551] Starting telnet daemon
[10.876918 0.023966] 
[10.916954 0.040036] Starting syslogd/klogd: done
[11.012984 0.096030] c
[11.013140 0.000156]  _____                    _____           _         _   
[11.016745 0.003605] |  _  |___ ___ ___ ___   |  _  |___ ___  |_|___ ___| |_ 
[11.021551 0.004806] |     |  _| .'| . | . |  |   __|  _| . | | | -_|  _|  _|
[11.027407 0.005856] |__|__|_| |__,|_  |___|  |__|  |_| |___|_| |___|___|_|  
[11.033254 0.005847]               |___|                    |___|            
[11.038081 0.004827] 
[11.038117 0.000036] Arago Project http://arago-project.org dm814x-evm ttyO0
[11.043999 0.005882] 
[11.044051 0.000052] Arago 2011.09 dm814x-evm ttyO0
[11.047034 0.002983] 
[11.047082 0.000048] dm814x-evm login: root (automatic login)
[11.185495 0.009107] root@dm814x-evm:~# 
[11.248187 0.062692]  [host] 
[11.248456 0.000269] Application Run Completed
[11.349149 0.003097] 
[11.349208 0.000059]  [host]    : total mem size  is 59703296
[11.352523 0.003315]  
[11.352612 0.000089]  [host] ITT server task running !!!
[11.356008 0.003396] 
[11.357936 0.001928]  [host] 
[11.358261 0.000325]  ITT ittServer_run
[11.360920 0.002659] 
[11.360988 0.000068]  [host] ITT Server Message initialization successful
[11.363723 0.002735] 
[11.363787 0.000064]  [host] 
[11.366069 0.002282]  ITT capture task created
[11.368932 0.002863] 
[11.368995 0.000063]  [host] DCC server Message ques is open succefully
[11.372709 0.003714] 
[11.372768 0.000059]  [host] Setting cmd <1> in message <403ebc00>
[11.377211 0.004443] 
[11.377274 0.000063]  [host] Posting message <403ebc00> in QId <10002> 
[11.391170 0.003350]  [m3vpss ] Command 1 recived from HOST A8 
[11.399984 0.005406] 
[11.400044 0.000060]  [host] Response from M3 is : 1
[11.403118 0.003074]  [m3vpss ] Command 2 recived from HOST A8 
[11.405498 0.002380]  [m3vpss ] New DCC data of size <33603> 
[11.408851 0.003353] 
[11.408911 0.000060]  [host] Response from M3 is : 1
[11.415681 0.006770] 
[11.415763 0.000082]  [host] Default parameters were sent sucessfully 
[11.417504 0.001741] 
[11.417554 0.000050]  [host] Server Socket created with ID <22> 
[11.424996 0.002886]  [m3vpss ] Call to VNF_LINK_CMD_UPDATE_DCC -1

  • Hi Raj,

    There is Vps_printf API which prints bios debug logs.

    If you dont want any bios related debug logs then remove remote_debug_client.out binury execute command from init.sh script.

    Regards,

    Prashant

    PathPartner Technology Pvt Ltd

  • Hi Prashant,

    I want to track error message so i need to run remote_debug_client.out. I want to know where exactly below message coming from so i could command it out.

    [8.520974 0.070999] [m3vpss ] {SWOSD} edma3Handle->tccVal = 36
    [8.524280 0.003306] [m3vpss ] {SWOSD} edma3Handle->chId = 36
    [8.532919 0.008639] [m3vpss ] {SWOSD} edma3Handle->tccVal = 37
    [8.535377 0.002458] [m3vpss ] {SWOSD} edma3Handle->chId = 37
    [8.538833 0.003456] [m3vpss ] {SWOSD} edma3Handle->tccVal = 38
    [8.544242 0.005409] [m3vpss ] {SWOSD} edma3Handle->chId = 38
    [8.547694 0.003452] [m3vpss ] {SWOSD} edma3Handle->tccVal = 39
    [8.553242 0.005548] [m3vpss ] {SWOSD} edma3Handle->chId = 39
    [8.555574 0.002332] [m3vpss ] {SWOSD} edma3Handle->tccVal = 40
    [8.560970 0.005396] [m3vpss ] {SWOSD} edma3Handle->chId = 40

    Regards,
    RAJ M
  • Hi Raj,

    Above logs are part of SWOSD algorithm library which you can not remove.

    Regards,
    Prashant
    PathPartner Technology Pvt Ltd
  • Hi Prashant,

    Ok Thank you.

    For us it takes 10sec to display camera output. I want to reduce boot time for loading firmware from host to M3 (m3vpss,m3video). it took around 2.2 seconds???

    Regards,
    RAJ M
  • Hi All,

    In finish_ubifs.sh after system_server remove sleep 7. We face /dev/cmem failed why do we need sleep after system server? why cmem open error?
    CMEM Error: init: Failed to open /dev/cmem: 'No such file or directory'
    CMEM Error: CMEM file descriptor -1 (failed 'open()'), ensure CMEMK kernel module cmemk.ko has been installed with 'insmod'CMEM Error: CMEM fil

    From where /dev/cmem device node created?

    Regards,
    RAJ M
  • Hi Raj,

    If you just want to load the m3 firmware and run your application then please add the below lines in your finish_ubish.sh

    #./system_server&
    #sleep 7
    #./autorun_ipnc.sh&

    scripts/load_vpss.sh
    scripts/load_video.sh
    bin/ipnc_rdk_mcfw.out TRISTREAM

    cd /

    Here i am running tristream usecase.

    Regards,

    Anuj

    Pathpartner Technology Pvt Ltd.