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.

DM816x EVM: boot time : Booting from SD Card

Hi,

I have installed EZSDK 5_05_02_00 and

I was going through the EZSDK/board-support/docs/TI81XX_PSP_04.04.00.01_Feature_Performance_Guide.pdf, under the section "Boot-time measurement", the document says ~6 sec boot up time for SD card boot. But when I tried the SD card boot with the pre-built binaries and the filesystem provided in the release package, I see that the boot up time is ~10 to 14 sec.

The break up of boot time observed is : 

1. Power up to U-Boot ready to copy the uImage : 1 s

2. U-Boot copying uImage from SD card: 3 s

3. U-Boot uncompressing and booting the Kernel: 3 s

4. Kernel boot up: 1s

5. User space boot up: 2s

Total Boot up time = 1 + 3 + 3 +  1 + 2 = 10 s

Please find the boot log corresponding to this bootup attached

[Fri Jan 10 13:45:00 2014] 
[Fri Jan 10 13:45:00 2014] 
[Fri Jan 10 13:45:00 2014] U-Boot 2010.06 (Jan 07 2014 - 11:51:02)
[Fri Jan 10 13:45:00 2014] 
[Fri Jan 10 13:45:00 2014] DRAM:  2 GiB
[Fri Jan 10 13:45:00 2014] MMC:   OMAP SD/MMC: 0
[Fri Jan 10 13:45:00 2014] Using default environment
[Fri Jan 10 13:45:00 2014] 
[Fri Jan 10 13:45:00 2014] Hit any key to stop autoboot:  0 
[Fri Jan 10 13:45:00 2014] raise: Signal # 8 caught
[Fri Jan 10 13:45:00 2014] reading u-boot.bin
[Fri Jan 10 13:45:01 2014] 
[Fri Jan 10 13:45:01 2014] 185068 bytes read
[Fri Jan 10 13:45:01 2014] ## Starting application at 0x80800000 ...
[Fri Jan 10 13:45:01 2014] 
[Fri Jan 10 13:45:01 2014] 
[Fri Jan 10 13:45:01 2014] U-Boot 2010.06 (Jan 07 2014 - 11:57:52)
[Fri Jan 10 13:45:01 2014] 
[Fri Jan 10 13:45:01 2014] TI8168-GP rev 1.1
[Fri Jan 10 13:45:01 2014] 
[Fri Jan 10 13:45:01 2014] ARM clk: 987MHz
[Fri Jan 10 13:45:01 2014] DDR clk: 796MHz
[Fri Jan 10 13:45:01 2014] 
[Fri Jan 10 13:45:01 2014] I2C:   ready
[Fri Jan 10 13:45:01 2014] DRAM:  2 GiB
[Fri Jan 10 13:45:01 2014] NAND:  HW ECC BCH8 Selected
[Fri Jan 10 13:45:01 2014] 256 MiB
[Fri Jan 10 13:45:01 2014] MMC:   OMAP SD/MMC: 0
[Fri Jan 10 13:45:01 2014] raise: Signal # 8 caught
[Fri Jan 10 13:45:01 2014] :,;;:;:;;;;;;;;r;;:,;;:;:;;;;;;;;:,;;:;:;;;;;;;;:,;;:;:;;;;;;;;:;;;;;;;;:,;;:;:
[Fri Jan 10 13:45:01 2014] ;,;:::;;;;r;;;rssiSiS552X5252525259GX2X9hX9X9XX2325S55252i5:,;;:;:;;;;;;;;:,;;:
[Fri Jan 10 13:45:01 2014] ;:;;;;;;;rrssSsS52S22h52299GGAAMHMM#BBH#B#HMM#HMBA&&XX2255S2S5Si:,;;:;:;;;;;;;;
[Fri Jan 10 13:45:01 2014] ;:;;r;;rsrrriiXS5S329&A&MH#BMB#A&9XXA252GXiSXX39AAMMMBB&G22S5i2SSiiiisi:,;;:;:;
[Fri Jan 10 13:45:01 2014] ;;;;;r;rr2iisiih393HB#B#AA99i22irrrX3X52AGsisss2Xii2299HBMA&X2S5S5iSiisSsi:,;;:
[Fri Jan 10 13:45:01 2014] r:r;rrsrsihXSi2&##MHB&Ahh3AGHGA9G9h&#H##@@@##MAMMXXX9SSS29&&HGGX2i5iisiiisisi:,
[Fri Jan 10 13:45:01 2014] ;;rrrrsSiiiA&ABH&A9GAGhAhBAMHA9HM@@@@@@@@@@@@@@@@@@@HHhAh2S2SX9&Gh22SSiisiiisii
[Fri Jan 10 13:45:01 2014] r:rrssisiS2XM##&h3AGAX&3GG3Ssr5H@M#HM2; ;2X&&&MHMB###GBB#B&XXSSs529XX55iSsisisi
[Fri Jan 10 13:45:01 2014] r;rsrisSi2XHAhX99A3XXG&&XS;:,rH#HGhAS   @@@@3rs2XBM@@A552&&AHA2XiisSS252SSsisSs
[Fri Jan 10 13:45:01 2014] r;issi5S22&&3iSSX292&hXsr;;:;h@&G339&S9@@@@2@MA&9&HB##Xris29ABMAAX2ir;rsSi5iss5
[Fri Jan 10 13:45:01 2014] rrsSi2XhG&9GXh399&X99i;;;;;;r#H&293H9X#@@@@@@@B&9GhAH@XrrsrsiXABHB&HG2rr;rrSiSi
[Fri Jan 10 13:45:01 2014] ;:rsisS599&AA9XG&3A35r;:::;,;BMh&&2iX5A@@@@@@@&392X5GB2;;;r;iSX393A##A&Xi:::rsi
[Fri Jan 10 13:45:01 2014] ;:rss552222X553&XHMhir;;::,:,h#HhGSXhG3#@@@@#AXXS2XAHA;::;;;;ss55XShBA3239r:,;;
[Fri Jan 10 13:45:01 2014] r;ii2S5SSi2i53hirsh2srr::,,,,;MMXX359&Ah3h&Si59SX99A#i:,::::;;sri2,.2r;:SGAr;,:
[Fri Jan 10 13:45:01 2014] ;:;rrrrssiriXGSi::shs;;;,,,:,,rBBA9h5s5h5iS5isi2SAHB5:,,,:::;rrs5&SrisSX5Srrr:,
[Fri Jan 10 13:45:01 2014] ;,r;;;;rsriSSrrrr;;5Xrr;;,:,,.,;9AA2SsisS5323XXXG9&i:.,,::;;r;;;srrrrrr;;:;::::
[Fri Jan 10 13:45:01 2014] :,;r;r;rrissrrr;:;::;s;;;;,:,,..,r293h222hXXAAGGGX;:,,,:,:,::;:;::,:,,,,...,,,,
[Fri Jan 10 13:45:01 2014] ;,;;;;rrrrrrrrirr;,.,,:::::::,,,,.,;SX&ABAB2hhXir:,,.,,.,,:,,,,..,,,..,..,,,..:
[Fri Jan 10 13:45:01 2014] :.:;:;;;:;;;;r;rrs;:.. ,,:::::,:,:,,.::rrsrr;;,,.......,..,....,,,,,,,...,.,,:,
[Fri Jan 10 13:45:01 2014] :.:::,::::::;;r;rrr;:.......,.,.,,:::,,...............,,::.,,,,:,::,,:,:,,,:,;:
[Fri Jan 10 13:45:01 2014] ,.::,:,,,,,;;;;;;;;r;;::,..............................;;;:;::::,:::::::,:,:,,,
[Fri Jan 10 13:45:01 2014] : ,,:,,,,,,,,,,,,,:;rrr;;:;,,,,,,,::,.,.:.,.,;s,:;;;;:;:;;;;;::::,:::,:::,:,:,:
[Fri Jan 10 13:45:01 2014] ,.,,,,,,,...,,.,,....................................:,............:,,,:,:,,,,,
[Fri Jan 10 13:45:01 2014] 
[Fri Jan 10 13:45:01 2014] Net:   Detected MACID:40:5f:c2:1f:b0:fc
[Fri Jan 10 13:45:01 2014] Ethernet PHY: GENERIC @ 0x01
[Fri Jan 10 13:45:01 2014] DaVinci EMAC
[Fri Jan 10 13:45:01 2014] Hit any key to stop autoboot:  0 
[Fri Jan 10 13:45:01 2014] raise: Signal # 8 caught
[Fri Jan 10 13:45:01 2014] raise: Signal # 8 caught
[Fri Jan 10 13:45:01 2014] reading uImage
[Fri Jan 10 13:45:04 2014] 
[Fri Jan 10 13:45:04 2014] 2566084 bytes read
[Fri Jan 10 13:45:04 2014] ## Booting kernel from Legacy Image at 81000000 ...
[Fri Jan 10 13:45:04 2014]    Image Name:   Arago/2.6.37-psp04.04.00.01/dm81
[Fri Jan 10 13:45:04 2014]    Image Type:   ARM Linux Kernel Image (uncompressed)
[Fri Jan 10 13:45:04 2014]    Data Size:    2566020 Bytes = 2.4 MiB
[Fri Jan 10 13:45:04 2014]    Load Address: 80008000
[Fri Jan 10 13:45:04 2014]    Entry Point:  80008000
[Fri Jan 10 13:45:04 2014]    Verifying Checksum ... OK
[Fri Jan 10 13:45:05 2014]    Loading Kernel Image ... OK
[Fri Jan 10 13:45:06 2014] OK
[Fri Jan 10 13:45:06 2014] 
[Fri Jan 10 13:45:06 2014] Starting kernel ...
[Fri Jan 10 13:45:06 2014] 
[Fri Jan 10 13:45:06 2014] Uncompressing Linux... done, booting the kernel.
[Fri Jan 10 13:45:07 2014] Linux version 2.6.37 (x0029463@swubn01) (gcc version 4.3.3 (Sourcery G++ Lite 2009q1-203) ) #1 Mon Dec 24 20:10:10 IST 2012
[Fri Jan 10 13:45:07 2014] CPU: ARMv7 Processor [413fc082] revision 2 (ARMv7), cr=10c53c7f
[Fri Jan 10 13:45:07 2014] CPU: VIPT nonaliasing data cache, VIPT aliasing instruction cache
[Fri Jan 10 13:45:07 2014] Machine: ti8168evm
[Fri Jan 10 13:45:07 2014] reserved size = 52428800 at 0x0
[Fri Jan 10 13:45:07 2014] FB: Reserving 52428800 bytes SDRAM for VRAM
[Fri Jan 10 13:45:07 2014] Memory policy: ECC disabled, Data cache writeback
[Fri Jan 10 13:45:07 2014] OMAP chip is TI8168 1.1
[Fri Jan 10 13:45:07 2014] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 19712
[Fri Jan 10 13:45:07 2014] Kernel command line: console=ttyO2,115200n8 root=/dev/mmcblk0p2 mem=128M rootwait
[Fri Jan 10 13:45:07 2014] PID hash table entries: 512 (order: -1, 2048 bytes)
[Fri Jan 10 13:45:07 2014] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
[Fri Jan 10 13:45:07 2014] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
[Fri Jan 10 13:45:07 2014] Memory: 78MB = 78MB total
[Fri Jan 10 13:45:07 2014] Memory: 73216k/73216k available, 57856k reserved, 0K highmem
[Fri Jan 10 13:45:07 2014] Virtual kernel memory layout:
[Fri Jan 10 13:45:07 2014]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[Fri Jan 10 13:45:07 2014]     fixmap  : 0xfff00000 - 0xfffe0000   ( 896 kB)
[Fri Jan 10 13:45:07 2014]     DMA     : 0xffc00000 - 0xffe00000   (   2 MB)
[Fri Jan 10 13:45:07 2014]     vmalloc : 0xc8800000 - 0xf8000000   ( 760 MB)
[Fri Jan 10 13:45:07 2014]     lowmem  : 0xc0000000 - 0xc8000000   ( 128 MB)
[Fri Jan 10 13:45:07 2014]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
[Fri Jan 10 13:45:07 2014]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
[Fri Jan 10 13:45:07 2014]       .init : 0xc0008000 - 0xc003c000   ( 208 kB)
[Fri Jan 10 13:45:07 2014]       .text : 0xc003c000 - 0xc04da000   (4728 kB)
[Fri Jan 10 13:45:07 2014]       .data : 0xc04da000 - 0xc0522f00   ( 292 kB)
[Fri Jan 10 13:45:07 2014] SLUB: Genslabs=11, HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[Fri Jan 10 13:45:07 2014] NR_IRQS:407
[Fri Jan 10 13:45:07 2014] IRQ: Found an INTC at 0xfa200000 (revision 5.0) with 128 interrupts
[Fri Jan 10 13:45:07 2014] Total of 128 interrupts on 1 active controller
[Fri Jan 10 13:45:07 2014] GPMC revision 6.0
[Fri Jan 10 13:45:07 2014] Trying to install interrupt handler for IRQ400
[Fri Jan 10 13:45:07 2014] Trying to install interrupt handler for IRQ401
[Fri Jan 10 13:45:07 2014] Trying to install interrupt handler for IRQ402
[Fri Jan 10 13:45:07 2014] Trying to install interrupt handler for IRQ403
[Fri Jan 10 13:45:07 2014] Trying to install interrupt handler for IRQ404
[Fri Jan 10 13:45:07 2014] Trying to install interrupt handler for IRQ405
[Fri Jan 10 13:45:07 2014] Trying to install interrupt handler for IRQ406
[Fri Jan 10 13:45:07 2014] Trying to install type control for IRQ407
[Fri Jan 10 13:45:07 2014] Trying to set irq flags for IRQ407
[Fri Jan 10 13:45:07 2014] OMAP clockevent source: GPTIMER1 at 27000000 Hz
[Fri Jan 10 13:45:07 2014] Console: colour dummy device 80x30
[Fri Jan 10 13:45:07 2014] Calibrating delay loop... 986.31 BogoMIPS (lpj=4931584)
[Fri Jan 10 13:45:07 2014] pid_max: default: 32768 minimum: 301
[Fri Jan 10 13:45:07 2014] Security Framework initialized
[Fri Jan 10 13:45:07 2014] Mount-cache hash table entries: 512
[Fri Jan 10 13:45:07 2014] CPU: Testing write buffer coherency: ok
[Fri Jan 10 13:45:07 2014] devtmpfs: initialized
[Fri Jan 10 13:45:07 2014] omap_voltage_early_init: voltage driver support not added
[Fri Jan 10 13:45:07 2014] regulator: core version 0.5
[Fri Jan 10 13:45:07 2014] regulator: dummy: 
[Fri Jan 10 13:45:07 2014] NET: Registered protocol family 16
[Fri Jan 10 13:45:07 2014] omap_voltage_domain_lookup: Voltage driver init not yet happened.Faulting!
[Fri Jan 10 13:45:07 2014] omap_voltage_add_dev: VDD specified does not exist!
[Fri Jan 10 13:45:07 2014] OMAP GPIO hardware version 0.1
[Fri Jan 10 13:45:07 2014] OMAP GPIO hardware version 0.1
[Fri Jan 10 13:45:07 2014] omap_mux_init: Add partition: #1: core, flags: 0
[Fri Jan 10 13:45:07 2014] _omap_mux_get_by_name: Could not find signal i2c2_scl.i2c2_scl
[Fri Jan 10 13:45:07 2014] _omap_mux_get_by_name: Could not find signal i2c2_sda.i2c2_sda
[Fri Jan 10 13:45:07 2014] NOR: Can't request GPMC CS
[Fri Jan 10 13:45:07 2014] registered ti816x_gpio_vr device
[Fri Jan 10 13:45:07 2014] registered ti816x_sr device
[Fri Jan 10 13:45:07 2014] pm_dbg_init: only OMAP3 supported
[Fri Jan 10 13:45:07 2014] registered ti81xx_vpss device
[Fri Jan 10 13:45:07 2014] registered ti81xx_vidout device
[Fri Jan 10 13:45:07 2014] registered ti81xx on-chip HDMI device
[Fri Jan 10 13:45:07 2014] registered ti81xx_fb device
[Fri Jan 10 13:45:07 2014] registered ti81xx_vin device
[Fri Jan 10 13:45:07 2014] ti81xx_pcie: Invoking PCI BIOS...
[Fri Jan 10 13:45:07 2014] ti81xx_pcie: Setting up Host Controller...
[Fri Jan 10 13:45:07 2014] ti81xx_pcie: Register base mapped @0xc8820000
[Fri Jan 10 13:45:07 2014] ti81xx_pcie: Starting PCI scan...
[Fri Jan 10 13:45:07 2014] PCI: bus0: Fast back to back transfers enabled
[Fri Jan 10 13:45:07 2014] bio: create slab <bio-0> at 0
[Fri Jan 10 13:45:07 2014] regulator: VFB: 800 <--> 1025 mV at 1010 mV 
[Fri Jan 10 13:45:07 2014] vgaarb: loaded
[Fri Jan 10 13:45:07 2014] SCSI subsystem initialized
[Fri Jan 10 13:45:07 2014] usbcore: registered new interface driver usbfs
[Fri Jan 10 13:45:07 2014] usbcore: registered new interface driver hub
[Fri Jan 10 13:45:07 2014] usbcore: registered new device driver usb
[Fri Jan 10 13:45:07 2014] USBSS revision 4ea20808
[Fri Jan 10 13:45:07 2014] registerd cppi-dma Intr @ IRQ 17
[Fri Jan 10 13:45:07 2014] Cppi41 Init Done
[Fri Jan 10 13:45:07 2014] omap_i2c omap_i2c.1: bus 1 rev4.0 at 100 kHz
[Fri Jan 10 13:45:07 2014] omap_i2c omap_i2c.2: bus 2 rev4.0 at 100 kHz
[Fri Jan 10 13:45:07 2014] Advanced Linux Sound Architecture Driver Version 1.0.23.
[Fri Jan 10 13:45:07 2014] Switching to clocksource gp timer
[Fri Jan 10 13:45:07 2014] musb-hdrc: version 6.0, host, debug=0
[Fri Jan 10 13:45:07 2014] musb-hdrc musb-hdrc.0: dma type: dma-cppi41
[Fri Jan 10 13:45:07 2014] MUSB controller-0 revision 4ea20800
[Fri Jan 10 13:45:07 2014] musb-hdrc: kernel must blacklist external hubs
[Fri Jan 10 13:45:07 2014] musb-hdrc musb-hdrc.0: MUSB HDRC host driver
[Fri Jan 10 13:45:07 2014] musb-hdrc musb-hdrc.0: new USB bus registered, assigned bus number 1
[Fri Jan 10 13:45:07 2014] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[Fri Jan 10 13:45:07 2014] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[Fri Jan 10 13:45:07 2014] usb usb1: Product: MUSB HDRC host driver
[Fri Jan 10 13:45:07 2014] usb usb1: Manufacturer: Linux 2.6.37 musb-hcd
[Fri Jan 10 13:45:07 2014] usb usb1: SerialNumber: musb-hdrc.0
[Fri Jan 10 13:45:07 2014] hub 1-0:1.0: USB hub found
[Fri Jan 10 13:45:07 2014] hub 1-0:1.0: 1 port detected
[Fri Jan 10 13:45:07 2014] musb-hdrc musb-hdrc.0: USB Host mode controller at c881e000 using DMA, IRQ 18
[Fri Jan 10 13:45:07 2014] musb-hdrc musb-hdrc.1: dma type: dma-cppi41
[Fri Jan 10 13:45:07 2014] MUSB controller-1 revision 4ea20800
[Fri Jan 10 13:45:07 2014] musb-hdrc: kernel must blacklist external hubs
[Fri Jan 10 13:45:07 2014] musb-hdrc musb-hdrc.1: MUSB HDRC host driver
[Fri Jan 10 13:45:07 2014] musb-hdrc musb-hdrc.1: new USB bus registered, assigned bus number 2
[Fri Jan 10 13:45:07 2014] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002
[Fri Jan 10 13:45:07 2014] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[Fri Jan 10 13:45:07 2014] usb usb2: Product: MUSB HDRC host driver
[Fri Jan 10 13:45:07 2014] usb usb2: Manufacturer: Linux 2.6.37 musb-hcd
[Fri Jan 10 13:45:07 2014] usb usb2: SerialNumber: musb-hdrc.1
[Fri Jan 10 13:45:07 2014] hub 2-0:1.0: USB hub found
[Fri Jan 10 13:45:07 2014] hub 2-0:1.0: 1 port detected
[Fri Jan 10 13:45:07 2014] musb-hdrc musb-hdrc.1: USB Host mode controller at c8826800 using DMA, IRQ 19
[Fri Jan 10 13:45:07 2014] NET: Registered protocol family 2
[Fri Jan 10 13:45:07 2014] IP route cache hash table entries: 1024 (order: 0, 4096 bytes)
[Fri Jan 10 13:45:07 2014] TCP established hash table entries: 4096 (order: 3, 32768 bytes)
[Fri Jan 10 13:45:07 2014] TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
[Fri Jan 10 13:45:07 2014] TCP: Hash tables configured (established 4096 bind 4096)
[Fri Jan 10 13:45:07 2014] TCP reno registered
[Fri Jan 10 13:45:07 2014] UDP hash table entries: 256 (order: 0, 4096 bytes)
[Fri Jan 10 13:45:07 2014] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[Fri Jan 10 13:45:07 2014] NET: Registered protocol family 1
[Fri Jan 10 13:45:07 2014] RPC: Registered udp transport module.
[Fri Jan 10 13:45:07 2014] RPC: Registered tcp transport module.
[Fri Jan 10 13:45:07 2014] RPC: Registered tcp NFSv4.1 backchannel transport module.
[Fri Jan 10 13:45:07 2014] NetWinder Floating Point Emulator V0.97 (double precision)
[Fri Jan 10 13:45:07 2014] PMU: registered new PMU device of type 0
[Fri Jan 10 13:45:07 2014] omap-iommu omap-iommu.0: ducati registered
[Fri Jan 10 13:45:07 2014] omap-iommu omap-iommu.1: sys registered
[Fri Jan 10 13:45:07 2014] JFFS2 version 2.2. (NAND) � 2001-2006 Red Hat, Inc.
[Fri Jan 10 13:45:07 2014] msgmni has been set to 143
[Fri Jan 10 13:45:07 2014] io scheduler noop registered
[Fri Jan 10 13:45:07 2014] io scheduler deadline registered
[Fri Jan 10 13:45:07 2014] io scheduler cfq registered (default)
[Fri Jan 10 13:45:07 2014] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[Fri Jan 10 13:45:07 2014] omap_uart.0: ttyO0 at MMIO 0x48020000 (irq = 72) is a OMAP UART0
[Fri Jan 10 13:45:07 2014] omap_uart.1: ttyO1 at MMIO 0x48022000 (irq = 73) is a OMAP UART1
[Fri Jan 10 13:45:07 2014] omap_uart.2: ttyO2 at MMIO 0x48024000 (irq = 74) is a OMAP UART2
[Fri Jan 10 13:45:07 2014] console [ttyO2] enabled
[Fri Jan 10 13:45:07 2014] brd: module loaded
[Fri Jan 10 13:45:07 2014] loop: module loaded
[Fri Jan 10 13:45:07 2014] ahci ahci.0: forcing PORTS_IMPL to 0x3
[Fri Jan 10 13:45:07 2014] ahci ahci.0: AHCI 0001.0100 32 slots 2 ports 3 Gbps 0x3 impl platform mode
[Fri Jan 10 13:45:07 2014] ahci ahci.0: flags: ncq sntf pm led clo only pmp pio slum part ccc 
[Fri Jan 10 13:45:07 2014] scsi0 : ahci_platform
[Fri Jan 10 13:45:07 2014] scsi1 : ahci_platform
[Fri Jan 10 13:45:07 2014] ata1: SATA max UDMA/133 mmio [mem 0x4a140000-0x4a150fff] port 0x100 irq 16
[Fri Jan 10 13:45:07 2014] ata2: SATA max UDMA/133 mmio [mem 0x4a140000-0x4a150fff] port 0x180 irq 16
[Fri Jan 10 13:45:07 2014] m25p80 spi1.0: found w25q32, expected m25p80
[Fri Jan 10 13:45:07 2014] m25p80 spi1.0: w25q32 (4096 Kbytes)
[Fri Jan 10 13:45:07 2014] Creating 4 MTD partitions on "spi_flash":
[Fri Jan 10 13:45:07 2014] 0x000000000000-0x000000040000 : "U-Boot"
[Fri Jan 10 13:45:07 2014] 0x000000040000-0x000000042000 : "U-Boot Env"
[Fri Jan 10 13:45:07 2014] 0x000000042000-0x0000002c2000 : "Kernel"
[Fri Jan 10 13:45:07 2014] 0x0000002c2000-0x000000400000 : "File System"
[Fri Jan 10 13:45:07 2014] omap2-nand driver initializing
[Fri Jan 10 13:45:07 2014] NAND device: Manufacturer ID: 0x2c, Chip ID: 0xca (Micron )
[Fri Jan 10 13:45:07 2014] Creating 5 MTD partitions on "omap2-nand.0":
[Fri Jan 10 13:45:07 2014] 0x000000000000-0x000000260000 : "U-Boot"
[Fri Jan 10 13:45:07 2014] 0x000000260000-0x000000280000 : "U-Boot Env"
[Fri Jan 10 13:45:07 2014] 0x000000280000-0x0000006c0000 : "Kernel"
[Fri Jan 10 13:45:07 2014] 0x0000006c0000-0x00000cee0000 : "File System"
[Fri Jan 10 13:45:08 2014] 0x00000cee0000-0x000010000000 : "Reserved"
[Fri Jan 10 13:45:08 2014] davinci_mdio davinci_mdio.0: davinci mdio revision 1.6
[Fri Jan 10 13:45:08 2014] davinci_mdio davinci_mdio.0: detected phy mask fffffff9
[Fri Jan 10 13:45:08 2014] davinci_mdio.0: probed
[Fri Jan 10 13:45:08 2014] davinci_mdio davinci_mdio.0: phy[1]: device 0:01, driver unknown
[Fri Jan 10 13:45:08 2014] davinci_mdio davinci_mdio.0: phy[2]: device 0:02, driver unknown
[Fri Jan 10 13:45:08 2014] usbcore: registered new interface driver cdc_ether
[Fri Jan 10 13:45:08 2014] usbcore: registered new interface driver dm9601
[Fri Jan 10 13:45:08 2014] Initializing USB Mass Storage driver...
[Fri Jan 10 13:45:08 2014] usbcore: registered new interface driver usb-storage
[Fri Jan 10 13:45:08 2014] USB Mass Storage support registered.
[Fri Jan 10 13:45:08 2014] mice: PS/2 mouse device common for all mice
[Fri Jan 10 13:45:08 2014] omap_rtc omap_rtc: rtc core: registered omap_rtc as rtc0
[Fri Jan 10 13:45:08 2014] i2c /dev entries driver
[Fri Jan 10 13:45:08 2014] Linux video capture interface: v2.00
[Fri Jan 10 13:45:08 2014] usbcore: registered new interface driver uvcvideo
[Fri Jan 10 13:45:08 2014] USB Video Class driver (v1.0.0)
[Fri Jan 10 13:45:08 2014] OMAP Watchdog Timer Rev 0x00: initial timeout 60 sec
[Fri Jan 10 13:45:08 2014] usbcore: registered new interface driver usbhid
[Fri Jan 10 13:45:08 2014] usbhid: USB HID core driver
[Fri Jan 10 13:45:08 2014] usbcore: registered new interface driver snd-usb-audio
[Fri Jan 10 13:45:08 2014] asoc: tlv320aic3x-hifi <-> davinci-mcasp.2 mapping ok
[Fri Jan 10 13:45:08 2014] ALSA device list:
[Fri Jan 10 13:45:08 2014]   #0: TI81XX EVM
[Fri Jan 10 13:45:08 2014] TCP cubic registered
[Fri Jan 10 13:45:08 2014] NET: Registered protocol family 17
[Fri Jan 10 13:45:08 2014] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3
[Fri Jan 10 13:45:08 2014] omap_voltage_late_init: Voltage driver support not added
[Fri Jan 10 13:45:08 2014] Power Management for TI81XX.
[Fri Jan 10 13:45:08 2014] smartreflex smartreflex: Driver initialized
[Fri Jan 10 13:45:08 2014] omap_rtc omap_rtc: setting system clock to 2000-01-01 00:00:00 UTC (946684800)
[Fri Jan 10 13:45:08 2014] ata1: SATA link down (SStatus 0 SControl 300)
[Fri Jan 10 13:45:08 2014] ata2: SATA link down (SStatus 0 SControl 300)
[Fri Jan 10 13:45:08 2014] Waiting for root device /dev/mmcblk0p2...
[Fri Jan 10 13:45:08 2014] mmc0: new high speed SDHC card at address e624
[Fri Jan 10 13:45:08 2014] mmcblk0: mmc0:e624 SD08G 7.40 GiB 
[Fri Jan 10 13:45:08 2014]  mmcblk0: p1 p2
[Fri Jan 10 13:45:08 2014] EXT3-fs: barriers not enabled
[Fri Jan 10 13:45:08 2014] kjournald starting.  Commit interval 5 seconds
[Fri Jan 10 13:45:08 2014] EXT3-fs (mmcblk0p2): using internal journal
[Fri Jan 10 13:45:08 2014] EXT3-fs (mmcblk0p2): recovery complete
[Fri Jan 10 13:45:08 2014] EXT3-fs (mmcblk0p2): mounted filesystem with writeback data mode
[Fri Jan 10 13:45:08 2014] VFS: Mounted root (ext3 filesystem) on device 179:2.
[Fri Jan 10 13:45:08 2014] devtmpfs: mounted
[Fri Jan 10 13:45:08 2014] Freeing init memory: 208K
[Fri Jan 10 13:45:08 2014] INIT: version 2.86 booting
[Fri Jan 10 13:45:08 2014] INIT: Entering runlevel: 5
[Fri Jan 10 13:45:09 2014] Starting system message bus: start-stop-daemon: unknown user messagebus
[Fri Jan 10 13:45:09 2014] Starting telnet daemon.
[Fri Jan 10 13:45:09 2014] Starting syslogd/klogd: done
[Fri Jan 10 13:45:09 2014] Starting thttpdNET: Registered protocol family 10
[Fri Jan 10 13:45:09 2014] .
[Fri Jan 10 13:45:10 2014] 
[Fri Jan 10 13:45:10 2014]  _____                    _____           _         _   
[Fri Jan 10 13:45:10 2014] |  _  |___ ___ ___ ___   |  _  |___ ___  |_|___ ___| |_ 
[Fri Jan 10 13:45:10 2014] |     |  _| .'| . | . |  |   __|  _| . | | | -_|  _|  _|
[Fri Jan 10 13:45:10 2014] |__|__|_| |__,|_  |___|  |__|  |_| |___|_| |___|___|_|  
[Fri Jan 10 13:45:10 2014]               |___|                    |___|            
[Fri Jan 10 13:45:10 2014] 
[Fri Jan 10 13:45:10 2014] Arago Project http://arago-project.org (none) ttyO2
[Fri Jan 10 13:45:10 2014] 
[Fri Jan 10 13:45:10 2014] Arago 2011.09 (none) ttyO2
[Fri Jan 10 13:45:10 2014] 
[Fri Jan 10 13:45:10 2014] (none) login: 

The hardware and software setup details:

EVM: DM816x RevG, Spectrum Digital

EZSDK: 5_05_02_00

First stage bootloader: u-boot.min.sd (89.8 KB) built from the release package 

Second stage bootloader: u-boot.bin (180 KB)

Kernel Image: uImage-dm816x-evm.bin (2.44 MB) , pre-built image from the release package

Filesystem: arago-base-tisdk-image-dm816x-evm.tar.gz (10.7 MB) from the release package

Default run-level : 5

List of files under: /etc/initS.d : (empty)

List of files under: /etc/init5.d :  S02dbus-1, S10telnetd, S20syslog, S20thttpd, S99gplv3-notice, S99rmnologin

bootargs used : console=ttyO2,115200n8 root=/dev/mmcblk0p2 mem=128M rootwait

bootcmd used : 'mmc rescan 0; fatload mmc 0 0x81000000 uImage; bootm 0x81000000'

bootdelay set : 0

Specs of SD Card Used: San Disk Extreme, SDHC, Class 10, 8 GB

With the same set up explained above I used another SD card (SanDisk SDHC, Class 4, 8GB) for boot time profiling and I observe the following boot up time,

1. Power up till U-Boot gets ready to copy the uImage : 1 s

2. U-Boot copying uImage from SD card: 3 s

3. U-Boot uncompressing and booting the Kernel: 3s

4. Kernel boot up: 3s

5. User space boot up: 4s

Total Boot up time = 1 + 3 + 3 + 3 + 4 = 14 s

The results of these experiments doesn't match with the EZSDK documentation.

 

Has anybody tried verifying the boot time performance quoted in the EZSDK documentation with SD card boot?

It will be helpful if anybody can let me know as to why this difference is observed in the boot up time in SD card boot mode.

Thank you.