search

coreos / coreos-installer

Installer for CoreOS disk images
https://coreos.github.io/coreos-installer/
Apache License 2.0
222 stars 93 forks source link

`download -d` improperly decompresses initramfs and doesn't notice #633

Closed raballew closed 3 years ago

raballew commented 3 years ago

Describe the bug

I am trying to iPXE boot the Raspberry Pi with FCOS. At this stage I was able to successfully kick of the process but it times out waiting for device /dev/disk/by-label/boot

Reproduction steps

# prepare SD card
curl -X GET https://github.com/ipxe/pipxe/releases/download/v20191103/sdcard.img -o sdcard.img -L
sudo dd if=sdcard.img of=/dev/sdX bs=4M conv=fsync

# prepare files
podman run --privileged --pull=always --rm -v ${PWD}:/data -w /data quay.io/coreos/coreos-installer:release download -f pxe --architecture aarch64 -d

# add ipxe config
cat <<EOT >> fcos.ipxe
#!ipxe
set VERSION 34.20210904.3.0
kernel tftp://${next-server}/fedora-coreos-${VERSION}-live-kernel-aarch64 initrd=main coreos.live.rootfs_url=tftp://${next-server}/fedora-coreos-${VERSION}-live-rootfs.aarch64.img ignition.firstboot ignition.platform.id=metal ignition.config.url=fcos.ign systemd.unified_cgroup_hierarchy=0
initrd --name main tftp://${next-server}/fedora-coreos-${VERSION}-live-initramfs.aarch64.img
boot
EOT

# move files to TFTP boot directory
mv fedora-coreos-* /var/lib/tftpboot/
mv fcos.ipxe /var/lib/tftpboot

# configure DHCP, TFTP and other tool so that the Raspberry fetches fcos.ipxe
# power on device

Expected behavior Job dev-disk-by\x2dlabel-boot.device/start should not time out.

Actual behavior For all details see the attached journalctl -b log. But in general, first this issue occurs but it can not be fixed by adding dtparam=sd_poll_once to config.txt of piPXE as I tried this with my own build of piPXE. The problem with this is, that the CoreOS kernel doesn't have any of the optional DT overlays like sd_poll_once so adding does nothing.

Sep 24 09:50:14 localhost kernel: mmc1: Timeout waiting for hardware cmd interrupt.
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: ============ SDHCI REGISTER DUMP ===========
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Sys addr:  0xf7400040 | Version:  0x00001002
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Blk size:  0x00007040 | Blk cnt:  0x00000000
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Argument:  0x50480000 | Trn mode: 0x00000013
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Present:   0x1fff0001 | Host ctl: 0x00000005
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Power:     0x0000000f | Blk gap:  0x00000080
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Wake-up:   0x00000000 | Clock:    0x00000000
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Timeout:   0x00000000 | Int stat: 0x00000000
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Int enab:  0x03ff108b | Sig enab: 0x03ff108b
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: ACmd stat: 0x00000000 | Slot int: 0x00000000
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Caps:      0x45ee6432 | Caps_1:   0x0000a525
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Cmd:       0x0000371a | Max curr: 0x00080008
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Resp[0]:   0x00000900 | Resp[1]:  0x00e7177f
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Resp[2]:   0x325b5900 | Resp[3]:  0x00400e00
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Host ctl2: 0x00000000
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: ============================================

Followed by a timeout of dev-disk-by\x2dlabel-boot.device/start.

Sep 24 09:51:32 localhost systemd[1]: dev-disk-by\x2dlabel-boot.device: Job dev-disk-by\x2dlabel-boot.device/start timed out.
Sep 24 09:51:32 localhost systemd[1]: Timed out waiting for device /dev/disk/by-label/boot.
Sep 24 09:51:32 localhost systemd[1]: Dependency failed for CoreOS Boot Edit.
Sep 24 09:51:32 localhost systemd[1]: Dependency failed for Ignition Boot Disk Setup.
Sep 24 09:51:32 localhost systemd[1]: Dependency failed for Ignition Complete.
Sep 24 09:51:32 localhost systemd[1]: Dependency failed for Initrd Default Target.

System details

Ignition config

{
  "ignition": {
    "version": "3.3.0"
  },
  "passwd": {
    "users": [
      {
        "name": "core",
        "sshAuthorizedKeys": [
          "ssh-rsa ..."
        ]
      }
    ]
  }
}

Additional information

-- Journal begins at Fri 2021-09-24 09:50:02 UTC, ends at Fri 2021-09-24 09:52:17 UTC. --
Sep 24 09:50:02 localhost kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083]
Sep 24 09:50:02 localhost kernel: Linux version 5.13.13-200.fc34.aarch64 (mockbuild@buildvm-a64-04.iad2.fedoraproject.org) (gcc (GCC) 11.2.1 20210728 (Red Hat 11.2.1-1), GNU ld version 2.35.2-5.fc34) coreos/fedora-coreos-tracker#1 SMP Thu Aug 26 16:51:14 UTC 2021
Sep 24 09:50:02 localhost kernel: efi: EFI v2.70 by EDK2
Sep 24 09:50:02 localhost kernel: efi: ACPI 2.0=0x33e70018 SMBIOS=0x372c0000 SMBIOS 3.0=0x33d90000 MEMATTR=0x35b25018 RNG=0x373dd098 MEMRESERVE=0x33f43c18 
Sep 24 09:50:02 localhost kernel: efi: seeding entropy pool
Sep 24 09:50:02 localhost kernel: ACPI: Early table checksum verification disabled
Sep 24 09:50:02 localhost kernel: ACPI: RSDP 0x0000000033E70018 000024 (v02 RPIFDN)
Sep 24 09:50:02 localhost kernel: ACPI: XSDT 0x0000000033E7FE98 000074 (v01 RPIFDN RPI4     00000200      01000013)
Sep 24 09:50:02 localhost kernel: ACPI: FACP 0x0000000033E7E998 000114 (v06 RPIFDN RPI4     00000200 EDK2 00000300)
Sep 24 09:50:02 localhost kernel: ACPI: DSDT 0x0000000033E77518 002298 (v02 RPIFDN RPI      00000002 INTL 20190509)
Sep 24 09:50:02 localhost kernel: ACPI: CSRT 0x0000000033E7FA98 000169 (v00 RPIFDN RPI4     00000200 EDK2 00000300)
Sep 24 09:50:02 localhost kernel: ACPI: DBG2 0x0000000033E7FD18 000061 (v00 RPIFDN RPI4     00000200 EDK2 00000300)
Sep 24 09:50:02 localhost kernel: ACPI: GTDT 0x0000000033E7F998 000068 (v03 RPIFDN RPI4     00000200 EDK2 00000300)
Sep 24 09:50:02 localhost kernel: ACPI: IORT 0x0000000033E7F598 0000B7 (v00 RPIFDN RPI4     00000200 EDK2 00000300)
Sep 24 09:50:02 localhost kernel: ACPI: APIC 0x0000000033E7F698 000184 (v05 RPIFDN RPI4     00000200 EDK2 00000300)
Sep 24 09:50:02 localhost kernel: ACPI: PPTT 0x0000000033E7EB18 000184 (v02 RPIFDN RPI4     00000200 EDK2 00000300)
Sep 24 09:50:02 localhost kernel: ACPI: SSDT 0x0000000033E7ED98 000277 (v02 RPIFDN RPI4EMMC 00000002 INTL 20190509)
Sep 24 09:50:02 localhost kernel: ACPI: SSDT 0x0000000033E7F198 000244 (v05 RPIFDN RPI4XHCI 00000002 INTL 20190509)
Sep 24 09:50:02 localhost kernel: ACPI: BGRT 0x0000000033E7FE18 000038 (v01 INTEL  EDK2     00000002      01000013)
Sep 24 09:50:02 localhost kernel: NUMA: Failed to initialise from firmware
Sep 24 09:50:02 localhost kernel: NUMA: Faking a node at [mem 0x0000000000000000-0x00000001ffffffff]
Sep 24 09:50:02 localhost kernel: NUMA: NODE_DATA [mem 0x1fefed8c0-0x1ff003fff]
Sep 24 09:50:02 localhost kernel: Zone ranges:
Sep 24 09:50:02 localhost kernel:   DMA      [mem 0x0000000000000000-0x000000003fffffff]
Sep 24 09:50:02 localhost kernel:   DMA32    [mem 0x0000000040000000-0x00000000ffffffff]
Sep 24 09:50:02 localhost kernel:   Normal   [mem 0x0000000100000000-0x00000001ffffffff]
Sep 24 09:50:02 localhost kernel:   Device   empty
Sep 24 09:50:02 localhost kernel: Movable zone start for each node
Sep 24 09:50:02 localhost kernel: Early memory node ranges
Sep 24 09:50:02 localhost kernel:   node   0: [mem 0x0000000000000000-0x00000000001fffff]
Sep 24 09:50:02 localhost kernel:   node   0: [mem 0x0000000000200000-0x0000000033a26fff]
Sep 24 09:50:02 localhost kernel:   node   0: [mem 0x0000000033a27000-0x0000000033e6ffff]
Sep 24 09:50:02 localhost kernel:   node   0: [mem 0x0000000033e70000-0x0000000033e7ffff]
Sep 24 09:50:02 localhost kernel:   node   0: [mem 0x0000000033e80000-0x0000000033f3ffff]
Sep 24 09:50:02 localhost kernel:   node   0: [mem 0x0000000033f40000-0x000000003721ffff]
Sep 24 09:50:02 localhost kernel:   node   0: [mem 0x0000000037220000-0x00000000372affff]
Sep 24 09:50:02 localhost kernel:   node   0: [mem 0x00000000372b0000-0x00000000372bffff]
Sep 24 09:50:02 localhost kernel:   node   0: [mem 0x00000000372c0000-0x00000000373dffff]
Sep 24 09:50:02 localhost kernel:   node   0: [mem 0x00000000373e0000-0x000000003b3fffff]
Sep 24 09:50:02 localhost kernel:   node   0: [mem 0x0000000040000000-0x00000000fbffffff]
Sep 24 09:50:02 localhost kernel:   node   0: [mem 0x0000000100000000-0x00000001ffffffff]
Sep 24 09:50:02 localhost kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001ffffffff]
Sep 24 09:50:02 localhost kernel: On node 0 totalpages: 2061312
Sep 24 09:50:02 localhost kernel:   DMA zone: 3792 pages used for memmap
Sep 24 09:50:02 localhost kernel:   DMA zone: 0 pages reserved
Sep 24 09:50:02 localhost kernel:   DMA zone: 242688 pages, LIFO batch:63
Sep 24 09:50:02 localhost kernel:   DMA32 zone: 12288 pages used for memmap
Sep 24 09:50:02 localhost kernel:   DMA32 zone: 770048 pages, LIFO batch:63
Sep 24 09:50:02 localhost kernel:   Normal zone: 16384 pages used for memmap
Sep 24 09:50:02 localhost kernel:   Normal zone: 1048576 pages, LIFO batch:63
Sep 24 09:50:02 localhost kernel: cma: Reserved 64 MiB at 0x000000002f000000
Sep 24 09:50:02 localhost kernel: psci: probing for conduit method from ACPI.
Sep 24 09:50:02 localhost kernel: psci: PSCIv1.1 detected in firmware.
Sep 24 09:50:02 localhost kernel: psci: Using standard PSCI v0.2 function IDs
Sep 24 09:50:02 localhost kernel: psci: MIGRATE_INFO_TYPE not supported.
Sep 24 09:50:02 localhost kernel: psci: SMC Calling Convention v1.2
Sep 24 09:50:02 localhost kernel: ACPI: SRAT not present
Sep 24 09:50:02 localhost kernel: percpu: Embedded 32 pages/cpu s91480 r8192 d31400 u131072
Sep 24 09:50:02 localhost kernel: pcpu-alloc: s91480 r8192 d31400 u131072 alloc=32*4096
Sep 24 09:50:02 localhost kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
Sep 24 09:50:02 localhost kernel: Detected PIPT I-cache on CPU0
Sep 24 09:50:02 localhost kernel: CPU features: detected: Spectre-v2
Sep 24 09:50:02 localhost kernel: CPU features: detected: Spectre-v3a
Sep 24 09:50:02 localhost kernel: CPU features: kernel page table isolation forced ON by KASLR
Sep 24 09:50:02 localhost kernel: CPU features: detected: Kernel page table isolation (KPTI)
Sep 24 09:50:02 localhost kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923
Sep 24 09:50:02 localhost kernel: Built 1 zonelists, mobility grouping on.  Total pages: 2028848
Sep 24 09:50:02 localhost kernel: Policy zone: Normal
Sep 24 09:50:02 localhost kernel: Kernel command line: fedora-coreos-34.20210904.3.0-live-kernel-aarch64 initrd=main coreos.live.rootfs_url=tftp://10.42.0.1/fedora-coreos-34.20210904.3.0-live-rootfs.aarch64.img ignition.firstboot ignition.platform.id=metal ignition.config.url=fcos.ign systemd.unified_cgroup_hierarchy=0
Sep 24 09:50:02 localhost kernel: Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)
Sep 24 09:50:02 localhost kernel: Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
Sep 24 09:50:02 localhost kernel: mem auto-init: stack:off, heap alloc:off, heap free:off
Sep 24 09:50:02 localhost kernel: software IO TLB: mapped [mem 0x0000000037400000-0x000000003b400000] (64MB)
Sep 24 09:50:02 localhost kernel: Memory: 7747448K/8245248K available (15680K kernel code, 4030K rwdata, 11008K rodata, 6912K init, 8675K bss, 432264K reserved, 65536K cma-reserved)
Sep 24 09:50:02 localhost kernel: random: get_random_u64 called from kmem_cache_open+0x30/0x320 with crng_init=0
Sep 24 09:50:02 localhost kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
Sep 24 09:50:02 localhost kernel: ftrace: allocating 51603 entries in 202 pages
Sep 24 09:50:02 localhost kernel: ftrace: allocated 202 pages with 4 groups
Sep 24 09:50:02 localhost kernel: trace event string verifier disabled
Sep 24 09:50:02 localhost kernel: rcu: Hierarchical RCU implementation.
Sep 24 09:50:02 localhost kernel: rcu:         RCU restricting CPUs from NR_CPUS=4096 to nr_cpu_ids=4.
Sep 24 09:50:02 localhost kernel:         Trampoline variant of Tasks RCU enabled.
Sep 24 09:50:02 localhost kernel:         Rude variant of Tasks RCU enabled.
Sep 24 09:50:02 localhost kernel:         Tracing variant of Tasks RCU enabled.
Sep 24 09:50:02 localhost kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
Sep 24 09:50:02 localhost kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
Sep 24 09:50:02 localhost kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
Sep 24 09:50:02 localhost kernel: Root IRQ handler: gic_handle_irq
Sep 24 09:50:02 localhost kernel: GIC: Using split EOI/Deactivate mode
Sep 24 09:50:02 localhost kernel: rcu:         Offload RCU callbacks from CPUs: (none).
Sep 24 09:50:02 localhost kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys).
Sep 24 09:50:02 localhost kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns
Sep 24 09:50:02 localhost kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
Sep 24 09:50:02 localhost kernel: Console: colour dummy device 80x25
Sep 24 09:50:02 localhost kernel: printk: console [tty0] enabled
Sep 24 09:50:02 localhost kernel: ACPI: Core revision 20210331
Sep 24 09:50:02 localhost kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000)
Sep 24 09:50:02 localhost kernel: pid_max: default: 32768 minimum: 301
Sep 24 09:50:02 localhost kernel: LSM: Security Framework initializing
Sep 24 09:50:02 localhost kernel: Yama: becoming mindful.
Sep 24 09:50:02 localhost kernel: SELinux:  Initializing.
Sep 24 09:50:02 localhost kernel: LSM support for eBPF active
Sep 24 09:50:02 localhost kernel: landlock: Up and running.
Sep 24 09:50:02 localhost kernel: Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
Sep 24 09:50:02 localhost kernel: Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
Sep 24 09:50:02 localhost kernel: rcu: Hierarchical SRCU implementation.
Sep 24 09:50:02 localhost kernel: Remapping and enabling EFI services.
Sep 24 09:50:02 localhost kernel: smp: Bringing up secondary CPUs ...
Sep 24 09:50:02 localhost kernel: Detected PIPT I-cache on CPU1
Sep 24 09:50:02 localhost kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083]
Sep 24 09:50:02 localhost kernel: Detected PIPT I-cache on CPU2
Sep 24 09:50:02 localhost kernel: CPU2: Booted secondary processor 0x0000000002 [0x410fd083]
Sep 24 09:50:02 localhost kernel: Detected PIPT I-cache on CPU3
Sep 24 09:50:02 localhost kernel: CPU3: Booted secondary processor 0x0000000003 [0x410fd083]
Sep 24 09:50:02 localhost kernel: smp: Brought up 1 node, 4 CPUs
Sep 24 09:50:02 localhost kernel: SMP: Total of 4 processors activated.
Sep 24 09:50:02 localhost kernel: CPU features: detected: 32-bit EL0 Support
Sep 24 09:50:02 localhost kernel: CPU features: detected: 32-bit EL1 Support
Sep 24 09:50:02 localhost kernel: CPU features: detected: CRC32 instructions
Sep 24 09:50:02 localhost kernel: CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching
Sep 24 09:50:02 localhost kernel: CPU: All CPU(s) started at EL2
Sep 24 09:50:02 localhost kernel: alternatives: patching kernel code
Sep 24 09:50:02 localhost kernel: devtmpfs: initialized
Sep 24 09:50:02 localhost kernel: Registered cp15_barrier emulation handler
Sep 24 09:50:02 localhost kernel: Registered setend emulation handler
Sep 24 09:50:02 localhost kernel: KASLR enabled
Sep 24 09:50:02 localhost kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
Sep 24 09:50:02 localhost kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
Sep 24 09:50:02 localhost kernel: pinctrl core: initialized pinctrl subsystem
Sep 24 09:50:02 localhost kernel: SMBIOS 3.3.0 present.
Sep 24 09:50:02 localhost kernel: DMI: Raspberry Pi Foundation Raspberry Pi 4 Model B/Raspberry Pi 4 Model B, BIOS EDK2-DEV 09/24/2021
Sep 24 09:50:02 localhost kernel: NET: Registered protocol family 16
Sep 24 09:50:02 localhost kernel: DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations
Sep 24 09:50:02 localhost kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
Sep 24 09:50:02 localhost kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
Sep 24 09:50:02 localhost kernel: audit: initializing netlink subsys (disabled)
Sep 24 09:50:02 localhost kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1
Sep 24 09:50:02 localhost kernel: thermal_sys: Registered thermal governor 'fair_share'
Sep 24 09:50:02 localhost kernel: thermal_sys: Registered thermal governor 'step_wise'
Sep 24 09:50:02 localhost kernel: thermal_sys: Registered thermal governor 'user_space'
Sep 24 09:50:02 localhost kernel: cpuidle: using governor menu
Sep 24 09:50:02 localhost kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
Sep 24 09:50:02 localhost kernel: ASID allocator initialised with 32768 entries
Sep 24 09:50:02 localhost kernel: ACPI: bus type PCI registered
Sep 24 09:50:02 localhost kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
Sep 24 09:50:02 localhost kernel: Serial: AMBA PL011 UART driver
Sep 24 09:50:02 localhost kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
Sep 24 09:50:02 localhost kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages
Sep 24 09:50:02 localhost kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
Sep 24 09:50:02 localhost kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages
Sep 24 09:50:02 localhost kernel: cryptd: max_cpu_qlen set to 1000
Sep 24 09:50:02 localhost kernel: alg: No test for 842 (842-generic)
Sep 24 09:50:02 localhost kernel: alg: No test for 842 (842-scomp)
Sep 24 09:50:02 localhost kernel: wait_for_initramfs() called before rootfs_initcalls
Sep 24 09:50:02 localhost kernel: raid6: skip pq benchmark and using algorithm neonx8
Sep 24 09:50:02 localhost kernel: raid6: using neon recovery algorithm
Sep 24 09:50:02 localhost kernel: fbcon: Taking over console
Sep 24 09:50:02 localhost kernel: ACPI: Added _OSI(Module Device)
Sep 24 09:50:02 localhost kernel: ACPI: Added _OSI(Processor Device)
Sep 24 09:50:02 localhost kernel: ACPI: Added _OSI(3.0 _SCP Extensions)
Sep 24 09:50:02 localhost kernel: ACPI: Added _OSI(Processor Aggregator Device)
Sep 24 09:50:02 localhost kernel: ACPI: Added _OSI(Linux-Dell-Video)
Sep 24 09:50:02 localhost kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
Sep 24 09:50:02 localhost kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
Sep 24 09:50:02 localhost kernel: ACPI: 3 ACPI AML tables successfully acquired and loaded
Sep 24 09:50:02 localhost kernel: ACPI: Interpreter enabled
Sep 24 09:50:02 localhost kernel: ACPI: Using GIC for interrupt routing
Sep 24 09:50:02 localhost kernel: BCM2837:00: ttyAMA0 at MMIO 0xfe201000 (irq = 22, base_baud = 0) is a SBSA
Sep 24 09:50:02 localhost kernel: serial serial0: tty port ttyAMA0 registered
Sep 24 09:50:02 localhost kernel: iommu: Default domain type: Translated 
Sep 24 09:50:02 localhost kernel: vgaarb: loaded
Sep 24 09:50:02 localhost kernel: SCSI subsystem initialized
Sep 24 09:50:02 localhost kernel: libata version 3.00 loaded.
Sep 24 09:50:02 localhost kernel: ACPI: bus type USB registered
Sep 24 09:50:02 localhost kernel: usbcore: registered new interface driver usbfs
Sep 24 09:50:02 localhost kernel: usbcore: registered new interface driver hub
Sep 24 09:50:02 localhost kernel: usbcore: registered new device driver usb
Sep 24 09:50:02 localhost kernel: pps_core: LinuxPPS API ver. 1 registered
Sep 24 09:50:02 localhost kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
Sep 24 09:50:02 localhost kernel: PTP clock support registered
Sep 24 09:50:02 localhost kernel: EDAC MC: Ver: 3.0.0
Sep 24 09:50:02 localhost kernel: Registered efivars operations
Sep 24 09:50:02 localhost kernel: NetLabel: Initializing
Sep 24 09:50:02 localhost kernel: NetLabel:  domain hash size = 128
Sep 24 09:50:02 localhost kernel: NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
Sep 24 09:50:02 localhost kernel: NetLabel:  unlabeled traffic allowed by default
Sep 24 09:50:02 localhost kernel: clocksource: Switched to clocksource arch_sys_counter
Sep 24 09:50:02 localhost kernel: VFS: Disk quotas dquot_6.6.0
Sep 24 09:50:02 localhost kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
Sep 24 09:50:02 localhost kernel: pnp: PnP ACPI init
Sep 24 09:50:02 localhost kernel: pnp: PnP ACPI: found 0 devices
Sep 24 09:50:02 localhost kernel: NET: Registered protocol family 2
Sep 24 09:50:02 localhost kernel: IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear)
Sep 24 09:50:02 localhost kernel: tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear)
Sep 24 09:50:02 localhost kernel: TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear)
Sep 24 09:50:02 localhost kernel: TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear)
Sep 24 09:50:02 localhost kernel: TCP: Hash tables configured (established 65536 bind 65536)
Sep 24 09:50:02 localhost kernel: MPTCP token hash table entries: 8192 (order: 5, 196608 bytes, linear)
Sep 24 09:50:02 localhost kernel: UDP hash table entries: 4096 (order: 5, 131072 bytes, linear)
Sep 24 09:50:02 localhost kernel: UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear)
Sep 24 09:50:02 localhost kernel: NET: Registered protocol family 1
Sep 24 09:50:02 localhost kernel: NET: Registered protocol family 44
Sep 24 09:50:02 localhost kernel: PCI: CLS 0 bytes, default 64
Sep 24 09:50:02 localhost kernel: Trying to unpack rootfs image as initramfs...
Sep 24 09:50:02 localhost kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available
Sep 24 09:50:02 localhost kernel: kvm [1]: IPA Size Limit: 44 bits
Sep 24 09:50:02 localhost kernel: kvm [1]: vgic interrupt IRQ9
Sep 24 09:50:02 localhost kernel: kvm [1]: Hyp mode initialized successfully
Sep 24 09:50:02 localhost kernel: Freeing initrd memory: 141352K
Sep 24 09:50:02 localhost kernel: Initialise system trusted keyrings
Sep 24 09:50:02 localhost kernel: Key type blacklist registered
Sep 24 09:50:02 localhost kernel: workingset: timestamp_bits=37 max_order=21 bucket_order=0
Sep 24 09:50:02 localhost kernel: zbud: loaded
Sep 24 09:50:02 localhost kernel: integrity: Platform Keyring initialized
Sep 24 09:50:02 localhost kernel: NET: Registered protocol family 38
Sep 24 09:50:02 localhost kernel: xor: measuring software checksum speed
Sep 24 09:50:02 localhost kernel:    8regs           :  6106 MB/sec
Sep 24 09:50:02 localhost kernel:    32regs          :  7232 MB/sec
Sep 24 09:50:02 localhost kernel:    arm64_neon      :  6356 MB/sec
Sep 24 09:50:02 localhost kernel: xor: using function: 32regs (7232 MB/sec)
Sep 24 09:50:02 localhost kernel: Key type asymmetric registered
Sep 24 09:50:02 localhost kernel: Asymmetric key parser 'x509' registered
Sep 24 09:50:02 localhost kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244)
Sep 24 09:50:02 localhost kernel: io scheduler mq-deadline registered
Sep 24 09:50:02 localhost kernel: io scheduler kyber registered
Sep 24 09:50:02 localhost kernel: io scheduler bfq registered
Sep 24 09:50:02 localhost kernel: atomic64_test: passed
Sep 24 09:50:02 localhost kernel: efifb: probing for efifb
Sep 24 09:50:02 localhost kernel: efifb: framebuffer at 0x3e29a000, using 9600k, total 9600k
Sep 24 09:50:02 localhost kernel: efifb: mode is 1920x1280x32, linelength=7680, pages=1
Sep 24 09:50:02 localhost kernel: efifb: scrolling: redraw
Sep 24 09:50:02 localhost kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0
Sep 24 09:50:02 localhost kernel: Console: switching to colour frame buffer device 240x80
Sep 24 09:50:02 localhost kernel: fb0: EFI VGA frame buffer device
Sep 24 09:50:02 localhost kernel: thermal LNXTHERM:00: registered as thermal_zone0
Sep 24 09:50:02 localhost kernel: ACPI: thermal: Thermal Zone [TZ00] (53 C)
Sep 24 09:50:02 localhost kernel: Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled
Sep 24 09:50:02 localhost kernel: msm_serial: driver initialized
Sep 24 09:50:02 localhost kernel: libphy: Fixed MDIO Bus: probed
Sep 24 09:50:02 localhost kernel: ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
Sep 24 09:50:02 localhost kernel: ehci-pci: EHCI PCI platform driver
Sep 24 09:50:02 localhost kernel: usbcore: registered new interface driver usbserial_generic
Sep 24 09:50:02 localhost kernel: usbserial: USB Serial support registered for generic
Sep 24 09:50:02 localhost kernel: mousedev: PS/2 mouse device common for all mice
Sep 24 09:50:02 localhost kernel: rtc-efi rtc-efi.0: registered as rtc0
Sep 24 09:50:02 localhost kernel: rtc-efi rtc-efi.0: setting system clock to 2021-09-24T09:50:01 UTC (1632477001)
Sep 24 09:50:02 localhost kernel: device-mapper: uevent: version 1.0.3
Sep 24 09:50:02 localhost kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com
Sep 24 09:50:02 localhost kernel: ledtrig-cpu: registered to indicate activity on CPUs
Sep 24 09:50:02 localhost kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping ....
Sep 24 09:50:02 localhost kernel: hid: raw HID events driver (C) Jiri Kosina
Sep 24 09:50:02 localhost kernel: usbcore: registered new interface driver usbhid
Sep 24 09:50:02 localhost kernel: usbhid: USB HID core driver
Sep 24 09:50:02 localhost kernel: drop_monitor: Initializing network drop monitor service
Sep 24 09:50:02 localhost kernel: Initializing XFRM netlink socket
Sep 24 09:50:02 localhost kernel: NET: Registered protocol family 10
Sep 24 09:50:02 localhost kernel: Segment Routing with IPv6
Sep 24 09:50:02 localhost kernel: RPL Segment Routing with IPv6
Sep 24 09:50:02 localhost kernel: mip6: Mobile IPv6
Sep 24 09:50:02 localhost kernel: NET: Registered protocol family 17
Sep 24 09:50:02 localhost kernel: registered taskstats version 1
Sep 24 09:50:02 localhost kernel: Loading compiled-in X.509 certificates
Sep 24 09:50:02 localhost kernel: Loaded X.509 cert 'Fedora kernel signing key: c09cbe8f82bc59fd1197a512ba8742b8c55dd36a'
Sep 24 09:50:02 localhost kernel: zswap: loaded using pool lzo/zbud
Sep 24 09:50:02 localhost kernel: page_owner is disabled
Sep 24 09:50:02 localhost kernel: Key type ._fscrypt registered
Sep 24 09:50:02 localhost kernel: Key type .fscrypt registered
Sep 24 09:50:02 localhost kernel: Key type fscrypt-provisioning registered
Sep 24 09:50:02 localhost kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=yes
Sep 24 09:50:02 localhost kernel: Key type encrypted registered
Sep 24 09:50:02 localhost kernel: ima: No TPM chip found, activating TPM-bypass!
Sep 24 09:50:02 localhost kernel: Loading compiled-in module X.509 certificates
Sep 24 09:50:02 localhost kernel: Loaded X.509 cert 'Fedora kernel signing key: c09cbe8f82bc59fd1197a512ba8742b8c55dd36a'
Sep 24 09:50:02 localhost kernel: ima: Allocated hash algorithm: sha256
Sep 24 09:50:02 localhost kernel: ima: No architecture policies found
Sep 24 09:50:02 localhost kernel: evm: Initialising EVM extended attributes:
Sep 24 09:50:02 localhost kernel: evm: security.selinux
Sep 24 09:50:02 localhost kernel: evm: security.ima
Sep 24 09:50:02 localhost kernel: evm: security.capability
Sep 24 09:50:02 localhost kernel: evm: HMAC attrs: 0x1
Sep 24 09:50:02 localhost kernel: Freeing unused kernel memory: 6912K
Sep 24 09:50:02 localhost kernel: Checked W+X mappings: passed, no W+X pages found
Sep 24 09:50:02 localhost kernel: rodata_test: all tests were successful
Sep 24 09:50:02 localhost kernel: Run /init as init process
Sep 24 09:50:02 localhost kernel:   with arguments:
Sep 24 09:50:02 localhost kernel:     /init
Sep 24 09:50:02 localhost kernel:   with environment:
Sep 24 09:50:02 localhost kernel:     HOME=/
Sep 24 09:50:02 localhost kernel:     TERM=linux
Sep 24 09:50:02 localhost systemd-journald[268]: Missed 5 kernel messages
Sep 24 09:50:02 localhost kernel: random: systemd: uninitialized urandom read (16 bytes read)
Sep 24 09:50:02 localhost systemd-journald[268]: Missed 1 kernel messages
Sep 24 09:50:02 localhost kernel: random: ln: uninitialized urandom read (6 bytes read)
Sep 24 09:50:02 localhost systemd-journald[268]: Missed 1 kernel messages
Sep 24 09:50:02 localhost kernel: random: systemd: uninitialized urandom read (16 bytes read)
Sep 24 09:50:02 localhost systemd-journald[268]: Journal started
Sep 24 09:50:02 localhost systemd-journald[268]: Runtime Journal (/run/log/journal/e6f84c8acbb74f33bb29876638f1f961) is 8.0M, max 155.4M, 147.4M free.
Sep 24 09:50:02 localhost audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=afterburn-network-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:02 localhost audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=afterburn-network-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:02 localhost systemd-journald[268]: Missed 27 kernel messages
Sep 24 09:50:02 localhost kernel: audit: type=1130 audit(1632477002.319:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=afterburn-network-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:02 localhost systemd-journald[268]: Missed 1 kernel messages
Sep 24 09:50:02 localhost kernel: audit: type=1131 audit(1632477002.319:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=afterburn-network-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:02 localhost kernel: fuse: init (API version 7.34)
Sep 24 09:50:02 localhost systemd-modules-load[269]: Inserted module 'fuse'
Sep 24 09:50:02 localhost audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:02 localhost systemd[1]: Finished CoreOS: Touch /run/agetty.reload.
Sep 24 09:50:02 localhost systemd-journald[268]: Missed 1 kernel messages
Sep 24 09:50:02 localhost kernel: audit: type=1130 audit(1632477002.339:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:02 localhost audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=coreos-touch-run-agetty comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:02 localhost systemd[1]: Finished Create List of Static Device Nodes.
Sep 24 09:50:02 localhost systemd-journald[268]: Missed 1 kernel messages
Sep 24 09:50:02 localhost kernel: audit: type=1130 audit(1632477002.349:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=coreos-touch-run-agetty comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:02 localhost audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:02 localhost systemd[1]: Finished Load Kernel Modules.
Sep 24 09:50:02 localhost systemd-journald[268]: Missed 1 kernel messages
Sep 24 09:50:02 localhost kernel: audit: type=1130 audit(1632477002.359:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:02 localhost audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:02 localhost systemd[1]: Finished Setup Virtual Console.
Sep 24 09:50:02 localhost systemd-journald[268]: Missed 1 kernel messages
Sep 24 09:50:02 localhost kernel: audit: type=1130 audit(1632477002.369:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:02 localhost audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:02 localhost systemd-journald[268]: Missed 1 kernel messages
Sep 24 09:50:02 localhost kernel: audit: type=1130 audit(1632477002.379:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:02 localhost systemd[1]: Starting dracut ask for additional cmdline parameters...
Sep 24 09:50:02 localhost systemd[1]: Starting Apply Kernel Variables...
Sep 24 09:50:02 localhost systemd[1]: Starting Create Static Device Nodes in /dev...
Sep 24 09:50:02 localhost systemd[1]: Starting Create Volatile Files and Directories...
Sep 24 09:50:02 localhost systemd[1]: Finished Apply Kernel Variables.
Sep 24 09:50:02 localhost audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:02 localhost systemd[1]: Finished dracut ask for additional cmdline parameters.
Sep 24 09:50:02 localhost systemd-journald[268]: Missed 5 kernel messages
Sep 24 09:50:02 localhost kernel: audit: type=1130 audit(1632477002.459:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:02 localhost audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:02 localhost systemd[1]: Finished Create Static Device Nodes in /dev.
Sep 24 09:50:02 localhost systemd-journald[268]: Missed 1 kernel messages
Sep 24 09:50:02 localhost kernel: audit: type=1130 audit(1632477002.469:10): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:02 localhost audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:02 localhost systemd[1]: Finished Create Volatile Files and Directories.
Sep 24 09:50:02 localhost audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:02 localhost systemd[1]: Starting dracut cmdline hook...
Sep 24 09:50:02 localhost dracut-cmdline[289]: dracut-34.20210904.3.0 (CoreOS) dracut-055-3.fc34
Sep 24 09:50:02 localhost dracut-cmdline[289]: Using kernel command line parameters:  rd.driver.pre=btrfs ip=dhcp,dhcp6 rd.driver.pre=dm_multipath   fedora-coreos-34.20210904.3.0-live-kernel-aarch64 initrd=main coreos.live.rootfs_url=tftp://10.42.0.1/fedora-coreos-34.20210904.3.0-live-rootfs.aarch64.img ignition.firstboot ignition.platform.id=metal ignition.config.url=fcos.ign systemd.unified_cgroup_hierarchy=0
Sep 24 09:50:03 localhost systemd[1]: Finished dracut cmdline hook.
Sep 24 09:50:03 localhost audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:03 localhost systemd[1]: Starting dracut pre-udev hook...
Sep 24 09:50:03 localhost systemd[1]: Finished dracut pre-udev hook.
Sep 24 09:50:03 localhost audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:03 localhost audit: BPF prog-id=5 op=LOAD
Sep 24 09:50:03 localhost audit: BPF prog-id=6 op=LOAD
Sep 24 09:50:03 localhost systemd[1]: Starting Rule-based Manager for Device Events and Files...
Sep 24 09:50:03 localhost systemd[1]: Started Rule-based Manager for Device Events and Files.
Sep 24 09:50:03 localhost audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:03 localhost systemd[1]: Starting dracut pre-trigger hook...
Sep 24 09:50:03 localhost dracut-pre-trigger[417]: rd.md=0: removing MD RAID activation
Sep 24 09:50:04 localhost systemd[1]: Finished dracut pre-trigger hook.
Sep 24 09:50:04 localhost audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:04 localhost systemd[1]: Starting Coldplug All udev Devices...
Sep 24 09:50:04 localhost systemd[1]: Finished Coldplug All udev Devices.
Sep 24 09:50:04 localhost audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:04 localhost systemd[1]: Starting Wait for udev To Complete Device Initialization...
Sep 24 09:50:04 localhost systemd-journald[268]: Missed 16 kernel messages
Sep 24 09:50:04 localhost kernel: sdhci: Secure Digital Host Controller Interface driver
Sep 24 09:50:04 localhost kernel: sdhci: Copyright(c) Pierre Ossman
Sep 24 09:50:04 localhost kernel: sdhci-pltfm: SDHCI platform and OF driver helper
Sep 24 09:50:04 localhost kernel: xhci-hcd PNP0D10:00: xHCI Host Controller
Sep 24 09:50:04 localhost kernel: mmc1 bounce up to 128 segments into one, max segment size 65536 bytes
Sep 24 09:50:04 localhost kernel: xhci-hcd PNP0D10:00: new USB bus registered, assigned bus number 1
Sep 24 09:50:04 localhost kernel: xhci-hcd PNP0D10:00: hcc params 0x002841eb hci version 0x100 quirks 0x0000000000010010
Sep 24 09:50:04 localhost kernel: dwc2 BCM2848:00: supply vusb_d not found, using dummy regulator
Sep 24 09:50:04 localhost kernel: xhci-hcd PNP0D10:00: irq 27, io mem 0x600000000
Sep 24 09:50:04 localhost kernel: bcmgenet BCM6E4E:00: GENET 5.0 EPHY: 0x0000
Sep 24 09:50:04 localhost kernel: mmc0: SDHCI controller on BCM2847:00 [BCM2847:00] using PIO
Sep 24 09:50:04 localhost kernel: mmc1: SDHCI controller on BRCME88C:00 [BRCME88C:00] using DMA
Sep 24 09:50:04 localhost kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.13
Sep 24 09:50:04 localhost kernel: dwc2 BCM2848:00: supply vusb_a not found, using dummy regulator
Sep 24 09:50:04 localhost kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Sep 24 09:50:04 localhost kernel: mmc0: queuing unknown CIS tuple 0x80 (2 bytes)
Sep 24 09:50:04 localhost kernel: usb usb1: Product: xHCI Host Controller
Sep 24 09:50:04 localhost kernel: mmc0: queuing unknown CIS tuple 0x80 (3 bytes)
Sep 24 09:50:04 localhost kernel: usb usb1: Manufacturer: Linux 5.13.13-200.fc34.aarch64 xhci-hcd
Sep 24 09:50:04 localhost kernel: mmc0: queuing unknown CIS tuple 0x80 (3 bytes)
Sep 24 09:50:04 localhost kernel: hub 1-0:1.0: 1 port detected
Sep 24 09:50:04 localhost kernel: xhci-hcd PNP0D10:00: xHCI Host Controller
Sep 24 09:50:04 localhost kernel: xhci-hcd PNP0D10:00: new USB bus registered, assigned bus number 2
Sep 24 09:50:04 localhost kernel: xhci-hcd PNP0D10:00: Host supports USB 3.0 SuperSpeed
Sep 24 09:50:04 localhost systemd-udevd[466]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
Sep 24 09:50:04 localhost kernel: usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
Sep 24 09:50:04 localhost systemd-journald[268]: Missed 1 kernel messages
Sep 24 09:50:04 localhost kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.13
Sep 24 09:50:04 localhost kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Sep 24 09:50:04 localhost kernel: random: fast init done
Sep 24 09:50:04 localhost kernel: usb usb2: Product: xHCI Host Controller
Sep 24 09:50:04 localhost kernel: mmc0: new high speed SDIO card at address 0001
Sep 24 09:50:04 localhost kernel: usb usb2: Manufacturer: Linux 5.13.13-200.fc34.aarch64 xhci-hcd
Sep 24 09:50:04 localhost kernel: usb usb2: SerialNumber: PNP0D10:00
Sep 24 09:50:04 localhost kernel: hub 2-0:1.0: USB hub found
Sep 24 09:50:04 localhost kernel: hub 2-0:1.0: 4 ports detected
Sep 24 09:50:04 localhost udevadm[473]: systemd-udev-settle.service is deprecated. Please fix multipathd.service, multipathd-configure.service not to pull it in.
Sep 24 09:50:04 localhost systemd-journald[268]: Missed 1 kernel messages
Sep 24 09:50:04 localhost kernel: dwc2 BCM2848:00: dwc2_check_params: Invalid parameter g_np_tx_fifo_size=1024
Sep 24 09:50:04 localhost kernel: dwc2 BCM2848:00: EPs: 8, dedicated fifos, 4080 entries in SPRAM
Sep 24 09:50:04 localhost kernel: dwc2 BCM2848:00: DWC OTG Controller
Sep 24 09:50:04 localhost kernel: dwc2 BCM2848:00: new USB bus registered, assigned bus number 3
Sep 24 09:50:04 localhost kernel: dwc2 BCM2848:00: irq 13, io mem 0xfe980000
Sep 24 09:50:04 localhost kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.13
Sep 24 09:50:04 localhost kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Sep 24 09:50:04 localhost kernel: usb usb3: Product: DWC OTG Controller
Sep 24 09:50:04 localhost kernel: usb usb3: Manufacturer: Linux 5.13.13-200.fc34.aarch64 dwc2_hsotg
Sep 24 09:50:04 localhost kernel: usb usb3: SerialNumber: BCM2848:00
Sep 24 09:50:04 localhost kernel: hub 3-0:1.0: USB hub found
Sep 24 09:50:04 localhost kernel: hub 3-0:1.0: 1 port detected
Sep 24 09:50:04 localhost kernel: usb 1-1: new high-speed USB device number 2 using xhci-hcd
Sep 24 09:50:05 localhost kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
Sep 24 09:50:05 localhost kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
Sep 24 09:50:05 localhost kernel: usb 1-1: Product: USB2.0 Hub
Sep 24 09:50:05 localhost kernel: hub 1-1:1.0: USB hub found
Sep 24 09:50:05 localhost kernel: hub 1-1:1.0: 4 ports detected
Sep 24 09:50:05 localhost kernel: usb 2-1: new SuperSpeed USB device number 2 using xhci-hcd
Sep 24 09:50:05 localhost kernel: usb 2-1: New USB device found, idVendor=152d, idProduct=1561, bcdDevice= 2.04
Sep 24 09:50:05 localhost kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Sep 24 09:50:05 localhost kernel: usb 2-1: Product: SABRENT
Sep 24 09:50:05 localhost kernel: usb 2-1: Manufacturer: SABRENT
Sep 24 09:50:05 localhost kernel: usb 2-1: SerialNumber: DB9876543214E
Sep 24 09:50:05 localhost kernel: usbcore: registered new interface driver usb-storage
Sep 24 09:50:05 localhost kernel: scsi host0: uas
Sep 24 09:50:05 localhost kernel: usbcore: registered new interface driver uas
Sep 24 09:50:05 localhost kernel: scsi 0:0:0:0: Direct-Access     SABRENT                   0204 PQ: 0 ANSI: 6
Sep 24 09:50:05 localhost kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0
Sep 24 09:50:05 localhost kernel: sd 0:0:0:0: [sda] 234441648 512-byte logical blocks: (120 GB/112 GiB)
Sep 24 09:50:05 localhost kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks
Sep 24 09:50:05 localhost kernel: sd 0:0:0:0: [sda] Write Protect is off
Sep 24 09:50:05 localhost kernel: sd 0:0:0:0: [sda] Mode Sense: 53 00 00 08
Sep 24 09:50:05 localhost kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Sep 24 09:50:05 localhost kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes not a multiple of physical block size (4096 bytes)
Sep 24 09:50:05 localhost kernel: usb 1-1.2: new low-speed USB device number 3 using xhci-hcd
Sep 24 09:50:05 localhost kernel: sd 0:0:0:0: [sda] Attached SCSI disk
Sep 24 09:50:05 localhost kernel: usb 1-1.2: New USB device found, idVendor=046a, idProduct=0023, bcdDevice= 2.20
Sep 24 09:50:05 localhost kernel: usb 1-1.2: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Sep 24 09:50:05 localhost kernel: input: HID 046a:0023 as /devices/system/container/ACPI0004:02/PNP0D10:00/usb1/1-1/1-1.2/1-1.2:1.0/0003:046A:0023.0001/input/input0
Sep 24 09:50:05 localhost kernel: cherry 0003:046A:0023.0001: input,hidraw0: USB HID v1.11 Keyboard [HID 046a:0023] on usb-PNP0D10:00-1.2/input0
Sep 24 09:50:05 localhost kernel: input: HID 046a:0023 as /devices/system/container/ACPI0004:02/PNP0D10:00/usb1/1-1/1-1.2/1-1.2:1.1/0003:046A:0023.0002/input/input1
Sep 24 09:50:05 localhost kernel: cherry 0003:046A:0023.0002: input,hidraw1: USB HID v1.11 Device [HID 046a:0023] on usb-PNP0D10:00-1.2/input1
Sep 24 09:50:05 localhost systemd-udevd[480]: Using default interface naming scheme 'v247'.
Sep 24 09:50:05 localhost kernel: libphy: bcmgenet MII bus: probed
Sep 24 09:50:05 localhost kernel: unimac-mdio unimac-mdio: Broadcom UniMAC MDIO bus
Sep 24 09:50:05 localhost systemd-journald[268]: Missed 1 kernel messages
Sep 24 09:50:05 localhost kernel: bcmgenet BCM6E4E:00 enabcm6e4ei0: renamed from eth0
Sep 24 09:50:05 localhost systemd-udevd[480]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
Sep 24 09:50:05 localhost systemd-udevd[449]: Using default interface naming scheme 'v247'.
Sep 24 09:50:05 localhost systemd-udevd[449]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
Sep 24 09:50:06 localhost systemd[1]: Finished Wait for udev To Complete Device Initialization.
Sep 24 09:50:06 localhost audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-settle comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:06 localhost systemd[1]: Condition check resulted in Device-Mapper Multipath Default Configuration being skipped.
Sep 24 09:50:06 localhost systemd-journald[268]: Missed 4 kernel messages
Sep 24 09:50:06 localhost kernel: kauditd_printk_skb: 9 callbacks suppressed
Sep 24 09:50:06 localhost kernel: audit: type=1130 audit(1632477006.009:20): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-settle comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:06 localhost systemd[1]: Starting Device-Mapper Multipath Device Controller...
Sep 24 09:50:06 localhost systemd[1]: Started Device-Mapper Multipath Device Controller.
Sep 24 09:50:06 localhost audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=multipathd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:06 localhost systemd-journald[268]: Missed 3 kernel messages
Sep 24 09:50:06 localhost kernel: audit: type=1130 audit(1632477006.029:21): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=multipathd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:50:06 localhost multipathd[494]: --------start up--------
Sep 24 09:50:06 localhost multipathd[494]: read /etc/multipath.conf
Sep 24 09:50:06 localhost multipathd[494]: /etc/multipath.conf does not exist, blacklisting all devices.
Sep 24 09:50:06 localhost multipathd[494]: You can run "/sbin/mpathconf --enable" to create
Sep 24 09:50:06 localhost multipathd[494]: /etc/multipath.conf. See man mpathconf(8) for more details
Sep 24 09:50:06 localhost multipathd[494]: failed to increase buffer size
Sep 24 09:50:06 localhost multipathd[494]: /etc/multipath.conf does not exist, blacklisting all devices.
Sep 24 09:50:06 localhost multipathd[494]: You can run "/sbin/mpathconf --enable" to create
Sep 24 09:50:06 localhost multipathd[494]: /etc/multipath.conf. See man mpathconf(8) for more details
Sep 24 09:50:06 localhost multipathd[494]: path checkers start up
Sep 24 09:50:14 localhost systemd-journald[268]: Missed 10 kernel messages
Sep 24 09:50:14 localhost kernel: mmc1: Timeout waiting for hardware cmd interrupt.
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: ============ SDHCI REGISTER DUMP ===========
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Sys addr:  0xf7400040 | Version:  0x00001002
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Blk size:  0x00007040 | Blk cnt:  0x00000000
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Argument:  0x50480000 | Trn mode: 0x00000013
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Present:   0x1fff0001 | Host ctl: 0x00000005
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Power:     0x0000000f | Blk gap:  0x00000080
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Wake-up:   0x00000000 | Clock:    0x00000000
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Timeout:   0x00000000 | Int stat: 0x00000000
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Int enab:  0x03ff108b | Sig enab: 0x03ff108b
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: ACmd stat: 0x00000000 | Slot int: 0x00000000
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Caps:      0x45ee6432 | Caps_1:   0x0000a525
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Cmd:       0x0000371a | Max curr: 0x00080008
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Resp[0]:   0x00000900 | Resp[1]:  0x00e7177f
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Resp[2]:   0x325b5900 | Resp[3]:  0x00400e00
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: Host ctl2: 0x00000000
Sep 24 09:50:14 localhost kernel: mmc1: sdhci: ============================================
Sep 24 09:50:24 localhost kernel: mmc1: Timeout waiting for hardware cmd interrupt.
Sep 24 09:50:24 localhost kernel: mmc1: sdhci: ============ SDHCI REGISTER DUMP ===========
Sep 24 09:50:24 localhost kernel: mmc1: sdhci: Sys addr:  0xf7400040 | Version:  0x00001002
Sep 24 09:50:24 localhost kernel: mmc1: sdhci: Blk size:  0x00007040 | Blk cnt:  0x00000000
Sep 24 09:50:24 localhost kernel: mmc1: sdhci: Argument:  0x50480000 | Trn mode: 0x00000013
Sep 24 09:50:24 localhost kernel: mmc1: sdhci: Present:   0x1fff0001 | Host ctl: 0x00000005
Sep 24 09:50:24 localhost kernel: mmc1: sdhci: Power:     0x0000000f | Blk gap:  0x00000080
Sep 24 09:50:24 localhost kernel: mmc1: sdhci: Wake-up:   0x00000000 | Clock:    0x00000000
Sep 24 09:50:24 localhost kernel: mmc1: sdhci: Timeout:   0x00000000 | Int stat: 0x00000000
Sep 24 09:50:24 localhost kernel: mmc1: sdhci: Int enab:  0x03ff108b | Sig enab: 0x03ff108b
Sep 24 09:50:24 localhost kernel: mmc1: sdhci: ACmd stat: 0x00000000 | Slot int: 0x00000000
Sep 24 09:50:24 localhost kernel: mmc1: sdhci: Caps:      0x45ee6432 | Caps_1:   0x0000a525
Sep 24 09:50:24 localhost kernel: mmc1: sdhci: Cmd:       0x0000371a | Max curr: 0x00080008
Sep 24 09:50:24 localhost kernel: mmc1: sdhci: Resp[0]:   0x00000900 | Resp[1]:  0x00e7177f
Sep 24 09:50:24 localhost kernel: mmc1: sdhci: Resp[2]:   0x325b5900 | Resp[3]:  0x00400e00
Sep 24 09:50:24 localhost kernel: mmc1: sdhci: Host ctl2: 0x00000000
Sep 24 09:50:24 localhost kernel: mmc1: sdhci: ============================================
Sep 24 09:50:35 localhost kernel: mmc1: Timeout waiting for hardware cmd interrupt.
Sep 24 09:50:35 localhost kernel: mmc1: sdhci: ============ SDHCI REGISTER DUMP ===========
Sep 24 09:50:35 localhost kernel: mmc1: sdhci: Sys addr:  0xf7400040 | Version:  0x00001002
Sep 24 09:50:35 localhost kernel: mmc1: sdhci: Blk size:  0x00007040 | Blk cnt:  0x00000000
Sep 24 09:50:35 localhost kernel: mmc1: sdhci: Argument:  0x50480000 | Trn mode: 0x00000013
Sep 24 09:50:35 localhost kernel: mmc1: sdhci: Present:   0x1fff0001 | Host ctl: 0x00000005
Sep 24 09:50:35 localhost kernel: mmc1: sdhci: Power:     0x0000000f | Blk gap:  0x00000080
Sep 24 09:50:35 localhost kernel: mmc1: sdhci: Wake-up:   0x00000000 | Clock:    0x00000000
Sep 24 09:50:35 localhost kernel: mmc1: sdhci: Timeout:   0x00000000 | Int stat: 0x00000000
Sep 24 09:50:35 localhost kernel: mmc1: sdhci: Int enab:  0x03ff108b | Sig enab: 0x03ff108b
Sep 24 09:50:35 localhost kernel: mmc1: sdhci: ACmd stat: 0x00000000 | Slot int: 0x00000000
Sep 24 09:50:35 localhost kernel: mmc1: sdhci: Caps:      0x45ee6432 | Caps_1:   0x0000a525
Sep 24 09:50:35 localhost kernel: mmc1: sdhci: Cmd:       0x0000371a | Max curr: 0x00080008
Sep 24 09:50:35 localhost kernel: mmc1: sdhci: Resp[0]:   0x00000900 | Resp[1]:  0x00e7177f
Sep 24 09:50:35 localhost kernel: mmc1: sdhci: Resp[2]:   0x325b5900 | Resp[3]:  0x00400e00
Sep 24 09:50:35 localhost kernel: mmc1: sdhci: Host ctl2: 0x00000000
Sep 24 09:50:35 localhost kernel: mmc1: sdhci: ============================================
Sep 24 09:50:45 localhost kernel: mmc1: Timeout waiting for hardware cmd interrupt.
Sep 24 09:50:45 localhost kernel: mmc1: sdhci: ============ SDHCI REGISTER DUMP ===========
Sep 24 09:50:45 localhost kernel: mmc1: sdhci: Sys addr:  0xf7400040 | Version:  0x00001002
Sep 24 09:50:45 localhost kernel: mmc1: sdhci: Blk size:  0x00007040 | Blk cnt:  0x00000000
Sep 24 09:50:45 localhost kernel: mmc1: sdhci: Argument:  0x50480000 | Trn mode: 0x00000013
Sep 24 09:50:45 localhost kernel: mmc1: sdhci: Present:   0x1fff0001 | Host ctl: 0x00000005
Sep 24 09:50:45 localhost kernel: mmc1: sdhci: Power:     0x0000000f | Blk gap:  0x00000080
Sep 24 09:50:45 localhost kernel: mmc1: sdhci: Wake-up:   0x00000000 | Clock:    0x00000000
Sep 24 09:50:45 localhost kernel: mmc1: sdhci: Timeout:   0x00000000 | Int stat: 0x00000000
Sep 24 09:50:45 localhost kernel: mmc1: sdhci: Int enab:  0x03ff108b | Sig enab: 0x03ff108b
Sep 24 09:50:45 localhost kernel: mmc1: sdhci: ACmd stat: 0x00000000 | Slot int: 0x00000000
Sep 24 09:50:45 localhost kernel: mmc1: sdhci: Caps:      0x45ee6432 | Caps_1:   0x0000a525
Sep 24 09:50:45 localhost kernel: mmc1: sdhci: Cmd:       0x0000371a | Max curr: 0x00080008
Sep 24 09:50:45 localhost kernel: mmc1: sdhci: Resp[0]:   0x00000900 | Resp[1]:  0x00e7177f
Sep 24 09:50:45 localhost kernel: mmc1: sdhci: Resp[2]:   0x325b5900 | Resp[3]:  0x00400e00
Sep 24 09:50:45 localhost kernel: mmc1: sdhci: Host ctl2: 0x00000000
Sep 24 09:50:45 localhost kernel: mmc1: sdhci: ============================================
Sep 24 09:50:45 localhost kernel: mmc1: error -110 whilst initialising SD card
Sep 24 09:50:55 localhost kernel: mmc1: Timeout waiting for hardware cmd interrupt.
Sep 24 09:50:55 localhost kernel: mmc1: sdhci: ============ SDHCI REGISTER DUMP ===========
Sep 24 09:50:55 localhost kernel: mmc1: sdhci: Sys addr:  0xf7400040 | Version:  0x00001002
Sep 24 09:50:55 localhost kernel: mmc1: sdhci: Blk size:  0x00007040 | Blk cnt:  0x00000000
Sep 24 09:50:55 localhost kernel: mmc1: sdhci: Argument:  0x00000000 | Trn mode: 0x00000013
Sep 24 09:50:55 localhost kernel: mmc1: sdhci: Present:   0x1fff0001 | Host ctl: 0x00000005
Sep 24 09:50:55 localhost kernel: mmc1: sdhci: Power:     0x0000000f | Blk gap:  0x00000080
Sep 24 09:50:55 localhost kernel: mmc1: sdhci: Wake-up:   0x00000000 | Clock:    0x00000000
Sep 24 09:50:55 localhost kernel: mmc1: sdhci: Timeout:   0x00000000 | Int stat: 0x00000000
Sep 24 09:50:55 localhost kernel: mmc1: sdhci: Int enab:  0x03ff108b | Sig enab: 0x03ff108b
Sep 24 09:50:55 localhost kernel: mmc1: sdhci: ACmd stat: 0x00000000 | Slot int: 0x00000000
Sep 24 09:50:55 localhost kernel: mmc1: sdhci: Caps:      0x45ee6432 | Caps_1:   0x0000a525
Sep 24 09:50:55 localhost kernel: mmc1: sdhci: Cmd:       0x00000102 | Max curr: 0x00080008
Sep 24 09:50:55 localhost kernel: mmc1: sdhci: Resp[0]:   0x00000900 | Resp[1]:  0x00e7177f
Sep 24 09:50:55 localhost kernel: mmc1: sdhci: Resp[2]:   0x325b5900 | Resp[3]:  0x00400e00
Sep 24 09:50:55 localhost kernel: mmc1: sdhci: Host ctl2: 0x00000000
Sep 24 09:50:55 localhost kernel: mmc1: sdhci: ============================================
Sep 24 09:51:32 localhost systemd[1]: dev-disk-by\x2dlabel-boot.device: Job dev-disk-by\x2dlabel-boot.device/start timed out.
Sep 24 09:51:32 localhost systemd[1]: Timed out waiting for device /dev/disk/by-label/boot.
Sep 24 09:51:32 localhost systemd[1]: Dependency failed for CoreOS Boot Edit.
Sep 24 09:51:32 localhost systemd[1]: Dependency failed for Ignition Boot Disk Setup.
Sep 24 09:51:32 localhost systemd[1]: Dependency failed for Ignition Complete.
Sep 24 09:51:32 localhost systemd[1]: Dependency failed for Initrd Default Target.
Sep 24 09:51:32 localhost systemd[1]: initrd.target: Job initrd.target/start failed with result 'dependency'.
Sep 24 09:51:32 localhost systemd[1]: initrd.target: Triggering OnFailure= dependencies.
Sep 24 09:51:32 localhost systemd[1]: ignition-complete.target: Job ignition-complete.target/start failed with result 'dependency'.
Sep 24 09:51:32 localhost systemd[1]: ignition-diskful.target: Job ignition-diskful.target/start failed with result 'dependency'.
Sep 24 09:51:32 localhost systemd[1]: coreos-boot-edit.service: Job coreos-boot-edit.service/start failed with result 'dependency'.
Sep 24 09:51:32 localhost systemd[1]: coreos-boot-edit.service: Triggering OnFailure= dependencies.
Sep 24 09:51:32 localhost systemd[1]: dev-disk-by\x2dlabel-boot.device: Job dev-disk-by\x2dlabel-boot.device/start failed with result 'timeout'.
Sep 24 09:51:32 localhost systemd[1]: Stopped target Local Encrypted Volumes.
Sep 24 09:51:32 localhost systemd[1]: Stopped target Path Units.
Sep 24 09:51:32 localhost systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
Sep 24 09:51:32 localhost systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.
Sep 24 09:51:32 localhost systemd[1]: Stopped target Local Encrypted Volumes (Pre).
Sep 24 09:51:32 localhost systemd[1]: clevis-luks-askpass.path: Deactivated successfully.
Sep 24 09:51:32 localhost systemd[1]: Stopped Forward Password Requests to Clevis Directory Watch.
Sep 24 09:51:32 localhost systemd[1]: Stopped target Slice Units.
Sep 24 09:51:32 localhost systemd[1]: Stopped target Socket Units.
Sep 24 09:51:32 localhost systemd[1]: Stopped target Swaps.
Sep 24 09:51:32 localhost systemd[1]: Stopped target Timer Units.
Sep 24 09:51:32 localhost systemd[1]: coreos-touch-run-agetty.service: Deactivated successfully.
Sep 24 09:51:32 localhost systemd[1]: Stopped CoreOS: Touch /run/agetty.reload.
Sep 24 09:51:32 localhost audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=coreos-touch-run-agetty comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:51:32 localhost systemd[1]: Started Emergency Shell.
Sep 24 09:51:32 localhost systemd-journald[268]: Missed 26 kernel messages
Sep 24 09:51:32 localhost kernel: audit: type=1131 audit(1632477092.389:22): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=coreos-touch-run-agetty comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:51:32 localhost audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=emergency comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:51:32 localhost systemd[1]: Reached target Emergency Mode.
Sep 24 09:51:32 localhost systemd-journald[268]: Missed 1 kernel messages
Sep 24 09:51:32 localhost kernel: audit: type=1130 audit(1632477092.399:23): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=emergency comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:51:32 localhost systemd[1]: Condition check resulted in Dump journal to virtio port being skipped.
Sep 24 09:51:32 localhost systemd[1]: Stopping Device-Mapper Multipath Device Controller...
Sep 24 09:51:32 localhost systemd[1]: dbus.socket: Deactivated successfully.
Sep 24 09:51:32 localhost systemd[1]: Closed D-Bus System Message Bus Socket.
Sep 24 09:51:32 localhost multipathd[494]: --------shut down-------
Sep 24 09:51:32 localhost multipathd[504]: 91.786672 | /etc/multipath.conf does not exist, blacklisting all devices.
Sep 24 09:51:32 localhost multipathd[504]: 91.786723 | You can run "/sbin/mpathconf --enable" to create
Sep 24 09:51:32 localhost multipathd[504]: 91.786737 | /etc/multipath.conf. See man mpathconf(8) for more details
Sep 24 09:51:32 localhost multipathd[504]: ok
Sep 24 09:51:32 localhost systemd[1]: systemd-sysctl.service: Deactivated successfully.
Sep 24 09:51:32 localhost systemd[1]: Stopped Apply Kernel Variables.
Sep 24 09:51:32 localhost audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:51:32 localhost systemd[1]: systemd-modules-load.service: Deactivated successfully.
Sep 24 09:51:32 localhost systemd[1]: Stopped Load Kernel Modules.
Sep 24 09:51:32 localhost systemd-journald[268]: Missed 12 kernel messages
Sep 24 09:51:32 localhost kernel: audit: type=1131 audit(1632477092.459:24): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:51:32 localhost audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:51:32 localhost systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.
Sep 24 09:51:32 localhost systemd-journald[268]: Missed 2 kernel messages
Sep 24 09:51:32 localhost kernel: audit: type=1131 audit(1632477092.469:25): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:51:32 localhost systemd[1]: Stopped Create Volatile Files and Directories.
Sep 24 09:51:32 localhost audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:51:32 localhost systemd-journald[268]: Missed 2 kernel messages
Sep 24 09:51:32 localhost kernel: audit: type=1131 audit(1632477092.479:26): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:51:32 localhost systemd[1]: multipathd.service: Deactivated successfully.
Sep 24 09:51:32 localhost systemd[1]: Stopped Device-Mapper Multipath Device Controller.
Sep 24 09:51:32 localhost audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=multipathd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:51:32 localhost systemd[1]: systemd-udev-settle.service: Deactivated successfully.
Sep 24 09:51:32 localhost systemd-journald[268]: Missed 2 kernel messages
Sep 24 09:51:32 localhost kernel: audit: type=1131 audit(1632477092.499:27): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=multipathd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:51:32 localhost systemd[1]: Stopped Wait for udev To Complete Device Initialization.
Sep 24 09:51:32 localhost audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-settle comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:51:32 localhost systemd[1]: systemd-udev-trigger.service: Deactivated successfully.
Sep 24 09:51:32 localhost systemd[1]: Stopped Coldplug All udev Devices.
Sep 24 09:51:32 localhost systemd-journald[268]: Missed 2 kernel messages
Sep 24 09:51:32 localhost kernel: audit: type=1131 audit(1632477092.519:28): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-settle comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:51:32 localhost audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:51:32 localhost systemd[1]: systemd-udevd.service: Deactivated successfully.
Sep 24 09:51:32 localhost systemd[1]: Stopped Rule-based Manager for Device Events and Files.
Sep 24 09:51:32 localhost audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:51:32 localhost systemd[1]: systemd-udevd-control.socket: Deactivated successfully.
Sep 24 09:51:32 localhost systemd[1]: Closed udev Control Socket.
Sep 24 09:51:32 localhost systemd-journald[268]: Missed 3 kernel messages
Sep 24 09:51:32 localhost kernel: audit: type=1131 audit(1632477092.579:31): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:51:32 localhost systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully.
Sep 24 09:51:32 localhost systemd[1]: Closed udev Kernel Socket.
Sep 24 09:51:32 localhost systemd[1]: dracut-pre-udev.service: Deactivated successfully.
Sep 24 09:51:32 localhost systemd[1]: Stopped dracut pre-udev hook.
Sep 24 09:51:32 localhost audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:51:32 localhost systemd[1]: dracut-cmdline.service: Deactivated successfully.
Sep 24 09:51:32 localhost systemd[1]: Stopped dracut cmdline hook.
Sep 24 09:51:32 localhost audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:51:32 localhost systemd[1]: dracut-cmdline-ask.service: Deactivated successfully.
Sep 24 09:51:32 localhost systemd[1]: Stopped dracut ask for additional cmdline parameters.
Sep 24 09:51:32 localhost audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:51:32 localhost systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.
Sep 24 09:51:32 localhost systemd[1]: Stopped Create Static Device Nodes in /dev.
Sep 24 09:51:32 localhost audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:51:32 localhost systemd[1]: kmod-static-nodes.service: Deactivated successfully.
Sep 24 09:51:32 localhost systemd[1]: Stopped Create List of Static Device Nodes.
Sep 24 09:51:32 localhost systemd[1]: Startup finished in 1.132s (kernel) + 0 (initrd) + 1min 30.921s (userspace) = 1min 32.053s.
Sep 24 09:51:32 localhost audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Sep 24 09:51:32 localhost systemd[1]: System is tainted: cgroupsv1
Sep 24 09:52:17 localhost systemd-journald[268]: Missed 16 kernel messages
Sep 24 09:52:17 localhost kernel: random: crng init done
Sep 24 09:52:17 localhost kernel: random: 7 urandom warning(s) missed due to ratelimiting
bgilbert commented 3 years ago

Thanks for the report. FCOS is starting ignition-diskful.target at boot, because it doesn't find /etc/coreos-live-initramfs indicating this is a live system, because you're booting with an incomplete initramfs, because coreos-installer corrupted the download, because it didn't realize that it shouldn't try to decompress the initramfs image.

There are several bugs here: coreos-installer download shouldn't try to decompress artifacts that aren't intended to be decompressed, and should properly fail if the compressed stream finishes before the download does. Try downloading without -d, since none of the PXE artifacts are intended to be decompressed before use.

raballew commented 3 years ago

@bgilbert @dustymabe Thanks for sharing this. I actually have to use an uncompressed kernel artifact as piPXE only supports EFI and this can not handle gzip. Therefore I used -d. Downloading everything again (without -d) and only decompressing the kernel fixed the issue. For those interested in more details, I created short documentation here. Not all features of the Raspberry are supported but I managed to get FCOS running on my Raspberry Pi's with iPXE.

Should I raise a separate issue for the bugs mentioned?

dustymabe commented 3 years ago

I actually have to use an uncompressed kernel artifact as piPXE only supports EFI and this can not handle gzip.

I wonder if you use https://github.com/pftf/RPi4 if you'll have better luck (IIUC supports PXE, but I guess maybe not iPXE).

bgilbert commented 3 years ago

Oh, interesting. On x86_64, vmlinuz is an EFI application, so no decompression is required. But I see that on aarch64 it's just a gzip-compressed binary. It's unfortunate that the Pi requires the extra decompression step.

No need to file a separate bug; I'll just move this over to coreos-installer.

raballew commented 3 years ago

I wonder if you use https://github.com/pftf/RPi4 if you'll have better luck (IIUC supports PXE, but I guess maybe not iPXE).

The build process of pftf's repository provides some useful insights that I can try include when building piPXE or maybe I can directly chainload iPXE with pftf's build.

bgilbert commented 3 years ago

The first bug should be fixed by https://github.com/coreos/coreos-installer/pull/639. The fix for the second one is forthcoming.

dustymabe commented 3 years ago

The fix for the second one is forthcoming.

should we re-open then?

bgilbert commented 3 years ago

Nah, the second bug isn't directly related to the user-visible issue here. I'm working on a fix and should hopefully get it posted soon.

bgilbert commented 3 years ago

The second fix is now posted in https://github.com/coreos/coreos-installer/pull/655.