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.

Linux/AM3352: Boot time optimization guide to achieve 20sec boot time

Part Number: AM3352

Tool/software: Linux

Dear Champs,

Is it possible to achieve 20sec booting  time in processor SDK on AM3352?

I found it takes 50sec to boot-up processor SDK(v3.02.05) on AM3352, and need a guide to reduce this boot time up to 20~30sec.

Is it possible to achieve 20~30sec boot time  using processor SDK?

Could you please provide a guide for this?

My customer measured booting time using grabserial as attached, and it takes 40sec time up to log-in.

5047.boottime.txt
jhhan@ubuntu:~/Downloads/grabserial-1.9.3$ sudo ./grabserial -d /dev/ttyUSB0 -t
[0.000001 0.000001] 
[0.000322 0.000321] U-Boot SPL 2016.05 (Feb 24 2017 - 13:23:25)
[0.006178 0.005856] Trying to boot from NAND
[0.196049 0.189871] 
[0.196249 0.000200] 
[0.196272 0.000023] U-Boot 2016.05 (Feb 24 2017 - 13:23:25 +0900)
[0.198823 0.002551] 
[0.198876 0.000053] AM335X-GP rev 2.1
[0.199564 0.000688] ARM clk: 300MHz
[0.201279 0.001715] DDR clk: 400MHz
[0.203489 0.002210] PER clk: 192MHz
[0.205309 0.001820] L3S clk: 100MHz
[0.205655 0.000346] I2C:   ready
[0.260046 0.054391] DRAM:  128 MiB
[0.688543 0.428497] NAND:  256 MiB
[0.964842 0.276299] Net:   cpsw
[0.985776 0.020934] Hit any key to stop autoboot:  0 
[0.989134 0.003358] starting USB...
[0.990629 0.001495] USB0:   Port not available.
[1.994729 1.004100] ** Bad device usb 0 **
[1.995246 0.000517] ** Bad device usb 0 **
[1.997740 0.002494] Saving Environment to NAND...
[2.007893 0.010153] Erasing NAND...
[2.009994 0.002101] Erasing at 0x180000 --  25% complete.Erasing at 0x1a0000 --  50% complete.Erasing at 0x1c0000 --  75% complete.Erasing at 0x1e0000 -- 100% complete.
[2.024860 0.014866] Writing to NAND... OK
[2.087278 0.062418] 
[2.087360 0.000082] NAND read: device 0 offset 0x200000, size 0x80000
[2.178247 0.090887]  524288 bytes read: OK
[2.178768 0.000521] 
[2.178796 0.000028] NAND read: device 0 offset 0x400000, size 0x800000
[3.507407 1.328611]  8388608 bytes read: OK
[3.512373 0.004966] Kernel image @ 0x82000000 [ 0x000000 - 0x2a61c0 ]
[3.513781 0.001408] ## Flattened Device Tree blob at 80000000
[3.516920 0.003139]    Booting using the fdt blob at 0x80000000
[3.522474 0.005554]    Loading Device Tree to 86f38000, end 86f43a65 ... OK
[3.549855 0.027381] 
[3.549905 0.000050] Starting kernel ...
[3.552059 0.002154] 
[9.008745 5.456686] Booting Linux on physical CPU 0x0
[9.013314 0.004569] Initializing cgroup subsys cpu
[9.016121 0.002807] Initializing cgroup subsys cpuacct
[9.020219 0.004098] Linux version 4.4.19-gdb0b54cdad (jhhan@DDSvr) (gcc version 5.3.1 20160113 (Linaro GCC 5.3-2016.02) ) #1 PREEMPT Fri Feb 24 12:20:03 KST 2017
[9.031191 0.010972] CPU: ARMv7 Processor [413fc082] revision 2 (ARMv7), cr=10c5387d
[9.037682 0.006491] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[9.047712 0.010030] Machine model: TI AM335x LFD
[9.047993 0.000281] cma: Reserved 24 MiB at 0x85000000
[9.049168 0.001175] Memory policy: Data cache writeback
[9.051647 0.002479] CPU: All CPU(s) started in SVC mode.
[9.055191 0.003544] AM335X ES2.1 (neon )
[9.057212 0.002021] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 32480
[9.063654 0.006442] Kernel command line: console=ttyS0,115200n8 root=ubi0:rootfs rw ubi.mtd=NAND.rfs-part1,2048 rootfstype=ubifs rootwait=1 ip=off
[9.074526 0.010872] PID hash table entries: 512 (order: -1, 2048 bytes)
[9.080428 0.005902] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
[9.086303 0.005875] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
[9.092555 0.006252] Memory: 97284K/131072K available (5107K kernel code, 255K rwdata, 1848K rodata, 232K init, 235K bss, 9212K reserved, 24576K cma-reserved, 0K highmem)
[9.103440 0.010885] Virtual kernel memory layout:
[9.110494 0.007054]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[9.113016 0.002522]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[9.116391 0.003375]     vmalloc : 0xc8800000 - 0xff800000   ( 880 MB)
[9.123286 0.006895]     lowmem  : 0xc0000000 - 0xc8000000   ( 128 MB)
[9.125749 0.002463]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
[9.130757 0.005008]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
[9.134593 0.003836]       .text : 0xc0008000 - 0xc06d302c   (6957 kB)
[9.140117 0.005524]       .init : 0xc06d4000 - 0xc070e000   ( 232 kB)
[9.142699 0.002582]       .data : 0xc070e000 - 0xc074dd10   ( 256 kB)
[9.148616 0.005917]        .bss : 0xc074dd10 - 0xc0788ba8   ( 236 kB)
[9.152428 0.003812] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[9.157848 0.005420] Preemptible hierarchical RCU implementation.
[9.160620 0.002772] 	Build-time adjustment of leaf fanout to 32.
[9.165426 0.004806] NR_IRQS:16 nr_irqs:16 16
[9.169484 0.004058] IRQ: Found an INTC at 0xfa200000 (revision 5.0) with 128 interrupts
[9.174539 0.005055] OMAP clockevent source: timer2 at 24000000 Hz
[9.176375 0.001836] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 89478484971ns
[9.183616 0.007241] clocksource: timer1: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns
[9.191357 0.007741] OMAP clocksource: timer1 at 24000000 Hz
[9.194725 0.003368] clocksource_probe: no matching clocksources found
[9.200447 0.005722] Console: colour dummy device 80x30
[9.202669 0.002222] Calibrating delay loop... 298.59 BogoMIPS (lpj=1492992)
[9.207354 0.004685] pid_max: default: 32768 minimum: 301
[9.210623 0.003269] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[9.216544 0.005921] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[9.226882 0.010338] Initializing cgroup subsys io
[9.227535 0.000653] Initializing cgroup subsys memory
[9.228349 0.000814] Initializing cgroup subsys devices
[9.233952 0.005603] Initializing cgroup subsys freezer
[9.234645 0.000693] Initializing cgroup subsys perf_event
[9.237291 0.002646] Initializing cgroup subsys pids
[9.241310 0.004019] CPU: Testing write buffer coherency: ok
[9.244611 0.003301] Setting up static identity map for 0x80008200 - 0x80008258
[9.249023 0.004412] devtmpfs: initialized
[9.251072 0.002049] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3
[9.257380 0.006308] omap_hwmod: debugss: _wait_target_disable failed
[9.262945 0.005565] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[9.270987 0.008042] pinctrl core: initialized pinctrl subsystem
[9.275520 0.004533] NET: Registered protocol family 16
[9.277463 0.001943] DMA: preallocated 256 KiB pool for atomic coherent allocations
[9.283522 0.006059] cpuidle: using governor ladder
[9.286664 0.003142] cpuidle: using governor menu
[9.289091 0.002427] OMAP GPIO hardware version 0.1
[9.291584 0.002493] omap-gpmc 50000000.gpmc: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/nand_flash_x8, deferring probe
[9.303137 0.011553] hw-breakpoint: debug architecture 0x4 unsupported.
[9.307508 0.004371] edma 49000000.edma: TI EDMA DMA engine driver
[9.318960 0.011452] SCSI subsystem initialized
[9.319782 0.000822] usbcore: registered new interface driver usbfs
[9.321098 0.001316] usbcore: registered new interface driver hub
[9.322975 0.001877] usbcore: registered new device driver usb
[9.327010 0.004035] omap_i2c 44e0b000.i2c: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/pinmux_i2c0_pins, deferring probe
[9.338936 0.011926] omap_i2c 4802a000.i2c: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/pinmux_i2c1_pins, deferring probe
[9.351748 0.012812] omap_i2c 4819c000.i2c: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/pinmux_i2c2_pins, deferring probe
[9.363151 0.011403] pps_core: LinuxPPS API ver. 1 registered
[9.365315 0.002164] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[9.373632 0.008317] PTP clock support registered
[9.377342 0.003710] EDAC MC: Ver: 3.0.0
[9.377449 0.000107] omap-mailbox 480c8000.mailbox: omap mailbox rev 0x400
[9.385085 0.007636] clocksource: Switched to clocksource timer1
[9.387009 0.001924] NET: Registered protocol family 2
[9.390178 0.003169] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[9.396182 0.006004] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[9.400332 0.004150] TCP: Hash tables configured (established 1024 bind 1024)
[9.408225 0.007893] UDP hash table entries: 256 (order: 0, 4096 bytes)
[9.412447 0.004222] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[9.416254 0.003807] NET: Registered protocol family 1
[9.420410 0.004156] RPC: Registered named UNIX socket transport module.
[9.424475 0.004065] RPC: Registered udp transport module.
[9.428121 0.003646] RPC: Registered tcp transport module.
[9.432093 0.003972] RPC: Registered tcp NFSv4.1 backchannel transport module.
[9.436249 0.004156] hw perfevents: enabled with armv7_cortex_a8 PMU driver, 5 counters available
[9.444201 0.007952] futex hash table entries: 256 (order: -1, 3072 bytes)
[9.448255 0.004054] NFS: Registering the id_resolver key type
[9.452292 0.004037] Key type id_resolver registered
[9.452528 0.000236] Key type id_legacy registered
[9.457207 0.004679] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[9.464389 0.007182] io scheduler noop registered
[9.464540 0.000151] io scheduler deadline registered
[9.468998 0.004458] io scheduler cfq registered (default)
[9.472748 0.003750] pinctrl-single 44e10800.pinmux: 142 pins at pa f9e10800 size 568
[9.476912 0.004164] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[9.484253 0.007341] console [ttyS0] disabled
[9.484433 0.000180] 44e09000.serial: ttyS0 at MMIO 0x44e09000 (irq = 158, base_baud = 3000000) is a 8250
[9.491683 0.007250] console [ttyS0] enabled
[9.499297 0.007614] 48022000.serial: ttyS1 at MMIO 0x48022000 (irq = 159, base_baud = 3000000) is a 8250
[9.507296 0.007999] 48024000.serial: ttyS2 at MMIO 0x48024000 (irq = 160, base_baud = 3000000) is a 8250
[9.516387 0.009091] 481a6000.serial: ttyS3 at MMIO 0x481a6000 (irq = 161, base_baud = 3000000) is a 8250
[9.540771 0.024384] loop: module loaded
[9.548776 0.008005] libphy: Fixed MDIO Bus: probed
[9.620590 0.071814] davinci_mdio 4a101000.mdio: davinci mdio revision 1.6
[9.626815 0.006225] davinci_mdio 4a101000.mdio: detected phy mask fffffff4
[9.634756 0.007941] libphy: 4a101000.mdio: probed
[9.638929 0.004173] davinci_mdio 4a101000.mdio: phy[0]: device 4a101000.mdio:00, driver unknown
[9.643348 0.004419] davinci_mdio 4a101000.mdio: phy[1]: device 4a101000.mdio:01, driver unknown
[9.650781 0.007433] davinci_mdio 4a101000.mdio: phy[3]: device 4a101000.mdio:03, driver unknown
[9.659090 0.008309] cpsw 4a100000.ethernet: Detected MACID = 98:5d:ad:53:8a:70
[9.666675 0.007585] usbcore: registered new interface driver usb-storage
[9.674553 0.007878] 47401300.usb-phy supply vcc not found, using dummy regulator
[9.703883 0.029330] musb-hdrc musb-hdrc.0.auto: Failed to request rx1.
[9.707783 0.003900] musb-hdrc musb-hdrc.0.auto: musb_init_controller failed with status -517
[9.732052 0.024269] i2c /dev entries driver
[9.734546 0.002494] cpuidle: enable-method property 'ti,am3352' found operations
[9.742196 0.007650] NET: Registered protocol family 17
[9.749872 0.007676] NET: Registered protocol family 15
[9.750127 0.000255] Key type dns_resolver registered
[9.759170 0.009043] omap_voltage_late_init: Voltage driver support not added
[9.764055 0.004885] omap-gpmc 50000000.gpmc: GPMC revision 6.0
[9.768352 0.004297] gpmc_mem_init: disabling cs 0 mapped at 0x0-0x1000000
[9.776093 0.007741] nand: device found, Manufacturer ID: 0x2c, Chip ID: 0xda
[9.780352 0.004259] nand: Micron MT29F2G08ABAEAWP
[9.784374 0.004022] nand: 256 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[9.790174 0.005800] nand: using OMAP_ECC_BCH8_CODE_HW ECC scheme
[9.794451 0.004277] 12 ofpart partitions found on MTD device 8000000.nand
[9.800061 0.005610] Creating 12 MTD partitions on "8000000.nand":
[9.804243 0.004182] 0x000000000000-0x000000080000 : "NAND.SPL"
[9.814333 0.010090] 0x000000080000-0x000000180000 : "NAND.u-boot"
[9.818127 0.003794] 0x000000180000-0x000000200000 : "NAND.u-boot-env"
[9.828726 0.010599] 0x000000200000-0x000000280000 : "NAND.dt1-part1"
[9.832392 0.003666] 0x000000280000-0x000000300000 : "NAND.dt1-part2"
[9.840238 0.007846] 0x000000300000-0x000000380000 : "NAND.dt2-part1"
[9.850174 0.009936] 0x000000380000-0x000000400000 : "NAND.dt2-part2"
[9.854440 0.004266] 0x000000400000-0x000000c00000 : "NAND.os-part1"
[9.870211 0.015771] 0x000000c00000-0x000001400000 : "NAND.os-part2"
[9.882338 0.012127] 0x000001400000-0x000008600000 : "NAND.rfs-part1"
[9.925516 0.043178] 0x000008600000-0x00000f800000 : "NAND.rfs-part2"
[9.975348 0.049832] 0x00000f800000-0x00000fc00000 : "NAND.user"
[9.985329 0.009981] omap_i2c 44e0b000.i2c: bus 0 rev0.11 at 400 kHz
[9.992885 0.007556] at24 1-0050: 32768 byte 24c256 EEPROM, writable, 64 bytes/write
[9.999642 0.006757] omap_i2c 4802a000.i2c: bus 1 rev0.11 at 400 kHz
[10.004579 0.004937] omap_i2c 4819c000.i2c: bus 2 rev0.11 at 400 kHz
[10.022779 0.018200] musb-hdrc musb-hdrc.0.auto: MUSB HDRC host driver
[10.027950 0.005171] musb-hdrc musb-hdrc.0.auto: new USB bus registered, assigned bus number 1
[10.035006 0.007056] hub 1-0:1.0: USB hub found
[10.039484 0.004478] hub 1-0:1.0: 1 port detected
[10.070915 0.031431] ubi0: attaching mtd9
[10.511622 0.440707] ubi0: scanning is finished
[10.523068 0.011446] ubi0 warning: print_rsvd_warning: cannot reserve enough PEBs for bad PEB handling, reserved 32, need 40
[10.535612 0.012544] ubi0: attached mtd9 (name "NAND.rfs-part1", size 114 MiB)
[10.541436 0.005824] ubi0: PEB size: 131072 bytes (128 KiB), LEB size: 126976 bytes
[10.546453 0.005017] ubi0: min./max. I/O unit sizes: 2048/2048, sub-page size 512
[10.550563 0.004110] ubi0: VID header offset: 2048 (aligned 2048), data offset: 4096
[10.554973 0.004410] ubi0: good PEBs: 912, bad PEBs: 0, corrupted PEBs: 0
[10.566064 0.011091] ubi0: user volume: 1, internal volumes: 1, max. volumes count: 128
[10.568959 0.002895] ubi0: max/mean erase counter: 2/0, WL threshold: 4096, image sequence number: 1038279339
[10.574108 0.005149] ubi0: available PEBs: 0, total reserved PEBs: 912, PEBs reserved for bad PEB handling: 32
[10.585607 0.011499] ubi0: background thread "ubi_bgt0d" started, PID 61
[10.599941 0.014334] UBIFS (ubi0:0): background thread "ubifs_bgt0_0" started, PID 62
[10.637759 0.037818] UBIFS (ubi0:0): recovery needed
[10.789583 0.151824] UBIFS (ubi0:0): recovery completed
[10.810107 0.020524] UBIFS (ubi0:0): UBIFS: mounted UBI device 0, volume 0, name "rootfs"
[10.815940 0.005833] UBIFS (ubi0:0): LEB size: 126976 bytes (124 KiB), min./max. I/O unit sizes: 2048 bytes/2048 bytes
[10.825779 0.009839] UBIFS (ubi0:0): FS size: 109707264 bytes (104 MiB, 864 LEBs), journal size 9023488 bytes (8 MiB, 72 LEBs)
[10.838483 0.012704] UBIFS (ubi0:0): reserved for root: 0 bytes (0 KiB)
[10.840037 0.001554] UBIFS (ubi0:0): media format: w4/r0 (latest is w4/r0), UUID FC8E448D-0AB7-48CE-A165-174EF8B74EB0, small LPT model
[10.849892 0.009855] VFS: Mounted root (ubifs filesystem) on device 0:14.
[10.857827 0.007935] devtmpfs: mounted
[10.858271 0.000444] Freeing unused kernel memory: 232K (c06d4000 - c070e000)
[10.862547 0.004276] This architecture does not have kernel memory protection.
[11.074365 0.211818] systemd[1]: System time before build time, advancing clock.
[11.142297 0.067932] random: systemd: uninitialized urandom read (16 bytes read, 4 bits of entropy available)
[11.175536 0.033239] random: systemd: uninitialized urandom read (16 bytes read, 4 bits of entropy available)
[11.213221 0.037685] systemd[1]: systemd 229 running in system mode. (-PAM -AUDIT -SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP -LIBCRYPTSETUP -GCRYPT -GNUTLS +ACL +XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -IDN)
[11.231433 0.018212] systemd[1]: Detected architecture arm.
[11.243302 0.011869] 
[11.243355 0.000053] Welcome to Arago 2016.12!
[11.244130 0.000775] 
[11.264775 0.020645] systemd[1]: Set hostname to <am335x-sec>.
[11.525935 0.261160] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 4 bits of entropy available)
[11.553104 0.027169] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 4 bits of entropy available)
[11.591011 0.037907] random: systemd: uninitialized urandom read (16 bytes read, 4 bits of entropy available)
[11.652259 0.061248] random: systemd: uninitialized urandom read (16 bytes read, 5 bits of entropy available)
[11.660801 0.008542] random: systemd: uninitialized urandom read (16 bytes read, 5 bits of entropy available)
[11.671139 0.010338] random: systemd: uninitialized urandom read (16 bytes read, 5 bits of entropy available)
[11.749253 0.078114] random: systemd: uninitialized urandom read (16 bytes read, 5 bits of entropy available)
[11.792678 0.043425] random: systemd: uninitialized urandom read (16 bytes read, 5 bits of entropy available)
[12.173625 0.380947] systemd[1]: Listening on Syslog Socket.
[12.181743 0.008118] [  OK  ] Listening on Syslog Socket.
[12.208542 0.026799] systemd[1]: Created slice User and Session Slice.
[12.216382 0.007840] [  OK  ] Created slice User and Session Slice.
[12.235399 0.019017] systemd[1]: Created slice System Slice.
[12.239790 0.004391] [  OK  ] Created slice System Slice.
[12.261832 0.022042] systemd[1]: Listening on udev Control Socket.
[12.266335 0.004503] [  OK  ] Listening on udev Control Socket.
[12.292349 0.026014] systemd[1]: Listening on Network Service Netlink Socket.
[12.299732 0.007383] [  OK  ] Listening on Network Service Netlink Socket.
[12.322033 0.022301] systemd[1]: Listening on udev Kernel Socket.
[12.326221 0.004188] [  OK  ] Listening on udev Kernel Socket.
[12.378251 0.052030] systemd[1]: Listening on Journal Socket.
[12.386221 0.007970] [  OK  ] Listening on Journal Socket.
[12.411964 0.025743] systemd[1]: Mounting Debug File System...
[12.435170 0.023206]          Mounting Debug File System...
[12.482069 0.046899] systemd[1]: Mounting Temporary Directory...
[12.505104 0.023035]          Mounting Temporary Directory...
[12.569946 0.064842] systemd[1]: Starting Remount Root and Kernel File Systems...
[12.642420 0.072474]          Starting Remount Root and Kernel File Systems...
[12.670740 0.028320] systemd[1]: Mounting POSIX Message Queue File System...
[12.702641 0.031901]          Mounting POSIX Message Queue File System...
[12.808039 0.105398] systemd[1]: Starting Create Static Device Nodes in /dev...
[12.843116 0.035077]          Starting Create Static Device Nodes in /dev...
[12.862359 0.019243] systemd[1]: Listening on Journal Socket (/dev/log).
[12.904257 0.041898] [  OK  ] Listening on Journal Socket (/dev/log).
[12.932298 0.028041] systemd[1]: Starting Journal Service...
[12.972551 0.040253]          Starting Journal Service...
[12.993600 0.021049] systemd[1]: Created slice system-serial\x2dgetty.slice.
[13.021483 0.027883] [  OK  ] Created slice system-serial\x2dgetty.slice.
[13.041904 0.020421] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[13.050085 0.008181] [  OK  ] Started Dispatch Password Requests to Console Directory Watch.
[13.063501 0.013416] systemd[1]: Created slice system-getty.slice.
[13.069532 0.006031] [  OK  ] Created slice system-getty.slice.
[13.081617 0.012085] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[13.111247 0.029630] [  OK  ] Started Forward Password Requests to Wall Directory Watch.
[13.132805 0.021558] systemd[1]: Reached target Slices.
[13.151984 0.019179] [  OK  ] Reached target Slices.
[13.171285 0.019301] systemd[1]: Reached target Remote File Systems.
[13.182959 0.011674] [  OK  ] Reached target Remote File Systems.
[13.203169 0.020210] systemd[1]: Reached target Swap.
[13.211373 0.008204] [  OK  ] Reached target Swap.
[13.231562 0.020189] systemd[1]: Reached target Paths.
[13.241761 0.010199] [  OK  ] Reached target Paths.
[13.261196 0.019435] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[13.269174 0.007978] [  OK  ] Listening on /dev/initctl Compatibility Named Pipe.
[13.305225 0.036051] systemd[1]: Starting Apply Kernel Variables...
[13.334814 0.029589]          Starting Apply Kernel Variables...
[13.400781 0.065967] systemd[1]: Starting Setup Virtual Console...
[13.434960 0.034179]          Starting Setup Virtual Console...
[13.546927 0.111967] systemd[1]: Mounted Debug File System.
[13.582129 0.035202] [  OK  ] Mounted Debug File System.
[13.602083 0.019954] systemd[1]: Mounted POSIX Message Queue File System.
[13.608116 0.006033] [  OK  ] Mounted POSIX Message Queue File System.
[13.620232 0.012116] systemd[1]: Mounted Temporary Directory.
[13.625400 0.005168] [  OK  ] Mounted Temporary Directory.
[13.652398 0.026998] systemd[1]: Started Journal Service.
[13.667210 0.014812] [  OK  ] Started Journal Service.
[13.698329 0.031119] [  OK  ] Started Remount Root and Kernel File Systems.
[13.733392 0.035063] [  OK  ] Started Create Static Device Nodes in /dev.
[13.760370 0.026978] [  OK  ] Started Apply Kernel Variables.
[13.790639 0.030269] [  OK  ] Started Setup Virtual Console.
[15.378584 1.587945]          Starting udev Kernel Device Manager...
[15.452645 0.074061]          Starting Rebuild Hardware Database...
[15.515880 0.063235]          Starting Rebuild Dynamic Linker Cache...
[15.547120 0.031240] [  OK  ] Reached target Local File Systems (Pre).
[15.614821 0.067701]          Mounting /media/ram...
[15.673018 0.058197]          Mounting /var/volatile...
[15.801324 0.128306]          Starting Flush Journal to Persistent Storage...
[15.972178 0.170854] [  OK  ] Mounted /var/volatile.
[16.001276 0.029098] [  OK  ] Mounted /media/ram.
[16.382903 0.381627] [  OK  ] Started udev Kernel Device Manager.
[16.431874 0.048971] [  OK  ] Started Rebuild Dynamic Linker Cache.
[17.985213 1.553339] systemd-journald[98]: Received request to flush runtime journal from PID 1
[18.069818 0.084605] [  OK  ] Started Flush Journal to Persistent Storage.
[18.351300 0.281482]          Starting Load/Save Random Seed...
[18.381269 0.029969] [  OK  ] Reached target Local File Systems.
[18.448207 0.066938]          Starting Rebuild Journal Catalog...
[18.533490 0.085283]          Starting Create Volatile Files and Directories...
[18.659952 0.126462] [  OK  ] Started Load/Save Random Seed.
[18.888672 0.228720] [  OK  ] Started Create Volatile Files and Directories.
[18.934140 0.045468] [  OK  ] Started Rebuild Journal Catalog.
[20.056406 1.122266]          Starting Update UTMP about System Boot/Shutdown...
[20.562508 0.506102] [  OK  ] Started Update UTMP about System Boot/Shutdown.
[23.530305 2.967797] [  OK  ] Started Rebuild Hardware Database.
[23.632748 0.102443]          Starting Update is Completed...
[23.687939 0.055191]          Starting udev Coldplug all Devices...
[23.781098 0.093159] [  OK  ] Started Update is Completed.
[26.612913 2.831815] [  OK  ] Found device /dev/ttyS0.
[27.237888 0.624975] [  OK  ] Started udev Coldplug all Devices.
[28.044502 0.806614] [  OK  ] Reached target System Initialization.
[28.080141 0.035639] [  OK  ] Listening on D-Bus System Message Bus Socket.
[28.111575 0.031434] [  OK  ] Reached target Sockets.
[28.135268 0.023693] [  OK  ] Reached target Basic System.
[28.220822 0.085554] [  OK  ] Started Getty on tty1.
[28.310936 0.090114] [  OK  ] Started Serial Getty on ttyS0.
[28.404500 0.093564] [  OK  ] Started Kernel Logging Service.
[28.550319 0.145819]          Starting Login Service...
[28.759077 0.208758] [  OK  ] Started System Logging Service.
[28.901872 0.142795] [  OK  ] Started D-Bus System Message Bus.
[32.978805 4.076933]          Starting Network Service...
[33.030452 0.051647] [  OK  ] Started Daily Cleanup of Temporary Directories.
[33.104500 0.074048] [  OK  ] Reached target Timers.
[34.011297 0.906797] 
[34.029574 0.018277]  _____                    _____           _         _   
[34.033547 0.003973] |  _  |___ ___ ___ ___   |  _  |___ ___  |_|___ ___| |_ 
[34.044976 0.011429] |     |  _| .'| . | . |  |   __|  _| . | | | -_|  _|  _|
[34.045604 0.000628] |__|__|_| |__,|_  |___|  |__|  |_| |___|_| |___|___|_|  
[34.048655 0.003051]               |___|                    |___|            
[34.058425 0.009770] 
[34.058443 0.000018] Arago Project http://arago-project.org am335x-sec ttyS0
[34.059592 0.001149] 
[34.059602 0.000010] Arago 2016.12 am335x-sec ttyS0
[34.063824 0.004222] 
[34.071684 0.007860] am335x-sec login: root (automatic login)
[34.074636 0.002952] 
[34.752430 0.677794]                                                                                                                                                                                       ^[[55;204Rnet eth0: initializing cpsw version 1.12 (0)
[34.804226 0.051796] net eth0: initialized cpsw ale version 1.4
[34.806387 0.002161] net eth0: ALE Table size 1024
[34.950539 0.144152] net eth0: phy found : id is : 0x1cc816
[35.058959 0.108420] net eth0: phy found : id is : 0x1cc816
[36.949775 1.890816] cpsw 4a100000.ethernet eth0: Link is Up - Unsupported (update phy.c)/Half - flow control off
[37.059710 0.109935] cpsw 4a100000.ethernet eth0: Link is Down
[37.949452 0.889742] cpsw 4a100000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx
[37.978748 0.029296] root@am335x-sec:~# random: nonblocking pool is initialized
1^[[1;6C

Thanks and Best Regards,

SI.