firmware
firmware copied to clipboard
raspberrypi
Pi4 reboot not reliable, hangs forever while rebooting.
Is this the right place for my bug report? Not exactly sure, the Raspberry Pi4 hangs when rebooting, often at the rainbow screen, so I suspect it's the firmware. But maybe it's the eeprom/vl805 or maybe even the Linux kernel.
Describe the bug Rrebooting is not reliable on the Pi4. Sometimes, after issueing the reboot command and after Linux has shutdown, it doesn't come back up and just sits there. After a while (not sure something around 20 seconds), the screen turns off and that's it. There seem to be different failure modes, sometimes the screen stays black (and/or turns off, probably due to missing HDMI signal), sometimes the Rainbow screen stays forever, sometimes there are red/black lines visible on the screen. Only way to get it out of this is to power-cycle.
To reproduce
- Write a fresh sdcard with Raspberry Pi OS Lite 2021-01-11
- Run "rpi-eeprom-update -a" (not sure if it's eeprom or vl805 firmware related, just wanted to make sure that it's using the "official" versions shipped with the distribution)
- Write "reboot -f " into /etc/init.d/raspi-config right below the "else" line, so that it looks like this:
case "$1" in
start)
log_daemon_msg "Checking if shift key is held down"
if [ -x /usr/sbin/thd ] && timeout 1 thd --dump /dev/input/event* | grep -q "LEFTSHIFT\|RIGHTSHIFT"; then
printf " Yes. Not enabling ondemand scaling governor"
log_end_msg 0
else
reboot -f
printf " No. Switching to ondemand scaling governor"
SYS_CPUFREQ_GOVERNOR=/sys/devices/system/cpu/cpu0/cpufreq/scaling_governor
if [ -e $SYS_CPUFREQ_GOVERNOR ]; then
echo "ondemand" > $SYS_CPUFREQ_GOVERNOR
echo 50 > /sys/devices/system/cpu/cpufreq/ondemand/up_threshold
echo 100000 > /sys/devices/system/cpu/cpufreq/ondemand/sampling_rate
echo 50 > /sys/devices/system/cpu/cpufreq/ondemand/sampling_down_factor
fi
log_end_msg 0
fi
;;
- Reboot
- Let it reboot over and over again, wait until it it is stuck. Sometimes it's stuck after 20 Minutes or so, sometimes it takes a day or two, but eventually, it'll be stuck.
- Press the shift key during boot-up to stop the bootloop to be able to login into Raspberry Pi OS
Expected behaviour Rebooting works all the time. We have Raspberry Pi4's deployed in hard to access remote locations, powercycling those Raspberries is not easy.
Actual behaviour Pi4 sometimes hangs during reboot.
System
------------------
Raspberry Pi 4 Model B Rev 1.1
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
Raspberry Pi reference 2021-01-11
Generated using pi-gen, https://github.com/RPi-Distro/pi-gen, 21090519d85bdaa1615d5d5057d37b09368ea5d2, stage4
Linux raspberrypi 5.4.83-v7l+ #1379 SMP Mon Dec 14 13:11:54 GMT 2020 armv7l GNU/Linux
Revision : c03111
Serial : 10000000ff334b1f
Model : Raspberry Pi 4 Model B Rev 1.1
Throttled flag : throttled=0x0
Camera : supported=0 detected=0
Videocore information
---------------------
Jan 8 2021 14:31:16
Copyright (c) 2012 Broadcom
version 194a85abd768c7334bbadc3f1911c10a7d18ed14 (clean) (release) (start)
alloc failures: 0
compactions: 0
legacy block fails: 0
Filesystem information
----------------------
Filesystem 1K-blocks Used Available Use% Mounted on
/dev/root 7196148 4465868 2385404 66% /
devtmpfs 1827800 0 1827800 0% /dev
tmpfs 1959896 0 1959896 0% /dev/shm
tmpfs 1959896 16964 1942932 1% /run
tmpfs 5120 4 5116 1% /run/lock
tmpfs 1959896 0 1959896 0% /sys/fs/cgroup
/dev/mmcblk0p1 258095 46273 211823 18% /boot
tmpfs 391976 4 391972 1% /run/user/1000
Filename Type Size Used Priority
/var/swap file 102396 0 -2
Package version information
---------------------------
raspberrypi-ui-mods:
Installed: 1.20201210+nmu1
raspberrypi-sys-mods:
Installed: 20201026
openbox:
Installed: 3.6.1-8+rpt5
lxpanel:
Installed: 0.10.0-2+rpt14
pcmanfm:
Installed: 1.3.1-1+rpt25
rpd-plym-splash:
Installed: 0.26
Networking Information
----------------------
eth0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST> mtu 1500
inet x.x.x.x netmask x.x.x.x broadcast x.x.x.x
inet6 y::y.y.y.y prefixlen 64 scopeid 0x20<link>
ether m.m.m.m txqueuelen 1000 (Ethernet)
RX packets 641 bytes 50413 (49.2 KiB)
RX errors 0 dropped 0 overruns 0 frame 0
TX packets 153 bytes 19534 (19.0 KiB)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0
lo: flags=73<UP,LOOPBACK,RUNNING> mtu 65536
inet x.x.x.x netmask x.x.x.x
inet6 ::1 prefixlen 128 scopeid 0x10<host>
loop txqueuelen 1000 (Local Loopback)
RX packets 0 bytes 0 (0.0 B)
RX errors 0 dropped 0 overruns 0 frame 0
TX packets 0 bytes 0 (0.0 B)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0
USB Information
---------------
/: Bus 02.Port 1: Dev 1, Class=root_hub, Driver=xhci_hcd/4p, 5000M
/: Bus 01.Port 1: Dev 1, Class=root_hub, Driver=xhci_hcd/1p, 480M
|__ Port 1: Dev 2, If 0, Class=Hub, Driver=hub/4p, 480M
|__ Port 3: Dev 3, If 0, Class=Human Interface Device, Driver=usbhid, 1.5M
|__ Port 3: Dev 3, If 1, Class=Human Interface Device, Driver=usbhid, 1.5M
config.txt
----------
arm_freq=1500
audio_pwm_mode=514
config_hdmi_boost=5
core_freq=500
core_freq_min=200
disable_commandline_tags=2
disable_l2cache=1
display_hdmi_rotate=-1
display_lcd_rotate=-1
enable_gic=1
enable_uart=1
force_eeprom_read=1
force_pwm_open=1
framebuffer_ignore_alpha=1
framebuffer_swap=1
gpu_freq=500
gpu_freq_min=250
init_uart_clock=0x2dc6c00
lcd_framerate=60
mask_gpu_interrupt0=1024
mask_gpu_interrupt1=0x10000
max_framebuffers=2
over_voltage_avs=-30000
pause_burst_frames=1
program_serial_random=1
total_mem=4096
hdmi_force_cec_address:0=65535
hdmi_force_cec_address:1=65535
hdmi_pixel_freq_limit:0=0x11e1a300
hdmi_pixel_freq_limit:1=0x11e1a300
device_tree=-
overlay_prefix=overlays/
hdmi_cvt:0=
hdmi_cvt:1=
hdmi_edid_filename:0=
hdmi_edid_filename:1=
hdmi_timings:0=
hdmi_timings:1=
cmdline.txt
-----------
coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 video=HDMI-A-1:1920x1080M@60 smsc95xx.macaddr=DC:A6:32:20:F8:BB vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 console=ttyS0,115200 console=tty1 earlycon=uart8250,mmio32,0xfe215040 root=PARTUUID=5140806e-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait quiet splash plymouth.ignore-serial-consoles
raspi-gpio settings
-------------------
BANK0 (GPIO 0 to 27):
GPIO 0: level=1 fsel=0 func=INPUT pull=UP
GPIO 1: level=1 fsel=0 func=INPUT pull=UP
GPIO 2: level=1 fsel=0 func=INPUT pull=UP
GPIO 3: level=1 fsel=0 func=INPUT pull=UP
GPIO 4: level=1 fsel=0 func=INPUT pull=UP
GPIO 5: level=1 fsel=0 func=INPUT pull=UP
GPIO 6: level=1 fsel=0 func=INPUT pull=UP
GPIO 7: level=1 fsel=0 func=INPUT pull=UP
GPIO 8: level=1 fsel=0 func=INPUT pull=UP
GPIO 9: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 10: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 11: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 12: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 13: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 14: level=1 fsel=2 alt=5 func=TXD1 pull=NONE
GPIO 15: level=1 fsel=2 alt=5 func=RXD1 pull=UP
GPIO 16: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 17: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 18: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 19: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 20: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 21: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 22: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 23: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 24: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 25: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 26: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 27: level=0 fsel=0 func=INPUT pull=DOWN
BANK1 (GPIO 28 to 45):
GPIO 28: level=1 fsel=2 alt=5 func=RGMII_MDIO pull=UP
GPIO 29: level=0 fsel=2 alt=5 func=RGMII_MDC pull=DOWN
GPIO 30: level=0 fsel=7 alt=3 func=CTS0 pull=UP
GPIO 31: level=0 fsel=7 alt=3 func=RTS0 pull=NONE
GPIO 32: level=1 fsel=7 alt=3 func=TXD0 pull=NONE
GPIO 33: level=1 fsel=7 alt=3 func=RXD0 pull=UP
GPIO 34: level=0 fsel=7 alt=3 func=SD1_CLK pull=NONE
GPIO 35: level=1 fsel=7 alt=3 func=SD1_CMD pull=UP
GPIO 36: level=1 fsel=7 alt=3 func=SD1_DAT0 pull=UP
GPIO 37: level=1 fsel=7 alt=3 func=SD1_DAT1 pull=UP
GPIO 38: level=1 fsel=7 alt=3 func=SD1_DAT2 pull=UP
GPIO 39: level=1 fsel=7 alt=3 func=SD1_DAT3 pull=UP
GPIO 40: level=0 fsel=4 alt=0 func=PWM1_0 pull=NONE
GPIO 41: level=1 fsel=4 alt=0 func=PWM1_1 pull=NONE
GPIO 42: level=0 fsel=1 func=OUTPUT pull=UP
GPIO 43: level=1 fsel=0 func=INPUT pull=UP
GPIO 44: level=1 fsel=0 func=INPUT pull=UP
GPIO 45: level=1 fsel=0 func=INPUT pull=UP
BANK2 (GPIO 46 to 53):
GPIO 46: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 47: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 48: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 49: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 50: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 51: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 52: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 53: level=0 fsel=0 func=INPUT pull=DOWN
vcdbg log messages
------------------
005499.434: arasan: arasan_emmc_open
005659.832: brfs: File read: /mfs/sd/config.txt
005663.069: brfs: File read: 1839 bytes
005746.466: brfs: File read: /mfs/sd/config.txt
005765.258: brfs: File read: 1839 bytes
006213.605: gpioman: gpioman_get_pin_num: pin DISPLAY_DSI_PORT not defined
006220.859: *** Restart logging
006248.280: hdmi: HDMI:hdmi_get_state is deprecated, use hdmi_get_display_state instead
006254.222: HDMI0: hdmi_pixel_encoding: 300000000
006259.696: HDMI1: hdmi_pixel_encoding: 300000000
006270.119: dtb_file 'bcm2711-rpi-4-b.dtb'
006279.315: brfs: File read: /mfs/sd/bcm2711-rpi-4-b.dtb
006282.557: Loading 'bcm2711-rpi-4-b.dtb' to 0x100 size 0xb97c
006301.340: brfs: File read: 47484 bytes
006318.802: brfs: File read: /mfs/sd/overlays/overlay_map.dtb
006381.480: brfs: File read: 1523 bytes
006387.666: brfs: File read: /mfs/sd/config.txt
006390.588: dtparam: audio=on
006403.262: brfs: File read: 1839 bytes
006422.143: brfs: File read: /mfs/sd/overlays/vc4-fkms-v3d.dtbo
006442.961: Loaded overlay 'vc4-fkms-v3d'
006554.352: brfs: File read: 1446 bytes
006560.049: brfs: File read: /mfs/sd/cmdline.txt
006562.703: Read command line from file 'cmdline.txt':
006568.543: 'console=serial0,115200 console=tty1 earlycon=uart8250,mmio32,0xfe215040 root=PARTUUID=5140806e-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait quiet splash plymouth.ignore-serial-consoles'
007708.646: brfs: File read: 202 bytes
008287.646: brfs: File read: /mfs/sd/kernel7l.img
008290.295: Loading 'kernel7l.img' to 0x8000 size 0x5dc998
008296.550: Device tree loaded to 0x2eff4000 (size 0xbfbc)
008304.623: uart: Set PL011 baud rate to 103448.300000 Hz
008311.826: uart: Set PL011 baud rate to 103448.300000 Hz
008311.868: uart: Baud rate change done...
008313.887: uart: Baud rate change done...
008320.626: gpioman: gpioman_get_pin_num: pin SDCARD_CONTROL_POWER not defined
011401.589: vchiq_core: vchiq_init_state: slot_zero = 0xded80000, is_master = 1
011403.685: hdmi: HDMI:hdmi_get_state is deprecated, use hdmi_get_display_state instead
011408.324: TV service:host side not connected, dropping notification 0x00000002, 0x00000002, 0x00000052
022818.797: TV service:host side not connected, dropping notification 0x00000004, 0x00000004, 0x00000000
dmesg log
---------
[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Linux version 5.4.83-v7l+ (dom@buildbot) (gcc version 8.4.0 (Ubuntu/Linaro 8.4.0-3ubuntu1)) #1379 SMP Mon Dec 14 13:11:54 GMT 2020
[ 0.000000] CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d
[ 0.000000] CPU: div instructions available: patching division code
[ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
[ 0.000000] OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.1
[ 0.000000] earlycon: uart8250 at MMIO32 0x00000000fe215040 (options '')
[ 0.000000] printk: bootconsole [uart8250] enabled
[ 0.000000] Memory policy: Data cache writealloc
[ 0.000000] Reserved memory: created CMA memory pool at 0x000000001ec00000, size 256 MiB
[ 0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[ 0.000000] On node 0 totalpages: 1012736
[ 0.000000] DMA zone: 2304 pages used for memmap
[ 0.000000] DMA zone: 0 pages reserved
[ 0.000000] DMA zone: 196608 pages, LIFO batch:63
[ 0.000000] HighMem zone: 816128 pages, LIFO batch:63
[ 0.000000] percpu: Embedded 20 pages/cpu s49804 r8192 d23924 u81920
[ 0.000000] pcpu-alloc: s49804 r8192 d23924 u81920 alloc=20*4096
[ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1010432
[ 0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 video=HDMI-A-1:1920x1080M@60 smsc95xx.macaddr=m.m.m.m vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 console=ttyS0,115200 console=tty1 earlycon=uart8250,mmio32,0xfe215040 root=PARTUUID=5140806e-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait quiet splash plymouth.ignore-serial-consoles
[ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)
[ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)
[ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[ 0.000000] software IO TLB: mapped [mem 0x17cc0000-0x1bcc0000] (64MB)
[ 0.000000] Memory: 3655604K/4050944K available (10240K kernel code, 739K rwdata, 2816K rodata, 2048K init, 854K bss, 133196K reserved, 262144K cma-reserved, 3264512K highmem)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[ 0.000000] ftrace: allocating 31115 entries in 61 pages
[ 0.000000] rcu: Hierarchical RCU implementation.
[ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[ 0.000000] GIC: Using split EOI/Deactivate mode
[ 0.000000] random: get_random_bytes called from start_kernel+0x344/0x518 with crng_init=0
[ 0.000007] sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns
[ 0.000023] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns
[ 0.000098] bcm2835: system timer (irq = 17)
[ 0.000743] arch_timer: cp15 timer(s) running at 54.00MHz (phys).
[ 0.000759] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns
[ 0.000775] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
[ 0.000788] Switching to timer-based delay loop, resolution 18ns
[ 0.001022] Console: colour dummy device 80x30
[ 0.001047] printk: console [tty1] enabled
[ 0.001061] printk: bootconsole [uart8250] disabled
[ 0.001122] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000)
[ 0.001143] pid_max: default: 32768 minimum: 301
[ 0.001289] LSM: Security Framework initializing
[ 0.001480] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[ 0.001502] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[ 0.002832] Disabling memory control group subsystem
[ 0.002953] CPU: Testing write buffer coherency: ok
[ 0.003454] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[ 0.004354] Setting up static identity map for 0x200000 - 0x20003c
[ 0.004557] rcu: Hierarchical SRCU implementation.
[ 0.005225] smp: Bringing up secondary CPUs ...
[ 0.006385] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[ 0.007677] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[ 0.008915] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[ 0.009074] smp: Brought up 1 node, 4 CPUs
[ 0.009090] SMP: Total of 4 processors activated (432.00 BogoMIPS).
[ 0.009103] CPU: All CPU(s) started in HYP mode.
[ 0.009116] CPU: Virtualization extensions available.
[ 0.009920] devtmpfs: initialized
[ 0.023836] VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0
[ 0.024092] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.024120] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[ 0.030928] pinctrl core: initialized pinctrl subsystem
[ 0.031918] NET: Registered protocol family 16
[ 0.035626] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[ 0.036222] audit: initializing netlink subsys (disabled)
[ 0.036470] audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1
[ 0.037615] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[ 0.037629] hw-breakpoint: maximum watchpoint size is 8 bytes.
[ 0.037950] Serial: AMBA PL011 UART driver
[ 0.041690] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[ 0.060805] raspberrypi-firmware soc:firmware: Attached to firmware from 2021-01-08 14:31, variant start
[ 0.070821] raspberrypi-firmware soc:firmware: Firmware hash is 194a85abd768c7334bbadc3f1911c10a7d18ed14
[ 0.125682] bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1
[ 0.129556] vgaarb: loaded
[ 0.130011] SCSI subsystem initialized
[ 0.130229] usbcore: registered new interface driver usbfs
[ 0.130286] usbcore: registered new interface driver hub
[ 0.130408] usbcore: registered new device driver usb
[ 0.130737] usb_phy_generic phy: phy supply vcc not found, using dummy regulator
[ 0.132623] clocksource: Switched to clocksource arch_sys_counter
[ 0.890785] VFS: Disk quotas dquot_6.6.0
[ 0.890883] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 0.891047] FS-Cache: Loaded
[ 0.891248] CacheFiles: Loaded
[ 0.892163] simple-framebuffer 3e3cf000.framebuffer: framebuffer at 0x3e3cf000, 0x7f8000 bytes, mapped to 0x(ptrval)
[ 0.892182] simple-framebuffer 3e3cf000.framebuffer: format=a8r8g8b8, mode=1920x1080x32, linelength=7680
[ 0.892728] Console: switching to colour frame buffer device 240x67
[ 0.902853] simple-framebuffer 3e3cf000.framebuffer: fb0: simplefb registered!
[ 0.913125] thermal_sys: Registered thermal governor 'step_wise'
[ 0.913484] NET: Registered protocol family 2
[ 0.914218] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
[ 0.914249] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
[ 0.914311] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
[ 0.914380] TCP: Hash tables configured (established 8192 bind 8192)
[ 0.914516] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[ 0.914548] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[ 0.914812] NET: Registered protocol family 1
[ 0.915544] RPC: Registered named UNIX socket transport module.
[ 0.915558] RPC: Registered udp transport module.
[ 0.915570] RPC: Registered tcp transport module.
[ 0.915583] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.915604] PCI: CLS 0 bytes, default 64
[ 0.917576] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
[ 0.919773] Initialise system trusted keyrings
[ 0.920008] workingset: timestamp_bits=14 max_order=20 bucket_order=6
[ 0.931097] FS-Cache: Netfs 'nfs' registered for caching
[ 0.931808] NFS: Registering the id_resolver key type
[ 0.931876] Key type id_resolver registered
[ 0.931889] Key type id_legacy registered
[ 0.931912] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[ 0.933045] Key type asymmetric registered
[ 0.933059] Asymmetric key parser 'x509' registered
[ 0.933248] bounce: pool size: 64 pages
[ 0.933299] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[ 0.933533] io scheduler mq-deadline registered
[ 0.933547] io scheduler kyber registered
[ 0.937454] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[ 0.937478] brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
[ 0.937549] brcm-pcie fd500000.pcie: MEM 0x0600000000..0x0603ffffff -> 0x00f8000000
[ 0.937620] brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000
[ 0.974785] brcm-pcie fd500000.pcie: link up, 5 GT/s x1 (SSC)
[ 0.975106] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[ 0.975125] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 0.975145] pci_bus 0000:00: root bus resource [mem 0x600000000-0x603ffffff] (bus address [0xf8000000-0xfbffffff])
[ 0.975203] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[ 0.975470] pci 0000:00:00.0: PME# supported from D0 D3hot
[ 0.978750] PCI: bus0: Fast back to back transfers disabled
[ 0.978990] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
[ 0.979137] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
[ 0.979569] pci 0000:01:00.0: PME# supported from D0 D3cold
[ 0.982867] PCI: bus1: Fast back to back transfers disabled
[ 0.982926] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
[ 0.982950] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
[ 0.983031] pci 0000:00:00.0: PCI bridge to [bus 01]
[ 0.983056] pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff]
[ 0.983394] pcieport 0000:00:00.0: enabling device (0140 -> 0142)
[ 0.983623] pcieport 0000:00:00.0: PME: Signaling with IRQ 56
[ 0.984017] pcieport 0000:00:00.0: AER: enabled with IRQ 56
[ 0.984359] pci 0000:01:00.0: enabling device (0140 -> 0142)
[ 0.988949] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
[ 0.992033] iproc-rng200 fe104000.rng: hwrng registered
[ 0.992383] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[ 0.993128] vc-sm: Videocore shared memory driver
[ 0.993705] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000
[ 1.005761] brd: module loaded
[ 1.018133] loop: module loaded
[ 1.019550] Loading iSCSI transport class v2.0-870.
[ 1.021547] libphy: Fixed MDIO Bus: probed
[ 1.022164] bcmgenet fd580000.ethernet: failed to get enet clock
[ 1.022183] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
[ 1.022206] bcmgenet fd580000.ethernet: failed to get enet-wol clock
[ 1.022228] bcmgenet fd580000.ethernet: failed to get enet-eee clock
[ 1.022256] bcmgenet: Skipping UMAC reset
[ 1.042662] libphy: bcmgenet MII bus: probed
[ 1.122747] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
[ 1.123778] usbcore: registered new interface driver r8152
[ 1.123846] usbcore: registered new interface driver lan78xx
[ 1.123903] usbcore: registered new interface driver smsc95xx
[ 1.124337] xhci_hcd 0000:01:00.0: xHCI Host Controller
[ 1.124382] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[ 1.127689] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0000003000000890
[ 1.128997] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04
[ 1.129016] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 1.129032] usb usb1: Product: xHCI Host Controller
[ 1.129048] usb usb1: Manufacturer: Linux 5.4.83-v7l+ xhci-hcd
[ 1.129063] usb usb1: SerialNumber: 0000:01:00.0
[ 1.129661] hub 1-0:1.0: USB hub found
[ 1.129757] hub 1-0:1.0: 1 port detected
[ 1.130314] xhci_hcd 0000:01:00.0: xHCI Host Controller
[ 1.130340] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[ 1.130364] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[ 1.130939] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.04
[ 1.130956] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 1.130972] usb usb2: Product: xHCI Host Controller
[ 1.130987] usb usb2: Manufacturer: Linux 5.4.83-v7l+ xhci-hcd
[ 1.131001] usb usb2: SerialNumber: 0000:01:00.0
[ 1.131602] hub 2-0:1.0: USB hub found
[ 1.131697] hub 2-0:1.0: 4 ports detected
[ 1.133254] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[ 1.133562] dwc_otg: FIQ enabled
[ 1.133575] dwc_otg: NAK holdoff enabled
[ 1.133587] dwc_otg: FIQ split-transaction FSM enabled
[ 1.133603] Module dwc_common_port init
[ 1.134036] usbcore: registered new interface driver uas
[ 1.134140] usbcore: registered new interface driver usb-storage
[ 1.134333] mousedev: PS/2 mouse device common for all mice
[ 1.136239] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[ 1.139626] sdhci: Secure Digital Host Controller Interface driver
[ 1.139638] sdhci: Copyright(c) Pierre Ossman
[ 1.140260] mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe
[ 1.140911] sdhci-pltfm: SDHCI platform and OF driver helper
[ 1.144756] ledtrig-cpu: registered to indicate activity on CPUs
[ 1.145023] hidraw: raw HID events driver (C) Jiri Kosina
[ 1.145182] usbcore: registered new interface driver usbhid
[ 1.145194] usbhid: USB HID core driver
[ 1.146263] vchiq: vchiq_init_state: slot_zero = (ptrval)
[ 1.147826] [vc_sm_connected_init]: start
[ 1.154286] [vc_sm_connected_init]: end - returning 0
[ 1.156471] Initializing XFRM netlink socket
[ 1.156510] NET: Registered protocol family 17
[ 1.156618] Key type dns_resolver registered
[ 1.157056] Registering SWP/SWPB emulation handler
[ 1.157394] registered taskstats version 1
[ 1.157412] Loading compiled-in X.509 certificates
[ 1.157923] Key type ._fscrypt registered
[ 1.157937] Key type .fscrypt registered
[ 1.168704] uart-pl011 fe201000.serial: cts_event_workaround enabled
[ 1.168773] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 29, base_baud = 0) is a PL011 rev2
[ 1.175393] printk: console [ttyS0] disabled
[ 1.175470] fe215040.serial: ttyS0 at MMIO 0x0 (irq = 30, base_baud = 62500000) is a 16550
[ 1.175537] printk: console [ttyS0] enabled
[ 1.176264] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[ 1.177261] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
[ 1.177277] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
[ 1.227887] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[ 1.229545] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 1.231203] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 1.234388] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[ 1.236061] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 1.245285] mmc0: SDHCI controller on fe340000.emmc2 [fe340000.emmc2] using ADMA
[ 1.248441] of_cfs_init
[ 1.248555] of_cfs_init: OK
[ 1.249382] Waiting for root device PARTUUID=5140806e-02...
[ 1.286092] random: fast init done
[ 1.318332] mmc1: new high speed SDIO card at address 0001
[ 1.359801] mmc0: new ultra high speed DDR50 SDHC card at address 0007
[ 1.360663] mmcblk0: mmc0:0007 SDCIT 7.29 GiB
[ 1.362732] mmcblk0: p1 p2
[ 1.402557] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[ 1.402673] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[ 1.403732] devtmpfs: mounted
[ 1.412288] Freeing unused kernel memory: 2048K
[ 1.442937] Run /sbin/init as init process
[ 1.492680] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[ 1.675421] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
[ 1.675458] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[ 1.675474] usb 1-1: Product: USB2.0 Hub
[ 1.677421] hub 1-1:1.0: USB hub found
[ 1.677729] hub 1-1:1.0: 4 ports detected
[ 1.902090] systemd[1]: System time before build time, advancing clock.
[ 2.002694] usb 1-1.3: new low-speed USB device number 3 using xhci_hcd
[ 2.012714] NET: Registered protocol family 10
[ 2.014035] Segment Routing with IPv6
[ 2.037250] systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid)
[ 2.038046] systemd[1]: Detected architecture arm.
[ 2.084545] systemd[1]: Set hostname to <raspberrypi>.
[ 2.156865] usb 1-1.3: New USB device found, idVendor=03f0, idProduct=2b4a, bcdDevice= 2.64
[ 2.156885] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ 2.156902] usb 1-1.3: Product: HP USB Slim Keyboard - Skylab EU
[ 2.156918] usb 1-1.3: Manufacturer: Lite-On Technology Corp
[ 2.170048] input: Lite-On Technology Corp HP USB Slim Keyboard - Skylab EU as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3:1.0/0003:03F0:2B4A.0001/input/input0
[ 2.233633] hid-generic 0003:03F0:2B4A.0001: input,hidraw0: USB HID v1.10 Keyboard [Lite-On Technology Corp HP USB Slim Keyboard - Skylab EU] on usb-0000:01:00.0-1.3/input0
[ 2.242101] input: Lite-On Technology Corp HP USB Slim Keyboard - Skylab EU Consumer Control as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3:1.1/0003:03F0:2B4A.0002/input/input1
[ 2.303098] input: Lite-On Technology Corp HP USB Slim Keyboard - Skylab EU System Control as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3:1.1/0003:03F0:2B4A.0002/input/input2
[ 2.303418] input: Lite-On Technology Corp HP USB Slim Keyboard - Skylab EU as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3:1.1/0003:03F0:2B4A.0002/input/input3
[ 2.303942] hid-generic 0003:03F0:2B4A.0002: input,hiddev96,hidraw1: USB HID v1.10 Device [Lite-On Technology Corp HP USB Slim Keyboard - Skylab EU] on usb-0000:01:00.0-1.3/input1
[ 2.748055] random: systemd: uninitialized urandom read (16 bytes read)
[ 2.759548] random: systemd: uninitialized urandom read (16 bytes read)
[ 2.760293] systemd[1]: Listening on udev Kernel Socket.
[ 2.760656] random: systemd: uninitialized urandom read (16 bytes read)
[ 2.762764] systemd[1]: Created slice User and Session Slice.
[ 2.762920] systemd[1]: Reached target Slices.
[ 2.763322] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[ 2.764030] systemd[1]: Listening on Syslog Socket.
[ 2.764672] systemd[1]: Listening on fsck to fsckd communication Socket.
[ 2.765113] systemd[1]: Listening on initctl Compatibility Named Pipe.
[ 2.922947] i2c /dev entries driver
[ 10.091153] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[ 10.178228] systemd-journald[119]: Received request to flush runtime journal from PID 1
[ 10.752181] rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000
[ 10.752772] rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000
[ 10.755873] rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000
[ 10.758600] rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000
[ 10.871683] mc: Linux media interface: v0.10
[ 10.874267] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[ 10.886013] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[ 10.886037] [vc_sm_connected_init]: start
[ 10.895179] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[ 10.932758] [vc_sm_connected_init]: installed successfully
[ 10.944525] bcm2835_audio bcm2835_audio: card created with 4 channels
[ 10.946742] bcm2835_audio bcm2835_audio: card created with 4 channels
[ 10.990544] videodev: Linux video capture interface: v2.00
[ 11.046695] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[ 11.048795] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[ 11.055841] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[ 11.068352] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[ 11.088731] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[ 11.141338] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[ 11.142204] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[ 11.149918] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[ 11.150026] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[ 11.150078] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[ 11.150654] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[ 11.150688] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[ 11.150709] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[ 11.150729] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[ 11.150749] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[ 11.150968] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[ 11.163336] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[ 11.163377] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[ 11.175825] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[ 11.175865] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[ 11.263484] [drm] Initialized v3d 1.0.0 20180419 for fec00000.v3d on minor 0
[ 11.666205] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[ 11.666964] vc4-drm gpu: bound fe600000.firmwarekms (ops vc4_fkms_ops [vc4])
[ 11.666985] checking generic (3e3cf000 7f8000) vs hw (0 ffffffffffffffff)
[ 11.666999] fb0: switching to vc4drmfb from simple
[ 11.669671] Console: switching to colour dummy device 80x30
[ 11.670116] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[ 11.670129] [drm] No driver support for vblank timestamp query.
[ 11.670142] [drm] Setting vblank_disable_immediate to false because get_vblank_timestamp == NULL
[ 11.671534] [drm] Initialized vc4 0.0.0 20140616 for gpu on minor 1
[ 11.684113] Console: switching to colour frame buffer device 240x67
[ 11.684170] vc4-drm gpu: fb0: vc4drmfb frame buffer device
[ 11.784737] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[ 11.861536] brcmfmac: F1 signature read @0x18000000=0x15264345
[ 11.876444] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[ 11.878808] usbcore: registered new interface driver brcmfmac
[ 11.897633] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,4-model-b.txt failed with error -2
[ 12.129989] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[ 12.141383] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Mar 23 2020 02:19:54 version 7.45.206 (r725000 CY) FWID 01-88ee44ea
[ 13.496072] random: crng init done
[ 13.496090] random: 7 urandom warning(s) missed due to ratelimiting
[ 13.523419] uart-pl011 fe201000.serial: no DMA platform data
[ 13.851514] 8021q: 802.1Q VLAN Support v1.8
[ 14.193056] Adding 102396k swap on /var/swap. Priority:-2 extents:1 across:102396k SSFS
[ 15.696725] bcmgenet: Skipping UMAC reset
[ 15.698435] bcmgenet fd580000.ethernet: configuring instance for external RGMII
[ 15.698652] bcmgenet fd580000.ethernet eth0: Link is Down
[ 18.255646] broken atomic modeset userspace detected, disabling atomic
[ 18.792732] bcmgenet fd580000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx
[ 18.792761] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 20.228199] Bluetooth: Core ver 2.22
[ 20.228244] NET: Registered protocol family 31
[ 20.228250] Bluetooth: HCI device and connection manager initialized
[ 20.228273] Bluetooth: HCI socket layer initialized
[ 20.228282] Bluetooth: L2CAP socket layer initialized
[ 20.228295] Bluetooth: SCO socket layer initialized
[ 20.242546] Bluetooth: HCI UART driver ver 2.3
[ 20.242555] Bluetooth: HCI UART protocol H4 registered
[ 20.242592] Bluetooth: HCI UART protocol Three-wire (H5) registered
[ 20.243381] Bluetooth: HCI UART protocol Broadcom registered
[ 20.462817] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[ 20.462831] Bluetooth: BNEP filters: protocol multicast
[ 20.462850] Bluetooth: BNEP socket layer initialized
[ 26.491913] fuse: init (API version 7.31)
[ 27.587811] Bluetooth: RFCOMM TTY layer initialized
[ 27.587828] Bluetooth: RFCOMM socket layer initialized
[ 27.587853] Bluetooth: RFCOMM ver 1.11
Logs Serial console output log with UART_2NDSTAGE=1 (This was an occurence with the Pi4 hanging at the rainbow screen, do not have logs of one of the other occurences with black screen or the screen with the red lines):
PM_RSTS: 0x00001020
RPi: BOOTLOADER release VERSION:c305221a DATE: Sep 3 2020 TIME: 13:11:46 BOOTMODE: 0x00000006 part: 0 BUILD_TIMESTAMP=1599135103 0xff334b1f 0x00c03111
uSD voltage 3.3V
Initialising SDRAM 'Micron' 16Gb x2 total-size: 32 Gbit 3200
XHCI-STOP
xHC ver: 256 HCS: 05000420 fc000031 00e70004 HCC: 002841eb
xHC ports 5 slots 32 intrs 4
Reset USB port-power 1000 ms
Boot mode: SD (01) order f4
SD HOST: 250000000 CTL0: 0x00000000 BUS: 100000 Hz actual: 100000 HZ div: 2500 (1250) status: 0x1fff0000 delay: 1080
SD HOST: 250000000 CTL0: 0x00000f00 BUS: 100000 Hz actual: 100000 HZ div: 2500 (1250) status: 0x1fff0000 delay: 1080
CID: 009f544953444349543000315f620146
CSD: 400e00325b5900003a4f7f800a400000
SD: bus-width: 4 spec: 2 SCR: 0x02358002 0x01000000
SD HOST: 250000000 CTL0: 0x00000f04 BUS: 50000000 Hz actual: 41666666 HZ div: 6 (3) status: 0x1fff0000 delay: 2
MBR: 0x00002000, 524288 type: 0x0c
MBR: 0x00082000,14753792 type: 0x83
MBR: 0x00000000, 0 type: 0x00
MBR: 0x00000000, 0 type: 0x00
lba: 8192 oem: 'mkfs.fat' volume: ' boot '
rsc 32 fat-sectors 4033 c-count 516190 c-size 1 r-dir 2 r-sec 0
PM_RSTS: 0x00001020
Partition: 0
lba: 8192 oem: 'mkfs.fat' volume: ' boot '
rsc 32 fat-sectors 4033 c-count 516190 c-size 1 r-dir 2 r-sec 0
Read config.txt bytes 1839 hnd 0x00016984 hash '668b35131f2ef964'
recover4.elf not found (6)
recovery.elf not found (6)
Read start4.elf bytes 2215776 hnd 0x0000d407 hash '906e34e8700956d7'
Read fixup4.dat bytes 5429 hnd 0x000003f8 hash '1d0725a9904ebc93'
0x00c03111 0x00000000 0x0000003f
MEM GPU: 76 ARM: 948 TOTAL: 1024
Starting start4.elf @ 0xfec00200 partition 0
MESS:00:00:05.381434:0: arasan: arasan_emmc_open
MESS:00:00:05.538478:0: brfs: File read: /mfs/sd/config.txt
MESS:00:00:05.541714:0: brfs: File read: 1839 bytes
MESS:00:00:05.625140:0: brfs: File read: /mfs/sd/config.txt
MESS:00:00:05.643963:0: brfs: File read: 1839 bytes
MESS:00:00:06.092298:0: gpioman: gpioman_get_pin_num: pin DISPLAY_DSI_PORT not defined
MESS:00:00:06.099552:0: *** Restart logging
MESS:00:00:06.126987:0: hdmi: HDMI:hdmi_get_state is deprecated, use hdmi_get_display_state instead
MESS:00:00:06.132929:0: HDMI0: hdmi_pixel_encoding: 300000000
MESS:00:00:06.138404:0: HDMI1: hdmi_pixel_encoding: 300000000
MESS:00:00:06.148824:0: dtb_file 'bcm2711-rpi-4-b.dtb'
M:0:0:8.58160:gpomn:gpomn_e_i_nm:pi SCAD_ONRO_PWE nt efne
ES:0:0:1.1336:: pima: poange_innu: inSDAR_OTOLPOE nt efne
MESS:00:00:18.309308:0: gpioman: gpioman_get_pin_num: pin SDCARD_CONTROL_WRITE_PROTECTED not defined
MESS:00:00:18.334594:0: Loading 'bcm2711-rpi-4-b.dtb' to 0x100 size 0x0
MESS:00:00:18.535275:0: Failed to read command line file 'cmdline.txt'
MESS:00:00:19.646034:0: Loading 'kernel7l.img' to 0x8000 size 0x0
MESS:00:00:19.649026:0: Device tree loaded to 0x2eff4100 (size 0xbe4e)
MESS:00:00:19.657010:0: uart: Set PL011 baud rate to 103448.300000 Hz
MESS:00:00:19.664348:0: uart: Baud rate change done...
Additional context Have tested this with two different Pi4 models (one 2GB and one 4GB model) and two different microSD card models: Kingston Industrial 8GB and Sandisk Ultra 32GB). Power supply used was the official Raspberry Pi4 Power Supply. The issue also occurs with no peripherals connected (only power and serialport TX and GND connected).
@ganzgustav22 I have found similar problem fix in recent eeprom changelog. https://github.com/raspberrypi/rpi-eeprom/blob/master/firmware/release-notes.md. You have firmware from Jan 8 2021, you should try to update eeprom and firmware.
sudo apt update
sudo apt full-upgrade
sudo rpi-eeprom-update -a
sudo reboot
Thanks for the info, totally missed that.
I'm not sure if upgrading the firmware and kernel is a good idea for me (as it's not a "stable" firmware and kernel). I did however just update to Pi EEPROM 2021-03-18.
Now the hang occurs even faster, have been able to reproduce it twice now within about two hours.
The issue occured on a different machine today (unfortunately this one didn't have serial logging connected). Firmware etc. on that one are still like in the first post (i.e. Raspberry Pi OS 2021-01-11 and the firmware/eeprom/vl805fw that comes with it)
This time it drew some weird pixels on the screen, mostly blue ones, but also some red ones in a seemingly random pattern:
Okay, I've upgraded the other one now:
sudo apt update sudo apt full-upgrade
Now I will test again with everything updated: `root@raspberrypi:/home/pi# uname -a Linux raspberrypi 5.10.17-v7l+ #1403 SMP Mon Feb 22 11:33:35 GMT 2021 armv7l GNU/Linux
root@raspberrypi:/home/pi# rpi-eeprom-update BOOTLOADER: up-to-date CURRENT: Thu 18 Mar 08:54:11 UTC 2021 (1616057651) LATEST: Thu 18 Mar 08:54:11 UTC 2021 (1616057651) RELEASE: stable (/lib/firmware/raspberrypi/bootloader/stable) Use raspi-config to change the release.
VL805_FW: Dedicated VL805 EEPROM VL805: up-to-date CURRENT: 000138a1 LATEST: 000138a1 `
It got stuck again with the above version numbers (it's basically Raspberry Pi OS 2021-03-04.
Could a Pi developer please comment on this or look into it? Running the latest Raspberry Pi OS version, everything is in default state, but still, reboots are not working properly.
Thanks for your help. Yes, I'll try that as soon as I get to the Pi (probably tomorrow).
I've added over_voltage=2 about two hours ago, now it's stuck again with a black screen. I'm not sure if it's just coincidence, but I think without the serial debugging options enabled it seems to happen faster (i.e. doesn't take as many reboots until it's stuck again).
How is it going? Is it fixed somehow? I have the same problem as well and try to figure it out. it hapens not very often but i expirienced it from time to rime in the last jear. i just updated my system and now it hapend in round about 3 out of 20 reboots.
It got stuck again twice (without serial logging enabled). Now I have serial logging enabled again and am again testing ...
It's rebooting now for 5 days (with serial logging enabled) and it's still not stuck. It seems, that it only acts up when nobody is watching :-)
Just tried it again with Raspberry Pi OS 2021-05-27 (and also ran rpi-eeprom-update -a to make sure it's using the firmware that comes with the release).
Issue still persists, it took about an hour of reboot loop until it got stuck again.
I think we'd need to see the serial logs including boot logs at the point of failure. For an unrelated hardware test I've just run 30K reboots on 10 different Pi 4Bs using the latest OS release / bootloader without error. The reboot mechanism was 'sudo reboot' in .bashrc with Raspberry Pi OS lite.
See above, when I enable serial logging, the issue seems to go away (or atleast takes way longer to reproduce, I stopped after 5 days of continous reboots.
Can you reproduce it when you do it exactly like I described in the first post?
It has been stuck three times now since I posted about an hour ago. It seems to occur more often than with Raspberry Pi OS 2021-01-11 (and the corresponding rpi-eeprom-update).
Serial logging from the bootloader shouldn't make any difference unless there is a problem with the USB UART hardware. For start.elf use the following configuration to avoid any impact on the core clock enable_uart=1 dtoverlay=disable-bt
The picture of the HDMI screen suggests that this might be a hardware failure. I don't think this is a software issue in Raspberry Pi OS.
I did not make hundreds of tests, so I cannot say for sure that it doesn't happen with serial logging enabled, but it looked very much like it. I'll try again though with serial logging enabled to make sure.
Regarding hardware failure: Definitely no. I have more than 10 Pi4s (bought from different vendors over a timeframe of about 6 months about a year ago) and I have tried with several different ones, they all show these weird graphic patterns from time to time. Also tried different sdcards and power supplies (including the official Pi4 power supply).
For start.elf use the following configuration to avoid any impact on the core clock
The effect of the disable-bt overlay is more profound than affecting the core clock speeds - on a Bluetooth-equipped Pi it changes the UART used for the kernel console to UART0/ttyAMA0/PL011. Disagreements between the EEPROM, firmware and kernel about the console UART can lead to the kernel hanging early in the boot process.
Found a better/faster/more-convenient way for reproducing the issue, have updated the first post.
(by adding "reboot -f" to /etc/init.d/raspi-config, this will speed up the whole loop for faster reproduceability and also allows to interrupt the bootloop to get back into the system by holding down the shift key).
I'm facing similar issues (with latest OS/FW), is there a solution for this problem?
I all,
I'm facing the same problem... anyone reached any conclusion on this issue?
Thanks.
My raspberry pi 4, 8GB of Ram, all uptodate, eeprom everything, bullseye lite, booting from USB SSD hangs on EVERY reboot. Reboot ONLY works by unplugging it. If I do "sudo reboot" it blinks, LEDs go dark, and nothing happens after that. Need to unplug to restart. If I add over_voltage=2 and also 4, nothing changes.
Without a serial cable attached this is tricky to debug, but a common cause for reboot failure is that the attached device is back-powering the Pi 4. If you have a USB hub to hand it would be interesting to see if that affects the reboot behaviour.
RAM perhaps? I'm by no means an expert, but I found this when experiencing similar issues with my router ... [https://dev.archive.openwrt.org/ticket/17839]
I discovered this issue the recent images (2022-01-28, 2021-11-08)(lite, 32 bit), with every single sudo reboot. Via console I see [ 37.357020] hwmon hwmon1: Failed to get throttled (-110) as the main error and then repeated output of (~every minute):
[ 66.037634] rcu: rcu_sched kthread starved for 997 jiffies! g1301 f0x0 RCU_GP_WAIT_FQS(5) ->state=0x402 ->cpu=1
[ 66.047880] rcu: Unless rcu_sched kthread gets sufficient CPU time, OOM is now expected behavior.
[ 66.056979] rcu: RCU grace-period kthread stack dump:
[ 119.065535] rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
[ 119.071556] rcu: 0-...0: (3 GPs behind) idle=212/1/0x40000000 softirq=2440/2440 fqs=3701
After I disabled bluetooth (dtoverlay=disable-bt) I got clearer console output, and saw "under-voltage".
My solution
I had supplied my Raspberry Pi 4 with 5.00 V through pins 2/4&6. After increasing the supply voltage to 5.20 V, the reboot trouble is gone. At least the deterministic one. Maybe that helps others too.
I've had this issue too. No need to loop, it just hangs on every reboot. Pi4 8GB.
Running headless with an external HDD, so will try unplugging that and make sure everything's up to date.
UPDATE
~~Just updated bootloader to Tue 25 Jan 2022 - lastest default release. Seems to reboot no problem now, HDD and all :)~~
I've had this issue too. No need to loop, it just hangs on every reboot. Pi4 8GB.
Running headless with an external HDD, so will try unplugging that and make sure everything's up to date.
UPDATE Just updated bootloader to
Tue 25 Jan 2022- lastest default release. Seems to reboot no problem now, HDD and all :)
I did this
rpi-eeprom-update -a
BOOTLOADER: up to date
CURRENT: Tue 25 Jan 2022 14:30:41 UTC (1643121041)
LATEST: Tue 25 Jan 2022 14:30:41 UTC (1643121041)
RELEASE: default (/lib/firmware/raspberrypi/bootloader/default)
Use raspi-config to change the release.
VL805_FW: Using bootloader EEPROM
VL805: up to date
CURRENT: 000138a1
LATEST: 000138a1
still the command sudo reboot hangs with an external ssd
