본문 바로가기

임베디드

쾌속 부팅 2차 도전

BootChart를 설치하여 "init" 직후에 일어나는 일들을 분석해 보았다.

Buildroot 작업 디렉토리에서 make busybox-menuconfig 실행하고 "Init Utilities" 메뉴에서 "bootchartd"를 선택하면 된다.

 

Pinchzoom이라는 Qt 어플리케이션은 init 시작 후 약 2.5초 후에 구동을 시작하는것으로 파악되었다.

그러나 화면이 보일때까지 추가로 2초 정도 더 기다려야 한다. 즉 4.5초 정도의 구동시간이 필요한 것이다.

 

 

무엇을 하느라 2.5초씩이나 기다리고 있는것일까? SD 카드 EXT4 파티션 마운팅 시간이 이렇게나 오래걸리나?

Qt5와 방대한 과련 라이브러리, 어플리케이션 로딩도 2초 이상 시간이 걸리는 것으로 파악되었다.

RNG(Random Number Generator) 초기화가 충분한 엔트로피로 완료될때까지 무작정 기다리는 Qt 라이브러리의 의존성 문제도 있는것으로 예상된다.

잘 이해는 안되지만 총 4.5초 정도의 시간이 소요되고 있는 것이다.

 

원인 파악하는데 시간이 많이 필요해 보여서 일단 Qt 대신에 훨씬 가벼운 SDL을 사용하는 ffmpeg 프로그램으로 카메라 preview를 시작 어플로 변경해 보았다. 변경하고 보드를 한변 켜봤는데 뭔가 엄청 빨라진것 같아서 grabserial로 시간 분석을 해보았다.

$ grabserial -v -d "/dev/ttyUSB0" -b 115200 -w 8 -p N -s 1 -e 30 -t -m "U-Boot SPL"
Opening serial port /dev/ttyUSB0
115200:8N1:xonxoff=0:rtscts=0
Program will end in 30 seconds
Printing timing information for each line
Matching pattern 'U-Boot SPL' to set base time
Use Control-C to stop...
[0.000000 0.000000] 
[0.000358 0.000358] U-Boot SPL 2017.01-rc2 (Jan 14 2020 - 09:56:03)
[0.004542 0.004542] DRAM: 128 MiB
[0.013257 0.008715] Trying to boot from MMC1
[0.321910 0.308653] 
[0.322080 0.000170] U-Boot 2017.01-rc2 (Jan 14 2020 - 09:56:03 +0900) Allwinner Technology
[0.327755 0.005675] 
[0.327826 0.000071] CPU:   Allwinner S3/V3s (SUN8I 1681)
[0.330953 0.003127] Model: FoxNux One
[0.334211 0.003258] DRAM:  128 MiB
[0.369283 0.035072] MMC:   SUNXI SD/MMC: 0
[0.460672 0.091389] Setting up a 1024x600 lcd console (overscan 0x0)
[0.504859 0.044187] dotclock: 51000kHz = 51000kHz: (1 * 3MHz * 102) / 6
[0.564508 0.059649] In:    serial@01c28000
[0.565890 0.001382] Out:   serial@01c28000
[0.568167 0.002277] Err:   serial@01c28000
[0.570549 0.002382] 
[0.570650 0.000101] 
[0.570719 0.000069] U-Boot 2017.01-rc2 (Jan 14 2020 - 09:56:03 +0900) Allwinner Technology
[0.576521 0.005802] 
[0.576585 0.000064] CPU:   Allwinner S3/V3s (SUN8I 1681)
[0.580451 0.003866] Model: FoxNux One
[0.581706 0.001255] DRAM:  128 MiB
[0.582406 0.000700] MMC:   SUNXI SD/MMC: 0
[0.584896 0.002490] Setting up a 1024x600 lcd console (overscan 0x0)
[0.589755 0.004859] dotclock: 51000kHz = 51000kHz: (1 * 3MHz * 102) / 6
[0.593667 0.003912] In:    serial@01c28000
[0.596081 0.002414] Out:   serial@01c28000
[0.598344 0.002263] Err:   serial@01c28000
[0.601627 0.003283] Net:   phy interface0
[0.602790 0.001163] eth0: ethernet@1c30000
[0.605073 0.002283] starting USB...
[0.607446 0.002373] No controllers found
[0.608546 0.001100] Press SPACE key two times to abort autoboot in 0 seconds
[0.694165 0.085619] switch to partitions #0, OK
[0.695815 0.001650] mmc0 is current device
[1.253231 0.557416] Scanning mmc 0:1...
[1.308660 0.055429] Found U-Boot script /boot.scr
[1.319149 0.010489] reading /boot.scr
[1.337721 0.018572] 585 bytes read in 20 ms (28.3 KiB/s)
[1.340763 0.003042] ## Executing script at 41900000
[1.353518 0.012755] reading zImage
[1.585630 0.232112] 4812672 bytes read in 233 ms (19.7 MiB/s)
[1.598812 0.013182] reading sun8i-s3-foxnux-one.dtb
[1.623683 0.024871] 14138 bytes read in 25 ms (551.8 KiB/s)
[1.626607 0.002924] ## Flattened Device Tree blob at 41800000
[1.629936 0.003329]    Booting using the fdt blob at 0x41800000
[1.634578 0.004642]    Loading Device Tree to 42df9000, end 42dff739 ... OK
[1.640564 0.005986] Cannot setup simplefb: node not found
[1.647146 0.006582] 
[1.647221 0.000075] Starting kernel ...
[1.648348 0.001127] 
[2.082096 0.433748] [    0.000000] Booting Linux on physical CPU 0x0
[2.085849 0.003753] [    0.000000] Linux version 5.5.0-rc4 (odroider@hardkernel) (gcc version 8.3.0 (GNU Toolchain for the A-profile Architecture 8.3-2019.03 (arm-rel-8.36))) #2 SMP Tue Jan 14 15:13:30 KST 2020
[2.102424 0.016575] [    0.000000] CPU: ARMv7 Processor [410fc075] revision 5 (ARMv7), cr=10c5387d
[2.111033 0.008609] [    0.000000] CPU: div instructions available: patching division code
[2.118502 0.007469] [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[2.124270 0.005768] [    0.000000] OF: fdt: Machine model: Hardkernel FoxNux One
[2.129612 0.005342] [    0.000000] Memory policy: Data cache writealloc
[2.133468 0.003856] [    0.000000] cma: Reserved 16 MiB at 0x47000000
[2.138487 0.005019] [    0.000000] percpu: Embedded 15 pages/cpu s30860 r8192 d22388 u61440
[2.144302 0.005815] [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 32512
[2.150755 0.006453] [    0.000000] Kernel command line: console=ttyS0,115200 console=tty0 rootwait root=/dev/mmcblk0p2 init=/sbin/bootchartd
[2.161294 0.010539] [    0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes, linear)
[2.169290 0.007996] [    0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes, linear)
[2.177096 0.007806] [    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[2.183050 0.005954] [    0.000000] Memory: 101428K/131072K available (7168K kernel code, 431K rwdata, 1944K rodata, 1024K init, 251K bss, 13260K reserved, 16384K cma-reserved, 0K highmem)
[2.200695 0.017645] [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[2.204770 0.004075] [    0.000000] rcu: Hierarchical RCU implementation.
[2.208788 0.004018] [    0.000000] rcu: 	RCU event tracing is enabled.
[2.213638 0.004850] [    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=1.
[2.220713 0.007075] [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[2.227620 0.006907] [    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
[2.233991 0.006371] [    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[2.239478 0.005487] [    0.000000] random: get_random_bytes called from start_kernel+0x2b0/0x448 with crng_init=0
[2.247874 0.008396] [    0.000000] arch_timer: cp15 timer(s) running at 24.00MHz (virt).
[2.254369 0.006495] [    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns
[2.264605 0.010236] [    0.000006] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns
[2.275498 0.010893] [    0.000017] Switching to timer-based delay loop, resolution 41ns
[2.279134 0.003636] [    0.000266] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns
[2.295057 0.015923] [    0.000553] Console: colour dummy device 80x30
[2.297782 0.002725] [    0.000853] printk: console [tty0] enabled
[2.300282 0.002500] [    0.000900] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=240000)
[2.306446 0.006164] [    0.000927] pid_max: default: 32768 minimum: 301
[2.311434 0.004988] [    0.001080] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
[2.319257 0.007823] [    0.001109] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
[2.327245 0.007988] [    0.001905] CPU: Testing write buffer coherency: ok
[2.331304 0.004059] [    0.002362] /cpus/cpu@0 missing clock-frequency property
[2.336783 0.005479] [    0.002402] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[2.342288 0.005505] [    0.003103] Setting up static identity map for 0x40100000 - 0x40100060
[2.349630 0.007342] [    0.003320] rcu: Hierarchical SRCU implementation.
[2.353598 0.003968] [    0.003849] smp: Bringing up secondary CPUs ...
[2.358743 0.005145] [    0.003884] smp: Brought up 1 node, 1 CPU
[2.361957 0.003214] [    0.003898] SMP: Total of 1 processors activated (48.00 BogoMIPS).
[2.369092 0.007135] [    0.003912] CPU: All CPU(s) started in SVC mode.
[2.372882 0.003790] [    0.004473] devtmpfs: initialized
[2.375973 0.003091] [    0.007961] VFP support v0.3: implementor 41 architecture 2 part 30 variant 7 rev 5
[2.384067 0.008094] [    0.008280] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[2.394308 0.010241] [    0.008327] futex hash table entries: 256 (order: 2, 16384 bytes, linear)
[2.401903 0.007595] [    0.009038] pinctrl core: initialized pinctrl subsystem
[2.406067 0.004164] [    0.009703] thermal_sys: Registered thermal governor 'step_wise'
[2.411865 0.005798] [    0.010402] NET: Registered protocol family 16
[2.416720 0.004855] [    0.011755] DMA: preallocated 256 KiB pool for atomic coherent allocations
[2.422997 0.006277] [    0.012875] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[2.431263 0.008266] [    0.012914] hw-breakpoint: maximum watchpoint size is 8 bytes.
[2.436987 0.005724] [    0.036641] SCSI subsystem initialized
[2.441476 0.004489] [    0.036893] usbcore: registered new interface driver usbfs
[2.445968 0.004492] [    0.036958] usbcore: registered new interface driver hub
[2.454378 0.008410] [    0.037080] usbcore: registered new device driver usb
[2.457263 0.002885] [    0.037317] mc: Linux media interface: v0.10
[2.461356 0.004093] [    0.037370] videodev: Linux video capture interface: v2.00
[2.466912 0.005556] [    0.037443] pps_core: LinuxPPS API ver. 1 registered
[2.472137 0.005225] [    0.037459] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[2.480892 0.008755] [    0.037492] PTP clock support registered
[2.484372 0.003480] [    0.037741] Advanced Linux Sound Architecture Driver Initialized.
[2.490138 0.005766] [    0.039098] clocksource: Switched to clocksource arch_sys_counter
[2.497290 0.007152] [    0.048659] NET: Registered protocol family 2
[2.500912 0.003622] [    0.049352] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
[2.509280 0.008368] [    0.049413] TCP established hash table entries: 1024 (order: 0, 4096 bytes, linear)
[2.517316 0.008036] [    0.049456] TCP bind hash table entries: 1024 (order: 1, 8192 bytes, linear)
[2.523789 0.006473] [    0.049486] TCP: Hash tables configured (established 1024 bind 1024)
[2.530866 0.007077] [    0.049615] UDP hash table entries: 256 (order: 1, 8192 bytes, linear)
[2.536933 0.006067] [    0.049678] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes, linear)
[2.544534 0.007601] [    0.049895] NET: Registered protocol family 1
[2.548260 0.003726] [    0.050960] RPC: Registered named UNIX socket transport module.
[2.554158 0.005898] [    0.050998] RPC: Registered udp transport module.
[2.559146 0.004988] [    0.051011] RPC: Registered tcp transport module.
[2.564175 0.005029] [    0.051023] RPC: Registered tcp NFSv4.1 backchannel transport module.
[2.570220 0.006045] [    0.053187] Initialise system trusted keyrings
[2.574118 0.003898] [    0.053536] workingset: timestamp_bits=30 max_order=15 bucket_order=0
[2.581309 0.007191] [    0.061376] NFS: Registering the id_resolver key type
[2.586506 0.005197] [    0.061446] Key type id_resolver registered
[2.590301 0.003795] [    0.061462] Key type id_legacy registered
[2.594794 0.004493] [    0.095884] Key type asymmetric registered
[2.598319 0.003525] [    0.095922] Asymmetric key parser 'x509' registered
[2.603551 0.005232] [    0.096035] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[2.610026 0.006475] [    0.096061] io scheduler mq-deadline registered
[2.615018 0.004992] [    0.096076] io scheduler kyber registered
[2.618482 0.003464] [    0.096818] sun4i-usb-phy 1c19400.phy: Couldn't request ID GPIO
[2.625487 0.007005] [    0.100740] sun8i-v3s-pinctrl 1c20800.pinctrl: initialized sunXi PIO driver
[2.632040 0.006553] [    0.162811] Serial: 8250/16550 driver, 8 ports, IRQ sharing disabled
[2.637944 0.005904] [    0.165729] printk: console [ttyS0] disabled
[2.642895 0.004951] [    0.186031] 1c28000.serial: ttyS0 at MMIO 0x1c28000 (irq = 39, base_baud = 1500000) is a U6_16550A
[2.654048 0.011153] [    0.756353] printk: console [ttyS0] enabled
[2.680145 0.026097] [    0.784163] 1c28400.serial: ttyS1 at MMIO 0x1c28400 (irq = 40, base_baud = 1500000) is a U6_16550A
[2.710889 0.030744] [    0.814669] 1c28800.serial: ttyS2 at MMIO 0x1c28800 (irq = 41, base_baud = 1500000) is a U6_16550A
[2.749819 0.038930] [    0.853831] libphy: Fixed MDIO Bus: probed
[2.754585 0.004766] [    0.858440] dwmac-sun8i 1c30000.ethernet: IRQ eth_wake_irq not found
[2.760025 0.005440] [    0.864937] dwmac-sun8i 1c30000.ethernet: IRQ eth_lpi not found
[2.765902 0.005877] [    0.871006] dwmac-sun8i 1c30000.ethernet: PTP uses main clock
[2.771659 0.005757] [    0.876805] dwmac-sun8i 1c30000.ethernet: No regulator found
[2.777663 0.006004] [    0.882989] dwmac-sun8i 1c30000.ethernet: Current syscon value is not the default 148000 (expect 38000)
[2.787922 0.010259] [    0.892478] dwmac-sun8i 1c30000.ethernet: No HW DMA feature register supported
[2.794804 0.006882] [    0.899739] dwmac-sun8i 1c30000.ethernet: RX Checksum Offload Engine supported
[2.802387 0.007583] [    0.906965] dwmac-sun8i 1c30000.ethernet: COE Type 2
[2.807548 0.005161] [    0.911950] dwmac-sun8i 1c30000.ethernet: TX Checksum insertion supported
[2.813661 0.006113] [    0.918739] dwmac-sun8i 1c30000.ethernet: Normal descriptors
[2.819549 0.005888] [    0.924413] dwmac-sun8i 1c30000.ethernet: Chain mode enabled
[2.825228 0.005679] [    0.930356] libphy: stmmac: probed
[2.830267 0.005039] [    0.934362] dwmac-sun8i 1c30000.ethernet: Found internal PHY node
[2.839095 0.008828] [    0.940719] libphy: mdio_mux: probed
[2.841141 0.002046] [    0.944333] dwmac-sun8i 1c30000.ethernet: Switch mux to internal PHY
[2.845729 0.004588] [    0.950745] dwmac-sun8i 1c30000.ethernet: Powering internal PHY
[2.852564 0.006835] [    0.957761] libphy: mdio_mux: probed
[2.857478 0.004914] [    0.961825] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[2.863765 0.006287] [    0.968367] ehci-platform: EHCI generic platform driver
[2.869307 0.005542] [    0.973902] ehci-platform 1c1a000.usb: EHCI Host Controller
[2.874957 0.005650] [    0.979599] ehci-platform 1c1a000.usb: new USB bus registered, assigned bus number 1
[2.883411 0.008454] [    0.987726] ehci-platform 1c1a000.usb: irq 28, io mem 0x01c1a000
[2.915084 0.031673] [    1.019096] ehci-platform 1c1a000.usb: USB 2.0 started, EHCI 1.00
[2.921893 0.006809] [    1.026201] hub 1-0:1.0: USB hub found
[2.925665 0.003772] [    1.030158] hub 1-0:1.0: 1 port detected
[2.929565 0.003900] [    1.034748] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[2.936725 0.007160] [    1.041068] ohci-platform: OHCI generic platform driver
[2.942419 0.005694] [    1.046574] ohci-platform 1c1a400.usb: Generic Platform OHCI controller
[2.948684 0.006265] [    1.053303] ohci-platform 1c1a400.usb: new USB bus registered, assigned bus number 2
[2.957171 0.008487] [    1.061409] ohci-platform 1c1a400.usb: irq 29, io mem 0x01c1a400
[3.030132 0.072961] [    1.134086] hub 2-0:1.0: USB hub found
[3.033509 0.003377] [    1.137930] hub 2-0:1.0: 1 port detected
[3.041161 0.007652] [    1.145171] usbcore: registered new interface driver usb-storage
[3.047857 0.006696] [    1.152137] mousedev: PS/2 mouse device common for all mice
[3.054113 0.006256] [    1.158415] input: 1c22800.lradc as /devices/platform/soc/1c22800.lradc/input/input0
[3.063812 0.009699] [    1.167654] sun6i-rtc 1c20400.rtc: registered as rtc0
[3.067870 0.004058] [    1.172826] sun6i-rtc 1c20400.rtc: RTC enabled
[3.072942 0.005072] [    1.177476] i2c /dev entries driver
[3.196619 0.123677] [    1.300611] Goodix-TS 0-0014: ID 911, version: 1060
[3.201428 0.004809] [    1.306384] Goodix-TS 0-0014: Direct firmware load for goodix_911_cfg.bin failed with error -2
[3.228973 0.027545] [    1.333019] input: Goodix Capacitive TouchScreen as /devices/platform/soc/1c2ac00.i2c/i2c-0/0-0014/input/input1
[3.248189 0.019216] [    1.352244] random: fast init done
[3.298141 0.049952] [    1.402167] ov7670 1-0021: chip found @ 0x42 (mv64xxx_i2c adapter)
[3.372992 0.074851] [    1.476998] usbcore: registered new interface driver uvcvideo
[3.378625 0.005633] [    1.482837] USB Video Class driver (1.1.1)
[3.383140 0.004515] [    1.487890] sunxi-wdt 1c20ca0.watchdog: Watchdog enabled (timeout=16 sec, nowayout=0)
[3.418880 0.035740] [    1.522844] sunxi-mmc 1c0f000.mmc: initialized, max. request size: 16384 KB
[3.450648 0.031768] [    1.554699] sunxi-mmc 1c10000.mmc: initialized, max. request size: 16384 KB
[3.458462 0.007814] [    1.563159] usbcore: registered new interface driver usbhid
[3.464066 0.005604] [    1.568755] usbhid: USB HID core driver
[3.470708 0.006642] [    1.574763] sun4i-codec 1c22c00.codec: Failed to register our card
[3.478082 0.007374] [    1.582329] NET: Registered protocol family 17
[3.481985 0.003903] [    1.587000] Key type dns_resolver registered
[3.487117 0.005132] [    1.591507] Registering SWP/SWPB emulation handler
[3.494519 0.007402] [    1.596832] registered taskstats version 1
[3.497213 0.002694] [    1.601070] Loading compiled-in X.509 certificates
[3.511678 0.014465] [    1.615580] sun4i-drm display-engine: bound 1100000.mixer (ops 0xc084c888)
[3.518481 0.006803] [    1.623169] sun4i-drm display-engine: bound 1c0c000.lcd-controller (ops 0xc0849b6c)
[3.526536 0.008055] [    1.630924] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[3.532787 0.006251] [    1.637545] [drm] No driver support for vblank timestamp query.
[3.542367 0.009580] [    1.644404] [drm] Initialized sun4i-drm 1.0.0 20150629 for display-engine on minor 0
[3.849515 0.307148] [    1.662257] mmc0: host does not support reading read-only switch, assuming write-enable
[3.857329 0.007814] [    1.665358] mmc0: new high speed SDHC card at address aaaa
[3.862254 0.004925] [    1.666291] mmcblk0: mmc0:aaaa SA08G 7.40 GiB 
[3.867203 0.004949] [    1.669482]  mmcblk0: p1 p2
[3.870303 0.003100] [    1.936356] Console: switching to colour frame buffer device 128x37
[3.877025 0.006722] [    1.981007] sun4i-drm display-engine: fb0: sun4i-drmdrmfb frame buffer device
[3.885053 0.008028] [    1.989156] usb_phy_generic usb_phy_generic.0.auto: usb_phy_generic.0.auto supply vcc not found, using dummy regulator
[3.900701 0.015648] [    2.000844] musb-hdrc musb-hdrc.1.auto: MUSB HDRC host driver
[3.910422 0.009721] [    2.010585] musb-hdrc musb-hdrc.1.auto: new USB bus registered, assigned bus number 3
[3.923092 0.012670] [    2.023388] hub 3-0:1.0: USB hub found
[3.930945 0.007853] [    2.031240] hub 3-0:1.0: 1 port detected
[3.940969 0.010024] [    2.041106] debugfs: Directory '1c22c00.codec' with parent 'V3s Audio Codec' already present!
[3.954749 0.013780] [    2.054925] sun4i-codec 1c22c00.codec: Codec <-> 1c22c00.codec mapping ok
[3.966970 0.012221] [    2.067112] sun6i-rtc 1c20400.rtc: setting system clock to 1970-01-01T01:03:25 UTC (3805)
[3.979459 0.012489] [    2.079541] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[3.994591 0.015132] [    2.094714] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[4.005366 0.010775] [    2.105458] ALSA device list:
[4.012008 0.006642] [    2.112376]   #0: V3s Audio Codec
[4.019725 0.007717] [    2.119824] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
[4.032305 0.012580] [    2.132453] cfg80211: failed to load regulatory.db
[4.045469 0.013164] [    2.145400] EXT4-fs (mmcblk0p2): INFO: recovery required on readonly filesystem
[4.056880 0.011411] [    2.156956] EXT4-fs (mmcblk0p2): write access will be enabled during recovery
[4.089939 0.033059] [    2.189961] EXT4-fs (mmcblk0p2): recovery complete
[4.102290 0.012351] [    2.202273] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[4.114606 0.012316] [    2.214572] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[4.127610 0.013004] [    2.227534] devtmpfs: mounted
[4.135747 0.008137] [    2.235795] Freeing unused kernel memory: 1024K
[4.144892 0.009145] [    2.244914] Run /sbin/bootchartd as init process
[4.285294 0.140402] [    2.385292] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[4.343994 0.058700] App Starting..
[4.366722 0.022728] App Started..
[4.409441 0.042719] [    2.509208] usb 1-1: new high-speed USB device number 2 using ehci-platform
[4.623229 0.213788] [    2.723364] hub 1-1:1.0: USB hub found
[4.639469 0.016240] [    2.739606] hub 1-1:1.0: 4 ports detected
[5.038564 0.399095] [    3.136234] dwmac-sun8i 1c30000.ethernet eth0: PHY [0.1:01] driver [Generic PHY]
[5.070638 0.032074] [    3.137482] dwmac-sun8i 1c30000.ethernet eth0: No Safety Features support found
[5.081589 0.010951] [    3.137497] dwmac-sun8i 1c30000.ethernet eth0: No MAC Management Counters available
[5.100001 0.018412] [    3.137506] dwmac-sun8i 1c30000.ethernet eth0: PTP not supported by HW
[5.113019 0.013018] [    3.137520] dwmac-sun8i 1c30000.ethernet eth0: configuring for phy/mii link mode
[5.139133 0.026114] [    3.211525] random: dhcpcd: uninitialized urandom read (120 bytes read)
[5.362592 0.069578] [    3.461861] sun6i-csi 1cb4000.csi: dma_alloc_coherent of size 614400 failed

4.5초의 지연시간이 약 1초로 줄어들면서 전원 연결에서 앱 구동까지 5.5초 정도만 소요됨을 확인할 수 있었다.

 

HW: 0.2초

SPL: 0.5초

U-boot: 1.1초

커널 : 2.1초

Init + App: 1.2초

 

이번주 금요일까지 계속 최적화에 매진한다면 4초 미만의 빠른 부팅 기기를 만들 수 있을것 같다.

일단 목표는 3초로 해보자. 원-투-쓰리 !!!

힘들고 지겹더라도 좀 더 노~~오~~력~~해 보자. ㅋㅋㅋ

'임베디드' 카테고리의 다른 글

쾌속 임베디드 리눅스 부팅 4차 도전  (0) 2020.01.16
쾌속 부팅 3차 도전  (0) 2020.01.15
쾌속 부팅을 향한 첫 걸음  (0) 2020.01.13
USB 무선랜 드라이버 구동  (0) 2020.01.11
SPI 포트 기능 테스트  (3) 2020.01.11