1s time pause on console when kernel boot

jakiewen · ‎12-14-2016

I try to reduce imx6q kernel(4.1) startup time,and occur a problem:one second pause every time.here is the log:

boota mmc1
Kernel load addr 0x14008000 size 8251 KiB
   Booting using the fdt blob at 0x14f00000
   Loading Kernel Image ... OK
   Using Device Tree in place at 14f00000, end 14f0e5d0
switch to ldo_bypass mode!

>>> Starting kernel ...

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Initializing cgroup subsys cpuacct
[    0.000000] Linux version 4.1.15 (wenjunkai@WINMU) (gcc version 4.9.x-google 20140827 (prerelease) (GCC) ) #4 SMP PREEMPT Tue Dec 6 166
[    0.000000] CPU: ARMv7 Processor [412fc09a] revision 10 (ARMv7), cr=10c53c7d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] Machine model: Freescale i.MX6 Quad SABRE Smart Device Board
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] PERCPU: Embedded 13 pages/cpu @ee79f000 s22144 r8192 d22912 u53248
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 260384
[    0.000000] Kernel command line: console=ttymxc0,115200 init=/init video=mxcfb0:dev=ldb,bpp=32 video=m9
[    0.000000] wjk:........console_setup,console input string = ttymxc0,115200... enter!        //uboot传参数console
[    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Memory: 1019124K/1047552K available (11558K kernel code, 962K rwdata, 3556K rodata, 1024K )
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xef800000 - 0xff000000   ( 248 MB)
[    0.000000]     lowmem : 0xc0000000 - 0xef000000   ( 752 MB)
[    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
[    0.000000]     modules : 0xbf000000 - 0xbfe00000   ( 14 MB)
[    0.000000]       .text : 0xc0008000 - 0xc0fc29c4   (16107 kB)
[    0.000000]       .init : 0xc1000000 - 0xc1100000   (1024 kB)
[    0.000000]       .data : 0xc1100000 - 0xc11f0968   ( 963 kB)
[    0.000000]        .bss : 0xc11f0968 - 0xc1291664   ( 644 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000] Additional per-CPU info printed with stalls.
[    0.000000] NR_IRQS:16 nr_irqs:16 16
[    0.000000] L2C-310 erratum 769419 enabled
[    0.000000] L2C-310 enabling early BRESP for Cortex-A9
[    0.000000] L2C-310 full line of zeros enabled for Cortex-A9
[    0.000000] L2C-310 ID prefetch enabled, offset 16 lines
[    0.000000] L2C-310 dynamic clock gating enabled, standby mode enabled
[    0.000000] L2C-310 cache controller enabled, 16 ways, 1024 kB
[    0.000000] L2C-310: CACHE_ID 0x410000c7, AUX_CTRL 0x76070001
[    0.000000] mxc_clocksource_init 3000000
[    0.000000] Switching to timer-based delay loop, resolution 333ns
[    0.000006] sched_clock: 32 bits at 3000kHz, resolution 333ns, wraps every 715827882841ns
[    0.000024] clocksource mxc_timer1: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 637086815595s
[    0.001058] Console: colour dummy device 80x30           //
[    0.001069] wjk:...111.....register_console enter!
[    0.001104] Calibrating delay loop (skipped), value calculated using timer frequency.. 6.00 BogoMIPS ()
[    0.001121] pid_max: default: 32768 minimum: 301
[    0.001203] Security Framework initialized
[    0.001216] SELinux: Initializing.
[    0.001259] AppArmor: AppArmor disabled by boot time parameter
[    0.001296] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.001307] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.002001] Initializing cgroup subsys freezer
[    0.002021] Initializing cgroup subsys debug
[    0.002049] CPU: Testing write buffer coherency: ok
[    0.002373] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.002478] Setting up static identity map for 0x10100000 - 0x10100058
[    0.060290] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.080269] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.100272] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.100346] Brought up 4 CPUs
[    0.100371] SMP: Total of 4 processors activated (24.00 BogoMIPS).
[    0.100380] CPU: All CPU(s) started in SVC mode.
[    0.101027] devtmpfs: initialized
[    0.112838] VFP support v0.3: implementor 41 architecture 3 part 30 variant 9 rev 4
[    0.113292] clocksource jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 191126044627500s
[    0.113743] pinctrl core: initialized pinctrl subsystem
[    0.114899] NET: Registered protocol family 16
[    0.117009] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.139695] cpuidle: using governor ladder
[    0.169692] cpuidle: using governor menu
[    0.170141] CPU identified as i.MX6Q, silicon rev 1.5
[    0.181349] anatop_regulator 20c8000.anatop:regulator-vddcore@140: Failed to read a valid default volt.
[    0.181374] anatop_regulator: probe of 20c8000.anatop:regulator-vddcore@140 failed with error -22
[    0.182059] anatop_regulator 20c8000.anatop:regulator-vddsoc@140: Failed to read a valid default volta.
[    0.182081] anatop_regulator: probe of 20c8000.anatop:regulator-vddsoc@140 failed with error -22
[    0.195712] !!request miniPCIE Power On gpio
[    0.195764] hw-breakpoint: found 5 (+1 reserved) breakpoint and 1 watchpoint registers.
[    0.195775] hw-breakpoint: maximum watchpoint size is 4 bytes.
[    0.195793] VDSO is not a valid ELF object!
[    0.196862] imx6q-pinctrl 20e0000.iomuxc: initialized IMX pinctrl driver
[    0.242827] mxs-dma 110000.dma-apbh: initialized
[    0.244480] MPCIE_3V3: Failed to request enable GPIO83: -16
[    0.244598] reg-fixed-voltage regulators:regulator@3: Failed to register regulator: -16
[    0.244627] reg-fixed-voltage: probe of regulators:regulator@3 failed with error -16
[    0.246973] SCSI subsystem initialized
[    0.247522] usbcore: registered new interface driver usbfs
[    0.247597] usbcore: registered new interface driver hub
[    0.247697] usbcore: registered new device driver usb
[    0.247886] 2000000.aips-bus:usbphy_nop1 supply vcc not found, using dummy regulator
[    0.248014] 2000000.aips-bus:usbphy_nop2 supply vcc not found, using dummy regulator
[    0.249345] i2c i2c-0: IMX I2C adapter registered
[    0.249365] i2c i2c-0: can't use DMA
[    0.250416] i2c i2c-1: IMX I2C adapter registered
[    0.250433] i2c i2c-1: can't use DMA
[    0.251167] i2c i2c-2: IMX I2C adapter registered
[    0.251185] i2c i2c-2: can't use DMA
[    0.251346] Linux video capture interface: v2.00
[    0.251434] pps_core: LinuxPPS API ver. 1 registered
[    0.251445] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.251477] PTP clock support registered
[    0.269737] imx-ipuv3 2400000.ipu: IPU DMFC NORMAL mode: 1(0~1), 5B(4,5), 5F(6,7)
[    0.289735] imx-ipuv3 2800000.ipu: IPU DMFC NORMAL mode: 1(0~1), 5B(4,5), 5F(6,7)
[    0.291079] mxc_mipi_csi2 21dc000.mipi_csi: i.MX MIPI CSI2 driver probed
[    0.291095] mxc_mipi_csi2 21dc000.mipi_csi: i.MX MIPI CSI2 dphy version is 0x3130302a
[    0.291189] MIPI CSI2 driver module loaded
[    0.291468] Advanced Linux Sound Architecture Driver Initialized.
[    0.292814] Bluetooth: Core ver 2.20
[    0.292863] NET: Registered protocol family 31
[    0.292873] Bluetooth: HCI device and connection manager initialized
[    0.292889] Bluetooth: HCI socket layer initialized
[    0.292905] Bluetooth: L2CAP socket layer initialized
[    0.292945] Bluetooth: SCO socket layer initialized
[    0.294466] Switched to clocksource mxc_timer1
[    0.374388] NET: Registered protocol family 2
[    0.375182] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[    0.375282] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[    0.375442] TCP: Hash tables configured (established 8192 bind 8192)
[    0.375636] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.375683] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.375923] NET: Registered protocol family 1
[    0.376332] RPC: Registered named UNIX socket transport module.
[    0.376343] RPC: Registered udp transport module.
[    0.376352] RPC: Registered tcp transport module.
[    0.376361] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.376656] Trying to unpack rootfs image as initramfs...
[    0.444253] Freeing initrd memory: 1012K (c5000000 - c50fd000)
[    0.445286] CPU PMU: Failed to parse /soc/pmu/interrupt-affinity[0]
[    0.445351] hw perfevents: enabled with armv7_cortex_a9 PMU driver, 7 counters available
[    0.447515] imx rpmsg driver is registered.         //imx_rpmsg_init
[    0.448524] Bus freq driver module loaded       //busfreq_init
[    0.450186] wjk:...111.....register_console enter!          //register_console                                                                               [    0.450226] wjk:.....register_console ........console_lock delete!
[    0.450233] wjk:...555.....register_console enter!
[    0.450936] wjk:.....register_console ........console_unlock delete!      //no 1s pause
[    0.450945] wjk:...555...111...register_console enter!
[    0.451000] wjk:...666.....register_console enter!
[    0.451014] console [pstore-1] enabled
[    0.451025] pstore: Registered ramoops as persistent store backend
[    0.451037] ramoops: attached 0x100000@0x4bf00000, ecc: 0/0
[    0.451056] mxc_ramoops device success loaded
[    0.451380] bt power gpio is:2
[    0.451583] rfkill: BT RF going to : on
[    0.451595] mxc_bt_rfkill_reset
[    0.454542] mxc_bt_rfkill driver success loaded
[    0.455865] futex hash table entries: 1024 (order: 4, 65536 bytes)
[    0.455976] audit: initializing netlink subsys (disabled)
[    0.456077] audit: type=2000 audit(0.450:1): initialized
[    0.463677] VFS: Disk quotas dquot_6.6.0
[    0.463875] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.466389] NFS: Registering the id_resolver key type
[    0.466432] Key type id_resolver registered
[    0.466444] Key type id_legacy registered
[    0.466545] ntfs: driver 2.1.32 [Flags: R/W DEBUG].
[    0.466843] jffs2: version 2.2. (NAND) �© 2001-2006 Red Hat, Inc.
[    0.467567] fuse init (API version 7.23)
[    0.473484] bounce: pool size: 64 pages
[    0.473509] io scheduler noop registered
[    0.473527] io scheduler deadline registered
[    0.473719] io scheduler cfq registered (default)
[    0.474282] imx-weim 21b8000.weim: Driver registered.
[    0.475758] pwm-backlight supply power not found, using dummy regulator
[    0.477644] mxc_mipi_dsi 21e0000.mipi: i.MX MIPI DSI driver probed
[    0.477768] MIPI DSI driver module loaded
[    0.478019] MIPI DSI driver module loaded
[    0.480815] mxc_sdc_fb fb@0: registered mxc display driver ldb
[    0.544567] imx-ipuv3 2800000.ipu: IPU DMFC DP HIGH RESOLUTION: 1(0,1), 5B(2~5), 5F(6,7)
[    0.610125] Console: switching to colour frame buffer device 128x48
[    0.657477] mxc_sdc_fb fb@1imx-sdma 20ec000.sdma: no iram assigned, using external mem
[    0.660428] imx-sdma 20ec000.sdma: no event needs to be remapped
[    0.660764] imx-sdma 20ec000.sdma: loaded firmware 3.3
[    0.663524] imx-sdma 20ec000.sdma: initi: Can't get fb option for mxcfb1!
[    0.657555] mxc_sdc_fb fb@2: Can't get fb option for mxcfb2!
[    0.657626] mxc_sdc_fb fb@3: Can't get fb option for mxcfb3!
[    0.660189] alized
[    0.665274] pfuze100-regulator 1-0008: Full layer: 2, Metal layer: 1
[    0.665902] pfuze100-regulator 1-0008: FAB: 0, FIN: 0
[    0.665918] pfuze100-regulator 1-0008: pfuze100 found.
[    0.681660] wjk:....111....uart_add_one_port enter!      //uart_add_one_port
[    0.681675] wjk:........uart_add_one_port enter!
[    0.681685] wjk:........uart_configure_port enter!
[    0.681694] wjk:........uart_report_port enter!
[    0.681708] 2020000.serial: ttymxc0 at MMIO 0x2020000 (irq = 25, base_baud = 5000000) is a IMX   //uart_report_port

[    0.681718] wjk:...222.....uart_configure_port enter!
[    0.681727] wjk:........uart_change_pm enter!
[    0.681836] wjk:.....register_console ........console_lock delete!        //1s pause
[    0.681847] wjk:...555.....register_console enter!
[    1.812801] wjk:.....register_console ........console_unlock delete!
[    1.819179] wjk:...555...111...register_console enter!
[    1.824359] wjk:...666.....register_console enter!
[    1.829182] console [ttymxc0] enabled
[    1.832858] wjk:...555.....uart_configure_port enter!
[    1.837934] wjk:...666.....uart_configure_port enter!
[    1.848698] wjk:........uart_add_one_port enter!
[    1.853330] wjk:........uart_configure_port enter!
[    1.858147] wjk:........uart_report_port enter!
[    1.862695] 21f4000.serial: ttymxc4 at MMIO 0x21f4000 (irq = 300, base_baud = 5000000) is a IMX
[    1.871417] wjk:...222.....uart_configure_port enter!
[    1.896079] wjk:........uart_change_pm enter!
[    1.900449] wjk:...666.....uart_configure_port enter!
[    1.907730] imx sema4 driver is registered.
[    1.912014] [drm] Initialized drm 1.1.0 20060810
[    1.917239] [drm] Initialized vivante 1.0.0 20120216 on minor 0
[    1.933238] brd: module loaded
[    1.942815] loop: module loaded
[    1.946614] zram: Created 1 device(s)
[    1.954240] imx6q-pinctrl 20e0000.iomuxc: pin MX6Q_PAD_KEY_COL1 already requested by 21f4000.serial; ci
[    1.966156] imx6q-pinctrl 20e0000.iomuxc: pin-128 (2008000.ecspi) status -22
[    1.973226] imx6q-pinctrl 20e0000.iomuxc: could not request pin 128 (MX6Q_PAD_KEY_COL1) from group ecsc
[    1.985354] spi_imx 2008000.ecspi: Error applying setting, reverse things back
[    1.993459] m25p80 spi0.0: unrecognized JEDEC id bytes: 00, 0, 0
[    1.999708] spi_imx 2008000.ecspi: probed
[    2.004707] tun: Universal TUN/TAP device driver, 1.6
[    2.009776] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
[    2.016224] CAN device driver interface
[    2.021975] 2188000.ethernet supply phy not found, using dummy regulator

jiujinhong · ‎01-17-2018

Hi ,

I have met the same issue, have you fixed?

jakiewen · ‎07-16-2018

Nope directly.I boot my board in quiet mode,so this question do not show up.

igorpadykov · ‎12-14-2016

Hi

please check how console_lock may work on

http://linux-kernel.2935.n7.nabble.com/Deadlock-in-fb-and-tty-td717929.html

fast boot is described on

Linux Fast Boot on i.MX6 Sabresd Board

Best regards
igor
-----------------------------------------------------------------------------------------------------------------------
Note: If this post answers your question, please click the Correct Answer button. Thank you!
-----------------------------------------------------------------------------------------------------------------------

1s time pause on console when kernel boot

1s time pause on console when kernel boot

i.MX6Quad

Linux