ttyAMA0/serial0 not working when using firmware from `next`

frezbo commented 2 years ago

Describe the bug Kernel console logs are missing when using firmware of next branch. Kernel doesn't seem to detect ttyAMA0, works fine with firmware from 1.20211029 tag

To reproduce List the steps required to reproduce the issue.

Expected behaviour Add a clear and concise description of what you expected to happen.

Actual behaviour Add a clear and concise description of what actually happened.

System

Raspbeery Pi 4 loading u-boot loading Grub loading kernel

config.txt

# See https://www.raspberrypi.com/documentation/computers/configuration.html
# Reduce GPU memory to give more to CPU.
gpu_mem=32
# Enable maximum compatibility on both HDMI ports;
# only the one closest to the power/USB-C port will work in practice.
hdmi_safe:0=1
hdmi_safe:1=1
armstub=bl31.bin
enable_gic=1
# Load U-Boot.
kernel=u-boot.bin
# Forces the kernel loading system to assume a 64-bit kernel.
arm_64bit=1
# Run as fast as firmware / board allows.
arm_boost=1
# Enable the primary/console UART.
enable_uart=1
uart_2ndstage=1
# Disable Bluetooth.
dtoverlay=disable-bt

Logs With Firmware from 1.20211029 tag. As can be seen below, after grub loads kernel the kernel console logs are visible and /dev/ttyAMA0 is shown as a serial device on kernel

1.20211029 firmware logs

```bash Read start4.elf bytes 2241504 hnd 0x0000f009 Read fixup4.dat bytes 5411 hnd 0x00000539 Firmware: b8a114e5a9877e91ca8f26d1a5ce904b2ad3cf13 Oct 29 2021 10:47:33 0x00d03114 0x00000000 0x00000fff MEM GPU: 32 ARM: 991 TOTAL: 1023 Starting start4.elf @ 0xfeb00200 partition 0 + MESS:00:00:06.152674:0: brfs: File read: /mfs/sd/config.txt MESS:00:00:06.155560:0: brfs: File read: 576 bytes MESS:00:00:06.210001:0: HDMI0:EDID error reading EDID block 0 attempt 0 MESS:00:00:06.219517:0: HDMI0:EDID error reading EDID block 0 attempt 1 MESS:00:00:06.229024:0: HDMI0:EDID error reading EDID block 0 attempt 2 MESS:00:00:06.238538:0: HDMI0:EDID error reading EDID block 0 attempt 3 MESS:00:00:06.248048:0: HDMI0:EDID error reading EDID block 0 attempt 4 MESS:00:00:06.257563:0: HDMI0:EDID error reading EDID block 0 attempt 5 MESS:00:00:06.267071:0: HDMI0:EDID error reading EDID block 0 attempt 6 MESS:00:00:06.276586:0: HDMI0:EDID error reading EDID block 0 attempt 7 MESS:00:00:06.286096:0: HDMI0:EDID error reading EDID block 0 attempt 8 MESS:00:00:06.295611:0: HDMI0:EDID error reading EDID block 0 attempt 9 MESS:00:00:06.300110:0: HDMI0:EDID giving up on reading EDID block 0 MESS:00:00:06.325414:0: HDMI1:EDID error reading EDID block 0 attempt 0 MESS:00:00:06.334929:0: HDMI1:EDID error reading EDID block 0 attempt 1 MESS:00:00:06.344439:0: HDMI1:EDID error reading EDID block 0 attempt 2 MESS:00:00:06.353953:0: HDMI1:EDID error reading EDID block 0 attempt 3 MESS:00:00:06.363461:0: HDMI1:EDID error reading EDID block 0 attempt 4 MESS:00:00:06.372976:0: HDMI1:EDID error reading EDID block 0 attempt 5 MESS:00:00:06.382486:0: HDMI1:EDID error reading EDID block 0 attempt 6 MESS:00:00:06.392000:0: HDMI1:EDID error reading EDID block 0 attempt 7 MESS:00:00:06.401507:0: HDMI1:EDID error reading EDID block 0 attempt 8 MESS:00:00:06.411021:0: HDMI1:EDID error reading EDID block 0 attempt 9 MESS:00:00:06.415522:0: HDMI1:EDID giving up on reading EDID block 0 MESS:00:00:06.421794:0: brfs: File read: /mfs/sd/config.txt MESS:00:00:07.547150:0: gpioman: gpioman_get_pin_num: pin DISPLAY_DSI_PORT not defined MESS:00:00:07.554440:0: *** Restart logging MESS:00:00:07.555865:0: brfs: File read: 576 bytes MESS:00:00:07.565834:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 0 MESS:00:00:07.575874:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 1 MESS:00:00:07.585905:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 2 MESS:00:00:07.595943:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 3 MESS:00:00:07.605976:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 4 MESS:00:00:07.616014:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 5 MESS:00:00:07.626044:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 6 MESS:00:00:07.636082:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 7 MESS:00:00:07.646115:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 8 MESS:00:00:07.656153:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 9 MESS:00:00:07.661174:0: hdmi: HDMI0:EDID giving up on reading EDID block 0 MESS:00:00:07.671799:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 0 MESS:00:00:07.681837:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 1 MESS:00:00:07.691867:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 2 MESS:00:00:07.701905:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 3 MESS:00:00:07.711938:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 4 MESS:00:00:07.721976:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 5 MESS:00:00:07.732006:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 6 MESS:00:00:07.742044:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 7 MESS:00:00:07.752076:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 8 MESS:00:00:07.762113:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 9 MESS:00:00:07.767134:0: hdmi: HDMI0:EDID giving up on reading EDID block 0 MESS:00:00:07.784391:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 0 MESS:00:00:07.794432:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 1 MESS:00:00:07.805012:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 2 MESS:00:00:07.817385:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 3 MESS:00:00:07.827476:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 4 MESS:00:00:07.837572:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 5 MESS:00:00:07.847660:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 6 MESS:00:00:07.857756:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 7 MESS:00:00:07.867845:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 8 MESS:00:00:07.877941:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 9 MESS:00:00:07.883010:0: hdmi: HDMI1:EDID giving up on reading EDID block 0 MESS:00:00:07.893665:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 0 MESS:00:00:07.903761:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 1 MESS:00:00:07.913848:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 2 MESS:00:00:07.923944:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 3 MESS:00:00:07.934033:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 4 MESS:00:00:07.944129:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 5 MESS:00:00:07.954215:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 6 MESS:00:00:07.964313:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 7 MESS:00:00:07.974403:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 8 MESS:00:00:07.984504:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 9 MESS:00:00:07.989573:0: hdmi: HDMI1:EDID giving up on reading EDID block 0 MESS:00:00:08.002771:0: HDMI0: hdmi_pixel_encoding: 300000000 MESS:00:00:08.005420:0: HDMI1: hdmi_pixel_encoding: 300000000 MESS:00:00:08.015970:0: dtb_file 'bcm2711-rpi-4-b.dtb' MESS:00:00:08.020642:0: brfs: File read: /mfs/sd/bcm2711-rpi-4-b.dtb MESS:00:00:08.024078:0: Loading 'bcm2711-rpi-4-b.dtb' to 0x100 size 0xc2a9 MESS:00:00:08.043458:0: brfs: File read: 49833 bytes MESS:00:00:08.053135:0: brfs: File read: /mfs/sd/overlays/overlay_map.dtb MESS:00:00:08.120576:0: brfs: File read: 1667 bytes MESS:00:00:08.124208:0: brfs: File read: /mfs/sd/config.txt MESS:00:00:08.128185:0: brfs: File read: 576 bytes MESS:00:00:08.134447:0: brfs: File read: /mfs/sd/overlays/disable-bt.dtbo MESS:00:00:08.153116:0: Loaded overlay 'disable-bt' MESS:00:00:08.195479:0: brfs: File read: 1073 bytes MESS:00:00:08.198813:0: Failed to open command line file 'cmdline.txt' MESS:00:00:08.319360:0: brfs: File read: /mfs/sd/bl31.bin MESS:00:00:08.321696:0: Loading 'bl31.bin' to 0x0 size 0xb06b MESS:00:00:08.327197:0: brfs: File read: 45163 bytes MESS:00:00:08.365241:0: brfs: File read: /mfs/sd/u-boot.bin MESS:00:00:08.367759:0: Loading 'u-boot.bin' to 0x80000 size 0x8f848 MESS:00:00:08.373850:0: Device tree loaded to 0x2eff3800 (size 0xc7fe) MESS:00:00:08.383449:0: uart: Set PL011 baud rate to 103448.300000 Hz MESS:00:00:08.389780:0: uart: Baud rate change done... MESS:00:00:08.391835:0: uart: Baud rate change done... MESS:00:00:08.397489:0: bfs_xhci_stop MESS:00:00:08.400077:0: XHCI-STOP MESS:00:00:08.403270:0: xHC ver: 256 HCS: 05000420 fc000031 00e70004 HCC: 002841eb MESS:00:00:08.410428:0: USBSTS 18 NOTICE: BL31: v2.6(release): NOTICE: BL31: Built : 16:06:15, Dec 27 2021 U-Boot 2021.10 (Dec 27 2021 - 16:06:23 +0000) DRAM: 7.9 GiB RPI 4 Model B (0xd03114) MMC: mmcnr@7e300000: 1, mmc@7e340000: 0 Loading Environment from FAT... Card did not respond to voltage select! : -110 In: serial Out: vidconsole Err: vidconsole Net: eth0: ethernet@7d580000 PCIe BRCM: link up, 5.0 Gbps x1 (SSC) starting USB... Bus xhci_pci: Register 5000420 NbrPorts 5 Starting the controller USB XHCI 1.00 scanning bus xhci_pci for devices... 3 USB Device(s) found scanning usb for storage devices... 1 Storage Device(s) found Hit any key to stop autoboot: 0 Card did not respond to voltage select! : -110 sdhci_set_clock: Timeout to wait cmd & data inhibit sdhci_set_clock: Timeout to wait cmd & data inhibit sdhci_set_clock: Timeout to wait cmd & data inhibit sdhci_send_command: MMC: 1 busy timeout increasing to: 200 ms. sdhci_send_command: MMC: 1 busy timeout increasing to: 400 ms. sdhci_send_command: MMC: 1 busy timeout increasing to: 800 ms. sdhci_send_command: MMC: 1 busy timeout increasing to: 1600 ms. sdhci_send_command: MMC: 1 busy timeout increasing to: 3200 ms. sdhci_send_command: MMC: 1 busy timeout. Device 0: Vendor: ORICO Rev: 0209 Prod: TCM2-C3 Type: Hard Disk Capacity: 238475.1 MB = 232.8 GB (488397168 x 512) ... is now current device Scanning usb 0:1... libfdt fdt_check_header(): FDT_ERR_BADMAGIC sdhci_set_clock: Timeout to wait cmd & data inhibit sdhci_set_clock: Timeout to wait cmd & data inhibit sdhci_set_clock: Timeout to wait cmd & data inhibit sdhci_send_command: MMC: 1 busy timeout. Scanning disk mmcnr@7e300000.blk... Disk mmcnr@7e300000.blk not ready Card did not respond to voltage select! : -110 Scanning disk mmc@7e340000.blk... Disk mmc@7e340000.blk not ready Scanning disk usb_mass_storage.lun0... Found 7 disks ** Unable to read file ubootefi.var ** Failed to load EFI variables BootOrder not defined EFI boot manager: Cannot load any image Found EFI removable media binary efi/boot/bootaa64.efi 147456 bytes read in 1 ms (140.6 MiB/s) libfdt fdt_check_header(): FDT_ERR_BADMAGIC Booting /efi\boot\bootaa64.efi Welcome to GRUB! ... Booting `A' EFI stub: Booting Linux Kernel... EFI stub: ERROR: FIRMWARE BUG: kernel image not aligned on 64k boundary EFI stub: Using DTB from configuration table EFI stub: ERROR: Failed to handle fs_proto EFI stub: ERROR: Failed to load initrd! EFI stub: Exiting boot services... [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd083] [ 0.000000] Linux version 5.15.11-talos (@buildkitsandbox) (gcc (GCC) 11.2.0, GNU ld (GNU Binutils) 2.37) #1 SMP Fri Dec 24 16:47:09 UTC 2021 [ 0.000000] Machine model: Raspberry Pi 4 Model B Rev 1.4 [ 0.000000] efi: EFI v2.80 by Das U-Boot ... ```

With Firmware from next branch. As can be seen below, after grub loads kernel the kernel console logs are not visible and /dev/ttyAMA0 is not registered

next firmware logs

```bash Read start4.elf bytes 2240224 hnd 0x00027dfd Read fixup4.dat bytes 5354 hnd 0x00018bce Firmware: 58e03c94953762222f2b838390dde54d46c38381 Jan 5 2022 12:29:20 0x00d03114 0x00000000 0x00000fff MEM GPU: 32 ARM: 991 TOTAL: 1023 Starting start4.elf @ 0xfeb00200 partition 0 + MESS:00:00:06.175846:0: brfs: File read: /mfs/sd/config.txt MESS:00:00:06.178740:0: brfs: File read: 576 bytes MESS:00:00:06.233538:0: HDMI0:EDID error reading EDID block 0 attempt 0 MESS:00:00:06.243053:0: HDMI0:EDID error reading EDID block 0 attempt 1 MESS:00:00:06.252560:0: HDMI0:EDID error reading EDID block 0 attempt 2 MESS:00:00:06.262075:0: HDMI0:EDID error reading EDID block 0 attempt 3 MESS:00:00:06.271584:0: HDMI0:EDID error reading EDID block 0 attempt 4 MESS:00:00:06.281098:0: HDMI0:EDID error reading EDID block 0 attempt 5 MESS:00:00:06.290606:0: HDMI0:EDID error reading EDID block 0 attempt 6 MESS:00:00:06.300120:0: HDMI0:EDID error reading EDID block 0 attempt 7 MESS:00:00:06.309630:0: HDMI0:EDID error reading EDID block 0 attempt 8 MESS:00:00:06.319144:0: HDMI0:EDID error reading EDID block 0 attempt 9 MESS:00:00:06.323642:0: HDMI0:EDID giving up on reading EDID block 0 MESS:00:00:06.349166:0: HDMI1:EDID error reading EDID block 0 attempt 0 MESS:00:00:06.358681:0: HDMI1:EDID error reading EDID block 0 attempt 1 MESS:00:00:06.368191:0: HDMI1:EDID error reading EDID block 0 attempt 2 MESS:00:00:06.377705:0: HDMI1:EDID error reading EDID block 0 attempt 3 MESS:00:00:06.387213:0: HDMI1:EDID error reading EDID block 0 attempt 4 MESS:00:00:06.396727:0: HDMI1:EDID error reading EDID block 0 attempt 5 MESS:00:00:06.406237:0: HDMI1:EDID error reading EDID block 0 attempt 6 MESS:00:00:06.415752:0: HDMI1:EDID error reading EDID block 0 attempt 7 MESS:00:00:06.425259:0: HDMI1:EDID error reading EDID block 0 attempt 8 MESS:00:00:06.434773:0: HDMI1:EDID error reading EDID block 0 attempt 9 MESS:00:00:06.439274:0: HDMI1:EDID giving up on reading EDID block 0 MESS:00:00:06.445573:0: brfs: File read: /mfs/sd/config.txt MESS:00:00:07.571026:0: gpioman: gpioman_get_pin_num: pin DISPLAY_DSI_PORT not defined MESS:00:00:07.578319:0: *** Restart logging MESS:00:00:07.579742:0: brfs: File read: 576 bytes MESS:00:00:07.589727:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 0 MESS:00:00:07.599767:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 1 MESS:00:00:07.609798:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 2 MESS:00:00:07.619836:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 3 MESS:00:00:07.629869:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 4 MESS:00:00:07.639907:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 5 MESS:00:00:07.649937:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 6 MESS:00:00:07.659975:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 7 MESS:00:00:07.670008:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 8 MESS:00:00:07.680046:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 9 MESS:00:00:07.685068:0: hdmi: HDMI0:EDID giving up on reading EDID block 0 MESS:00:00:07.695692:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 0 MESS:00:00:07.705730:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 1 MESS:00:00:07.715760:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 2 MESS:00:00:07.725797:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 3 MESS:00:00:07.735831:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 4 MESS:00:00:07.745869:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 5 MESS:00:00:07.755899:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 6 MESS:00:00:07.765936:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 7 MESS:00:00:07.775969:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 8 MESS:00:00:07.786007:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 9 MESS:00:00:07.791028:0: hdmi: HDMI0:EDID giving up on reading EDID block 0 MESS:00:00:07.808350:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 0 MESS:00:00:07.818390:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 1 MESS:00:00:07.835676:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 2 MESS:00:00:07.845791:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 3 MESS:00:00:07.855894:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 4 MESS:00:00:07.865993:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 5 MESS:00:00:07.876080:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 6 MESS:00:00:07.886176:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 7 MESS:00:00:07.896265:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 8 MESS:00:00:07.906360:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 9 MESS:00:00:07.911430:0: hdmi: HDMI1:EDID giving up on reading EDID block 0 MESS:00:00:07.922086:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 0 MESS:00:00:07.932181:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 1 MESS:00:00:07.942267:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 2 MESS:00:00:07.952362:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 3 MESS:00:00:07.962469:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 4 MESS:00:00:07.972564:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 5 MESS:00:00:07.982651:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 6 MESS:00:00:07.992745:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 7 MESS:00:00:08.002834:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 8 MESS:00:00:08.012930:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 9 MESS:00:00:08.017999:0: hdmi: HDMI1:EDID giving up on reading EDID block 0 MESS:00:00:08.031280:0: HDMI0: hdmi_pixel_encoding: 300000000 MESS:00:00:08.033916:0: HDMI1: hdmi_pixel_encoding: 300000000 MESS:00:00:08.044491:0: dtb_file 'bcm2711-rpi-4-b.dtb' MESS:00:00:08.049401:0: brfs: File read: /mfs/sd/bcm2711-rpi-4-b.dtb MESS:00:00:08.052651:0: Loading 'bcm2711-rpi-4-b.dtb' to 0x100 size 0xc957 MESS:00:00:08.072339:0: brfs: File read: 51543 bytes MESS:00:00:08.081439:0: brfs: File read: /mfs/sd/overlays/overlay_map.dtb MESS:00:00:08.156748:0: brfs: File read: 1703 bytes MESS:00:00:08.160262:0: brfs: File read: /mfs/sd/config.txt MESS:00:00:08.164379:0: brfs: File read: 576 bytes MESS:00:00:08.170526:0: brfs: File read: /mfs/sd/overlays/disable-bt.dtbo MESS:00:00:08.189587:0: Loaded overlay 'disable-bt' MESS:00:00:08.233188:0: brfs: File read: 1073 bytes MESS:00:00:08.236406:0: Failed to open command line file 'cmdline.txt' MESS:00:00:08.354102:0: brfs: File read: /mfs/sd/bl31.bin MESS:00:00:08.356394:0: Loading 'bl31.bin' to 0x0 size 0xb06b MESS:00:00:08.361877:0: brfs: File read: 45163 bytes MESS:00:00:08.391726:0: brfs: File read: /mfs/sd/u-boot.bin MESS:00:00:08.394198:0: Loading 'u-boot.bin' to 0x80000 size 0x8f848 MESS:00:00:08.400283:0: Device tree loaded to 0x2eff3000 (size 0xcf5e) MESS:00:00:08.410121:0: uart: Set PL011 baud rate to 103448.300000 Hz MESS:00:00:08.416452:0: uart: Baud rate change done... MESS:00:00:08.418506:0: uart: Baud rate change done... MESS:00:00:08.424171:0: bfs_xhci_stop MESS:00:00:08.426748:0: XHCI-STOP MESS:00:00:08.429941:0: xHC ver: 256 HCS: 05000420 fc000031 00e70004 HCC: 002841eb MESS:00:00:08.437099:0: USBSTS 18 NOTICE: BL31: v2.6(release): NOTICE: BL31: Built : 16:06:15, Dec 27 2021 U-Boot 2021.10 (Dec 27 2021 - 16:06:23 +0000) DRAM: 7.9 GiB RPI 4 Model B (0xd03114) MMC: mmcnr@7e300000: 1, mmc@7e340000: 0 Loading Environment from FAT... Card did not respond to voltage select! : -110 In: serial Out: vidconsole Err: vidconsole Net: eth0: ethernet@7d580000 PCIe BRCM: link up, 5.0 Gbps x1 (SSC) starting USB... Bus xhci_pci: Register 5000420 NbrPorts 5 Starting the controller USB XHCI 1.00 scanning bus xhci_pci for devices... 3 USB Device(s) found scanning usb for storage devices... 1 Storage Device(s) found Hit any key to stop autoboot: 0 Card did not respond to voltage select! : -110 sdhci_set_clock: Timeout to wait cmd & data inhibit sdhci_set_clock: Timeout to wait cmd & data inhibit sdhci_set_clock: Timeout to wait cmd & data inhibit sdhci_send_command: MMC: 1 busy timeout increasing to: 200 ms. sdhci_send_command: MMC: 1 busy timeout increasing to: 400 ms. sdhci_send_command: MMC: 1 busy timeout increasing to: 800 ms. sdhci_send_command: MMC: 1 busy timeout increasing to: 1600 ms. sdhci_send_command: MMC: 1 busy timeout increasing to: 3200 ms. sdhci_send_command: MMC: 1 busy timeout. Device 0: Vendor: ORICO Rev: 0209 Prod: TCM2-C3 Type: Hard Disk Capacity: 238475.1 MB = 232.8 GB (488397168 x 512) ... is now current device Scanning usb 0:1... libfdt fdt_check_header(): FDT_ERR_BADMAGIC sdhci_set_clock: Timeout to wait cmd & data inhibit sdhci_set_clock: Timeout to wait cmd & data inhibit sdhci_set_clock: Timeout to wait cmd & data inhibit sdhci_send_command: MMC: 1 busy timeout. Scanning disk mmcnr@7e300000.blk... Disk mmcnr@7e300000.blk not ready Card did not respond to voltage select! : -110 Scanning disk mmc@7e340000.blk... Disk mmc@7e340000.blk not ready Scanning disk usb_mass_storage.lun0... Found 7 disks ** Unable to read file ubootefi.var ** Failed to load EFI variables BootOrder not defined EFI boot manager: Cannot load any image Found EFI removable media binary efi/boot/bootaa64.efi 147456 bytes read in 1 ms (140.6 MiB/s) libfdt fdt_check_header(): FDT_ERR_BADMAGIC Booting /efi\boot\bootaa64.efi Welcome to GRUB! ... Booting `A' EFI stub: Booting Linux Kernel... EFI stub: ERROR: FIRMWARE BUG: kernel image not aligned on 64k boundary EFI stub: Using DTB from configuration table EFI stub: ERROR: Failed to handle fs_proto EFI stub: ERROR: Failed to load initrd! EFI stub: Exiting boot services... ```

As seen above once grub loads the kernel there are no further console logs as ttyAMA0 is not registered

pelwell commented 2 years ago

Does it work if the firmware loads the kernel directly?

pelwell commented 2 years ago

Starting with a copy of your config.txt and commenting out armstub=bl31.bin and kernel=u-boot.bin leads to a working ttyAMA0 for me:

...
[    5.129602] systemd[1]: Mounted Kernel Configuration File System.

Raspbian GNU/Linux 11 raspberrypi ttyAMA0

raspberrypi login: pi
Password:
Linux raspberrypi 5.15.13-v8+ #1510 SMP PREEMPT Wed Jan 5 12:28:30 GMT 2022 aarch64

The programs included with the Debian GNU/Linux system are free software;
the exact distribution terms for each program are described in the
individual files in /usr/share/doc/*/copyright.

Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent
permitted by applicable law.
Last login: Mon Jan 17 10:14:15 GMT 2022 on tty1
pi@raspberrypi:~$

EFI stub: Booting Linux Kernel...
EFI stub: ERROR: FIRMWARE BUG: kernel image not aligned on 64k boundary

Does the EFI stub understand that kernel8.img is gzip-compressed, reducing it from >20MB to ~8MB? What is the size of the 5.10 kernel8.img that is working for you?

frezbo commented 2 years ago

I tried gzipping the kernel and if I use it, no console logs. The un-compressed kernel size is 54M and compressed is 20M

❯ du -sh vmlinuz 
54M vmlinuz
❯ du -sh Image.gz 
20M Image.gz

In my case grub boots and un-compressed kernel image

frezbo commented 2 years ago

logs when config.txt is updated with kernel=kernel8.img and cmdline.txt added

Read start4.elf bytes  2240224 hnd 0x00027dfd 
Read fixup4.dat bytes     5354 hnd 0x00018bce 
Firmware: 58e03c94953762222f2b838390dde54d46c38381 Jan  5 2022 12:29:20
0x00d03114 0x00000000 0x00000fff
MEM GPU: 32 ARM: 991 TOTAL: 1023
Starting start4.elf @ 0xfeb00200 partition 0
+

MESS:00:00:06.150819:0: brfs: File read: /mfs/sd/config.txt
MESS:00:00:06.153727:0: brfs: File read: 596 bytes
MESS:00:00:06.208491:0: HDMI0:EDID error reading EDID block 0 attempt 0
MESS:00:00:06.218008:0: HDMI0:EDID error reading EDID block 0 attempt 1
MESS:00:00:06.227516:0: HDMI0:EDID error reading EDID block 0 attempt 2
MESS:00:00:06.237030:0: HDMI0:EDID error reading EDID block 0 attempt 3
MESS:00:00:06.246540:0: HDMI0:EDID error reading EDID block 0 attempt 4
MESS:00:00:06.256054:0: HDMI0:EDID error reading EDID block 0 attempt 5
MESS:00:00:06.265561:0: HDMI0:EDID error reading EDID block 0 attempt 6
MESS:00:00:06.275076:0: HDMI0:EDID error reading EDID block 0 attempt 7
MESS:00:00:06.284586:0: HDMI0:EDID error reading EDID block 0 attempt 8
MESS:00:00:06.294100:0: HDMI0:EDID error reading EDID block 0 attempt 9
MESS:00:00:06.298598:0: HDMI0:EDID giving up on reading EDID block 0
MESS:00:00:06.324082:0: HDMI1:EDID error reading EDID block 0 attempt 0
MESS:00:00:06.333597:0: HDMI1:EDID error reading EDID block 0 attempt 1
MESS:00:00:06.343107:0: HDMI1:EDID error reading EDID block 0 attempt 2
MESS:00:00:06.352621:0: HDMI1:EDID error reading EDID block 0 attempt 3
MESS:00:00:06.362129:0: HDMI1:EDID error reading EDID block 0 attempt 4
MESS:00:00:06.371643:0: HDMI1:EDID error reading EDID block 0 attempt 5
MESS:00:00:06.381153:0: HDMI1:EDID error reading EDID block 0 attempt 6
MESS:00:00:06.390667:0: HDMI1:EDID error reading EDID block 0 attempt 7
MESS:00:00:06.400175:0: HDMI1:EDID error reading EDID block 0 attempt 8
MESS:00:00:06.409689:0: HDMI1:EDID error reading EDID block 0 attempt 9
MESS:00:00:06.414190:0: HDMI1:EDID giving up on reading EDID block 0
MESS:00:00:06.420490:0: brfs: File read: /mfs/sd/config.txt
MESS:00:00:07.612920:0: gpioman: gpioman_get_pin_num: pin DISPLAY_DSI_PORT not defined
MESS:00:00:07.620216:0: *** Restart logging
MESS:00:00:07.621636:0: brfs: File read: 596 bytes
MESS:00:00:07.631629:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 0
MESS:00:00:07.641668:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 1
MESS:00:00:07.651698:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 2
MESS:00:00:07.661736:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 3
MESS:00:00:07.671768:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 4
MESS:00:00:07.681806:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 5
MESS:00:00:07.691836:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 6
MESS:00:00:07.701874:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 7
MESS:00:00:07.711907:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 8
MESS:00:00:07.721945:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 9
MESS:00:00:07.726966:0: hdmi: HDMI0:EDID giving up on reading EDID block 0
MESS:00:00:07.737591:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 0
MESS:00:00:07.747629:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 1
MESS:00:00:07.757659:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 2
MESS:00:00:07.767697:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 3
MESS:00:00:07.777730:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 4
MESS:00:00:07.787768:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 5
MESS:00:00:07.797797:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 6
MESS:00:00:07.807836:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 7
MESS:00:00:07.817868:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 8
MESS:00:00:07.827907:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 9
MESS:00:00:07.832928:0: hdmi: HDMI0:EDID giving up on reading EDID block 0
MESS:00:00:07.850247:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 0
MESS:00:00:07.860287:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 1
MESS:00:00:07.874122:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 2
MESS:00:00:07.884219:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 3
MESS:00:00:07.894324:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 4
MESS:00:00:07.904420:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 5
MESS:00:00:07.914506:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 6
MESS:00:00:07.924601:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 7
MESS:00:00:07.934690:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 8
MESS:00:00:07.944787:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 9
MESS:00:00:07.949855:0: hdmi: HDMI1:EDID giving up on reading EDID block 0
MESS:00:00:07.960511:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 0
MESS:00:00:07.970608:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 1
MESS:00:00:07.980694:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 2
MESS:00:00:07.990796:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 3
MESS:00:00:08.000885:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 4
MESS:00:00:08.010995:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 5
MESS:00:00:08.021082:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 6
MESS:00:00:08.031178:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 7
MESS:00:00:08.041267:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 8
MESS:00:00:08.051362:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 9
MESS:00:00:08.056431:0: hdmi: HDMI1:EDID giving up on reading EDID block 0
MESS:00:00:08.069711:0: HDMI0: hdmi_pixel_encoding: 300000000
MESS:00:00:08.072352:0: HDMI1: hdmi_pixel_encoding: 300000000
MESS:00:00:08.082934:0: dtb_file 'bcm2711-rpi-4-b.dtb'
MESS:00:00:08.087896:0: brfs: File read: /mfs/sd/bcm2711-rpi-4-b.dtb
MESS:00:00:08.091145:0: Loading 'bcm2711-rpi-4-b.dtb' to 0x100 size 0xc957
MESS:00:00:08.110892:0: brfs: File read: 51543 bytes
MESS:00:00:08.120465:0: brfs: File read: /mfs/sd/overlays/overlay_map.dtb
MESS:00:00:08.195826:0: brfs: File read: 1703 bytes
MESS:00:00:08.199336:0: brfs: File read: /mfs/sd/config.txt
MESS:00:00:08.203465:0: brfs: File read: 596 bytes
MESS:00:00:08.209659:0: brfs: File read: /mfs/sd/overlays/disable-bt.dtbo
MESS:00:00:08.228756:0: Loaded overlay 'disable-bt'
MESS:00:00:08.272239:0: brfs: File read: 1073 bytes
MESS:00:00:08.275242:0: brfs: File read: /mfs/sd/cmdline.txt
MESS:00:00:08.279440:0: Read command line from file 'cmdline.txt':
MESS:00:00:08.285291:0: 'talos.platform=metal console=tty0 console=ttyAMA0,115200 init_on_alloc=1 slab_nomerge pti=on consoleblank=0 nvme_core.io_timeout=4294967295 random.trust_cpu=on printk.devkmsg=on ima_template=ima-ng ima_appraise=fix ima_hash=sha
MESS:00:00:08.417865:0: brfs: File read: 249 bytes
MESS:00:00:08.424420:0: brfs: File read: /mfs/sd/bl31.bin
MESS:00:00:08.426753:0: Loading 'bl31.bin' to 0x0 size 0xb06b
MESS:00:00:08.432255:0: brfs: File read: 45163 bytes
MESS:00:00:09.647195:0: brfs: File read: /mfs/sd/kernel8.img
MESS:00:00:09.649801:0: Loading 'kernel8.img' to 0x80000 size 0x13dcf51
MESS:00:00:16.506656:0: Kernel relocated to 0x200000
MESS:00:00:16.508547:0: Device tree loaded to 0x2eff3000 (size 0xcfa8)
MESS:00:00:16.518190:0: uart: Set PL011 baud rate to 103448.300000 Hz
MESS:00:00:16.524539:0: uart: Baud rate change done...
MESS:00:00:16.526592:0: uart: Baud rate change done...
MESS:00:00:16.532309:0: bfs_xhci_stop
MESS:00:00:16.534834:0: XHCI-STOP
MESS:00:00:16.538028:0: xHC ver: 256 HCS: 05000420 fc000031 00e70004 HCC: 002841eb
MESS:00:00:16.545186:0: USBSTS 18
NOTICE:  BL31: v2.6(release):
NOTICE:  BL31: Built : 16:06:15, Dec 27 2021
Read start4.elf bytes  2240224 hnd 0x00027dfd 
Read fixup4.dat bytes     5354 hnd 0x00018bce 
Firmware: 58e03c94953762222f2b838390dde54d46c38381 Jan  5 2022 12:29:20

frezbo commented 2 years ago

I'm not sure if this is a bug on u-boot/grub/dtbs, but in this case the console is broken only when the overlays are changed, the u-boot.grub and upstream kernel version remains the same

frezbo commented 2 years ago

@pelwell seems this has something to do with overlays, when using 1.20211029 firmware dtb's, directly loading the kernel from config.txt works, switching back to the next bracnh console is broken again:

Read start4.elf bytes  2241504 hnd 0x0000f009 
Read fixup4.dat bytes     5411 hnd 0x00000539 
Firmware: b8a114e5a9877e91ca8f26d1a5ce904b2ad3cf13 Oct 29 2021 10:47:33
0x00d03114 0x00000000 0x00000fff
MEM GPU: 32 ARM: 991 TOTAL: 1023
Starting start4.elf @ 0xfeb00200 partition 0
+

MESS:00:00:06.140108:0: brfs: File read: /mfs/sd/config.txt
MESS:00:00:06.142970:0: brfs: File read: 597 bytes
MESS:00:00:06.197356:0: HDMI0:EDID error reading EDID block 0 attempt 0
MESS:00:00:06.206873:0: HDMI0:EDID error reading EDID block 0 attempt 1
MESS:00:00:06.216381:0: HDMI0:EDID error reading EDID block 0 attempt 2
MESS:00:00:06.225896:0: HDMI0:EDID error reading EDID block 0 attempt 3
MESS:00:00:06.235406:0: HDMI0:EDID error reading EDID block 0 attempt 4
MESS:00:00:06.244920:0: HDMI0:EDID error reading EDID block 0 attempt 5
MESS:00:00:06.254427:0: HDMI0:EDID error reading EDID block 0 attempt 6
MESS:00:00:06.263941:0: HDMI0:EDID error reading EDID block 0 attempt 7
MESS:00:00:06.273451:0: HDMI0:EDID error reading EDID block 0 attempt 8
MESS:00:00:06.282966:0: HDMI0:EDID error reading EDID block 0 attempt 9
MESS:00:00:06.287464:0: HDMI0:EDID giving up on reading EDID block 0
MESS:00:00:06.312938:0: HDMI1:EDID error reading EDID block 0 attempt 0
MESS:00:00:06.322453:0: HDMI1:EDID error reading EDID block 0 attempt 1
MESS:00:00:06.331963:0: HDMI1:EDID error reading EDID block 0 attempt 2
MESS:00:00:06.341477:0: HDMI1:EDID error reading EDID block 0 attempt 3
MESS:00:00:06.350985:0: HDMI1:EDID error reading EDID block 0 attempt 4
MESS:00:00:06.360499:0: HDMI1:EDID error reading EDID block 0 attempt 5
MESS:00:00:06.370009:0: HDMI1:EDID error reading EDID block 0 attempt 6
MESS:00:00:06.379523:0: HDMI1:EDID error reading EDID block 0 attempt 7
MESS:00:00:06.389030:0: HDMI1:EDID error reading EDID block 0 attempt 8
MESS:00:00:06.398544:0: HDMI1:EDID error reading EDID block 0 attempt 9
MESS:00:00:06.403045:0: HDMI1:EDID giving up on reading EDID block 0
MESS:00:00:06.409321:0: brfs: File read: /mfs/sd/config.txt
MESS:00:00:07.534662:0: gpioman: gpioman_get_pin_num: pin DISPLAY_DSI_PORT not defined
MESS:00:00:07.541954:0: *** Restart logging
MESS:00:00:07.543378:0: brfs: File read: 597 bytes
MESS:00:00:07.553348:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 0
MESS:00:00:07.563388:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 1
MESS:00:00:07.573418:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 2
MESS:00:00:07.583456:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 3
MESS:00:00:07.593489:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 4
MESS:00:00:07.603527:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 5
MESS:00:00:07.613557:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 6
MESS:00:00:07.623595:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 7
MESS:00:00:07.633628:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 8
MESS:00:00:07.643666:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 9
MESS:00:00:07.648688:0: hdmi: HDMI0:EDID giving up on reading EDID block 0
MESS:00:00:07.659312:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 0
MESS:00:00:07.669350:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 1
MESS:00:00:07.679380:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 2
MESS:00:00:07.689418:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 3
MESS:00:00:07.699451:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 4
MESS:00:00:07.709489:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 5
MESS:00:00:07.719519:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 6
MESS:00:00:07.729557:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 7
MESS:00:00:07.739590:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 8
MESS:00:00:07.749628:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 9
MESS:00:00:07.754649:0: hdmi: HDMI0:EDID giving up on reading EDID block 0
MESS:00:00:07.771908:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 0
MESS:00:00:07.781949:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 1
MESS:00:00:07.798049:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 2
MESS:00:00:07.808146:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 3
MESS:00:00:07.818237:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 4
MESS:00:00:07.828333:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 5
MESS:00:00:07.838420:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 6
MESS:00:00:07.848516:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 7
MESS:00:00:07.858607:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 8
MESS:00:00:07.868704:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 9
MESS:00:00:07.873772:0: hdmi: HDMI1:EDID giving up on reading EDID block 0
MESS:00:00:07.884429:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 0
MESS:00:00:07.894526:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 1
MESS:00:00:07.904611:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 2
MESS:00:00:07.914707:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 3
MESS:00:00:07.924797:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 4
MESS:00:00:07.934894:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 5
MESS:00:00:07.944980:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 6
MESS:00:00:07.955075:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 7
MESS:00:00:07.965165:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 8
MESS:00:00:07.975261:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 9
MESS:00:00:07.980330:0: hdmi: HDMI1:EDID giving up on reading EDID block 0
MESS:00:00:07.993533:0: HDMI0: hdmi_pixel_encoding: 300000000
MESS:00:00:07.996169:0: HDMI1: hdmi_pixel_encoding: 300000000
MESS:00:00:08.006736:0: dtb_file 'bcm2711-rpi-4-b.dtb'
MESS:00:00:08.011424:0: brfs: File read: /mfs/sd/bcm2711-rpi-4-b.dtb
MESS:00:00:08.014844:0: Loading 'bcm2711-rpi-4-b.dtb' to 0x100 size 0xc2a9
MESS:00:00:08.034246:0: brfs: File read: 49833 bytes
MESS:00:00:08.043860:0: brfs: File read: /mfs/sd/overlays/overlay_map.dtb
MESS:00:00:08.111163:0: brfs: File read: 1667 bytes
MESS:00:00:08.114804:0: brfs: File read: /mfs/sd/config.txt
MESS:00:00:08.118758:0: brfs: File read: 597 bytes
MESS:00:00:08.125026:0: brfs: File read: /mfs/sd/overlays/disable-bt.dtbo
MESS:00:00:08.143657:0: Loaded overlay 'disable-bt'
MESS:00:00:08.185950:0: brfs: File read: 1073 bytes
MESS:00:00:08.189444:0: brfs: File read: /mfs/sd/cmdline.txt
MESS:00:00:08.193148:0: Read command line from file 'cmdline.txt':
MESS:00:00:08.199003:0: 'talos.platform=metal console=tty0 console=ttyAMA0,115200 init_on_alloc=1 slab_nomerge pti=on consoleblank=0 nvme_core.io_timeout=4294967295 random.trust_cpu=on printk.devkmsg=on ima_template=ima-ng ima_appraise=fix ima_hash=sha
MESS:00:00:08.331264:0: brfs: File read: 249 bytes
MESS:00:00:09.547227:0: brfs: File read: /mfs/sd/kernel8.img
MESS:00:00:09.549832:0: Loading 'kernel8.img' to 0x80000 size 0x13dcf51
MESS:00:00:16.382787:0: Kernel relocated to 0x200000
MESS:00:00:16.384681:0: Device tree loaded to 0x2eff3700 (size 0xc848)
MESS:00:00:16.394381:0: uart: Set PL011 baud rate to 103448.300000 Hz
MESS:00:00:16.400708:0: uart: Baud rate change done...
MESS:00:00:16.402764:0: uart: Baud rate change done...
MESS:00:00:16.408480:0: bfs_xhci_stop
MESS:00:00:16.411006:0: XHCI-STOP
MESS:00:00:16.414199:0: xHC ver: 256 HCS: 05000420 fc000031 00e70004 HCC: 002841eb
MESS:00:00:16.421358:0: USBSTS 18
[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd083]
[    0.000000] Linux version 5.15.11-talos (@buildkitsandbox) (gcc (GCC) 11.2.0, GNU ld (GNU Binutils) 2.37) #1 SMP Fri Dec 24 16:47:09 UTC 2021
[    0.000000] Machine model: Raspberry Pi 4 Model B Rev 1.4
[    0.000000] efi: UEFI not found.

pelwell commented 2 years ago

Can you explain what you mean by these two statements?: "the console is broken only when the overlays are changed" and: "this has something to do with overlays, when using 1.20211029 firmware dtb's, directly loading the kernel from config.txt works, switching back to the next bracnh console is broken again"

How are you changing just the overlays?

I don't think there is anything fundamentally wrong with the latest firmware and/or the 5.15 kernels used in the next branch, but I acknowledge that you have a problem with your configuration.

frezbo commented 2 years ago

Scenario one:

the first partition contains files from 1.20211029 tag of firmware repository

Serial console over ttyAMA0 works

Second scenario:

the first partition files are overwritten from files from boot folder from next branch of firmware

serial console over ttyAMA0 doesn't work.

In both scenarios the upstream kernel version is unchanged. The kernel used is from torvalds/linux not using the raspberrypi/linux

I hope this helps, let me know if you need anything extra

pelwell commented 2 years ago

I understand - "overlays" didn't just refer to the overlays directory, but rather the whole of the firmware.

I hope this helps, let me know if you need anything extra

This isn't a problem I'll be investigating further. I've established that the kernel and firmware in the next branch have a working ttyAMA0 serial port when used with the majority of your config.txt. If somebody more familiar with U-boot and Grub - software we don't support - can help to pinpoint the underlying cause (starting with reproducing the issue) and demonstrate that we've broken something then we'll obviously try to fix it, but until then this won't get any attention.

frezbo commented 2 years ago

Thanks, but removing u-boot+grub combo and directly booting the kernel also has the same effect.

pelwell commented 2 years ago

The kernel used is from torvalds/linux not using the raspberrypi/linux

Editing a comment to add significant information like this is bad practise - there is no notification of the change, so the person who has read the original comment in the email will probably miss it.

ssh-ing in to look at the kernel logs would remove the current doubt over whether this is non-working serial port or a non-booting kernel. Adding earlycon=pl011,0xfe201000 might also get you some output if it's crashing early on.

frezbo commented 2 years ago

i apologize for the edits and confusion. the kernel indeed boots up and I can access it via the network, it's just that the console is not working, I'll will try adding the earlycon and report back

pelwell commented 2 years ago

earlycon is unlikely to help then, but it's still worth a try.

Have you thought to post the kernel log retrieved over ssh?

frezbo commented 2 years ago

After adding the earlycon I can see the console logs. Here's console logs with earlycon enabled copied from serial console:

Read start4.elf bytes  2240224 hnd 0x00027dfd 
Read fixup4.dat bytes     5354 hnd 0x00018bce 
Firmware: 58e03c94953762222f2b838390dde54d46c38381 Jan  5 2022 12:29:20
0x00d03114 0x00000000 0x00000fff
MEM GPU: 32 ARM: 991 TOTAL: 1023
Starting start4.elf @ 0xfeb00200 partition 0
+

MESS:00:00:06.145336:0: brfs: File read: /mfs/sd/config.txt
MESS:00:00:06.148212:0: brfs: File read: 597 bytes
MESS:00:00:06.203014:0: HDMI0:EDID error reading EDID block 0 attempt 0
MESS:00:00:06.212530:0: HDMI0:EDID error reading EDID block 0 attempt 1
MESS:00:00:06.222037:0: HDMI0:EDID error reading EDID block 0 attempt 2
MESS:00:00:06.231552:0: HDMI0:EDID error reading EDID block 0 attempt 3
MESS:00:00:06.241062:0: HDMI0:EDID error reading EDID block 0 attempt 4
MESS:00:00:06.250577:0: HDMI0:EDID error reading EDID block 0 attempt 5
MESS:00:00:06.260084:0: HDMI0:EDID error reading EDID block 0 attempt 6
MESS:00:00:06.269599:0: HDMI0:EDID error reading EDID block 0 attempt 7
MESS:00:00:06.279109:0: HDMI0:EDID error reading EDID block 0 attempt 8
MESS:00:00:06.288623:0: HDMI0:EDID error reading EDID block 0 attempt 9
MESS:00:00:06.293121:0: HDMI0:EDID giving up on reading EDID block 0
MESS:00:00:06.318615:0: HDMI1:EDID error reading EDID block 0 attempt 0
MESS:00:00:06.328130:0: HDMI1:EDID error reading EDID block 0 attempt 1
MESS:00:00:06.337640:0: HDMI1:EDID error reading EDID block 0 attempt 2
MESS:00:00:06.347154:0: HDMI1:EDID error reading EDID block 0 attempt 3
MESS:00:00:06.356662:0: HDMI1:EDID error reading EDID block 0 attempt 4
MESS:00:00:06.366176:0: HDMI1:EDID error reading EDID block 0 attempt 5
MESS:00:00:06.375686:0: HDMI1:EDID error reading EDID block 0 attempt 6
MESS:00:00:06.385200:0: HDMI1:EDID error reading EDID block 0 attempt 7
MESS:00:00:06.394708:0: HDMI1:EDID error reading EDID block 0 attempt 8
MESS:00:00:06.404222:0: HDMI1:EDID error reading EDID block 0 attempt 9
MESS:00:00:06.408723:0: HDMI1:EDID giving up on reading EDID block 0
MESS:00:00:06.415044:0: brfs: File read: /mfs/sd/config.txt
MESS:00:00:07.507843:0: gpioman: gpioman_get_pin_num: pin DISPLAY_DSI_PORT not defined
MESS:00:00:07.515139:0: *** Restart logging
MESS:00:00:07.516559:0: brfs: File read: 597 bytes
MESS:00:00:07.526551:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 0
MESS:00:00:07.536591:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 1
MESS:00:00:07.546621:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 2
MESS:00:00:07.556659:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 3
MESS:00:00:07.566692:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 4
MESS:00:00:07.576730:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 5
MESS:00:00:07.586760:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 6
MESS:00:00:07.596798:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 7
MESS:00:00:07.606830:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 8
MESS:00:00:07.616868:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 9
MESS:00:00:07.621889:0: hdmi: HDMI0:EDID giving up on reading EDID block 0
MESS:00:00:07.632514:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 0
MESS:00:00:07.642552:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 1
MESS:00:00:07.652582:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 2
MESS:00:00:07.662619:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 3
MESS:00:00:07.672651:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 4
MESS:00:00:07.682689:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 5
MESS:00:00:07.692718:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 6
MESS:00:00:07.702757:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 7
MESS:00:00:07.712789:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 8
MESS:00:00:07.722828:0: hdmi: HDMI0:EDID error reading EDID block 0 attempt 9
MESS:00:00:07.727848:0: hdmi: HDMI0:EDID giving up on reading EDID block 0
MESS:00:00:07.745175:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 0
MESS:00:00:07.755215:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 1
MESS:00:00:07.769041:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 2
MESS:00:00:07.779139:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 3
MESS:00:00:07.789228:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 4
MESS:00:00:07.799340:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 5
MESS:00:00:07.809427:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 6
MESS:00:00:07.819521:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 7
MESS:00:00:07.829609:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 8
MESS:00:00:07.839705:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 9
MESS:00:00:07.844773:0: hdmi: HDMI1:EDID giving up on reading EDID block 0
MESS:00:00:07.855430:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 0
MESS:00:00:07.865525:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 1
MESS:00:00:07.875611:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 2
MESS:00:00:07.885708:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 3
MESS:00:00:07.895797:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 4
MESS:00:00:07.905892:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 5
MESS:00:00:07.915978:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 6
MESS:00:00:07.926075:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 7
MESS:00:00:07.936164:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 8
MESS:00:00:07.946259:0: hdmi: HDMI1:EDID error reading EDID block 0 attempt 9
MESS:00:00:07.951327:0: hdmi: HDMI1:EDID giving up on reading EDID block 0
MESS:00:00:07.964613:0: HDMI0: hdmi_pixel_encoding: 300000000
MESS:00:00:07.967248:0: HDMI1: hdmi_pixel_encoding: 300000000
MESS:00:00:07.977832:0: dtb_file 'bcm2711-rpi-4-b.dtb'
MESS:00:00:07.982769:0: brfs: File read: /mfs/sd/bcm2711-rpi-4-b.dtb
MESS:00:00:07.986018:0: Loading 'bcm2711-rpi-4-b.dtb' to 0x100 size 0xc957
MESS:00:00:08.005771:0: brfs: File read: 51543 bytes
MESS:00:00:08.015526:0: brfs: File read: /mfs/sd/overlays/overlay_map.dtb
MESS:00:00:08.091019:0: brfs: File read: 1703 bytes
MESS:00:00:08.094731:0: brfs: File read: /mfs/sd/config.txt
MESS:00:00:08.098639:0: brfs: File read: 597 bytes
MESS:00:00:08.104857:0: brfs: File read: /mfs/sd/overlays/disable-bt.dtbo
MESS:00:00:08.123945:0: Loaded overlay 'disable-bt'
MESS:00:00:08.167523:0: brfs: File read: 1073 bytes
MESS:00:00:08.170653:0: brfs: File read: /mfs/sd/cmdline.txt
MESS:00:00:08.174731:0: Read command line from file 'cmdline.txt':
MESS:00:00:08.180577:0: 'talos.platform=metal console=tty0 console=ttyAMA0,115200 earlycon=pl011,0xfe201000 init_on_alloc=1 slab_nomerge pti=on consoleblank=0 nvme_core.io_timeout=4294967295 random.trust_cpu=on printk.devkmsg=on ima_template=ima-ng ima
MESS:00:00:08.312772:0: brfs: File read: 275 bytes
MESS:00:00:09.527165:0: brfs: File read: /mfs/sd/kernel8.img
MESS:00:00:09.529762:0: Loading 'kernel8.img' to 0x80000 size 0x13dcf51
MESS:00:00:16.386207:0: Kernel relocated to 0x200000
MESS:00:00:16.388104:0: Device tree loaded to 0x2eff3000 (size 0xcfc0)
MESS:00:00:16.397768:0: uart: Set PL011 baud rate to 103448.300000 Hz
MESS:00:00:16.404106:0: uart: Baud rate change done...
MESS:00:00:16.406166:0: uart: Baud rate change done...
MESS:00:00:16.411901:0: bfs_xhci_stop
MESS:00:00:16.414408:0: XHCI-STOP
MESS:00:00:16.417601:0: xHC ver: 256 HCS: 05000420 fc000031 00e70004 HCC: 002841eb
MESS:00:00:16.424760:0: USBSTS 18
[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd083]
[    0.000000] Linux version 5.15.11-talos (@buildkitsandbox) (gcc (GCC) 11.2.0, GNU ld (GNU Binutils) 2.37) #1 SMP Fri Dec 24 16:47:09 UTC 2021
[    0.000000] Machine model: Raspberry Pi 4 Model B Rev 1.4
[    0.000000] earlycon: pl11 at MMIO 0x00000000fe201000 (options '')
[    0.000000] printk: bootconsole [pl11] enabled
[    0.000000] efi: UEFI not found.
[    0.000000] Reserved memory: created CMA memory pool at 0x000000002ac00000, size 64 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] NUMA: No NUMA configuration found
[    0.000000] NUMA: Faking a node at [mem 0x0000000000000000-0x00000001ffffffff]
[    0.000000] NUMA: NODE_DATA [mem 0x1fefe8400-0x1fefebfff]
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000000000-0x000000003fffffff]
[    0.000000]   DMA32    [mem 0x0000000040000000-0x00000000ffffffff]
[    0.000000]   Normal   [mem 0x0000000100000000-0x00000001ffffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x000000003defffff]
[    0.000000]   node   0: [mem 0x0000000040000000-0x00000000fbffffff]
[    0.000000]   node   0: [mem 0x0000000100000000-0x00000001ffffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000001ffffffff]
[    0.000000] On node 0, zone DMA32: 256 pages in unavailable ranges
[    0.000000] percpu: Embedded 29 pages/cpu s80656 r8192 d29936 u118784
[    0.000000] Detected PIPT I-cache on CPU0
[    0.000000] CPU features: detected: Spectre-v2
[    0.000000] CPU features: detected: Spectre-v3a
[    0.000000] CPU features: detected: Spectre-v4
[    0.000000] CPU features: kernel page table isolation forced ON by KASLR
[    0.000000] CPU features: detected: Kernel page table isolation (KPTI)
[    0.000000] CPU features: detected: ARM errata 1165522, 1319367, or 1530923
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 2039552
[    0.000000] Policy zone: Normal
[    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=592 bcm2708_fb.fbheight=448 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:AD:31:E2 vc_mem.mem_base=0x3eb00000 vc_mem.mem_size=0x3ff00000  talos.platform=metal console=tty0 console=ttyAMA0,115200 earlycon=pl011,0xfe201000 init_on_alloc=1 slab_nomerge pti=on consoleblank=0 nvme_core.io_timeout=4294967295 random.trust_cpu=on printk.devkmsg=on ima_template=ima-ng ima_appraise=fix ima_hash=sha512 talos.board=rpi_4
[    0.000000] Unknown kernel command line parameters "pti=on", will be passed to user space.
[    0.000000] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)
[    0.000000] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
[    0.000000] mem auto-init: stack:byref_all(zero), heap alloc:on, heap free:off
[    0.000000] software IO TLB: mapped [mem 0x0000000039f00000-0x000000003df00000] (64MB)
[    0.000000] Memory: 7941208K/8289280K available (31488K kernel code, 4900K rwdata, 10180K rodata, 8192K init, 943K bss, 282536K reserved, 65536K cma-reserved)
[    0.000000] random: get_random_u64 called from __kmem_cache_create+0x30/0x57c with crng_init=0
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] trace event string verifier disabled
[    0.000000] rcu: Hierarchical RCU implementation.
[    0.000000] rcu:     RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4.
[    0.000000]  Tracing variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] Root IRQ handler: gic_handle_irq
[    0.000000] GIC: Using split EOI/Deactivate mode
[    0.000000] arch_timer: cp15 timer(s) running at 54.00MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns
[    0.000001] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
[    0.008632] Console: colour dummy device 80x25
[    0.013657] printk: console [tty0] enabled
[    0.017944] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000)
[    0.028524] pid_max: default: 32768 minimum: 301
[    0.033354] LSM: Security Framework initializing
[    0.038060] Yama: becoming mindful.
[    0.041767] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    0.049542] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    0.060092] rcu: Hierarchical SRCU implementation.
[    0.066548] EFI services will not be available.
[    0.071538] smp: Bringing up secondary CPUs ...
[    0.076812] Detected PIPT I-cache on CPU1
[    0.076887] CPU1: Booted secondary processor 0x0000000001 [0x410fd083]
[    0.077657] Detected PIPT I-cache on CPU2
[    0.077706] CPU2: Booted secondary processor 0x0000000002 [0x410fd083]
[    0.078413] Detected PIPT I-cache on CPU3
[    0.078462] CPU3: Booted secondary processor 0x0000000003 [0x410fd083]
[    0.078562] smp: Brought up 1 node, 4 CPUs
[    0.114724] SMP: Total of 4 processors activated.
[    0.119490] CPU features: detected: 32-bit EL0 Support
[    0.124692] CPU features: detected: 32-bit EL1 Support
[    0.129901] CPU features: detected: CRC32 instructions
[    0.135314] CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching
[    0.189080] CPU: All CPU(s) started at EL2
[    0.193359] alternatives: patching kernel code
[    0.201539] devtmpfs: initialized
[    0.217722] Registered cp15_barrier emulation handler
[    0.222890] Registered setend emulation handler
[    0.227490] KASLR enabled
[    0.230399] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.240354] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.249654] pinctrl core: initialized pinctrl subsystem
[    0.256133] DMI not present or invalid.
[    0.260753] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.273209] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations
[    0.281988] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.292178] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.300481] audit: initializing netlink subsys (disabled)
[    0.306235] audit: type=2000 audit(0.208:1): state=initialized audit_enabled=0 res=1
[    0.307767] thermal_sys: Registered thermal governor 'step_wise'
[    0.314112] thermal_sys: Registered thermal governor 'user_space'
[    0.320423] cpuidle: using governor ladder
[    0.330768] cpuidle: using governor menu
[    0.335903] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.342901] ASID allocator initialised with 32768 entries
[    0.350070] Serial: AMBA PL011 UART driver
[    0.402832] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
[    0.409679] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages
[    0.416472] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.423260] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages
[    0.432739] cryptd: max_cpu_qlen set to 1000
[    0.441579] ACPI: Interpreter disabled.
[    0.447340] iommu: Default domain type: Translated 
[    0.452304] iommu: DMA domain TLB invalidation policy: strict mode 
[    0.459048] vgaarb: loaded
[    0.462525] SCSI subsystem initialized
[    0.466940] usbcore: registered new interface driver usbfs
[    0.472565] usbcore: registered new interface driver hub
[    0.477998] usbcore: registered new device driver usb
[    0.483309] usb_phy_generic phy: supply vcc not found, using dummy regulator
[    0.491410] pps_core: LinuxPPS API ver. 1 registered
[    0.496450] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.505718] PTP clock support registered
[    0.510063] EDAC MC: Ver: 3.0.0
[    0.514515] Advanced Linux Sound Architecture Driver Initialized.
[    0.521568] NET: Registered PF_ATMPVC protocol family
[    0.526698] NET: Registered PF_ATMSVC protocol family
[    0.531869] NetLabel: Initializing
[    0.535308] NetLabel:  domain hash size = 128
[    0.539722] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[    0.545526] NetLabel:  unlabeled traffic allowed by default
[    0.552957] clocksource: Switched to clocksource arch_sys_counter
[    0.663476] VFS: Disk quotas dquot_6.6.0
[    0.667562] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.675062] pnp: PnP ACPI: disabled
[    0.689364] NET: Registered PF_INET protocol family
[    0.695374] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[    0.708629] tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear)
[    0.717887] TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear)
[    0.727825] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear)
[    0.736138] TCP: Hash tables configured (established 65536 bind 65536)
[    0.743321] UDP hash table entries: 4096 (order: 5, 131072 bytes, linear)
[    0.750505] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear)
[    0.758168] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    0.764550] RPC: Registered named UNIX socket transport module.
[    0.770567] RPC: Registered udp transport module.
[    0.775332] RPC: Registered tcp transport module.
[    0.780095] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.787556] PCI: CLS 0 bytes, default 64
[    1.702147] hw perfevents: enabled with armv8_cortex_a72 PMU driver, 7 counters available
[    1.710776] kvm [1]: IPA Size Limit: 44 bits
[    1.717400] kvm [1]: vgic interrupt IRQ9
[    1.721565] kvm [1]: Hyp mode initialized successfully
[    1.733595] Initialise system trusted keyrings
[    1.738371] workingset: timestamp_bits=40 max_order=21 bucket_order=0
[    1.756047] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    1.763335] NFS: Registering the id_resolver key type
[    1.768534] Key type id_resolver registered
[    1.772785] Key type id_legacy registered
[    1.776862] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.783664] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    1.792450] Key type cifs.spnego registered
[    1.796738] Key type cifs.idmap registered
[    1.801127] fuse: init (API version 7.34)
[    1.805900] SGI XFS with ACLs, security attributes, no debug enabled
[    1.814941] ceph: loaded (mds proto 32)
[    1.883495] NET: Registered PF_ALG protocol family
[    1.888386] Key type asymmetric registered
[    1.892543] Asymmetric key parser 'x509' registered
[    1.897728] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)
[    1.905389] io scheduler mq-deadline registered
[    1.909992] io scheduler kyber registered
[    1.926000] pinctrl-bcm2835 fe200000.gpio: could not add GPIO chip
[    1.937223] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[    1.945636] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[    1.952746] brcm-pcie fd500000.pcie:   No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
[    1.961989] brcm-pcie fd500000.pcie:      MEM 0x0600000000..0x063fffffff -> 0x00c0000000
[    1.970238] brcm-pcie fd500000.pcie:   IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000
[    2.047035] brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC)
[    2.053782] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[    2.060154] pci_bus 0000:00: root bus resource [bus 00-ff]
[    2.065724] pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff])
[    2.076260] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[    2.082452] pci 0000:00:00.0: PME# supported from D0 D3hot
[    2.091955] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
[    2.098096] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
[    2.105096] pci 0000:01:00.0: PME# supported from D0 D3hot
[    2.124243] pci 0000:00:00.0: BAR 14: assigned [mem 0x600000000-0x6000fffff]
[    2.131436] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
[    2.139039] pci 0000:00:00.0: PCI bridge to [bus 01]
[    2.144079] pci 0000:00:00.0:   bridge window [mem 0x600000000-0x6000fffff]
[    2.151371] pcieport 0000:00:00.0: enabling device (0000 -> 0002)
[    2.157793] pcieport 0000:00:00.0: PME: Signaling with IRQ 46
[    2.164012] pcieport 0000:00:00.0: AER: enabled with IRQ 46
[    2.171574] IPMI message handler: version 39.2
[    2.176237] ipmi device interface
[    2.179863] ipmi_si: IPMI System Interface driver
[    2.185113] ipmi_si: Unable to find any System Interface(s)
[    2.190799] IPMI poweroff: Copyright (C) 2004 MontaVista Software - IPMI Powerdown via sys_reboot
[    2.200500] EINJ: ACPI disabled.
[    2.222883] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
[    2.231541] Serial: AMBA driver
[    2.234816] SuperH (H)SCI(F) driver initialized
[    2.240051] msm_serial: driver initialized
[    2.245534] iproc-rng200 fe104000.rng: hwrng registered
[    2.262054] cacheinfo: Unable to detect cache hierarchy for CPU 0
[    2.282753] brd: module loaded
[    2.295035] loop: module loaded
[    2.298843] rbd: loaded (major 252)
[    2.303816] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    2.313070] Loading iSCSI transport class v2.0-870.
[    2.319854] iscsi: registered transport (tcp)
[    2.324423] Microchip SmartPQI Driver (v2.1.10-020)
[    2.329453] megasas: 07.717.02.00-rc1
[    2.333339] mpt3sas version 39.100.00.00 loaded
[    2.350686] wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information.
[    2.358696] wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld <Jason@zx2c4.com>. All Rights Reserved.
[    2.374627] libphy: Fixed MDIO Bus: probed
[    2.385871] tun: Universal TUN/TAP device driver, 1.6
[    2.394996] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
[    2.416974] libphy: bcmgenet MII bus: probed
[    2.469057] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
[    2.478146] e100: Intel(R) PRO/100 Network Driver
[    2.482969] e100: Copyright(c) 1999-2006 Intel Corporation
[    2.488645] e1000: Intel(R) PRO/1000 Network Driver
[    2.493596] e1000: Copyright (c) 1999-2006 Intel Corporation.
[    2.499518] e1000e: Intel(R) PRO/1000 Network Driver
[    2.504557] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
[    2.510635] igb: Intel(R) Gigabit Ethernet Network Driver
[    2.516113] igb: Copyright (c) 2007-2014 Intel Corporation.
[    2.521849] igbvf: Intel(R) Gigabit Virtual Function Network Driver
[    2.528205] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
[    2.534281] ixgbe: Intel(R) 10 Gigabit PCI Express Network Driver
[    2.540461] ixgbe: Copyright (c) 1999-2016 Intel Corporation.
[    2.546536] ixgbevf: Intel(R) 10 Gigabit PCI Express Virtual Function Network Driver
[    2.554450] ixgbevf: Copyright (c) 2009 - 2018 Intel Corporation.
[    2.560836] i40e: Intel(R) Ethernet Connection XL710 Network Driver
[    2.567204] i40e: Copyright (c) 2013 - 2019 Intel Corporation.
[    2.573371] ixgb: Intel(R) PRO/10GbE Network Driver
[    2.578343] ixgb: Copyright (c) 1999-2008 Intel Corporation.
[    2.584183] iavf: Intel(R) Ethernet Adaptive Virtual Function Network Driver
[    2.591331] Copyright (c) 2013 - 2018 Intel Corporation.
[    2.597488] sky2: driver version 1.30
[    2.602450] QLogic FastLinQ 4xxxx Core Module qed
[    2.607267] qede init: QLogic FastLinQ 4xxxx Ethernet Driver qede
[    2.616634] usbcore: registered new interface driver rtl8xxxu
[    2.622594] usbcore: registered new interface driver rndis_wlan
[    2.628607] VMware vmxnet3 virtual NIC driver - version 1.6.0.0-k-NAPI
[    2.635315] pegasus: Pegasus/Pegasus II USB Ethernet driver
[    2.641041] usbcore: registered new interface driver pegasus
[    2.646841] usbcore: registered new interface driver rtl8150
[    2.652649] usbcore: registered new interface driver r8152
[    2.658226] hso: drivers/net/usb/hso.c: Option Wireless
[    2.663604] usbcore: registered new interface driver hso
[    2.669052] usbcore: registered new interface driver lan78xx
[    2.674848] usbcore: registered new interface driver asix
[    2.680385] usbcore: registered new interface driver ax88179_178a
[    2.686644] usbcore: registered new interface driver cdc_ether
[    2.692617] usbcore: registered new interface driver dm9601
[    2.698320] usbcore: registered new interface driver CoreChips
[    2.704300] usbcore: registered new interface driver smsc75xx
[    2.710201] usbcore: registered new interface driver smsc95xx
[    2.716090] usbcore: registered new interface driver net1080
[    2.721888] usbcore: registered new interface driver plusb
[    2.727521] usbcore: registered new interface driver rndis_host
[    2.733586] usbcore: registered new interface driver cdc_subset
[    2.739668] usbcore: registered new interface driver zaurus
[    2.745399] usbcore: registered new interface driver MOSCHIP usb-ethernet driver
[    2.752984] usbcore: registered new interface driver int51x1
[    2.758786] usbcore: registered new interface driver kalmia
[    2.764495] usbcore: registered new interface driver ipheth
[    2.770219] usbcore: registered new interface driver sierra_net
[    2.776290] usbcore: registered new interface driver cx82310_eth
[    2.782456] usbcore: registered new interface driver cdc_ncm
[    2.788250] usbcore: registered new interface driver lg-vl600
[    2.794140] usbcore: registered new interface driver qmi_wwan
[    2.800038] usbcore: registered new interface driver ch9200
[    2.805963] VFIO - User Level meta-driver version: 0.3
[    2.812908] aoe: AoE v85 initialised.
[    2.819322] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    2.825983] ehci-pci: EHCI PCI platform driver
[    2.830570] ehci-platform: EHCI generic platform driver
[    2.836086] ehci-orion: EHCI orion driver
[    2.840308] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    2.846603] ohci-pci: OHCI PCI platform driver
[    2.851203] ohci-platform: OHCI generic platform driver
[    2.857242] usbcore: registered new interface driver cdc_acm
[    2.863000] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[    2.871187] usbcore: registered new interface driver cdc_wdm
[    2.877169] usbcore: registered new interface driver uas
[    2.882673] usbcore: registered new interface driver usb-storage
[    2.888839] usbcore: registered new interface driver ums-alauda
[    2.894909] usbcore: registered new interface driver ums-cypress
[    2.901059] usbcore: registered new interface driver ums-datafab
[    2.907211] usbcore: registered new interface driver ums_eneub6250
[    2.913540] usbcore: registered new interface driver ums-freecom
[    2.919693] usbcore: registered new interface driver ums-isd200
[    2.925754] usbcore: registered new interface driver ums-jumpshot
[    2.932006] usbcore: registered new interface driver ums-karma
[    2.937978] usbcore: registered new interface driver ums-onetouch
[    2.944228] usbcore: registered new interface driver ums-realtek
[    2.950435] usbcore: registered new interface driver ums-sddr09
[    2.956511] usbcore: registered new interface driver ums-sddr55
[    2.962645] usbcore: registered new interface driver ums-usbat
[    2.968648] usbcore: registered new interface driver mdc800
[    2.974305] mdc800: v0.7.5 (30/10/2000):USB Driver for Mustek MDC800 Digital Camera
[    2.982131] usbcore: registered new interface driver microtekX6
[    2.988279] usbcore: registered new interface driver ch341
[    2.993898] usbserial: USB Serial support registered for ch341-uart
[    3.000319] usbcore: registered new interface driver cp210x
[    3.006004] usbserial: USB Serial support registered for cp210x
[    3.012084] usbcore: registered new interface driver ftdi_sio
[    3.017972] usbserial: USB Serial support registered for FTDI USB Serial Device
[    3.025447] usbcore: registered new interface driver pl2303
[    3.031146] usbserial: USB Serial support registered for pl2303
[    3.037219] usbcore: registered new interface driver adutux
[    3.042937] usbcore: registered new interface driver appledisplay
[    3.049180] usbcore: registered new interface driver cypress_cy7c63
[    3.055612] usbcore: registered new interface driver cytherm
[    3.061419] usbcore: registered new interface driver emi26 - firmware loader
[    3.068637] usbcore: registered new interface driver emi62 - firmware loader
[    3.075804] ftdi_elan: driver ftdi-elan
[    3.079777] usbcore: registered new interface driver ftdi-elan
[    3.085762] usbcore: registered new interface driver idmouse
[    3.091570] usbcore: registered new interface driver iowarrior
[    3.097563] usbcore: registered new interface driver isight_firmware
[    3.104072] usbcore: registered new interface driver usblcd
[    3.109790] usbcore: registered new interface driver ldusb
[    3.115417] usbcore: registered new interface driver legousbtower
[    3.121692] usbcore: registered new interface driver usbtest
[    3.127511] usbcore: registered new interface driver usb_ehset_test
[    3.133946] usbcore: registered new interface driver trancevibrator
[    3.140365] usbcore: registered new interface driver usbsevseg
[    3.146343] usbcore: registered new interface driver yurex
[    3.152386] usbcore: registered new interface driver chaoskey
[    3.158308] usbcore: registered new interface driver sisusb
[    3.164027] usbcore: registered new interface driver lvs
[    3.172386] vhci_hcd vhci_hcd.0: USB/IP Virtual Host Controller
[    3.178443] vhci_hcd vhci_hcd.0: new USB bus registered, assigned bus number 1
[    3.185852] vhci_hcd: created sysfs vhci_hcd.0
[    3.191184] hub 1-0:1.0: USB hub found
[    3.195059] hub 1-0:1.0: 8 ports detected
[    3.200106] vhci_hcd vhci_hcd.0: USB/IP Virtual Host Controller
[    3.206142] vhci_hcd vhci_hcd.0: new USB bus registered, assigned bus number 2
[    3.213557] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[    3.222490] hub 2-0:1.0: USB hub found
[    3.226371] hub 2-0:1.0: 8 ports detected
[    3.231977] usbcore: registered new device driver usbip-host
[    3.239032] mousedev: PS/2 mouse device common for all mice
[    3.247686] i2c_dev: i2c /dev entries driver
[    3.257973] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    3.265890] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
[    3.278886] device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com
[    3.290559] sdhci: Secure Digital Host Controller Interface driver
[    3.296892] sdhci: Copyright(c) Pierre Ossman
[    3.302135] Synopsys Designware Multimedia Card Interface Driver
[    3.309705] sdhci-pltfm: SDHCI platform and OF driver helper
[    3.317700] ledtrig-cpu: registered to indicate activity on CPUs
[    3.324566] No iBFT detected.
[    3.330895] hid: raw HID events driver (C) Jiri Kosina
[    3.338427] usbcore: registered new interface driver usbhid
[    3.344150] usbhid: USB HID core driver
[    3.349505] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[    3.364042] NET: Registered PF_LLC protocol family
[    3.369046] GACT probability NOT on
[    3.372591] Mirror/redirect action on
[    3.376356] Simple TC action Loaded
[    3.380647] netem: version 1.3
[    3.383920] u32 classifier
[    3.386669]     input device check on
[    3.390386]     Actions configured
[    3.400437] xt_time: kernel timezone is -0000
[    3.404978] IPVS: Registered protocols (TCP, UDP)
[    3.409912] IPVS: Connection hash table configured (size=4096, memory=64Kbytes)
[    3.417540] IPVS: ipvs loaded.
[    3.420638] IPVS: [rr] scheduler registered.
[    3.424985] IPVS: [wrr] scheduler registered.
[    3.429432] IPVS: [lc] scheduler registered.
[    3.433766] IPVS: [sh] scheduler registered.
[    3.438264] ipip: IPv4 and MPLS over IPv4 tunneling driver
[    3.444521] gre: GRE over IPv4 demultiplexor driver
[    3.449592] Initializing XFRM netlink socket
[    3.454570] NET: Registered PF_INET6 protocol family
[    3.461435] Segment Routing with IPv6
[    3.465231] In-situ OAM (IOAM) with IPv6
[    3.469459] mip6: Mobile IPv6
[    3.472735] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[    3.480220] NET: Registered PF_PACKET protocol family
[    3.485555] Bridge firewalling registered
[    3.489852] NET: Registered PF_APPLETALK protocol family
[    3.495331] NET: Registered PF_X25 protocol family
[    3.500237] X25: Linux Version 0.2
[    3.503777] RPC: Registered rdma transport module.
[    3.508639] RPC: Registered rdma backchannel transport module.
[    3.514759] l2tp_core: L2TP core driver, V2.0
[    3.519194] NET4: DECnet for Linux: V.2.5.68s (C) 1995-2003 Linux DECnet Project Team
[    3.527470] DECnet: Routing cache hash table of 1024 buckets, 16Kbytes
[    3.534124] NET: Registered PF_DECnet protocol family
[    3.539337] NET: Registered PF_PHONET protocol family
[    3.544669] 8021q: 802.1Q VLAN Support v1.8
[    3.558051] DCCP: Activated CCID 2 (TCP-like)
[    3.562754] DCCP: Activated CCID 3 (TCP-Friendly Rate Control)
[    3.569596] sctp: Hash tables configured (bind 256/256)
[    3.575811] NET: Registered PF_RDS protocol family
[    3.580705] tipc: Activated (version 2.0.0)
[    3.585159] NET: Registered PF_TIPC protocol family
[    3.590281] tipc: Started in single node mode
[    3.596605] NET: Registered PF_IEEE802154 protocol family
[    3.602148] Key type dns_resolver registered
[    3.606512] Key type ceph registered
[    3.610445] libceph: loaded (mon/osd proto 15/24)
[    3.616055] batman_adv: B.A.T.M.A.N. advanced 2021.3 (compatibility version 15) loaded
[    3.624137] openvswitch: Open vSwitch switching datapath
[    3.630345] NET: Registered PF_VSOCK protocol family
[    3.635598] mpls_gso: MPLS GSO support
[    3.640813] registered taskstats version 1
[    3.645019] Loading compiled-in X.509 certificates
[    3.653927] Loaded X.509 cert 'Sidero Labs, Inc.: Build time throw-away kernel key: 9093aafff06ba28e5828193dfd57d5a82d79a212'
[    3.665732] ima: No TPM chip found, activating TPM-bypass!
[    3.671324] ima: Allocated hash algorithm: sha512
[    3.676161] ima: No architecture policies found
[    3.688086] raspberrypi-firmware soc:firmware: Attached to firmware from 2022-01-05T12:29:20
[    3.698273] pinctrl-bcm2835 fe200000.gpio: could not add GPIO chip
[    3.884248] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    3.889633] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 3
[    3.897834] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0000040000000890
[    3.908481] hub 3-0:1.0: USB hub found
[    3.912352] hub 3-0:1.0: 1 port detected
[    3.916758] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    3.922041] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 4
[    3.929505] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[    3.936405] hub 4-0:1.0: USB hub found
[    3.940266] hub 4-0:1.0: 4 ports detected
[    4.041478] pinctrl-bcm2835 fe200000.gpio: could not add GPIO chip
[    4.053339] pinctrl-bcm2835 fe200000.gpio: could not add GPIO chip
[    4.062910] printk: console [netcon0] enabled
[    4.067380] netconsole: network logging started
[    4.072586] rdma_rxe: loaded
[    4.075968] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[    4.084813] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[    4.091491] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
[    4.100211] cfg80211: failed to load regulatory.db
[    4.105616] mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA
[    4.113103] pinctrl-bcm2835 fe200000.gpio: could not add GPIO chip
[    4.125880] ALSA device list:

Here's the console logs when earlycon is not enabled and obtained from dmesg output:

192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: Booting Linux on physical CPU 0x0000000000 [0x410fd083]
192.168.15.202: kern:  notice: [2022-01-17T14:07:54.057983495Z]: Linux version 5.15.11-talos (@buildkitsandbox) (gcc (GCC) 11.2.0, GNU ld (GNU Binutils) 2.37) #1 SMP Fri Dec 24 16:47:09 UTC 2021
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: Machine model: Raspberry Pi 4 Model B Rev 1.4
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: efi: EFI v2.80 by Das U-Boot
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: efi: RTPROP=0x39c2b040 SMBIOS=0x39c27000 RNG=0x21d52040 MEMRESERVE=0x21d51040 
192.168.15.202: kern:  notice: [2022-01-17T14:07:54.057983495Z]: efi: seeding entropy pool
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: Reserved memory: created CMA memory pool at 0x000000002c000000, size 64 MiB
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: NUMA: No NUMA configuration found
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: NUMA: Faking a node at [mem 0x0000000000000000-0x00000001ffffffff]
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: NUMA: NODE_DATA [mem 0x1fefff400-0x1ff002fff]
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: Zone ranges:
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]:   DMA      [mem 0x0000000000000000-0x000000003fffffff]
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]:   DMA32    [mem 0x0000000040000000-0x00000000ffffffff]
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]:   Normal   [mem 0x0000000100000000-0x00000001ffffffff]
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: Movable zone start for each node
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: Early memory node ranges
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]:   node   0: [mem 0x0000000000000000-0x0000000000000fff]
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]:   node   0: [mem 0x0000000000001000-0x0000000021d51fff]
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]:   node   0: [mem 0x0000000021d52000-0x0000000021d52fff]
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]:   node   0: [mem 0x0000000021d53000-0x0000000039c26fff]
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]:   node   0: [mem 0x0000000039c27000-0x0000000039c27fff]
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]:   node   0: [mem 0x0000000039c28000-0x0000000039c2afff]
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]:   node   0: [mem 0x0000000039c2b000-0x0000000039c2dfff]
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]:   node   0: [mem 0x0000000039c2e000-0x0000000039c2efff]
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]:   node   0: [mem 0x0000000039c2f000-0x0000000039c32fff]
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]:   node   0: [mem 0x0000000039c33000-0x000000003b04ffff]
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]:   node   0: [mem 0x000000003b050000-0x000000003b05ffff]
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]:   node   0: [mem 0x000000003b060000-0x000000003b2fffff]
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]:   node   0: [mem 0x000000003ee5f000-0x000000003ee5ffff]
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]:   node   0: [mem 0x0000000040000000-0x00000000fbffffff]
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]:   node   0: [mem 0x0000000100000000-0x00000001ffffffff]
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: Initmem setup node 0 [mem 0x0000000000000000-0x00000001ffffffff]
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: On node 0, zone DMA: 256 pages in unavailable ranges
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: percpu: Embedded 29 pages/cpu s80656 r8192 d29936 u118784
192.168.15.202: kern:   debug: [2022-01-17T14:07:54.057983495Z]: pcpu-alloc: s80656 r8192 d29936 u118784 alloc=29*4096
192.168.15.202: kern:   debug: [2022-01-17T14:07:54.057983495Z]: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: Detected PIPT I-cache on CPU0
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: CPU features: detected: Spectre-v2
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: CPU features: detected: Spectre-v3a
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: CPU features: detected: Spectre-v4
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: CPU features: kernel page table isolation forced ON by KASLR
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: CPU features: detected: Kernel page table isolation (KPTI)
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: CPU features: detected: ARM errata 1165522, 1319367, or 1530923
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: Built 1 zonelists, mobility grouping on.  Total pages: 2028596
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: Policy zone: Normal
192.168.15.202: kern:  notice: [2022-01-17T14:07:54.057983495Z]: Kernel command line: BOOT_IMAGE=/A/vmlinuz talos.platform=metal console=tty0 console=ttyAMA0,115200 init_on_alloc=1 slab_nomerge pti=on consoleblank=0 nvme_core.io_timeout=4294967295 random.trust_cpu=on printk.devkmsg=on ima_template=ima-ng ima_appraise=fix ima_hash=sha512 talos.board=rpi_4 initrd=/A/initramfs.xz
192.168.15.202: kern:  notice: [2022-01-17T14:07:54.057983495Z]: Unknown kernel command line parameters "BOOT_IMAGE=/A/vmlinuz pti=on", will be passed to user space.
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: mem auto-init: stack:byref_all(zero), heap alloc:on, heap free:off
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: software IO TLB: mapped [mem 0x0000000035c27000-0x0000000039c27000] (64MB)
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: Memory: 7851460K/8244228K available (31488K kernel code, 4900K rwdata, 10180K rodata, 8192K init, 943K bss, 327232K reserved, 65536K cma-reserved)
192.168.15.202: kern:  notice: [2022-01-17T14:07:54.057983495Z]: random: get_random_u64 called from __kmem_cache_create+0x30/0x57c with crng_init=0
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: trace event string verifier disabled
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: rcu: Hierarchical RCU implementation.
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: rcu: \x09RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4.
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: \x09Tracing variant of Tasks RCU enabled.
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: Root IRQ handler: gic_handle_irq
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: GIC: Using split EOI/Deactivate mode
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: arch_timer: cp15 timer(s) running at 54.00MHz (phys).
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057983495Z]: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns
192.168.15.202: kern:    info: [2022-01-17T14:07:54.057984495Z]: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
192.168.15.202: kern:    info: [2022-01-17T14:07:54.058585495Z]: Console: colour dummy device 80x25
192.168.15.202: kern:    info: [2022-01-17T14:07:54.059217495Z]: printk: console [tty0] enabled
192.168.15.202: kern:    info: [2022-01-17T14:07:54.059369495Z]: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000)
192.168.15.202: kern:    info: [2022-01-17T14:07:54.059402495Z]: pid_max: default: 32768 minimum: 301
192.168.15.202: kern:    info: [2022-01-17T14:07:54.059570495Z]: LSM: Security Framework initializing
192.168.15.202: kern:    info: [2022-01-17T14:07:54.059611495Z]: Yama: becoming mindful.
192.168.15.202: kern:    info: [2022-01-17T14:07:54.059810495Z]: Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
192.168.15.202: kern:    info: [2022-01-17T14:07:54.059940495Z]: Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
192.168.15.202: kern:    info: [2022-01-17T14:07:54.062533495Z]: rcu: Hierarchical SRCU implementation.
192.168.15.202: kern:    info: [2022-01-17T14:07:54.064192495Z]: Remapping and enabling EFI services.
192.168.15.202: kern:    info: [2022-01-17T14:07:54.064628495Z]: smp: Bringing up secondary CPUs ...
192.168.15.202: kern:    info: [2022-01-17T14:07:54.065317495Z]: Detected PIPT I-cache on CPU1
192.168.15.202: kern:    info: [2022-01-17T14:07:54.065393495Z]: CPU1: Booted secondary processor 0x0000000001 [0x410fd083]
192.168.15.202: kern:    info: [2022-01-17T14:07:54.066241495Z]: Detected PIPT I-cache on CPU2
192.168.15.202: kern:    info: [2022-01-17T14:07:54.066290495Z]: CPU2: Booted secondary processor 0x0000000002 [0x410fd083]
192.168.15.202: kern:    info: [2022-01-17T14:07:54.067022495Z]: Detected PIPT I-cache on CPU3
192.168.15.202: kern:    info: [2022-01-17T14:07:54.067070495Z]: CPU3: Booted secondary processor 0x0000000003 [0x410fd083]
192.168.15.202: kern:    info: [2022-01-17T14:07:54.067170495Z]: smp: Brought up 1 node, 4 CPUs
192.168.15.202: kern:    info: [2022-01-17T14:07:54.067247495Z]: SMP: Total of 4 processors activated.
192.168.15.202: kern:    info: [2022-01-17T14:07:54.067263495Z]: CPU features: detected: 32-bit EL0 Support
192.168.15.202: kern:    info: [2022-01-17T14:07:54.067276495Z]: CPU features: detected: 32-bit EL1 Support
192.168.15.202: kern:    info: [2022-01-17T14:07:54.067293495Z]: CPU features: detected: CRC32 instructions
192.168.15.202: kern:    info: [2022-01-17T14:07:54.067499495Z]: CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching
192.168.15.202: kern:    info: [2022-01-17T14:07:54.113198495Z]: CPU: All CPU(s) started at EL2
192.168.15.202: kern:    info: [2022-01-17T14:07:54.113337495Z]: alternatives: patching kernel code
192.168.15.202: kern:    info: [2022-01-17T14:07:54.117176495Z]: devtmpfs: initialized
192.168.15.202: kern:  notice: [2022-01-17T14:07:54.129732495Z]: Registered cp15_barrier emulation handler
192.168.15.202: kern:  notice: [2022-01-17T14:07:54.129787495Z]: Registered setend emulation handler
192.168.15.202: kern:    info: [2022-01-17T14:07:54.129810495Z]: KASLR enabled
192.168.15.202: kern:    info: [2022-01-17T14:07:54.130125495Z]: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
192.168.15.202: kern:    info: [2022-01-17T14:07:54.130236495Z]: futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
192.168.15.202: kern:    info: [2022-01-17T14:07:54.132792495Z]: pinctrl core: initialized pinctrl subsystem
192.168.15.202: kern:    info: [2022-01-17T14:07:54.134052495Z]: SMBIOS 3.0 present.
192.168.15.202: kern:    info: [2022-01-17T14:07:54.134150495Z]: DMI: Unknown Unknown Product/Unknown Product, BIOS 2021.10 10/01/2021
192.168.15.202: kern:    info: [2022-01-17T14:07:54.134914495Z]: NET: Registered PF_NETLINK/PF_ROUTE protocol family
192.168.15.202: kern:    info: [2022-01-17T14:07:54.140872495Z]: DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations
192.168.15.202: kern:    info: [2022-01-17T14:07:54.142216495Z]: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
192.168.15.202: kern:    info: [2022-01-17T14:07:54.144382495Z]: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
192.168.15.202: kern:    info: [2022-01-17T14:07:54.144638495Z]: audit: initializing netlink subsys (disabled)
192.168.15.202: kern:  notice: [2022-01-17T14:07:54.144974495Z]: audit: type=2000 audit(0.084:1): state=initialized audit_enabled=0 res=1
192.168.15.202: kern:    info: [2022-01-17T14:07:54.146663495Z]: thermal_sys: Registered thermal governor 'step_wise'
192.168.15.202: kern:    info: [2022-01-17T14:07:54.146676495Z]: thermal_sys: Registered thermal governor 'user_space'
192.168.15.202: kern:    info: [2022-01-17T14:07:54.146931495Z]: cpuidle: using governor ladder
192.168.15.202: kern:    info: [2022-01-17T14:07:54.146979495Z]: cpuidle: using governor menu
192.168.15.202: kern:    info: [2022-01-17T14:07:54.147767495Z]: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
192.168.15.202: kern:    info: [2022-01-17T14:07:54.147907495Z]: ASID allocator initialised with 32768 entries
192.168.15.202: kern:    info: [2022-01-17T14:07:54.149715495Z]: Serial: AMBA PL011 UART driver
192.168.15.202: kern:    info: [2022-01-17T14:07:54.199691495Z]: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
192.168.15.202: kern:    info: [2022-01-17T14:07:54.199737495Z]: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages
192.168.15.202: kern:    info: [2022-01-17T14:07:54.199756495Z]: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
192.168.15.202: kern:    info: [2022-01-17T14:07:54.199774495Z]: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages
192.168.15.202: kern:    info: [2022-01-17T14:07:54.202654495Z]: cryptd: max_cpu_qlen set to 1000
192.168.15.202: kern:    info: [2022-01-17T14:07:54.206976495Z]: ACPI: Interpreter disabled.
192.168.15.202: kern:    info: [2022-01-17T14:07:54.208765495Z]: iommu: Default domain type: Translated 
192.168.15.202: kern:    info: [2022-01-17T14:07:54.208790495Z]: iommu: DMA domain TLB invalidation policy: strict mode 
192.168.15.202: kern:    info: [2022-01-17T14:07:54.209221495Z]: vgaarb: loaded
192.168.15.202: kern:  notice: [2022-01-17T14:07:54.209997495Z]: SCSI subsystem initialized
192.168.15.202: kern:   debug: [2022-01-17T14:07:54.210240495Z]: libata version 3.00 loaded.
192.168.15.202: kern:    info: [2022-01-17T14:07:54.210618495Z]: usbcore: registered new interface driver usbfs
192.168.15.202: kern:    info: [2022-01-17T14:07:54.210690495Z]: usbcore: registered new interface driver hub
192.168.15.202: kern:    info: [2022-01-17T14:07:54.210744495Z]: usbcore: registered new device driver usb
192.168.15.202: kern: warning: [2022-01-17T14:07:54.210965495Z]: usb_phy_generic phy: supply vcc not found, using dummy regulator
 SUBSYSTEM=platform
 DEVICE=+platform:phy
192.168.15.202: kern:    info: [2022-01-17T14:07:54.211960495Z]: pps_core: LinuxPPS API ver. 1 registered
192.168.15.202: kern:    info: [2022-01-17T14:07:54.211981495Z]: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
192.168.15.202: kern:    info: [2022-01-17T14:07:54.212018495Z]: PTP clock support registered
192.168.15.202: kern:    info: [2022-01-17T14:07:54.212413495Z]: EDAC MC: Ver: 3.0.0
192.168.15.202: kern:    info: [2022-01-17T14:07:54.213255495Z]: Registered efivars operations
192.168.15.202: kern:    info: [2022-01-17T14:07:54.213962495Z]: Advanced Linux Sound Architecture Driver Initialized.
192.168.15.202: kern:    info: [2022-01-17T14:07:54.214924495Z]: NET: Registered PF_ATMPVC protocol family
192.168.15.202: kern:    info: [2022-01-17T14:07:54.214947495Z]: NET: Registered PF_ATMSVC protocol family
192.168.15.202: kern:    info: [2022-01-17T14:07:54.215017495Z]: NetLabel: Initializing
192.168.15.202: kern:    info: [2022-01-17T14:07:54.215030495Z]: NetLabel:  domain hash size = 128
192.168.15.202: kern:    info: [2022-01-17T14:07:54.215043495Z]: NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
192.168.15.202: kern:    info: [2022-01-17T14:07:54.215130495Z]: NetLabel:  unlabeled traffic allowed by default
192.168.15.202: kern:    info: [2022-01-17T14:07:54.216561495Z]: clocksource: Switched to clocksource arch_sys_counter
192.168.15.202: kern:  notice: [2022-01-17T14:07:54.321503495Z]: VFS: Disk quotas dquot_6.6.0
192.168.15.202: kern:    info: [2022-01-17T14:07:54.321620495Z]: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
192.168.15.202: kern:    info: [2022-01-17T14:07:54.322160495Z]: simple-framebuffer 3e9f1000.framebuffer: framebuffer at 0x3e9f1000, 0x103000 bytes
 SUBSYSTEM=platform
 DEVICE=+platform:3e9f1000.framebuffer
192.168.15.202: kern:    info: [2022-01-17T14:07:54.322194495Z]: simple-framebuffer 3e9f1000.framebuffer: format=a8r8g8b8, mode=592x448x32, linelength=2368
 SUBSYSTEM=platform
 DEVICE=+platform:3e9f1000.framebuffer
192.168.15.202: kern:    info: [2022-01-17T14:07:54.325538495Z]: Console: switching to colour frame buffer device 74x28
192.168.15.202: kern:    info: [2022-01-17T14:07:54.328244495Z]: simple-framebuffer 3e9f1000.framebuffer: fb0: simplefb registered!
 SUBSYSTEM=platform
 DEVICE=+platform:3e9f1000.framebuffer
192.168.15.202: kern:    info: [2022-01-17T14:07:54.328625495Z]: pnp: PnP ACPI: disabled
192.168.15.202: kern:    info: [2022-01-17T14:07:54.340856495Z]: NET: Registered PF_INET protocol family
192.168.15.202: kern:    info: [2022-01-17T14:07:54.343494495Z]: IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear)
192.168.15.202: kern:    info: [2022-01-17T14:07:54.352473495Z]: tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear)
192.168.15.202: kern:    info: [2022-01-17T14:07:54.356599495Z]: TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear)
192.168.15.202: kern:    info: [2022-01-17T14:07:54.362013495Z]: TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear)
192.168.15.202: kern:    info: [2022-01-17T14:07:54.366617495Z]: TCP: Hash tables configured (established 65536 bind 65536)
192.168.15.202: kern:    info: [2022-01-17T14:07:54.369149495Z]: UDP hash table entries: 4096 (order: 5, 131072 bytes, linear)
192.168.15.202: kern:    info: [2022-01-17T14:07:54.373460495Z]: UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear)
192.168.15.202: kern:    info: [2022-01-17T14:07:54.377815495Z]: NET: Registered PF_UNIX/PF_LOCAL protocol family
192.168.15.202: kern:    info: [2022-01-17T14:07:54.380523495Z]: RPC: Registered named UNIX socket transport module.
192.168.15.202: kern:    info: [2022-01-17T14:07:54.382570495Z]: RPC: Registered udp transport module.
192.168.15.202: kern:    info: [2022-01-17T14:07:54.384523495Z]: RPC: Registered tcp transport module.
192.168.15.202: kern:    info: [2022-01-17T14:07:54.386397495Z]: RPC: Registered tcp NFSv4.1 backchannel transport module.
192.168.15.202: kern:    info: [2022-01-17T14:07:54.389262495Z]: PCI: CLS 0 bytes, default 64
192.168.15.202: kern:    info: [2022-01-17T14:07:54.391452495Z]: Trying to unpack rootfs image as initramfs...
192.168.15.202: kern:    info: [2022-01-17T14:07:55.363647495Z]: hw perfevents: enabled with armv8_cortex_a72 PMU driver, 7 counters available
192.168.15.202: kern:    info: [2022-01-17T14:07:55.367672495Z]: kvm [1]: IPA Size Limit: 44 bits
192.168.15.202: kern:    info: [2022-01-17T14:07:55.371967495Z]: kvm [1]: vgic interrupt IRQ9
192.168.15.202: kern:    info: [2022-01-17T14:07:55.374014495Z]: kvm [1]: Hyp mode initialized successfully
192.168.15.202: kern:  notice: [2022-01-17T14:07:55.382238495Z]: Initialise system trusted keyrings
192.168.15.202: kern:    info: [2022-01-17T14:07:55.384255495Z]: workingset: timestamp_bits=40 max_order=21 bucket_order=0
192.168.15.202: kern:    info: [2022-01-17T14:07:55.397463495Z]: squashfs: version 4.0 (2009/01/31) Phillip Lougher
192.168.15.202: kern:  notice: [2022-01-17T14:07:55.400609495Z]: NFS: Registering the id_resolver key type
192.168.15.202: kern:  notice: [2022-01-17T14:07:55.402351495Z]: Key type id_resolver registered
192.168.15.202: kern:  notice: [2022-01-17T14:07:55.403989495Z]: Key type id_legacy registered
192.168.15.202: kern:    info: [2022-01-17T14:07:55.405586495Z]: nfs4filelayout_init: NFSv4 File Layout Driver Registering...
192.168.15.202: kern:    info: [2022-01-17T14:07:55.408732495Z]: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
192.168.15.202: kern:  notice: [2022-01-17T14:07:55.413362495Z]: Key type cifs.spnego registered
192.168.15.202: kern:  notice: [2022-01-17T14:07:55.414959495Z]: Key type cifs.idmap registered
192.168.15.202: kern:    info: [2022-01-17T14:07:55.416766495Z]: fuse: init (API version 7.34)
192.168.15.202: kern:    info: [2022-01-17T14:07:55.418956495Z]: SGI XFS with ACLs, security attributes, no debug enabled
192.168.15.202: kern:    info: [2022-01-17T14:07:55.423279495Z]: ceph: loaded (mds proto 32)
192.168.15.202: kern:    info: [2022-01-17T14:07:55.489850495Z]: NET: Registered PF_ALG protocol family
192.168.15.202: kern:  notice: [2022-01-17T14:07:55.491505495Z]: Key type asymmetric registered
192.168.15.202: kern:  notice: [2022-01-17T14:07:55.493103495Z]: Asymmetric key parser 'x509' registered
192.168.15.202: kern:    info: [2022-01-17T14:07:55.494924495Z]: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)
192.168.15.202: kern:    info: [2022-01-17T14:07:55.498216495Z]: io scheduler mq-deadline registered
192.168.15.202: kern:    info: [2022-01-17T14:07:55.499837495Z]: io scheduler kyber registered
192.168.15.202: kern:     err: [2022-01-17T14:07:55.512866495Z]: pinctrl-bcm2835 fe200000.gpio: could not add GPIO chip
 SUBSYSTEM=platform
 DEVICE=+platform:fe200000.gpio
192.168.15.202: kern:    info: [2022-01-17T14:07:55.519208495Z]: shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
192.168.15.202: kern:    info: [2022-01-17T14:07:55.523961495Z]: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
 SUBSYSTEM=platform
 DEVICE=+platform:fd500000.pcie
192.168.15.202: kern:    info: [2022-01-17T14:07:55.527313495Z]: brcm-pcie fd500000.pcie:   No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
 SUBSYSTEM=platform
 DEVICE=+platform:fd500000.pcie
192.168.15.202: kern:    info: [2022-01-17T14:07:55.530824495Z]: brcm-pcie fd500000.pcie:      MEM 0x0600000000..0x063fffffff -> 0x00c0000000
 SUBSYSTEM=platform
 DEVICE=+platform:fd500000.pcie
192.168.15.202: kern:    info: [2022-01-17T14:07:55.534328495Z]: brcm-pcie fd500000.pcie:   IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000
 SUBSYSTEM=platform
 DEVICE=+platform:fd500000.pcie
192.168.15.202: kern:    info: [2022-01-17T14:07:55.598773495Z]: brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC)
 SUBSYSTEM=platform
 DEVICE=+platform:fd500000.pcie
192.168.15.202: kern:    info: [2022-01-17T14:07:55.601135495Z]: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
 SUBSYSTEM=platform
 DEVICE=+platform:fd500000.pcie
192.168.15.202: kern:    info: [2022-01-17T14:07:55.603053495Z]: pci_bus 0000:00: root bus resource [bus 00-ff]
 SUBSYSTEM=pci_bus
 DEVICE=+pci_bus:0000:00
192.168.15.202: kern:    info: [2022-01-17T14:07:55.604951495Z]: pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff])
 SUBSYSTEM=pci_bus
 DEVICE=+pci_bus:0000:00
192.168.15.202: kern:    info: [2022-01-17T14:07:55.608696495Z]: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
 SUBSYSTEM=pci
 DEVICE=+pci:0000:00:00.0
192.168.15.202: kern:    info: [2022-01-17T14:07:55.610702495Z]: pci 0000:00:00.0: PME# supported from D0 D3hot
 SUBSYSTEM=pci
 DEVICE=+pci:0000:00:00.0
192.168.15.202: kern:    info: [2022-01-17T14:07:55.617052495Z]: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
 SUBSYSTEM=pci
 DEVICE=+pci:0000:01:00.0
192.168.15.202: kern:    info: [2022-01-17T14:07:55.618981495Z]: pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
 SUBSYSTEM=pci
 DEVICE=+pci:0000:01:00.0
192.168.15.202: kern:    info: [2022-01-17T14:07:55.622710495Z]: pci 0000:01:00.0: PME# supported from D0 D3hot
 SUBSYSTEM=pci
 DEVICE=+pci:0000:01:00.0
192.168.15.202: kern:    info: [2022-01-17T14:07:55.644164495Z]: pci 0000:00:00.0: BAR 14: assigned [mem 0x600000000-0x6000fffff]
 SUBSYSTEM=pci
 DEVICE=+pci:0000:00:00.0
192.168.15.202: kern:    info: [2022-01-17T14:07:55.647956495Z]: pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
 SUBSYSTEM=pci
 DEVICE=+pci:0000:01:00.0
192.168.15.202: kern:    info: [2022-01-17T14:07:55.651715495Z]: pci 0000:00:00.0: PCI bridge to [bus 01]
 SUBSYSTEM=pci
 DEVICE=+pci:0000:00:00.0
192.168.15.202: kern:    info: [2022-01-17T14:07:55.653600495Z]: pci 0000:00:00.0:   bridge window [mem 0x600000000-0x6000fffff]
 SUBSYSTEM=pci
 DEVICE=+pci:0000:00:00.0
192.168.15.202: kern:    info: [2022-01-17T14:07:55.657482495Z]: pcieport 0000:00:00.0: enabling device (0000 -> 0002)
 SUBSYSTEM=pci
 DEVICE=+pci:0000:00:00.0
192.168.15.202: kern:    info: [2022-01-17T14:07:55.659549495Z]: pcieport 0000:00:00.0: PME: Signaling with IRQ 46
 SUBSYSTEM=pci
 DEVICE=+pci:0000:00:00.0
192.168.15.202: kern:    info: [2022-01-17T14:07:55.661746495Z]: pcieport 0000:00:00.0: AER: enabled with IRQ 46
 SUBSYSTEM=pci
 DEVICE=+pci:0000:00:00.0
192.168.15.202: kern:    info: [2022-01-17T14:07:55.665234495Z]: IPMI message handler: version 39.2
192.168.15.202: kern:    info: [2022-01-17T14:07:55.667038495Z]: ipmi device interface
192.168.15.202: kern:    info: [2022-01-17T14:07:55.668941495Z]: ipmi_si: IPMI System Interface driver
192.168.15.202: kern: warning: [2022-01-17T14:07:55.671029495Z]: ipmi_si: Unable to find any System Interface(s)
192.168.15.202: kern:    info: [2022-01-17T14:07:55.672691495Z]: IPMI poweroff: Copyright (C) 2004 MontaVista Software - IPMI Powerdown via sys_reboot
192.168.15.202: kern:    info: [2022-01-17T14:07:55.676850495Z]: EINJ: ACPI disabled.
192.168.15.202: kern:    info: [2022-01-17T14:07:55.697091495Z]: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
192.168.15.202: kern:    info: [2022-01-17T14:07:55.701769495Z]: Serial: AMBA driver
192.168.15.202: kern:    info: [2022-01-17T14:07:55.703511495Z]: SuperH (H)SCI(F) driver initialized
192.168.15.202: kern:    info: [2022-01-17T14:07:55.705995495Z]: msm_serial: driver initialized
192.168.15.202: kern:    info: [2022-01-17T14:07:55.708902495Z]: iproc-rng200 fe104000.rng: hwrng registered
 SUBSYSTEM=platform
 DEVICE=+platform:fe104000.rng
192.168.15.202: kern: warning: [2022-01-17T14:07:55.722000495Z]: cacheinfo: Unable to detect cache hierarchy for CPU 0
192.168.15.202: kern:    info: [2022-01-17T14:07:55.738695495Z]: brd: module loaded
192.168.15.202: kern:    info: [2022-01-17T14:07:55.749419495Z]: loop: module loaded
192.168.15.202: kern:    info: [2022-01-17T14:07:55.751627495Z]: rbd: loaded (major 252)
192.168.15.202: kern:    info: [2022-01-17T14:07:55.754492495Z]: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
 SUBSYSTEM=platform
 DEVICE=+platform:bcm2835-power
192.168.15.202: kern:    info: [2022-01-17T14:07:55.759376495Z]: Loading iSCSI transport class v2.0-870.
192.168.15.202: kern:  notice: [2022-01-17T14:07:55.762590495Z]: iscsi: registered transport (tcp)
192.168.15.202: kern:    info: [2022-01-17T14:07:55.764262495Z]: Microchip SmartPQI Driver (v2.1.10-020)
192.168.15.202: kern:    info: [2022-01-17T14:07:55.765908495Z]: megasas: 07.717.02.00-rc1
192.168.15.202: kern:    info: [2022-01-17T14:07:55.767549495Z]: mpt3sas version 39.100.00.00 loaded
192.168.15.202: kern:    info: [2022-01-17T14:07:55.779788495Z]: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information.
192.168.15.202: kern:    info: [2022-01-17T14:07:55.782943495Z]: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld <Jason@zx2c4.com>. All Rights Reserved.
192.168.15.202: kern:    info: [2022-01-17T14:07:55.790672495Z]: libphy: Fixed MDIO Bus: probed
192.168.15.202: kern:    info: [2022-01-17T14:07:55.799314495Z]: tun: Universal TUN/TAP device driver, 1.6
192.168.15.202: kern:    info: [2022-01-17T14:07:55.803589495Z]: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
 SUBSYSTEM=platform
 DEVICE=+platform:fd580000.ethernet
192.168.15.202: kern:    info: [2022-01-17T14:07:55.820580495Z]: libphy: bcmgenet MII bus: probed
192.168.15.202: kern:    info: [2022-01-17T14:07:56.365192495Z]: Freeing initrd memory: 44480K
192.168.15.202: kern:    info: [2022-01-17T14:07:56.429843495Z]: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
 SUBSYSTEM=platform
 DEVICE=+platform:unimac-mdio.-19
192.168.15.202: kern:    info: [2022-01-17T14:07:56.434619495Z]: e100: Intel(R) PRO/100 Network Driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.436315495Z]: e100: Copyright(c) 1999-2006 Intel Corporation
192.168.15.202: kern:    info: [2022-01-17T14:07:56.438093495Z]: e1000: Intel(R) PRO/1000 Network Driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.439718495Z]: e1000: Copyright (c) 1999-2006 Intel Corporation.
192.168.15.202: kern:    info: [2022-01-17T14:07:56.441493495Z]: e1000e: Intel(R) PRO/1000 Network Driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.443171495Z]: e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
192.168.15.202: kern:    info: [2022-01-17T14:07:56.444951495Z]: igb: Intel(R) Gigabit Ethernet Network Driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.446599495Z]: igb: Copyright (c) 2007-2014 Intel Corporation.
192.168.15.202: kern:    info: [2022-01-17T14:07:56.448325495Z]: igbvf: Intel(R) Gigabit Virtual Function Network Driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.450001495Z]: igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
192.168.15.202: kern:    info: [2022-01-17T14:07:56.451747495Z]: ixgbe: Intel(R) 10 Gigabit PCI Express Network Driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.453441495Z]: ixgbe: Copyright (c) 1999-2016 Intel Corporation.
192.168.15.202: kern:    info: [2022-01-17T14:07:56.455387495Z]: ixgbevf: Intel(R) 10 Gigabit PCI Express Virtual Function Network Driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.458741495Z]: ixgbevf: Copyright (c) 2009 - 2018 Intel Corporation.
192.168.15.202: kern:    info: [2022-01-17T14:07:56.460670495Z]: i40e: Intel(R) Ethernet Connection XL710 Network Driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.462352495Z]: i40e: Copyright (c) 2013 - 2019 Intel Corporation.
192.168.15.202: kern:    info: [2022-01-17T14:07:56.464274495Z]: ixgb: Intel(R) PRO/10GbE Network Driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.465976495Z]: ixgb: Copyright (c) 1999-2008 Intel Corporation.
192.168.15.202: kern:    info: [2022-01-17T14:07:56.467744495Z]: iavf: Intel(R) Ethernet Adaptive Virtual Function Network Driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.471068495Z]: Copyright (c) 2013 - 2018 Intel Corporation.
192.168.15.202: kern:    info: [2022-01-17T14:07:56.473581495Z]: sky2: driver version 1.30
192.168.15.202: kern:    info: [2022-01-17T14:07:56.476473495Z]: QLogic FastLinQ 4xxxx Core Module qed
192.168.15.202: kern:    info: [2022-01-17T14:07:56.478226495Z]: qede init: QLogic FastLinQ 4xxxx Ethernet Driver qede
192.168.15.202: kern:    info: [2022-01-17T14:07:56.483298495Z]: usbcore: registered new interface driver rtl8xxxu
192.168.15.202: kern:    info: [2022-01-17T14:07:56.485178495Z]: usbcore: registered new interface driver rndis_wlan
192.168.15.202: kern:    info: [2022-01-17T14:07:56.486874495Z]: VMware vmxnet3 virtual NIC driver - version 1.6.0.0-k-NAPI
192.168.15.202: kern:    info: [2022-01-17T14:07:56.488714495Z]: pegasus: Pegasus/Pegasus II USB Ethernet driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.490507495Z]: usbcore: registered new interface driver pegasus
192.168.15.202: kern:    info: [2022-01-17T14:07:56.492279495Z]: usbcore: registered new interface driver rtl8150
192.168.15.202: kern:    info: [2022-01-17T14:07:56.494020495Z]: usbcore: registered new interface driver r8152
192.168.15.202: kern:    info: [2022-01-17T14:07:56.495625495Z]: hso: drivers/net/usb/hso.c: Option Wireless
192.168.15.202: kern:    info: [2022-01-17T14:07:56.497320495Z]: usbcore: registered new interface driver hso
192.168.15.202: kern:    info: [2022-01-17T14:07:56.498975495Z]: usbcore: registered new interface driver lan78xx
192.168.15.202: kern:    info: [2022-01-17T14:07:56.500593495Z]: usbcore: registered new interface driver asix
192.168.15.202: kern:    info: [2022-01-17T14:07:56.502119495Z]: usbcore: registered new interface driver ax88179_178a
192.168.15.202: kern:    info: [2022-01-17T14:07:56.503598495Z]: usbcore: registered new interface driver cdc_ether
192.168.15.202: kern:    info: [2022-01-17T14:07:56.505027495Z]: usbcore: registered new interface driver dm9601
192.168.15.202: kern:    info: [2022-01-17T14:07:56.506385495Z]: usbcore: registered new interface driver CoreChips
192.168.15.202: kern:    info: [2022-01-17T14:07:56.507703495Z]: usbcore: registered new interface driver smsc75xx
192.168.15.202: kern:    info: [2022-01-17T14:07:56.509006495Z]: usbcore: registered new interface driver smsc95xx
192.168.15.202: kern:    info: [2022-01-17T14:07:56.510214495Z]: usbcore: registered new interface driver net1080
192.168.15.202: kern:    info: [2022-01-17T14:07:56.511335495Z]: usbcore: registered new interface driver plusb
192.168.15.202: kern:    info: [2022-01-17T14:07:56.512396495Z]: usbcore: registered new interface driver rndis_host
192.168.15.202: kern:    info: [2022-01-17T14:07:56.513439495Z]: usbcore: registered new interface driver cdc_subset
192.168.15.202: kern:    info: [2022-01-17T14:07:56.514447495Z]: usbcore: registered new interface driver zaurus
192.168.15.202: kern:    info: [2022-01-17T14:07:56.515394495Z]: usbcore: registered new interface driver MOSCHIP usb-ethernet driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.517137495Z]: usbcore: registered new interface driver int51x1
192.168.15.202: kern:    info: [2022-01-17T14:07:56.518102495Z]: usbcore: registered new interface driver kalmia
192.168.15.202: kern:    info: [2022-01-17T14:07:56.519000495Z]: usbcore: registered new interface driver ipheth
192.168.15.202: kern:    info: [2022-01-17T14:07:56.519845495Z]: usbcore: registered new interface driver sierra_net
192.168.15.202: kern:    info: [2022-01-17T14:07:56.520738495Z]: usbcore: registered new interface driver cx82310_eth
192.168.15.202: kern:    info: [2022-01-17T14:07:56.521611495Z]: usbcore: registered new interface driver cdc_ncm
192.168.15.202: kern:    info: [2022-01-17T14:07:56.522423495Z]: usbcore: registered new interface driver lg-vl600
192.168.15.202: kern:    info: [2022-01-17T14:07:56.523190495Z]: usbcore: registered new interface driver qmi_wwan
192.168.15.202: kern:    info: [2022-01-17T14:07:56.523969495Z]: usbcore: registered new interface driver ch9200
192.168.15.202: kern:    info: [2022-01-17T14:07:56.524974495Z]: VFIO - User Level meta-driver version: 0.3
192.168.15.202: kern:    info: [2022-01-17T14:07:56.527420495Z]: aoe: AoE v85 initialised.
192.168.15.202: kern:    info: [2022-01-17T14:07:56.531049495Z]: ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.531969495Z]: ehci-pci: EHCI PCI platform driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.533124495Z]: ehci-platform: EHCI generic platform driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.534320495Z]: ehci-orion: EHCI orion driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.535441495Z]: ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.536514495Z]: ohci-pci: OHCI PCI platform driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.537719495Z]: ohci-platform: OHCI generic platform driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.539572495Z]: usbcore: registered new interface driver cdc_acm
192.168.15.202: kern:    info: [2022-01-17T14:07:56.540785495Z]: cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
192.168.15.202: kern:    info: [2022-01-17T14:07:56.543308495Z]: usbcore: registered new interface driver cdc_wdm
192.168.15.202: kern:    info: [2022-01-17T14:07:56.544868495Z]: usbcore: registered new interface driver uas
192.168.15.202: kern:    info: [2022-01-17T14:07:56.546169495Z]: usbcore: registered new interface driver usb-storage
192.168.15.202: kern:    info: [2022-01-17T14:07:56.547456495Z]: usbcore: registered new interface driver ums-alauda
192.168.15.202: kern:    info: [2022-01-17T14:07:56.548781495Z]: usbcore: registered new interface driver ums-cypress
192.168.15.202: kern:    info: [2022-01-17T14:07:56.550073495Z]: usbcore: registered new interface driver ums-datafab
192.168.15.202: kern:    info: [2022-01-17T14:07:56.551354495Z]: usbcore: registered new interface driver ums_eneub6250
192.168.15.202: kern:    info: [2022-01-17T14:07:56.552706495Z]: usbcore: registered new interface driver ums-freecom
192.168.15.202: kern:    info: [2022-01-17T14:07:56.554005495Z]: usbcore: registered new interface driver ums-isd200
192.168.15.202: kern:    info: [2022-01-17T14:07:56.555285495Z]: usbcore: registered new interface driver ums-jumpshot
192.168.15.202: kern:    info: [2022-01-17T14:07:56.556589495Z]: usbcore: registered new interface driver ums-karma
192.168.15.202: kern:    info: [2022-01-17T14:07:56.557831495Z]: usbcore: registered new interface driver ums-onetouch
192.168.15.202: kern:    info: [2022-01-17T14:07:56.559038495Z]: usbcore: registered new interface driver ums-realtek
192.168.15.202: kern:    info: [2022-01-17T14:07:56.560171495Z]: usbcore: registered new interface driver ums-sddr09
192.168.15.202: kern:    info: [2022-01-17T14:07:56.561291495Z]: usbcore: registered new interface driver ums-sddr55
192.168.15.202: kern:    info: [2022-01-17T14:07:56.562330495Z]: usbcore: registered new interface driver ums-usbat
192.168.15.202: kern:    info: [2022-01-17T14:07:56.563347495Z]: usbcore: registered new interface driver mdc800
192.168.15.202: kern:    info: [2022-01-17T14:07:56.564226495Z]: mdc800: v0.7.5 (30/10/2000):USB Driver for Mustek MDC800 Digital Camera
192.168.15.202: kern:    info: [2022-01-17T14:07:56.566050495Z]: usbcore: registered new interface driver microtekX6
192.168.15.202: kern:    info: [2022-01-17T14:07:56.567149495Z]: usbcore: registered new interface driver ch341
192.168.15.202: kern:    info: [2022-01-17T14:07:56.568074495Z]: usbserial: USB Serial support registered for ch341-uart
192.168.15.202: kern:    info: [2022-01-17T14:07:56.569050495Z]: usbcore: registered new interface driver cp210x
192.168.15.202: kern:    info: [2022-01-17T14:07:56.569945495Z]: usbserial: USB Serial support registered for cp210x
192.168.15.202: kern:    info: [2022-01-17T14:07:56.570919495Z]: usbcore: registered new interface driver ftdi_sio
192.168.15.202: kern:    info: [2022-01-17T14:07:56.571908495Z]: usbserial: USB Serial support registered for FTDI USB Serial Device
192.168.15.202: kern:    info: [2022-01-17T14:07:56.574000495Z]: usbcore: registered new interface driver pl2303
192.168.15.202: kern:    info: [2022-01-17T14:07:56.575184495Z]: usbserial: USB Serial support registered for pl2303
192.168.15.202: kern:    info: [2022-01-17T14:07:56.576448495Z]: usbcore: registered new interface driver adutux
192.168.15.202: kern:    info: [2022-01-17T14:07:56.577762495Z]: usbcore: registered new interface driver appledisplay
192.168.15.202: kern:    info: [2022-01-17T14:07:56.579092495Z]: usbcore: registered new interface driver cypress_cy7c63
192.168.15.202: kern:    info: [2022-01-17T14:07:56.580428495Z]: usbcore: registered new interface driver cytherm
192.168.15.202: kern:    info: [2022-01-17T14:07:56.581780495Z]: usbcore: registered new interface driver emi26 - firmware loader
192.168.15.202: kern:    info: [2022-01-17T14:07:56.584376495Z]: usbcore: registered new interface driver emi62 - firmware loader
192.168.15.202: kern:    info: [2022-01-17T14:07:56.587223495Z]: ftdi_elan: driver ftdi-elan
192.168.15.202: kern:    info: [2022-01-17T14:07:56.588800495Z]: usbcore: registered new interface driver ftdi-elan
192.168.15.202: kern:    info: [2022-01-17T14:07:56.590473495Z]: usbcore: registered new interface driver idmouse
192.168.15.202: kern:    info: [2022-01-17T14:07:56.592061495Z]: usbcore: registered new interface driver iowarrior
192.168.15.202: kern:    info: [2022-01-17T14:07:56.593597495Z]: usbcore: registered new interface driver isight_firmware
192.168.15.202: kern:    info: [2022-01-17T14:07:56.595067495Z]: usbcore: registered new interface driver usblcd
192.168.15.202: kern:    info: [2022-01-17T14:07:56.596525495Z]: usbcore: registered new interface driver ldusb
192.168.15.202: kern:    info: [2022-01-17T14:07:56.597955495Z]: usbcore: registered new interface driver legousbtower
192.168.15.202: kern:    info: [2022-01-17T14:07:56.599332495Z]: usbcore: registered new interface driver usbtest
192.168.15.202: kern:    info: [2022-01-17T14:07:56.600662495Z]: usbcore: registered new interface driver usb_ehset_test
192.168.15.202: kern:    info: [2022-01-17T14:07:56.601936495Z]: usbcore: registered new interface driver trancevibrator
192.168.15.202: kern:    info: [2022-01-17T14:07:56.603159495Z]: usbcore: registered new interface driver usbsevseg
192.168.15.202: kern:    info: [2022-01-17T14:07:56.604320495Z]: usbcore: registered new interface driver yurex
192.168.15.202: kern:    info: [2022-01-17T14:07:56.605862495Z]: usbcore: registered new interface driver chaoskey
192.168.15.202: kern:    info: [2022-01-17T14:07:56.606932495Z]: usbcore: registered new interface driver sisusb
192.168.15.202: kern:    info: [2022-01-17T14:07:56.607918495Z]: usbcore: registered new interface driver lvs
192.168.15.202: kern:    info: [2022-01-17T14:07:56.611915495Z]: vhci_hcd vhci_hcd.0: USB/IP Virtual Host Controller
 SUBSYSTEM=platform
 DEVICE=+platform:vhci_hcd.0
192.168.15.202: kern:    info: [2022-01-17T14:07:56.613021495Z]: vhci_hcd vhci_hcd.0: new USB bus registered, assigned bus number 1
 SUBSYSTEM=platform
 DEVICE=+platform:vhci_hcd.0
192.168.15.202: kern:    info: [2022-01-17T14:07:56.615060495Z]: vhci_hcd: created sysfs vhci_hcd.0
192.168.15.202: kern:    info: [2022-01-17T14:07:56.616916495Z]: hub 1-0:1.0: USB hub found
 SUBSYSTEM=usb
 DEVICE=+usb:1-0:1.0
192.168.15.202: kern:    info: [2022-01-17T14:07:56.617943495Z]: hub 1-0:1.0: 8 ports detected
 SUBSYSTEM=usb
 DEVICE=+usb:1-0:1.0
192.168.15.202: kern:    info: [2022-01-17T14:07:56.619847495Z]: vhci_hcd vhci_hcd.0: USB/IP Virtual Host Controller
 SUBSYSTEM=platform
 DEVICE=+platform:vhci_hcd.0
192.168.15.202: kern:    info: [2022-01-17T14:07:56.620840495Z]: vhci_hcd vhci_hcd.0: new USB bus registered, assigned bus number 2
 SUBSYSTEM=platform
 DEVICE=+platform:vhci_hcd.0
192.168.15.202: kern:    info: [2022-01-17T14:07:56.622769495Z]: usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
 SUBSYSTEM=usb
 DEVICE=+usb:usb2
192.168.15.202: kern:    info: [2022-01-17T14:07:56.625656495Z]: hub 2-0:1.0: USB hub found
 SUBSYSTEM=usb
 DEVICE=+usb:2-0:1.0
192.168.15.202: kern:    info: [2022-01-17T14:07:56.626869495Z]: hub 2-0:1.0: 8 ports detected
 SUBSYSTEM=usb
 DEVICE=+usb:2-0:1.0
192.168.15.202: kern:    info: [2022-01-17T14:07:56.629477495Z]: usbcore: registered new device driver usbip-host
192.168.15.202: kern:    info: [2022-01-17T14:07:56.632152495Z]: mousedev: PS/2 mouse device common for all mice
192.168.15.202: kern:    info: [2022-01-17T14:07:56.636316495Z]: i2c_dev: i2c /dev entries driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.643982495Z]: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
 SUBSYSTEM=platform
 DEVICE=+platform:bcm2835-wdt
192.168.15.202: kern: warning: [2022-01-17T14:07:56.646912495Z]: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
192.168.15.202: kern:    info: [2022-01-17T14:07:56.650325495Z]: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com
192.168.15.202: kern:    info: [2022-01-17T14:07:56.656658495Z]: sdhci: Secure Digital Host Controller Interface driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.658439495Z]: sdhci: Copyright(c) Pierre Ossman
192.168.15.202: kern:    info: [2022-01-17T14:07:56.661203495Z]: Synopsys Designware Multimedia Card Interface Driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.664419495Z]: sdhci-pltfm: SDHCI platform and OF driver helper
192.168.15.202: kern:    info: [2022-01-17T14:07:56.668538495Z]: ledtrig-cpu: registered to indicate activity on CPUs
192.168.15.202: kern:    info: [2022-01-17T14:07:56.671324495Z]: No iBFT detected.
192.168.15.202: kern:     err: [2022-01-17T14:07:56.674070495Z]: efi-framebuffer efi-framebuffer.0: efifb: a framebuffer is already registered
 SUBSYSTEM=platform
 DEVICE=+platform:efi-framebuffer.0
192.168.15.202: kern: warning: [2022-01-17T14:07:56.677693495Z]: efi-framebuffer: probe of efi-framebuffer.0 failed with error -22
192.168.15.202: kern:    info: [2022-01-17T14:07:56.684123495Z]: hid: raw HID events driver (C) Jiri Kosina
192.168.15.202: kern:    info: [2022-01-17T14:07:56.688319495Z]: usbcore: registered new interface driver usbhid
192.168.15.202: kern:    info: [2022-01-17T14:07:56.690200495Z]: usbhid: USB HID core driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.693512495Z]: bcm2835-mbox fe00b880.mailbox: mailbox enabled
 SUBSYSTEM=platform
 DEVICE=+platform:fe00b880.mailbox
192.168.15.202: kern:    info: [2022-01-17T14:07:56.704600495Z]: NET: Registered PF_LLC protocol family
192.168.15.202: kern:    info: [2022-01-17T14:07:56.706542495Z]: GACT probability NOT on
192.168.15.202: kern:    info: [2022-01-17T14:07:56.708371495Z]: Mirror/redirect action on
192.168.15.202: kern:    info: [2022-01-17T14:07:56.710217495Z]: Simple TC action Loaded
192.168.15.202: kern:    info: [2022-01-17T14:07:56.712692495Z]: netem: version 1.3
192.168.15.202: kern:    info: [2022-01-17T14:07:56.714563495Z]: u32 classifier
192.168.15.202: kern:    info: [2022-01-17T14:07:56.716193495Z]:     input device check on
192.168.15.202: kern:    info: [2022-01-17T14:07:56.717794495Z]:     Actions configured
192.168.15.202: kern:    info: [2022-01-17T14:07:56.726525495Z]: xt_time: kernel timezone is -0000
192.168.15.202: kern:    info: [2022-01-17T14:07:56.728157495Z]: IPVS: Registered protocols (TCP, UDP)
192.168.15.202: kern:    info: [2022-01-17T14:07:56.729851495Z]: IPVS: Connection hash table configured (size=4096, memory=64Kbytes)
192.168.15.202: kern:    info: [2022-01-17T14:07:56.733036495Z]: IPVS: ipvs loaded.
192.168.15.202: kern:    info: [2022-01-17T14:07:56.734507495Z]: IPVS: [rr] scheduler registered.
192.168.15.202: kern:    info: [2022-01-17T14:07:56.735958495Z]: IPVS: [wrr] scheduler registered.
192.168.15.202: kern:    info: [2022-01-17T14:07:56.737416495Z]: IPVS: [lc] scheduler registered.
192.168.15.202: kern:    info: [2022-01-17T14:07:56.738783495Z]: IPVS: [sh] scheduler registered.
192.168.15.202: kern:    info: [2022-01-17T14:07:56.740220495Z]: ipip: IPv4 and MPLS over IPv4 tunneling driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.742212495Z]: gre: GRE over IPv4 demultiplexor driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.743602495Z]: Initializing XFRM netlink socket
192.168.15.202: kern:    info: [2022-01-17T14:07:56.745553495Z]: NET: Registered PF_INET6 protocol family
192.168.15.202: kern:    info: [2022-01-17T14:07:56.748604495Z]: Segment Routing with IPv6
192.168.15.202: kern:    info: [2022-01-17T14:07:56.749890495Z]: In-situ OAM (IOAM) with IPv6
192.168.15.202: kern:    info: [2022-01-17T14:07:56.751335495Z]: mip6: Mobile IPv6
192.168.15.202: kern:    info: [2022-01-17T14:07:56.752796495Z]: sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
192.168.15.202: kern:    info: [2022-01-17T14:07:56.755530495Z]: NET: Registered PF_PACKET protocol family
192.168.15.202: kern:  notice: [2022-01-17T14:07:56.757063495Z]: Bridge firewalling registered
192.168.15.202: kern:    info: [2022-01-17T14:07:56.758561495Z]: NET: Registered PF_APPLETALK protocol family
192.168.15.202: kern:    info: [2022-01-17T14:07:56.759930495Z]: NET: Registered PF_X25 protocol family
192.168.15.202: kern:    info: [2022-01-17T14:07:56.761292495Z]: X25: Linux Version 0.2
192.168.15.202: kern:    info: [2022-01-17T14:07:56.762707495Z]: RPC: Registered rdma transport module.
192.168.15.202: kern:    info: [2022-01-17T14:07:56.764045495Z]: RPC: Registered rdma backchannel transport module.
192.168.15.202: kern:    info: [2022-01-17T14:07:56.765545495Z]: l2tp_core: L2TP core driver, V2.0
192.168.15.202: kern:    info: [2022-01-17T14:07:56.766862495Z]: NET4: DECnet for Linux: V.2.5.68s (C) 1995-2003 Linux DECnet Project Team
192.168.15.202: kern:    info: [2022-01-17T14:07:56.769789495Z]: DECnet: Routing cache hash table of 1024 buckets, 16Kbytes
192.168.15.202: kern:    info: [2022-01-17T14:07:56.771243495Z]: NET: Registered PF_DECnet protocol family
192.168.15.202: kern:    info: [2022-01-17T14:07:56.772756495Z]: NET: Registered PF_PHONET protocol family
192.168.15.202: kern:    info: [2022-01-17T14:07:56.774378495Z]: 8021q: 802.1Q VLAN Support v1.8
192.168.15.202: kern:    info: [2022-01-17T14:07:56.785280495Z]: DCCP: Activated CCID 2 (TCP-like)
192.168.15.202: kern:    info: [2022-01-17T14:07:56.786963495Z]: DCCP: Activated CCID 3 (TCP-Friendly Rate Control)
192.168.15.202: kern:    info: [2022-01-17T14:07:56.789402495Z]: sctp: Hash tables configured (bind 256/256)
192.168.15.202: kern:    info: [2022-01-17T14:07:56.791719495Z]: NET: Registered PF_RDS protocol family
192.168.15.202: kern:    info: [2022-01-17T14:07:56.793196495Z]: tipc: Activated (version 2.0.0)
192.168.15.202: kern:    info: [2022-01-17T14:07:56.794816495Z]: NET: Registered PF_TIPC protocol family
192.168.15.202: kern:    info: [2022-01-17T14:07:56.796445495Z]: tipc: Started in single node mode
192.168.15.202: kern:    info: [2022-01-17T14:07:56.799819495Z]: NET: Registered PF_IEEE802154 protocol family
192.168.15.202: kern:  notice: [2022-01-17T14:07:56.801361495Z]: Key type dns_resolver registered
192.168.15.202: kern:  notice: [2022-01-17T14:07:56.802834495Z]: Key type ceph registered
192.168.15.202: kern:    info: [2022-01-17T14:07:56.804590495Z]: libceph: loaded (mon/osd proto 15/24)
192.168.15.202: kern:    info: [2022-01-17T14:07:56.806838495Z]: batman_adv: B.A.T.M.A.N. advanced 2021.3 (compatibility version 15) loaded
192.168.15.202: kern:    info: [2022-01-17T14:07:56.809826495Z]: openvswitch: Open vSwitch switching datapath
192.168.15.202: kern:    info: [2022-01-17T14:07:56.812151495Z]: NET: Registered PF_VSOCK protocol family
192.168.15.202: kern:    info: [2022-01-17T14:07:56.813935495Z]: mpls_gso: MPLS GSO support
192.168.15.202: kern:    info: [2022-01-17T14:07:56.816510495Z]: registered taskstats version 1
192.168.15.202: kern:  notice: [2022-01-17T14:07:56.818066495Z]: Loading compiled-in X.509 certificates
192.168.15.202: kern:  notice: [2022-01-17T14:07:56.823501495Z]: Loaded X.509 cert 'Sidero Labs, Inc.: Build time throw-away kernel key: 9093aafff06ba28e5828193dfd57d5a82d79a212'
192.168.15.202: kern:    info: [2022-01-17T14:07:56.826795495Z]: ima: No TPM chip found, activating TPM-bypass!
192.168.15.202: kern:    info: [2022-01-17T14:07:56.828345495Z]: ima: Allocated hash algorithm: sha512
192.168.15.202: kern:    info: [2022-01-17T14:07:56.829971495Z]: ima: No architecture policies found
192.168.15.202: kern:    info: [2022-01-17T14:07:56.838896495Z]: raspberrypi-firmware soc:firmware: Attached to firmware from 2022-01-05T12:29:20
 SUBSYSTEM=platform
 DEVICE=+platform:soc:firmware
192.168.15.202: kern:     err: [2022-01-17T14:07:56.845311495Z]: pinctrl-bcm2835 fe200000.gpio: could not add GPIO chip
 SUBSYSTEM=platform
 DEVICE=+platform:fe200000.gpio
192.168.15.202: kern:    info: [2022-01-17T14:07:57.036051495Z]: xhci_hcd 0000:01:00.0: xHCI Host Controller
 SUBSYSTEM=pci
 DEVICE=+pci:0000:01:00.0
192.168.15.202: kern:    info: [2022-01-17T14:07:57.036960495Z]: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 3
 SUBSYSTEM=pci
 DEVICE=+pci:0000:01:00.0
192.168.15.202: kern:    info: [2022-01-17T14:07:57.039116495Z]: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0000040000000890
 SUBSYSTEM=pci
 DEVICE=+pci:0000:01:00.0
192.168.15.202: kern:    info: [2022-01-17T14:07:57.042364495Z]: hub 3-0:1.0: USB hub found
 SUBSYSTEM=usb
 DEVICE=+usb:3-0:1.0
192.168.15.202: kern:    info: [2022-01-17T14:07:57.044182495Z]: hub 3-0:1.0: 1 port detected
 SUBSYSTEM=usb
 DEVICE=+usb:3-0:1.0
192.168.15.202: kern:    info: [2022-01-17T14:07:57.046572495Z]: xhci_hcd 0000:01:00.0: xHCI Host Controller
 SUBSYSTEM=pci
 DEVICE=+pci:0000:01:00.0
192.168.15.202: kern:    info: [2022-01-17T14:07:57.048302495Z]: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 4
 SUBSYSTEM=pci
 DEVICE=+pci:0000:01:00.0
192.168.15.202: kern:    info: [2022-01-17T14:07:57.050631495Z]: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
 SUBSYSTEM=pci
 DEVICE=+pci:0000:01:00.0
192.168.15.202: kern:    info: [2022-01-17T14:07:57.052016495Z]: hub 4-0:1.0: USB hub found
 SUBSYSTEM=usb
 DEVICE=+usb:4-0:1.0
192.168.15.202: kern:    info: [2022-01-17T14:07:57.052924495Z]: hub 4-0:1.0: 4 ports detected
 SUBSYSTEM=usb
 DEVICE=+usb:4-0:1.0
192.168.15.202: kern:     err: [2022-01-17T14:07:57.155444495Z]: pinctrl-bcm2835 fe200000.gpio: could not add GPIO chip
 SUBSYSTEM=platform
 DEVICE=+platform:fe200000.gpio
192.168.15.202: kern:     err: [2022-01-17T14:07:57.161028495Z]: pinctrl-bcm2835 fe200000.gpio: could not add GPIO chip
 SUBSYSTEM=platform
 DEVICE=+platform:fe200000.gpio
192.168.15.202: kern:    info: [2022-01-17T14:07:57.168267495Z]: printk: console [netcon0] enabled
192.168.15.202: kern:    info: [2022-01-17T14:07:57.169144495Z]: netconsole: network logging started
192.168.15.202: kern:    info: [2022-01-17T14:07:57.170301495Z]: rdma_rxe: loaded
192.168.15.202: kern:  notice: [2022-01-17T14:07:57.171297495Z]: cfg80211: Loading compiled-in X.509 certificates for regulatory database
192.168.15.202: kern:  notice: [2022-01-17T14:07:57.174214495Z]: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
192.168.15.202: kern: warning: [2022-01-17T14:07:57.175170495Z]: platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
 SUBSYSTEM=platform
 DEVICE=+platform:regulatory.0
192.168.15.202: kern:    info: [2022-01-17T14:07:57.176863495Z]: cfg80211: failed to load regulatory.db
192.168.15.202: kern:    info: [2022-01-17T14:07:57.204933495Z]: ALSA device list:

frezbo commented 2 years ago

Update: after adding earlycon=pl011,0xfe201000, with next firmware, serial console with u-boot+grub combo also works, but ttyAMA0 is not present under /dev. Falling back to 1.20211029 firmware, ttyAMA0 is present under /dev

pelwell commented 2 years ago

The UART driver is not probing successfully - you should be seeing messages like this:

[    0.524770] uart-pl011 fe201000.serial: there is not valid maps for state default
[    0.524983] uart-pl011 fe201000.serial: cts_event_workaround enabled
[    0.525045] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 36, base_baud = 0) is a PL011 rev2
[    0.525303] printk: console [ttyAMA0]: printing thread started
[    0.525312] printk: console [ttyAMA0] enabled

pelwell commented 2 years ago

It might save time if you can run dtc -I fs /proc/device-tree > tx.txt and then upload the output somewhere (don't be alarmed by the warnings).

pelwell commented 2 years ago

Taking a step back, your problem is likely to be due to mixing downstream dtbs with an upstream (torvalds/linux) kernel. Try with the bcm2711-rpi-4-b.dtb you get from the upstream kernel build, or use the downstream kernel.

frezbo commented 2 years ago

Uploaded the output of dtc -I fs /proc/device-tree at https://gist.github.com/frezbo/a158688ad5aca8a7cfba81cb9fa1a1ff

frezbo commented 2 years ago

I tried the bcm2711-rpi-4-b.dtb from the upstream kernel, but then it fails to load the disable-bt overlay


MESS:00:00:07.964630:0: dtb_file 'bcm2711-rpi-4-b.dtb'
MESS:00:00:07.968162:0: brfs: File read: /mfs/sd/bcm2711-rpi-4-b.dtb
MESS:00:00:07.972737:0: Loading 'bcm2711-rpi-4-b.dtb' to 0x100 size 0x67a9
MESS:00:00:07.989268:0: brfs: File read: 26537 bytes
MESS:00:00:07.999023:0: brfs: File read: /mfs/sd/overlays/overlay_map.dtb
MESS:00:00:08.037595:0: brfs: File read: 1703 bytes
MESS:00:00:08.041239:0: brfs: File read: /mfs/sd/config.txt
MESS:00:00:08.045230:0: brfs: File read: 598 bytes
MESS:00:00:08.051440:0: brfs: File read: /mfs/sd/overlays/disable-bt.dtbo
MESS:00:00:08.058311:0: dterror: no symbols found
MESS:00:00:08.060102:0: Failed to resolve overlay 'disable-bt'
MESS:00:00:08.073133:0: dterror: no symbols found
MESS:00:00:08.076980:0: dterror: no symbols found
MESS:00:00:08.083235:0: brfs: File read: 1073 bytes
MESS:00:00:08.086542:0: Failed to open command line file 'cmdline.txt'
MESS:00:00:08.093551:0: dterror: no symbols found
MESS:00:00:08.097857:0: dterror: no symbols found
MESS:00:00:08.110037:0: dterror: no symbols found
MESS:00:00:08.115964:0: dterror: no symbols found
MESS:00:00:08.121673:0: dterror: no symbols found
MESS:00:00:08.156264:0: dterror: no symbols found
MESS:00:00:08.170604:0: dterror: no symbols found
MESS:00:00:08.199227:0: brfs: File read: /mfs/sd/u-boot.bin
MESS:00:00:08.201693:0: Loading 'u-boot.bin' to 0x80000 size 0x8f848
MESS:00:00:08.207778:0: Device tree loaded to 0x2eff9000 (size 0x6f9a)
MESS:00:00:08.215757:0: uart: Set PL011 baud rate to 103448.300000 Hz
MESS:00:00:08.223088:0: uart: Baud rate change done...
MESS:00:00:08.225110:0:

pelwell commented 2 years ago

The DT dump shows that the DT node for UART0 is enabled, and yet the driver isn't starting (other than the AMBA PL011 UART driver message from the init method). It's likely that there is a driver required by the downstream dtb that isn't present in the upstream kernel, and that is preventing the UART0 device instance from being created.

The upstream kernel doesn't acknowledge DT overlays - if you want to change the configuration you have to write a new dts/dtb file for it. The standard upstream configuration is to have UART0 for Bluetooth and UART1 for the console, with no way to switch. Is there some reason you have to use the upstream kernel rather than one of ours?

frezbo commented 2 years ago

Thanks for taking time to look through this. We can't use downstream rpi kernel since out product is based on upstream kernel and generic (talos.dev). Initially I got the PoE fan working by using upstream kernel and combining bcm2711-rpi-4-b.dts with the poe overlay. Since rpi-5.15 has the upstream driver and the right overlay it just worked. (Sorry for the tangent on the PoE hat, it was what initially made me use the next branch of firmware). But it's strange that the upstream kernel works fine with the 1.20211029 firmware and serial console is only broken when the next branch is used. As a workaround I can add earlycon=pl011,0xfe201000 to the cmdline

pelwell commented 2 years ago

The earlycon workaround is only useful for kernel log output - /dev/ttyAMA0 won't be available for anything else. If you don't care about /dev/ttyAMA0 then use the upstream dtb as is without the earlycon patch and you should get serial output from /dev/ttyS0 instead - be sure to change your cmdline.txt to say "console=ttyS0,115200".

frezbo commented 2 years ago

yeh, just the kernel logs

raspberrypi / firmware

ttyAMA0/serial0 not working when using firmware from `next` #1682