Painfully slow boot on RockPro64 with NetBSD-daily/netbsd-10/202308250610Z

[joelp%sdf.org@localhost]

I wanted to rebuild my RockPro64 and but my build machine is temporarily 
down, and so I pulled down 2023-08-25 daily. During boot, the CPU clock 
speed appears to be painfully slow. Here is a screen copy that shows 
where I see very slow kernel load, where I pressed space to see if it was locked (was not), 
and then typed boot:

  \\-__,------,___.
   \\        __,---`  NetBSD/evbarm efiboot (arm64)
    \\       `---,_.  Revision 2.13 (Wed Aug 23 18:21:39 UTC 2023)
     \\-,_____,.---`
      \\
       \\
        \\

Press return to boot now, any other key for boot prompt
booting netbsd - starting in 5 seconds.
0 seconds.
> boot
boot
8551984+3670808+4179424+1742096 [588013+1181904+707743]=0x1603460
[   1.0000000] NetBSD/evbarm (fdt) booting ...


The "...starting in 5 seconds." sits there for more then 5 seconds before 
it counts down to 4, for example. It's brutal.

Once past this, things are speedy as usual.

Is there a boot setting that can speed this up?

On previous builds, I noticed the 5 second count down was actually faster 
(as if it was too fast).

Thanks - Joel

More of dmesg:

[   1.0000000] Copyright (c) 1996, 1997, 1998, 1999, 2000, 2001, 2002, 
2003,
[   1.0000000]     2004, 2005, 2006, 2007, 2008, 2009, 2010, 2011, 2012, 
2013,
[   1.0000000]     2014, 2015, 2016, 2017, 2018, 2019, 2020, 2021, 2022, 
2023
[   1.0000000]     The NetBSD Foundation, Inc.  All rights reserved.
[   1.0000000] Copyright (c) 1982, 1986, 1989, 1991, 1993
[   1.0000000]     The Regents of the University of California.  All 
rights reserved.

[   1.0000000] NetBSD 10.0_BETA (GENERIC64) #0: Wed Aug 23 18:21:39 UTC 
2023
[   1.0000000] 
mkrepro%mkrepro.NetBSD.org@localhost:/usr/src/sys/arch/evbarm/compile/GENERIC64
[   1.0000000] total memory = 3937 MB
[   1.0000000] avail memory = 3795 MB
[   1.0000000] entropy: ready
[   1.0000000] armfdt0 (root)
[   1.0000000] simplebus0 at armfdt0: Pine64 RockPro64 v2.1
[   1.0000000] cpus0 at simplebus0
[   1.0000000] psci0 at simplebus0: PSCI 1.1
[   1.0000000] simplebus1 at simplebus0
[   1.0000000] cpu0 at cpus0: Arm Cortex-A53 r0p4 (v8-A), id 0x0
[   1.0000000] cpu0: package 0, core 0, smt 0
[   1.0000000] cpufreqdt0 at cpu0
[   1.0000000] cpu1 at cpus0: Arm Cortex-A53 r0p4 (v8-A), id 0x1
[   1.0000000] cpu1: package 0, core 1, smt 0
[   1.0000000] cpufreqdt1 at cpu1
[   1.0000000] cpu2 at cpus0: Arm Cortex-A53 r0p4 (v8-A), id 0x2
[   1.0000000] cpu2: package 0, core 2, smt 0
[   1.0000000] cpufreqdt2 at cpu2
[   1.0000000] cpu3 at cpus0: Arm Cortex-A53 r0p4 (v8-A), id 0x3
[   1.0000000] cpu3: package 0, core 3, smt 0
[   1.0000000] cpufreqdt3 at cpu3
[   1.0000000] cpu4 at cpus0: Arm Cortex-A72 r0p2 (v8-A), id 0x100
[   1.0000000] cpu4: package 1, core 0, smt 0
[   1.0000000] cpufreqdt4 at cpu4
[   1.0000000] cpu5 at cpus0: Arm Cortex-A72 r0p2 (v8-A), id 0x101
[   1.0000000] cpu5: package 1, core 1, smt 0
[   1.0000000] cpufreqdt5 at cpu5
[   1.0000000] gicvthree0 at simplebus0: GICv3
[   1.0000000] gicvthree0: ITS [#0] Devices table @ 0x271000/0x80000, 
Non-cacheable, Non-shareab
le
[   1.0000000] syscon0 at simplebus0: System Controller Registers
[   1.0000000] syscon1 at simplebus0: System Controller Registers
[   1.0000000] fclock0 at simplebus0: 125000000 Hz fixed clock 
(clkin_gmac)
[   1.0000000] syscon2 at simplebus0: System Controller Registers
[   1.0000000] syscon3 at simplebus0: System Controller Registers
[   1.0000000] syscon4 at simplebus0: System Controller Registers
[   1.0000000] syscon5 at simplebus0: System Controller Registers
[   1.0000000] syscon6 at simplebus0: System Controller Registers
[   1.0000000] syscon7 at simplebus0: System Controller Registers
[   1.0000000] syscon8 at simplebus0: System Controller Registers
[   1.0000000] syscon9 at simplebus0: System Controller Registers
[   1.0000000] syscon10 at simplebus0: System Controller Registers
[   1.0000000] syscon11 at simplebus0: System Controller Registers
[   1.0000000] syscon12 at simplebus0: System Controller Registers
[   1.0000000] syscon13 at simplebus0: System Controller Registers
[   1.0000000] syscon14 at simplebus0: System Controller Registers
[   1.0000000] syscon15 at simplebus0: System Controller Registers
[   1.0000000] syscon16 at simplebus0: System Controller Registers
[   1.0000000] syscon17 at simplebus0: System Controller Registers
[   1.0000000] syscon18 at simplebus0: System Controller Registers
[   1.0000000] syscon19 at simplebus0: System Controller Registers
[   1.0000000] syscon20 at simplebus0: System Controller Registers
[   1.0000000] syscon21 at simplebus0: System Controller Registers
[   1.0000000] syscon22 at simplebus0: System Controller Registers
[   1.0000000] syscon23 at simplebus0: System Controller Registers
[   1.0000000] fclock1 at simplebus0: 24000000 Hz fixed clock (xin24m)
[   1.0000000] syscon24 at simplebus0: System Controller Registers
[   1.0000000] syscon25 at simplebus0: System Controller Registers
[   1.0000000] syscon26 at simplebus0: System Controller Registers
[   1.0000000] syscon27 at simplebus0: System Controller Registers
[   1.0000000] rkcru0 at simplebus0: RK3399 CRU
[   1.0000000] rkcru1 at simplebus0: RK3399 PMU CRU
[   1.0000000] gtmr0 at simplebus0: Generic Timer
[   1.0000000] gtmr0: interrupting on GICv3 irq 27
[   1.0000000] armgtmr0 at gtmr0: Generic Timer (24000 kHz, virtual)
[   1.0000040] rkiomux0 at simplebus0: RK3399 IOMUX control
[   1.0000040] rkgpio0 at rkiomux0: GPIO (gpio0@ff720000)
[   1.0000040] gpio0 at rkgpio0: 32 pins
[   1.0000040] rkgpio1 at rkiomux0: GPIO (gpio1@ff730000)
[   1.0000040] gpio1 at rkgpio1: 32 pins
[   1.0000040] rkgpio2 at rkiomux0: GPIO (gpio2@ff780000)
[   1.0000040] gpio2 at rkgpio2: 32 pins
[   1.0000040] rkgpio3 at rkiomux0: GPIO (gpio3@ff788000)
[   1.0000040] gpio3 at rkgpio3: 32 pins
[   1.0000040] rkgpio4 at rkiomux0: GPIO (gpio4@ff790000)
[   1.0000040] gpio4 at rkgpio4: 32 pins
[   1.0000040] rkpwm0 at simplebus0: PWM
[   1.0000040] rkpwm1 at simplebus0: PWM
[   1.0000040] rkpwm2 at simplebus0: PWM
[   1.0000040] rkiic0 at simplebus0: Rockchip I2C (400000 Hz)
[   1.0000040] iic0 at rkiic0: I2C bus
[   1.0000040] rkpmic0 at iic0 addr 0x1b: RK808 Power Management and Real 
Time Clock IC
[   1.0000040] rkreg0 at rkpmic0: vdd_center
[   1.0000040] rkreg1 at rkpmic0: vdd_cpu_l
[   1.0000040] rkreg2 at rkpmic0: vcc_ddr
[   1.0000040] rkreg3 at rkpmic0: vcc_1v8
[   1.0000040] rkreg4 at rkpmic0: vcc1v8_dvp
[   1.0000040] rkreg5 at rkpmic0: vcc3v0_touch
[   1.0000040] rkreg6 at rkpmic0: vcca_1v8
[   1.0000040] rkreg7 at rkpmic0: vcc_sdio
[   1.0000040] rkreg8 at rkpmic0: vcca3v0_codec
[   1.0000040] rkreg9 at rkpmic0: vcc_1v5
[   1.0000040] rkreg10 at rkpmic0: vcca1v8_codec
[   1.0000040] rkreg11 at rkpmic0: vcc_3v0
[   1.0000040] rkreg12 at rkpmic0: vcc3v3_s3
[   1.0000040] rkreg13 at rkpmic0: vcc3v3_s0
[   1.0000040] fan53555reg0 at iic0 addr 0x40: Silergy SYR82X
[   1.0000040] fan53555reg1 at iic0 addr 0x41: Silergy SYR82X
[   1.0000040] fregulator0 at simplebus0: vcc12v_dcin
[   1.0000040] fregulator1 at simplebus0: vcc3v3_pcie
[   1.0000040] fregulator2 at simplebus0: vcc3v0_sd
[   1.0000040] fregulator3 at simplebus0: vcc1v8_s3
[   1.0000040] fregulator4 at simplebus0: vcc3v3_sys
[   1.0000040] fregulator5 at simplebus0: vcc5v0_sys
[   1.0000040] fregulator6 at simplebus0: vcc5v0_typec
[   1.0000040] fregulator7 at simplebus0: vcc5v0_host
[   1.0000040] rkiic1 at simplebus0: Rockchip I2C (100000 Hz)
[   1.0000040] iic1 at rkiic1: I2C bus
[   1.0000040] es8316ac0 at iic1 addr 0x11: Everest Semi ES8316 Audio 
CODEC
[   1.0000040] rkiic2 at simplebus0: Rockchip I2C (100000 Hz)
[   1.0000040] iic2 at rkiic2: I2C bus
[   1.0000040] fregulator8 at simplebus0: vcc5v0_usb
[   1.0000040] com0 at simplebus0: DesignWare APB UART, 64-byte FIFO
[   1.0000040] com0: interrupting on GICv3 irq 131
[   1.0000040] com1 at simplebus0: DesignWare APB UART, 64-byte FIFO
[   1.0000040] com1: console
[   1.0000040] com1: interrupting on GICv3 irq 132
[   1.0000040] rkiic3 at simplebus0: Rockchip I2C (100000 Hz)
[   1.0000040] iic3 at rkiic3: I2C bus
[   1.0000040] typec-portc (fcs,fusb302) at iic3 addr 0x22 not configured
[   1.0000040] pregulator0 at simplebus0: vdd_log
[   1.0000040] mmcpwrseq0 at simplebus0: Simple MMC power sequence 
provider
[   1.0000040] rkdrm0 at simplebus0
[   1.0000040] rkpciephy0 at syscon0: RK3399 PCIe PHY
[   1.0000040] rkusb0 at syscon0: USB2 PHY
[   1.0000040] rkusbphy0 at rkusb0: USB2 host port
[   1.0000040] rkusbphy1 at rkusb0: USB2 OTG port
[   1.0000040] rkusb1 at syscon0: USB2 PHY
[   1.0000040] rkusbphy2 at rkusb1: USB2 host port
[   1.0000040] rkusbphy3 at rkusb1: USB2 OTG port
[   1.0000040] /opp-table0 at simplebus0 not configured
[   1.0000040] /opp-table1 at simplebus0 not configured
[   1.0000040] rkvop0 at simplebus0: RK3399 VOPL
[   1.0000040] rkvop0: interrupting on GICv3 irq 151
[   1.0000040] rkvop1 at simplebus0: RK3399 VOPB
[   1.0000040] rkvop1: interrupting on GICv3 irq 150
[   1.0000040] rkpcie0 at simplebus0: RK3399 PCIe
[   1.0000040] rkpcie0: link training timeout (link_st 0)
[   1.0000040] /power-management@ff310000/power-controller at syscon1 not 
configured
[   1.0000040] rkemmcphy0 at syscon0: eMMC PHY
[   1.0000040] /phy@ff7c0000 at simplebus0 not configured
[   1.0000040] /phy@ff800000 at simplebus0 not configured
[   1.0000040] rki2s0 at simplebus0: I2S/PCM controller
[   1.0000040] rki2s0: interrupting on GICv3 irq 72
[   1.0000040] /dma-controller@ff6e0000 at simplebus0 not configured
[   1.0000040] /dma-controller@ff6d0000 at simplebus0 not configured
[   1.0000040] rktsadc0 at simplebus0: RK3399 Temperature Sensor ADC
[   1.0000040] rktsadc0: interrupting on GICv3 irq 129
[   1.0000040] /gpu@ff9a0000 at simplebus0 not configured
[   1.0000040] /iommu@ff650800 at simplebus0 not configured
[   1.0000040] /iommu@ff660480 at simplebus0 not configured
[   1.0000040] sdhc0 at simplebus0: Arasan SDHCI controller
[   1.0000040] sdhc0: interrupting on GICv3 irq 43
[   1.0000040] sdhc0: SDHC 3.0, rev 16, 32-bit ADMA2, 200000 kHz, embedded 
slot, HS SDR50 DDR50
SDR104 HS200 1.8V, re-tuning mode 1, 1024 byte blocks
[   1.0000040] sdmmc0 at sdhc0 slot 0
[   1.0000040] spdiftx0 at simplebus0: SPDIF transmitter
[   1.0000040] /iommu@ff8f3f00 at simplebus0 not configured
[   1.0000040] dwhdmi0 at simplebus0: HDMI TX
[   1.0000040] dwhdmi0: version 2.11a, phytype 0xf3
[   1.0000040] /iommu@ff903f00 at simplebus0 not configured
[   1.0000040] /iommu@ff914000 at simplebus0 not configured
[   1.0000040] rki2s1 at simplebus0: I2S/PCM controller
[   1.0000040] rki2s1: interrupting on GICv3 irq 73
[   1.0000040] /opp-table2 at simplebus0 not configured
[   1.0000040] awge0 at simplebus0: GMAC
[   1.0000040] awge0: Core version: 00001035
[   1.0000040] awge0: Ethernet address 26:80:27:df:b6:ae
[   1.0000040] awge0: HW feature mask: d0f17
[   1.0000040] rgephy0 at awge0 phy 0: RTL8211F 1000BASE-T media interface
[   1.0000040] rgephy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, 
1000baseT-FDX, auto
[   1.0000040] rgephy1 at awge0 phy 1: RTL8211F 1000BASE-T media interface
[   1.0000040] rgephy1: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, 
1000baseT-FDX, auto
[   1.0000040] awge0: interrupting on GICv3 irq 44
[   1.0000040] dwcmmc0 at simplebus0: DesignWare SD/MMC
[   1.0000040] dwcmmc0: interrupting on GICv3 irq 96
[   1.0000040] dwcmmc1 at simplebus0: DesignWare SD/MMC
[   1.0000040] dwcmmc1: interrupting on GICv3 irq 97
[   1.0000040] ehci0 at simplebus0: EHCI
[   1.0000040] ehci0: interrupting on GICv3 irq 58
[   1.0000040] ehci0: 1 companion controller, 1 port
[   1.0000040] ehci0: Using DMA subregion for control data structures
[   1.0000040] usb0 at ehci0: USB revision 2.0
[   1.0000040] ohci0 at simplebus0: OHCI
[   1.0000040] ohci0: interrupting on GICv3 irq 60
[   1.0000040] ohci0: OHCI version 1.0
[   1.0000040] usb1 at ohci0: USB revision 1.0
[   1.0000040] ehci1 at simplebus0: EHCI
[   1.0000040] ehci1: interrupting on GICv3 irq 62
[   1.0000040] ehci1: 1 companion controller, 1 port
[   1.0000040] ehci1: Using DMA subregion for control data structures
[   1.0000040] usb2 at ehci1: USB revision 2.0
[   1.0000040] ohci1 at simplebus0: OHCI
[   1.0000040] ohci1: interrupting on GICv3 irq 64
[   1.0000040] ohci1: OHCI version 1.0
[   1.0000040] usb3 at ohci1: USB revision 1.0
[   1.0000040] xhci0 at simplebus0: DesignWare USB3 XHCI (rev. 3.00a)
[   1.0000040] xhci0: interrupting on GICv3 irq 137
[   1.0000040] usb4 at xhci0: USB revision 3.0
[   1.0000040] usb5 at xhci0: USB revision 2.0
[   1.0000040] xhci1 at simplebus0: DesignWare USB3 XHCI (rev. 3.00a)
[   1.0000040] xhci1: interrupting on GICv3 irq 142
[   1.0000040] usb6 at xhci1: USB revision 3.0
[   1.0000040] usb7 at xhci1: USB revision 2.0
[   1.0000040] /saradc@ff100000 at simplebus0 not configured
[   1.0000040] rkspi0 at simplebus0: SPI
[   1.0000040] rkspi0: interrupting on GICv3 irq 85
[   1.0000040] spi0 at rkspi0: SPI bus
[   1.0000040] m25p0 at spi0 slave 0
[   1.0000040] /syscon@ff320000/io-domains at syscon23 not configured
[   1.0000040] /video-codec@ff650000 at simplebus0 not configured
[   1.0000040] /video-codec@ff660000 at simplebus0 not configured
[   1.0000040] /rga@ff680000 at simplebus0 not configured
[   1.0000040] /efuse@ff690000 at simplebus0 not configured
[   1.0000040] /syscon@ff770000/io-domains at syscon0 not configured
[   1.0000040] /rktimer@ff850000 at simplebus0 not configured
[   1.0000040] rki2s2 at simplebus0: I2S/PCM controller
[   1.0000040] rki2s2: interrupting on GICv3 irq 71
[   1.0000040] /iommu@ff924000 at simplebus0 not configured
[   1.0000040] ausoc0 at simplebus0: hdmi-sound
[   1.0000040] gpioleds0 at simplebus0: work diy
[   1.0000040] pwmfan0 at simplebus0: couldn't get 'cooling-levels' 
property
[   1.0000040] rkv1crypto0 at simplebus0: Crypto v1
[   1.0000040] armpmu0 at simplebus0: Performance Monitor Unit
[   1.0000040] armpmu1 at simplebus0: Performance Monitor Unit
[   1.0000040] dwcwdt0 at simplebus0: DesignWare Watchdog Timer
[   1.0000040] dwcwdt0: default watchdog period is 15 seconds
[   1.0000040] gpiokeys0 at simplebus0: GPIO Key Power
[   1.0000040] /ir-receiver at simplebus0 not configured
[   1.0000040] /sound at simplebus0 not configured
[   1.0000040] /sound-dit at simplebus0 not configured
[   1.0000040] rkdrm0: reserved 40 MB DRAM for CMA
[   1.0000040] rkfb0 at rkdrm0
[   1.0000040] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[   1.0000040] [drm] No driver support for vblank timestamp query.
[   1.0000040] [drm] Initialized rk 1.0.0 20191109 for rkdrm0 on minor 0
[   1.0000040] rkdrm0: initialized rk 1.0.0 20191109 on minor 0
[   1.0000040] warning: 
/usr/src/sys/external/bsd/drm2/dist/drm/drm_atomic_helper.c:1497: [CRTC:
33:crtc-0] vblank wait timed out
[   1.0000040] wsdisplay0 at rkfb0 kbdmux 1: console (default, vt100 
emulation)
[   1.0000040] ausoc0: codec: dwhdmi0, cpu: rki2s1
[   1.0000040] audio0 at ausoc0: playback, capture, full duplex
[   1.0000040] audio0: slinear_le:16 -> slinear_le:16 2ch 48000Hz, blk 
1920 bytes (10ms) for pla
yback
[   1.0000040] audio0: slinear_le:16 2ch 48000Hz, blk 1920 bytes (10ms) 
for recording
[   1.0000040] spkr0 at audio0: PC Speaker (synthesized)
[   1.0000040] wsbell at spkr0 not configured
[   2.5668631] cpufreqdt0: rate: 1416.000 MHz, 1125000 uV
[   2.5868644] cpufreqdt4: rate: 1800.000 MHz, 1200000 uV
[   2.5968633] sdmmc1 at dwcmmc0
[   2.5968633] sdmmc2 at dwcmmc1
[   2.6468640] uhub0 at usb0: NetBSD (0x0000) EHCI root hub (0x0000), 
class 9/0, rev 2.00/1.00,
addr 1
[   2.6468640] uhub1 at usb4: NetBSD (0x0000) xHCI root hub (0x0000), 
class 9/0, rev 3.00/1.00,
addr 0
[   2.6568650] uhub2 at usb5: NetBSD (0x0000) xHCI root hub (0x0000), 
class 9/0, rev 2.00/1.00,
addr 0
[   2.6568650] uhub3 at usb6: NetBSD (0x0000) xHCI root hub (0x0000), 
class 9/0, rev 3.00/1.00,
addr 0
[   2.6568650] uhub4 at usb7: NetBSD (0x0000) xHCI root hub (0x0000), 
class 9/0, rev 2.00/1.00,
addr 0
[   2.6668651] m25p0: JEDEC ID mfgid:0xC8, devid:0x4018
[   2.6668651] spiflash0 at m25p0: GigaDevice 25Q127CSIG SPI flash
[   2.6668651] spiflash0: 16384 KB, 256 sectors of 64 KB each
[   2.6768660] uhub5 at usb1: NetBSD (0x0000) OHCI root hub (0x0000), 
class 9/0, rev 1.00/1.00,
addr 1
[   2.6768660] uhub6 at usb2: NetBSD (0x0000) EHCI root hub (0x0000), 
class 9/0, rev 2.00/1.00,
addr 1
[   2.6768660] uhub7 at usb3: NetBSD (0x0000) OHCI root hub (0x0000), 
class 9/0, rev 1.00/1.00,
addr 1
[   2.6868667] armpmu1: interrupting on GICv3 irq 23
[   2.6868667] armpmu0: couldn't initialise PMU event counter[ 
2.7868678] sdmmc1: couldn't ena
ble card: 60
[   3.9668943] sdhc0: timeout waiting for mask 0x3 value 0 
(state=0x1fff0a06)
[   3.9768944] sdhc0: command or data phase inhibited
[   3.9868950] sdhc0: tuning did not complete, using fixed sampling clock
[   3.9868950] ehci0: handing over low speed device on port 1 to companion 
controller
[   3.9968957] ld0 at sdmmc0: <0x45:0x0100:DA4064:0x00:0x1c96c945:0x000>
[   3.9968957] ld0: 59640 MB, 7603 cyl, 255 head, 63 sec, 512 bytes/sect x 
122142720 sectors
[   3.9968957] dk0 at ld0: "EFI", 163840 blocks at 32768, type: msdos
[   3.9968957] dk1 at ld0: "netbsd-root", 121937920 blocks at 196608, 
type: ffs
[   3.9968957] ld0: 8-bit width, HS200, 4096 KB cache, 200.000 MHz
[   5.0569185] uhidev0 at uhub5 port 1 configuration 1 interface 0
[   5.0569185] uhidev0: SEM (0x1a2c) USB Keyboard (0x506f), rev 1.10/1.11, 
addr 2, iclass 3/1
[   5.0669186] ukbd0 at uhidev0
[   5.0669186] wskbd0 at ukbd0: console keyboard, using wsdisplay0
[   5.0669186] uhidev1 at uhub5 port 1 configuration 1 interface 1
[   5.0669186] uhidev1: SEM (0x1a2c) USB Keyboard (0x506f), rev 1.10/1.11, 
addr 2, iclass 3/0
[   5.0869200] uhidev1: 2 report ids
[   5.0869200] uhid0 at uhidev1 reportid 1: input=2, output=0, feature=0
[   5.0869200] uhid1 at uhidev1 reportid 2: input=1, output=0, feature=0
[   9.1270052] uhub4: device problem, disabling port 1
[   9.1270052] swwdog0: software watchdog initialized
[   9.1370059] WARNING: 6 errors while detecting hardware; check system 
log.
[   9.1370059] boot device: dk1
[   9.1370059] root on dk1
[   9.1370059] root file system type: ffs
[   9.1370059] kern.module.path=/stand/evbarm/10.0/modules
Sat Aug 26 13:27:24 PDT 2023