Oct 28 23:20:21.690181 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 28 23:20:21.690199 kernel: Linux version 5.10.107-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo 9.3.0-r1 p3) 9.3.0, GNU ld (Gentoo 2.36.1 p5) 2.36.1) #1 SMP PREEMPT Tue Mar 22 19:39:09 -00 2022 Oct 28 23:20:21.690207 kernel: efi: EFI v2.70 by EDK II Oct 28 23:20:21.690212 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 28 23:20:21.690217 kernel: efi: seeding entropy pool Oct 28 23:20:21.690222 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 28 23:20:21.690228 kernel: ACPI: Early table checksum verification disabled Oct 28 23:20:21.690233 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 28 23:20:21.690238 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 28 23:20:21.690243 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 23:20:21.690250 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 23:20:21.690255 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 23:20:21.690260 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 23:20:21.690265 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 23:20:21.690271 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 23:20:21.690277 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 23:20:21.690284 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 23:20:21.690289 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 23:20:21.690295 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 28 23:20:21.690300 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 28 23:20:21.690306 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 28 23:20:21.690311 kernel: NUMA: NODE_DATA [mem 0xdc95b900-0xdc960fff] Oct 28 23:20:21.690316 kernel: Zone ranges: Oct 28 23:20:21.690321 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 28 23:20:21.690327 kernel: DMA32 empty Oct 28 23:20:21.690332 kernel: Normal empty Oct 28 23:20:21.690337 kernel: Movable zone start for each node Oct 28 23:20:21.690343 kernel: Early memory node ranges Oct 28 23:20:21.690349 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 28 23:20:21.690354 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 28 23:20:21.690359 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 28 23:20:21.690365 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 28 23:20:21.690370 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 28 23:20:21.690375 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 28 23:20:21.690380 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 28 23:20:21.690386 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 28 23:20:21.690391 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 28 23:20:21.690396 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 28 23:20:21.690401 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 28 23:20:21.690407 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 28 23:20:21.690415 kernel: On node 0 totalpages: 643072 Oct 28 23:20:21.690421 kernel: DMA zone: 10048 pages used for memmap Oct 28 23:20:21.690426 kernel: DMA zone: 0 pages reserved Oct 28 23:20:21.690432 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 28 23:20:21.690437 kernel: psci: probing for conduit method from ACPI. Oct 28 23:20:21.690443 kernel: psci: PSCIv1.1 detected in firmware. Oct 28 23:20:21.690448 kernel: psci: Using standard PSCI v0.2 function IDs Oct 28 23:20:21.690454 kernel: psci: Trusted OS migration not required Oct 28 23:20:21.690461 kernel: psci: SMC Calling Convention v1.1 Oct 28 23:20:21.690467 kernel: ACPI: SRAT not present Oct 28 23:20:21.690472 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 28 23:20:21.690478 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 28 23:20:21.690483 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 28 23:20:21.690489 kernel: Detected PIPT I-cache on CPU0 Oct 28 23:20:21.690494 kernel: CPU features: detected: GIC system register CPU interface Oct 28 23:20:21.690500 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 28 23:20:21.690506 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 28 23:20:21.690511 kernel: CPU features: detected: Hardware dirty bit management Oct 28 23:20:21.690517 kernel: CPU features: detected: Spectre-v4 Oct 28 23:20:21.690524 kernel: CPU features: detected: ARM erratum 1418040 Oct 28 23:20:21.690529 kernel: CPU features: detected: Spectre-BHB Oct 28 23:20:21.690535 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 28 23:20:21.690541 kernel: Policy zone: DMA Oct 28 23:20:21.690547 kernel: Kernel command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 28 23:20:21.690553 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 28 23:20:21.690559 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 28 23:20:21.690565 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 28 23:20:21.690571 kernel: Memory: 2452104K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120184K reserved, 0K cma-reserved) Oct 28 23:20:21.690577 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 28 23:20:21.690582 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 28 23:20:21.690589 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 28 23:20:21.690595 kernel: rcu: RCU event tracing is enabled. Oct 28 23:20:21.690601 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 28 23:20:21.690606 kernel: Trampoline variant of Tasks RCU enabled. Oct 28 23:20:21.690612 kernel: Tracing variant of Tasks RCU enabled. Oct 28 23:20:21.690618 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 28 23:20:21.690623 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 28 23:20:21.690629 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 28 23:20:21.690634 kernel: GICv3: 256 SPIs implemented Oct 28 23:20:21.690640 kernel: GICv3: 0 Extended SPIs implemented Oct 28 23:20:21.690645 kernel: GICv3: Distributor has no Range Selector support Oct 28 23:20:21.690651 kernel: GICv3: 16 PPIs implemented Oct 28 23:20:21.690658 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 28 23:20:21.690664 kernel: ACPI: SRAT not present Oct 28 23:20:21.690669 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 28 23:20:21.690675 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 28 23:20:21.690681 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 28 23:20:21.690686 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 28 23:20:21.690692 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 28 23:20:21.690698 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 23:20:21.690703 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 28 23:20:21.690709 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 28 23:20:21.690715 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 28 23:20:21.690721 kernel: arm-pv: using stolen time PV Oct 28 23:20:21.690728 kernel: Console: colour dummy device 80x25 Oct 28 23:20:21.690734 kernel: ACPI: Core revision 20200925 Oct 28 23:20:21.690740 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 28 23:20:21.690746 kernel: pid_max: default: 32768 minimum: 301 Oct 28 23:20:21.690752 kernel: LSM: Security Framework initializing Oct 28 23:20:21.690757 kernel: SELinux: Initializing. Oct 28 23:20:21.690763 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 28 23:20:21.690769 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 28 23:20:21.690775 kernel: rcu: Hierarchical SRCU implementation. Oct 28 23:20:21.690782 kernel: Platform MSI: ITS@0x8080000 domain created Oct 28 23:20:21.690788 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 28 23:20:21.690794 kernel: Remapping and enabling EFI services. Oct 28 23:20:21.690799 kernel: smp: Bringing up secondary CPUs ... Oct 28 23:20:21.690805 kernel: Detected PIPT I-cache on CPU1 Oct 28 23:20:21.690811 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 28 23:20:21.690817 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 28 23:20:21.690823 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 23:20:21.690829 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 28 23:20:21.690835 kernel: Detected PIPT I-cache on CPU2 Oct 28 23:20:21.690842 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 28 23:20:21.690848 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 28 23:20:21.690854 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 23:20:21.690860 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 28 23:20:21.690866 kernel: Detected PIPT I-cache on CPU3 Oct 28 23:20:21.690876 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 28 23:20:21.690883 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 28 23:20:21.690890 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 23:20:21.690896 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 28 23:20:21.690902 kernel: smp: Brought up 1 node, 4 CPUs Oct 28 23:20:21.690908 kernel: SMP: Total of 4 processors activated. Oct 28 23:20:21.690914 kernel: CPU features: detected: Privileged Access Never Oct 28 23:20:21.690921 kernel: CPU features: detected: LSE atomic instructions Oct 28 23:20:21.690927 kernel: CPU features: detected: User Access Override Oct 28 23:20:21.690933 kernel: CPU features: detected: 32-bit EL0 Support Oct 28 23:20:21.690939 kernel: CPU features: detected: Common not Private translations Oct 28 23:20:21.690945 kernel: CPU features: detected: RAS Extension Support Oct 28 23:20:21.690952 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 28 23:20:21.690959 kernel: CPU features: detected: CRC32 instructions Oct 28 23:20:21.690965 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 28 23:20:21.690971 kernel: CPU: All CPU(s) started at EL1 Oct 28 23:20:21.690977 kernel: alternatives: patching kernel code Oct 28 23:20:21.690984 kernel: devtmpfs: initialized Oct 28 23:20:21.690990 kernel: KASLR enabled Oct 28 23:20:21.690996 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 28 23:20:21.691002 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 28 23:20:21.691009 kernel: pinctrl core: initialized pinctrl subsystem Oct 28 23:20:21.691015 kernel: SMBIOS 3.0.0 present. Oct 28 23:20:21.691021 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 28 23:20:21.691027 kernel: NET: Registered protocol family 16 Oct 28 23:20:21.691033 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 28 23:20:21.691040 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 28 23:20:21.691047 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 28 23:20:21.691053 kernel: audit: initializing netlink subsys (disabled) Oct 28 23:20:21.691059 kernel: audit: type=2000 audit(0.032:1): state=initialized audit_enabled=0 res=1 Oct 28 23:20:21.691065 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 28 23:20:21.691072 kernel: cpuidle: using governor menu Oct 28 23:20:21.691078 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 28 23:20:21.691084 kernel: ASID allocator initialised with 32768 entries Oct 28 23:20:21.691090 kernel: ACPI: bus type PCI registered Oct 28 23:20:21.691096 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 28 23:20:21.691103 kernel: Serial: AMBA PL011 UART driver Oct 28 23:20:21.691109 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 28 23:20:21.691129 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 28 23:20:21.691136 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 28 23:20:21.691142 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 28 23:20:21.691148 kernel: cryptd: max_cpu_qlen set to 1000 Oct 28 23:20:21.691154 kernel: ACPI: Added _OSI(Module Device) Oct 28 23:20:21.691161 kernel: ACPI: Added _OSI(Processor Device) Oct 28 23:20:21.691167 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 28 23:20:21.691175 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 28 23:20:21.691181 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 28 23:20:21.691187 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 28 23:20:21.691193 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 28 23:20:21.691199 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 28 23:20:21.691205 kernel: ACPI: Interpreter enabled Oct 28 23:20:21.691211 kernel: ACPI: Using GIC for interrupt routing Oct 28 23:20:21.691218 kernel: ACPI: MCFG table detected, 1 entries Oct 28 23:20:21.691224 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 28 23:20:21.691230 kernel: printk: console [ttyAMA0] enabled Oct 28 23:20:21.691237 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 28 23:20:21.691351 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 28 23:20:21.691411 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 28 23:20:21.691464 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 28 23:20:21.691517 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 28 23:20:21.691569 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 28 23:20:21.691577 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 28 23:20:21.691585 kernel: PCI host bridge to bus 0000:00 Oct 28 23:20:21.691647 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 28 23:20:21.691696 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 28 23:20:21.691752 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 28 23:20:21.691803 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 28 23:20:21.691881 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 28 23:20:21.691944 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 28 23:20:21.692003 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 28 23:20:21.692064 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 28 23:20:21.692139 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 28 23:20:21.692199 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 28 23:20:21.692255 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 28 23:20:21.692310 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 28 23:20:21.692359 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 28 23:20:21.692410 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 28 23:20:21.692458 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 28 23:20:21.692466 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 28 23:20:21.692473 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 28 23:20:21.692479 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 28 23:20:21.692485 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 28 23:20:21.692491 kernel: vgaarb: loaded Oct 28 23:20:21.692497 kernel: Registered efivars operations Oct 28 23:20:21.692504 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 28 23:20:21.692512 kernel: VFS: Disk quotas dquot_6.6.0 Oct 28 23:20:21.692518 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 28 23:20:21.692524 kernel: pnp: PnP ACPI init Oct 28 23:20:21.692585 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 28 23:20:21.692635 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 28 23:20:21.692644 kernel: pnp: PnP ACPI: found 1 devices Oct 28 23:20:21.692650 kernel: NET: Registered protocol family 2 Oct 28 23:20:21.692656 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 28 23:20:21.692664 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 28 23:20:21.692671 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 28 23:20:21.692677 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 28 23:20:21.692684 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 28 23:20:21.692690 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 28 23:20:21.692697 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 28 23:20:21.692703 kernel: NET: Registered protocol family 1 Oct 28 23:20:21.692709 kernel: PCI: CLS 0 bytes, default 64 Oct 28 23:20:21.692716 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 28 23:20:21.692723 kernel: kvm [1]: HYP mode not available Oct 28 23:20:21.692730 kernel: Initialise system trusted keyrings Oct 28 23:20:21.692736 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 28 23:20:21.692742 kernel: Key type asymmetric registered Oct 28 23:20:21.692748 kernel: Asymmetric key parser 'x509' registered Oct 28 23:20:21.692754 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 28 23:20:21.692761 kernel: io scheduler mq-deadline registered Oct 28 23:20:21.692767 kernel: io scheduler kyber registered Oct 28 23:20:21.692773 kernel: io scheduler bfq registered Oct 28 23:20:21.692781 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 28 23:20:21.692787 kernel: ACPI: Power Button [PWRB] Oct 28 23:20:21.692793 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 28 23:20:21.692847 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 28 23:20:21.692856 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 28 23:20:21.692862 kernel: msm_serial: driver initialized Oct 28 23:20:21.692869 kernel: random: fast init done Oct 28 23:20:21.692875 kernel: random: crng init done Oct 28 23:20:21.692881 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 28 23:20:21.692887 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 28 23:20:21.692895 kernel: thunder_xcv, ver 1.0 Oct 28 23:20:21.692901 kernel: thunder_bgx, ver 1.0 Oct 28 23:20:21.692908 kernel: nicpf, ver 1.0 Oct 28 23:20:21.692914 kernel: nicvf, ver 1.0 Oct 28 23:20:21.692978 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 28 23:20:21.693030 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-28T23:20:21 UTC (1761693621) Oct 28 23:20:21.693039 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 28 23:20:21.693045 kernel: NET: Registered protocol family 10 Oct 28 23:20:21.693053 kernel: Segment Routing with IPv6 Oct 28 23:20:21.693059 kernel: NET: Registered protocol family 17 Oct 28 23:20:21.693065 kernel: Key type dns_resolver registered Oct 28 23:20:21.693071 kernel: registered taskstats version 1 Oct 28 23:20:21.693077 kernel: Loading compiled-in X.509 certificates Oct 28 23:20:21.693084 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 28 23:20:21.693090 kernel: Key type ._fscrypt registered Oct 28 23:20:21.693096 kernel: Key type .fscrypt registered Oct 28 23:20:21.693102 kernel: Key type fscrypt-provisioning registered Oct 28 23:20:21.693110 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 28 23:20:21.693130 kernel: ima: Allocated hash algorithm: sha1 Oct 28 23:20:21.693137 kernel: ima: No architecture policies found Oct 28 23:20:21.693143 kernel: Freeing unused kernel memory: 34240K Oct 28 23:20:21.693150 kernel: Run /init as init process Oct 28 23:20:21.693175 kernel: with arguments: Oct 28 23:20:21.693181 kernel: /init Oct 28 23:20:21.693187 kernel: with environment: Oct 28 23:20:21.693193 kernel: HOME=/ Oct 28 23:20:21.693199 kernel: TERM=linux Oct 28 23:20:21.693207 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 28 23:20:21.693216 systemd[1]: systemd 249 running in system mode (+PAM -AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Oct 28 23:20:21.693225 systemd[1]: Detected virtualization kvm. Oct 28 23:20:21.693232 systemd[1]: Detected architecture arm64. Oct 28 23:20:21.693238 systemd[1]: Running in initial RAM disk. Oct 28 23:20:21.693245 systemd[1]: No hostname configured, using default hostname. Oct 28 23:20:21.693251 systemd[1]: Hostname set to . Oct 28 23:20:21.693259 systemd[1]: Initializing machine ID from VM UUID. Oct 28 23:20:21.693266 systemd[1]: Queued start job for default target Initrd Default Target. Oct 28 23:20:21.693272 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 28 23:20:21.693279 systemd[1]: Reached target Local Encrypted Volumes. Oct 28 23:20:21.693285 systemd[1]: Reached target Path Units. Oct 28 23:20:21.693292 systemd[1]: Reached target Slice Units. Oct 28 23:20:21.693298 systemd[1]: Reached target Swaps. Oct 28 23:20:21.693305 systemd[1]: Reached target Timer Units. Oct 28 23:20:21.693313 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 28 23:20:21.693320 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 28 23:20:21.693326 systemd[1]: Listening on Journal Audit Socket. Oct 28 23:20:21.693333 systemd[1]: Listening on Journal Socket (/dev/log). Oct 28 23:20:21.693339 systemd[1]: Listening on Journal Socket. Oct 28 23:20:21.693346 systemd[1]: Listening on Network Service Netlink Socket. Oct 28 23:20:21.693353 systemd[1]: Listening on udev Control Socket. Oct 28 23:20:21.693359 systemd[1]: Listening on udev Kernel Socket. Oct 28 23:20:21.693366 systemd[1]: Reached target Socket Units. Oct 28 23:20:21.693374 systemd[1]: Starting Create List of Static Device Nodes... Oct 28 23:20:21.693380 systemd[1]: Finished Network Cleanup. Oct 28 23:20:21.693387 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 28 23:20:21.693393 systemd[1]: Starting Journal Service... Oct 28 23:20:21.693400 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 28 23:20:21.693407 systemd[1]: Starting Apply Kernel Variables... Oct 28 23:20:21.693414 systemd[1]: Starting Setup Virtual Console... Oct 28 23:20:21.693420 systemd[1]: Finished Create List of Static Device Nodes. Oct 28 23:20:21.693427 systemd[1]: Finished Apply Kernel Variables. Oct 28 23:20:21.693433 systemd[1]: Finished Setup Virtual Console. Oct 28 23:20:21.693441 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 28 23:20:21.693450 systemd-journald[279]: Journal started Oct 28 23:20:21.693487 systemd-journald[279]: Runtime Journal (/run/log/journal/aee9fbc22d0f45d9a987af37fdde7c89) is 6.0M, max 48.5M, 42.4M free. Oct 28 23:20:21.696332 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 28 23:20:21.697749 systemd[1]: Started Journal Service. Oct 28 23:20:21.698553 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 28 23:20:21.714452 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 28 23:20:21.716245 systemd[1]: Starting dracut cmdline hook... Oct 28 23:20:21.725469 dracut-cmdline[296]: dracut-dracut-053 Oct 28 23:20:21.727726 dracut-cmdline[296]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 28 23:20:21.787155 kernel: SCSI subsystem initialized Oct 28 23:20:21.791146 kernel: Loading iSCSI transport class v2.0-870. Oct 28 23:20:21.797147 kernel: iscsi: registered transport (tcp) Oct 28 23:20:21.809151 kernel: iscsi: registered transport (qla4xxx) Oct 28 23:20:21.809179 kernel: QLogic iSCSI HBA Driver Oct 28 23:20:21.819183 systemd[1]: Finished dracut cmdline hook. Oct 28 23:20:21.820959 systemd[1]: Starting dracut pre-udev hook... Oct 28 23:20:21.836026 kernel: device-mapper: uevent: version 1.0.3 Oct 28 23:20:21.836067 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 28 23:20:21.872489 kernel: raid6: neonx8 gen() 11474 MB/s Oct 28 23:20:21.889150 kernel: raid6: neonx8 xor() 10195 MB/s Oct 28 23:20:21.906152 kernel: raid6: neonx4 gen() 11680 MB/s Oct 28 23:20:21.923148 kernel: raid6: neonx4 xor() 11561 MB/s Oct 28 23:20:21.940149 kernel: raid6: neonx2 gen() 13019 MB/s Oct 28 23:20:21.957154 kernel: raid6: neonx2 xor() 9892 MB/s Oct 28 23:20:21.974161 kernel: raid6: neonx1 gen() 10817 MB/s Oct 28 23:20:21.991164 kernel: raid6: neonx1 xor() 8833 MB/s Oct 28 23:20:22.008154 kernel: raid6: int64x8 gen() 4677 MB/s Oct 28 23:20:22.025673 kernel: raid6: int64x8 xor() 2997 MB/s Oct 28 23:20:22.042151 kernel: raid6: int64x4 gen() 5787 MB/s Oct 28 23:20:22.059154 kernel: raid6: int64x4 xor() 3720 MB/s Oct 28 23:20:22.076149 kernel: raid6: int64x2 gen() 6175 MB/s Oct 28 23:20:22.093149 kernel: raid6: int64x2 xor() 3310 MB/s Oct 28 23:20:22.110149 kernel: raid6: int64x1 gen() 4993 MB/s Oct 28 23:20:22.127306 kernel: raid6: int64x1 xor() 2576 MB/s Oct 28 23:20:22.127319 kernel: raid6: using algorithm neonx2 gen() 13019 MB/s Oct 28 23:20:22.127327 kernel: raid6: .... xor() 9892 MB/s, rmw enabled Oct 28 23:20:22.128444 kernel: raid6: using neon recovery algorithm Oct 28 23:20:22.138153 kernel: xor: measuring software checksum speed Oct 28 23:20:22.139146 kernel: 8regs : 16348 MB/sec Oct 28 23:20:22.140145 kernel: 32regs : 20755 MB/sec Oct 28 23:20:22.141590 kernel: arm64_neon : 28713 MB/sec Oct 28 23:20:22.141601 kernel: xor: using function: arm64_neon (28713 MB/sec) Oct 28 23:20:22.188155 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 28 23:20:22.199505 systemd[1]: Finished dracut pre-udev hook. Oct 28 23:20:22.200000 audit: BPF prog-id=6 op=LOAD Oct 28 23:20:22.201000 audit: BPF prog-id=7 op=LOAD Oct 28 23:20:22.202240 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 28 23:20:22.204486 kernel: audit: type=1334 audit(1761693622.200:2): prog-id=6 op=LOAD Oct 28 23:20:22.204509 kernel: audit: type=1334 audit(1761693622.201:3): prog-id=7 op=LOAD Oct 28 23:20:22.204518 kernel: audit: type=1334 audit(1761693622.201:4): prog-id=8 op=LOAD Oct 28 23:20:22.201000 audit: BPF prog-id=8 op=LOAD Oct 28 23:20:22.214599 systemd-udevd[415]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 28 23:20:22.216330 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 28 23:20:22.219926 systemd[1]: Starting dracut pre-trigger hook... Oct 28 23:20:22.222332 systemd[1]: Starting Network Configuration... Oct 28 23:20:22.221000 audit: BPF prog-id=9 op=LOAD Oct 28 23:20:22.226150 kernel: audit: type=1334 audit(1761693622.221:5): prog-id=9 op=LOAD Oct 28 23:20:22.231144 dracut-pre-trigger[426]: rd.md=0: removing MD RAID activation Oct 28 23:20:22.241940 systemd-networkd[428]: lo: Link UP Oct 28 23:20:22.241947 systemd-networkd[428]: lo: Gained carrier Oct 28 23:20:22.242418 systemd-networkd[428]: Enumeration completed Oct 28 23:20:22.244000 audit: BPF prog-id=10 op=LOAD Oct 28 23:20:22.243265 systemd[1]: Started Network Configuration. Oct 28 23:20:22.253889 kernel: audit: type=1334 audit(1761693622.244:6): prog-id=10 op=LOAD Oct 28 23:20:22.245495 systemd[1]: Starting Network Name Resolution... Oct 28 23:20:22.260020 systemd[1]: Finished dracut pre-trigger hook. Oct 28 23:20:22.261669 systemd[1]: Starting Coldplug All udev Devices... Oct 28 23:20:22.271634 systemd-udevd[415]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 28 23:20:22.290025 systemd-resolved[448]: Positive Trust Anchors: Oct 28 23:20:22.291062 systemd-resolved[448]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 28 23:20:22.292632 systemd-resolved[448]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Oct 28 23:20:22.294147 systemd[1]: Finished Coldplug All udev Devices. Oct 28 23:20:22.300002 systemd-resolved[448]: Defaulting to hostname 'linux'. Oct 28 23:20:22.301762 systemd[1]: Started Network Name Resolution. Oct 28 23:20:22.303385 systemd[1]: Reached target Network. Oct 28 23:20:22.304163 systemd[1]: Reached target Host and Network Name Lookups. Oct 28 23:20:22.306505 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 28 23:20:22.312761 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 28 23:20:22.316503 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 28 23:20:22.316622 kernel: vda: detected capacity change from 0 to 4756340736 Oct 28 23:20:22.314500 systemd[1]: Starting Open-iSCSI... Oct 28 23:20:22.317760 iscsid[483]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 28 23:20:22.317760 iscsid[483]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log Oct 28 23:20:22.317760 iscsid[483]: into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Oct 28 23:20:22.317760 iscsid[483]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 28 23:20:22.317760 iscsid[483]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 28 23:20:22.317760 iscsid[483]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 28 23:20:22.317760 iscsid[483]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 28 23:20:22.320955 systemd[1]: Started Open-iSCSI. Oct 28 23:20:22.327743 systemd[1]: Starting dracut initqueue hook... Oct 28 23:20:22.335621 systemd-udevd[464]: Using default interface naming scheme 'v249'. Oct 28 23:20:22.341141 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 28 23:20:22.342434 systemd-networkd[428]: eth0: Link UP Oct 28 23:20:22.360661 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 28 23:20:22.363305 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 28 23:20:22.366610 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by systemd-udevd (464) Oct 28 23:20:22.365367 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 28 23:20:22.373283 systemd[1]: Found device /dev/disk/by-label/EFI-SYSTEM. Oct 28 23:20:22.376316 systemd[1]: Found device /dev/disk/by-label/OEM. Oct 28 23:20:22.377218 systemd[1]: Reached target Initrd Root Device. Oct 28 23:20:22.379415 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 28 23:20:22.381713 systemd[1]: Finished dracut initqueue hook. Oct 28 23:20:22.382602 systemd[1]: Reached target Preparation for Remote File Systems. Oct 28 23:20:22.384474 systemd[1]: Reached target Remote Encrypted Volumes. Oct 28 23:20:22.386277 systemd[1]: Reached target Remote File Systems. Oct 28 23:20:22.388787 systemd[1]: Starting dracut pre-mount hook... Oct 28 23:20:22.396144 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 28 23:20:22.400537 systemd[1]: Finished dracut pre-mount hook. Oct 28 23:20:23.343483 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Oct 28 23:20:23.343152 systemd-networkd[428]: eth0: Gained carrier Oct 28 23:20:23.353259 systemd-networkd[428]: eth0: DHCPv4 address 10.0.0.7/16 via 10.0.0.1 Oct 28 23:20:23.403702 disk-uuid[502]: The operation has completed successfully. Oct 28 23:20:23.404973 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 28 23:20:23.428142 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 28 23:20:23.428264 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 28 23:20:23.432280 systemd[1]: Starting Ignition (setup)... Oct 28 23:20:23.441339 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 28 23:20:23.441392 kernel: BTRFS info (device vda6): has skinny extents Oct 28 23:20:23.447981 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 28 23:20:23.493680 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 28 23:20:23.493806 systemd[1]: Finished Ignition (setup). Oct 28 23:20:23.496327 systemd[1]: Starting Ignition (disks)... Oct 28 23:20:23.505800 ignition[538]: Ignition v0.36.1-15-gde4e6cc9 Oct 28 23:20:23.505818 ignition[538]: Stage: disks Oct 28 23:20:23.505829 ignition[538]: reading system config file "/usr/lib/ignition/base.ign" Oct 28 23:20:23.505840 ignition[538]: no config at "/usr/lib/ignition/base.ign" Oct 28 23:20:23.505888 ignition[538]: parsed url from cmdline: "" Oct 28 23:20:23.505891 ignition[538]: no config URL provided Oct 28 23:20:23.505896 ignition[538]: reading system config file "/usr/lib/ignition/user.ign" Oct 28 23:20:23.505903 ignition[538]: no config at "/usr/lib/ignition/user.ign" Oct 28 23:20:23.505921 ignition[538]: op(1): [started] loading QEMU firmware config module Oct 28 23:20:23.505926 ignition[538]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 28 23:20:23.506795 ignition[538]: op(1): [finished] loading QEMU firmware config module Oct 28 23:20:23.521247 ignition[538]: parsing config with SHA512: d86629fec9897b9f108315dd5a6996579752c59e6f6b17c578e166aa989f186cfa26888b35479e276608aa03347db60ed755b256d461342ed33f66a481752fde Oct 28 23:20:23.523186 ignition[538]: disks: disks passed Oct 28 23:20:23.523196 ignition[538]: Ignition finished successfully Oct 28 23:20:23.523808 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 28 23:20:23.523932 systemd[1]: Finished Ignition (disks). Oct 28 23:20:23.525405 systemd[1]: Reached target Preparation for Local File Systems. Oct 28 23:20:23.527812 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 28 23:20:23.530312 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 28 23:20:23.544157 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 28 23:20:23.544592 systemd-fsck[553]: ROOT: clean, 528/553792 files, 37752/553472 blocks Oct 28 23:20:23.549803 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 28 23:20:23.554426 systemd[1]: Mounting /sysroot... Oct 28 23:20:23.570217 systemd[1]: Found device /dev/mapper/usr. Oct 28 23:20:23.571728 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 28 23:20:23.574859 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 28 23:20:23.583432 systemd-fsck[566]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 28 23:20:23.583432 systemd-fsck[566]: You must have r/w access to the filesystem or be root Oct 28 23:20:23.586461 systemd-fsck[564]: fsck failed with exit status 8. Oct 28 23:20:23.586469 systemd-fsck[564]: Ignoring error. Oct 28 23:20:23.587751 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 28 23:20:23.590766 systemd[1]: Mounting /sysusr/usr... Oct 28 23:20:23.596953 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 28 23:20:23.596985 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 28 23:20:23.597562 systemd[1]: Mounted /sysroot. Oct 28 23:20:23.598349 systemd[1]: Reached target Initrd Root File System. Oct 28 23:20:23.606146 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 28 23:20:23.606543 systemd[1]: Mounted /sysusr/usr. Oct 28 23:20:23.607336 systemd[1]: Reached target Local File Systems. Oct 28 23:20:23.608583 systemd[1]: Reached target System Initialization. Oct 28 23:20:23.609997 systemd[1]: Reached target Basic System. Oct 28 23:20:23.612647 systemd[1]: Mounting /sysroot/usr... Oct 28 23:20:23.614891 systemd[1]: Mounted /sysroot/usr. Oct 28 23:20:23.616438 systemd[1]: Starting Root filesystem setup... Oct 28 23:20:23.646730 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 28 23:20:23.646857 systemd[1]: Finished Root filesystem setup. Oct 28 23:20:23.649346 systemd[1]: Starting Ignition (files)... Oct 28 23:20:23.650671 systemd[1]: Starting /sysroot/boot... Oct 28 23:20:23.652797 ignition[582]: Ignition v0.36.1-15-gde4e6cc9 Oct 28 23:20:23.652808 ignition[582]: Stage: files Oct 28 23:20:23.652839 ignition[582]: reading system config file "/usr/lib/ignition/base.ign" Oct 28 23:20:23.652851 ignition[582]: no config at "/usr/lib/ignition/base.ign" Oct 28 23:20:23.653612 ignition[582]: files: compiled without relabeling support, skipping Oct 28 23:20:23.669686 systemd[1]: Finished /sysroot/boot. Oct 28 23:20:23.675597 ignition[582]: files: createUsers: op(1): [started] creating or modifying user "core" Oct 28 23:20:23.675609 ignition[582]: files: createUsers: op(1): executing: "/usr/sbin/usermod" "--root" "/sysroot" "core" Oct 28 23:20:23.676931 ignition[582]: files: createUsers: op(1): [finished] creating or modifying user "core" Oct 28 23:20:23.676938 ignition[582]: files: createUsers: op(2): [started] adding ssh keys to user "core" Oct 28 23:20:23.678209 ignition[582]: files: createUsers: op(2): [finished] adding ssh keys to user "core" Oct 28 23:20:23.679777 systemd[1]: ignition-files.service: Deactivated successfully. Oct 28 23:20:23.678232 ignition[582]: files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/etc/flatcar/update.conf" Oct 28 23:20:23.679897 systemd[1]: Finished Ignition (files). Oct 28 23:20:23.678531 ignition[582]: files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/etc/flatcar/update.conf" Oct 28 23:20:23.681865 systemd[1]: Condition check resulted in Populate torcx store to satisfy profile being skipped. Oct 28 23:20:23.678539 ignition[582]: files: op(4): [started] processing unit "coreos-metadata.service" Oct 28 23:20:23.690000 audit: BPF prog-id=3 op=UNLOAD Oct 28 23:20:23.692145 kernel: audit: type=1334 audit(1761693623.690:7): prog-id=3 op=UNLOAD Oct 28 23:20:23.682566 systemd[1]: Starting Ignition (record completion)... Oct 28 23:20:23.694733 kernel: audit: type=1334 audit(1761693623.692:8): prog-id=10 op=UNLOAD Oct 28 23:20:23.694757 kernel: audit: type=1334 audit(1761693623.693:9): prog-id=9 op=UNLOAD Oct 28 23:20:23.692000 audit: BPF prog-id=10 op=UNLOAD Oct 28 23:20:23.693000 audit: BPF prog-id=9 op=UNLOAD Oct 28 23:20:23.678627 ignition[582]: files: op(4): op(5): [started] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 28 23:20:23.684014 systemd[1]: Starting Reload Configuration from the Real Root... Oct 28 23:20:23.679095 ignition[582]: files: op(4): op(5): [finished] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 28 23:20:23.686080 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 28 23:20:23.699000 audit: BPF prog-id=6 op=UNLOAD Oct 28 23:20:23.679111 ignition[582]: files: op(4): [finished] processing unit "coreos-metadata.service" Oct 28 23:20:23.705279 kernel: audit: type=1334 audit(1761693623.699:10): prog-id=6 op=UNLOAD Oct 28 23:20:23.686243 systemd[1]: Finished Ignition (record completion). Oct 28 23:20:23.679116 ignition[582]: files: files passed Oct 28 23:20:23.688606 systemd[1]: Reloading. Oct 28 23:20:23.679120 ignition[582]: Ignition finished successfully Oct 28 23:20:23.792000 audit: BPF prog-id=11 op=LOAD Oct 28 23:20:23.792000 audit: BPF prog-id=12 op=LOAD Oct 28 23:20:23.792000 audit: BPF prog-id=13 op=LOAD Oct 28 23:20:23.792000 audit: BPF prog-id=4 op=UNLOAD Oct 28 23:20:23.792000 audit: BPF prog-id=5 op=UNLOAD Oct 28 23:20:23.792000 audit: BPF prog-id=14 op=LOAD Oct 28 23:20:23.793000 audit: BPF prog-id=15 op=LOAD Oct 28 23:20:23.793000 audit: BPF prog-id=16 op=LOAD Oct 28 23:20:23.793000 audit: BPF prog-id=17 op=LOAD Oct 28 23:20:23.793000 audit: BPF prog-id=18 op=LOAD Oct 28 23:20:23.793000 audit: BPF prog-id=7 op=UNLOAD Oct 28 23:20:23.793000 audit: BPF prog-id=8 op=UNLOAD Oct 28 23:20:23.809457 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 28 23:20:23.809601 systemd[1]: Finished Reload Configuration from the Real Root. Oct 28 23:20:23.811630 systemd[1]: Reached target Initrd File Systems. Oct 28 23:20:23.813198 systemd[1]: Reached target Initrd Default Target. Oct 28 23:20:23.814759 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 28 23:20:23.815539 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 28 23:20:23.826271 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 28 23:20:23.827963 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 28 23:20:23.834535 systemd[1]: Stopped target Host and Network Name Lookups. Oct 28 23:20:23.835638 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 28 23:20:23.837272 systemd[1]: Stopped target Timer Units. Oct 28 23:20:23.838737 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 28 23:20:23.838866 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 28 23:20:23.840268 systemd[1]: Stopped target Initrd Default Target. Oct 28 23:20:23.841890 systemd[1]: Stopped target Basic System. Oct 28 23:20:23.843353 systemd[1]: Stopped target Initrd Root Device. Oct 28 23:20:23.844794 systemd[1]: Stopped target Path Units. Oct 28 23:20:23.846040 systemd[1]: Stopped target Remote File Systems. Oct 28 23:20:23.847531 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 28 23:20:23.849060 systemd[1]: Stopped target Slice Units. Oct 28 23:20:23.850523 systemd[1]: Stopped target Socket Units. Oct 28 23:20:23.852037 systemd[1]: Stopped target System Initialization. Oct 28 23:20:23.853457 systemd[1]: Stopped target Local File Systems. Oct 28 23:20:23.854842 systemd[1]: Stopped target Preparation for Local File Systems. Oct 28 23:20:23.856476 systemd[1]: Stopped target Swaps. Oct 28 23:20:23.857772 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 28 23:20:23.857890 systemd[1]: Stopped dracut pre-mount hook. Oct 28 23:20:23.859448 systemd[1]: Stopped target Local Encrypted Volumes. Oct 28 23:20:23.860723 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 28 23:20:23.864157 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 28 23:20:23.865295 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 28 23:20:23.865416 systemd[1]: Stopped dracut initqueue hook. Oct 28 23:20:23.871198 iscsid[483]: iscsid shutting down. Oct 28 23:20:23.867151 systemd[1]: Stopping Open-iSCSI... Oct 28 23:20:23.871312 systemd[1]: Stopping /sysroot/boot... Oct 28 23:20:23.871936 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 28 23:20:23.872081 systemd[1]: Stopped Coldplug All udev Devices. Oct 28 23:20:23.873377 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 28 23:20:23.873467 systemd[1]: Stopped dracut pre-trigger hook. Oct 28 23:20:23.875682 systemd[1]: iscsid.service: Deactivated successfully. Oct 28 23:20:23.875819 systemd[1]: Stopped Open-iSCSI. Oct 28 23:20:23.877542 systemd[1]: iscsid.socket: Deactivated successfully. Oct 28 23:20:23.877608 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 28 23:20:23.879652 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 28 23:20:23.882572 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 28 23:20:23.882699 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 28 23:20:23.884120 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 28 23:20:23.884248 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 28 23:20:23.885697 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 28 23:20:23.885809 systemd[1]: Stopped /sysroot/boot. Oct 28 23:20:23.887448 systemd[1]: Stopped target Network. Oct 28 23:20:23.888187 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 28 23:20:23.888259 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 28 23:20:23.890767 systemd[1]: Stopping Network Name Resolution... Oct 28 23:20:23.895185 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 28 23:20:23.895302 systemd[1]: Stopped Network Name Resolution. Oct 28 23:20:23.897000 systemd[1]: Stopping Network Configuration... Oct 28 23:20:23.901322 systemd-networkd[428]: eth0: DHCP lease lost Oct 28 23:20:23.909000 audit: BPF prog-id=14 op=UNLOAD Oct 28 23:20:23.914226 systemd-networkd[428]: eth0: DHCPv6 lease lost Oct 28 23:20:23.916092 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 28 23:20:23.916240 systemd[1]: Stopped Network Configuration. Oct 28 23:20:23.917984 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 28 23:20:23.918015 systemd[1]: Closed Network Service Netlink Socket. Oct 28 23:20:23.919894 systemd[1]: Stopping Network Cleanup... Oct 28 23:20:23.920683 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 28 23:20:23.920757 systemd[1]: Stopped Apply Kernel Variables. Oct 28 23:20:23.925000 audit: BPF prog-id=15 op=UNLOAD Oct 28 23:20:23.922236 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 28 23:20:23.928808 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 28 23:20:23.928932 systemd[1]: Stopped Network Cleanup. Oct 28 23:20:23.932173 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 28 23:20:23.932303 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 28 23:20:23.933602 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 28 23:20:23.933642 systemd[1]: Closed udev Control Socket. Oct 28 23:20:23.935016 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 28 23:20:23.935056 systemd[1]: Closed udev Kernel Socket. Oct 28 23:20:23.936650 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 28 23:20:23.941000 audit: BPF prog-id=16 op=UNLOAD Oct 28 23:20:23.936701 systemd[1]: Stopped dracut pre-udev hook. Oct 28 23:20:23.938338 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 28 23:20:23.938384 systemd[1]: Stopped dracut cmdline hook. Oct 28 23:20:23.939628 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 28 23:20:23.939674 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 28 23:20:23.942287 systemd[1]: Starting Cleanup udev Database... Oct 28 23:20:23.943847 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 28 23:20:23.945152 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 28 23:20:23.945213 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 28 23:20:23.946947 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 28 23:20:23.946994 systemd[1]: Stopped Create List of Static Device Nodes. Oct 28 23:20:23.948517 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 28 23:20:23.948564 systemd[1]: Stopped Setup Virtual Console. Oct 28 23:20:23.950383 systemd[1]: rngd.service: Deactivated successfully. Oct 28 23:20:23.950510 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 28 23:20:23.961000 audit: BPF prog-id=11 op=UNLOAD Oct 28 23:20:23.952212 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 28 23:20:23.952331 systemd[1]: Finished Cleanup udev Database. Oct 28 23:20:23.953554 systemd[1]: Reached target Switch Root. Oct 28 23:20:23.955665 systemd[1]: Starting Switch Root... Oct 28 23:20:23.960997 systemd[1]: Switching root. Oct 28 23:20:23.979618 systemd-journald[279]: Journal stopped Oct 28 23:20:26.145521 systemd-journald[279]: Received SIGTERM from PID 1 (systemd). Oct 28 23:20:26.145573 kernel: SELinux: policy capability network_peer_controls=1 Oct 28 23:20:26.145585 kernel: SELinux: policy capability open_perms=1 Oct 28 23:20:26.145596 kernel: SELinux: policy capability extended_socket_class=1 Oct 28 23:20:26.145605 kernel: SELinux: policy capability always_check_network=0 Oct 28 23:20:26.145615 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 28 23:20:26.145624 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 28 23:20:26.145637 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 28 23:20:26.145647 systemd[1]: Successfully loaded SELinux policy in 32.466ms. Oct 28 23:20:26.145663 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.624ms. Oct 28 23:20:26.145673 systemd[1]: systemd 249 running in system mode (+PAM -AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Oct 28 23:20:26.145683 systemd[1]: Detected virtualization kvm. Oct 28 23:20:26.145694 systemd[1]: Detected architecture arm64. Oct 28 23:20:26.145703 systemd[1]: Detected first boot. Oct 28 23:20:26.145713 systemd[1]: Initializing machine ID from VM UUID. Oct 28 23:20:26.145724 systemd[1]: Populated /etc with preset unit settings. Oct 28 23:20:26.145736 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 28 23:20:26.145747 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 28 23:20:26.145757 kernel: kauditd_printk_skb: 24 callbacks suppressed Oct 28 23:20:26.145766 kernel: audit: type=1334 audit(1761693625.992:35): prog-id=21 op=LOAD Oct 28 23:20:26.145775 kernel: audit: type=1334 audit(1761693625.993:36): prog-id=22 op=LOAD Oct 28 23:20:26.145784 kernel: audit: type=1334 audit(1761693625.994:37): prog-id=23 op=LOAD Oct 28 23:20:26.145793 kernel: audit: type=1334 audit(1761693625.994:38): prog-id=12 op=UNLOAD Oct 28 23:20:26.145804 kernel: audit: type=1334 audit(1761693625.994:39): prog-id=13 op=UNLOAD Oct 28 23:20:26.145817 kernel: audit: type=1334 audit(1761693626.011:40): prog-id=24 op=LOAD Oct 28 23:20:26.145827 kernel: audit: type=1334 audit(1761693626.011:41): prog-id=21 op=UNLOAD Oct 28 23:20:26.145836 kernel: audit: type=1334 audit(1761693626.012:42): prog-id=25 op=LOAD Oct 28 23:20:26.145845 kernel: audit: type=1334 audit(1761693626.013:43): prog-id=26 op=LOAD Oct 28 23:20:26.145854 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 28 23:20:26.145868 kernel: audit: type=1334 audit(1761693626.013:44): prog-id=22 op=UNLOAD Oct 28 23:20:26.145880 systemd[1]: Stopped Switch Root. Oct 28 23:20:26.145891 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 28 23:20:26.145902 systemd[1]: Created slice Slice /system/addon-config. Oct 28 23:20:26.145912 systemd[1]: Created slice Slice /system/addon-run. Oct 28 23:20:26.145921 systemd[1]: Created slice Slice /system/getty. Oct 28 23:20:26.145931 systemd[1]: Created slice Slice /system/modprobe. Oct 28 23:20:26.145941 systemd[1]: Created slice Slice /system/serial-getty. Oct 28 23:20:26.145951 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 28 23:20:26.145961 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 28 23:20:26.145971 systemd[1]: Created slice User and Session Slice. Oct 28 23:20:26.145982 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 28 23:20:26.145993 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 28 23:20:26.146003 systemd[1]: Set up automount Boot partition Automount Point. Oct 28 23:20:26.146015 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 28 23:20:26.146026 systemd[1]: Stopped target Switch Root. Oct 28 23:20:26.146036 systemd[1]: Stopped target Initrd File Systems. Oct 28 23:20:26.146046 systemd[1]: Stopped target Initrd Root File System. Oct 28 23:20:26.146056 systemd[1]: Reached target Remote Encrypted Volumes. Oct 28 23:20:26.146066 systemd[1]: Reached target Remote File Systems. Oct 28 23:20:26.146076 systemd[1]: Reached target Slice Units. Oct 28 23:20:26.146099 systemd[1]: Reached target Swaps. Oct 28 23:20:26.146110 systemd[1]: Reached target Verify torcx succeeded. Oct 28 23:20:26.146119 systemd[1]: Reached target Local Verity Protected Volumes. Oct 28 23:20:26.146137 systemd[1]: Listening on Process Core Dump Socket. Oct 28 23:20:26.146147 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 28 23:20:26.146156 systemd[1]: Listening on Network Service Netlink Socket. Oct 28 23:20:26.146168 systemd[1]: Listening on udev Control Socket. Oct 28 23:20:26.146177 systemd[1]: Listening on udev Kernel Socket. Oct 28 23:20:26.146187 systemd[1]: Mounting Huge Pages File System... Oct 28 23:20:26.146197 systemd[1]: Mounting POSIX Message Queue File System... Oct 28 23:20:26.146207 systemd[1]: Mounting External Media Directory... Oct 28 23:20:26.146217 systemd[1]: Mounting Kernel Debug File System... Oct 28 23:20:26.146228 systemd[1]: Mounting Kernel Trace File System... Oct 28 23:20:26.146237 systemd[1]: Mounting Temporary Directory /tmp... Oct 28 23:20:26.146247 systemd[1]: Starting Create missing system files... Oct 28 23:20:26.146257 systemd[1]: Starting Create List of Static Device Nodes... Oct 28 23:20:26.146267 systemd[1]: Starting Load Kernel Module configfs... Oct 28 23:20:26.146277 systemd[1]: Starting Load Kernel Module drm... Oct 28 23:20:26.146287 systemd[1]: Starting Load Kernel Module fuse... Oct 28 23:20:26.146298 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 28 23:20:26.146308 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 28 23:20:26.146318 systemd[1]: Stopped File System Check on Root Device. Oct 28 23:20:26.146327 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 28 23:20:26.146336 systemd[1]: Stopped systemd-fsck-usr.service. Oct 28 23:20:26.146346 kernel: fuse: init (API version 7.32) Oct 28 23:20:26.146355 systemd[1]: Stopped Journal Service. Oct 28 23:20:26.146364 systemd[1]: Starting Journal Service... Oct 28 23:20:26.146374 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 28 23:20:26.146383 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 28 23:20:26.146394 systemd[1]: Starting Apply Kernel Variables... Oct 28 23:20:26.146404 systemd[1]: Starting Coldplug All udev Devices... Oct 28 23:20:26.146414 systemd[1]: verity-setup.service: Deactivated successfully. Oct 28 23:20:26.146424 systemd[1]: Stopped verity-setup.service. Oct 28 23:20:26.146436 systemd-journald[797]: Journal started Oct 28 23:20:26.146469 systemd-journald[797]: Runtime Journal (/run/log/journal/aee9fbc22d0f45d9a987af37fdde7c89) is 6.0M, max 48.5M, 42.4M free. Oct 28 23:20:24.044000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 28 23:20:24.075000 audit[1]: AVC avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 28 23:20:24.075000 audit[1]: AVC avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 28 23:20:24.075000 audit: BPF prog-id=19 op=LOAD Oct 28 23:20:24.075000 audit: BPF prog-id=19 op=UNLOAD Oct 28 23:20:24.076000 audit: BPF prog-id=20 op=LOAD Oct 28 23:20:24.076000 audit: BPF prog-id=20 op=UNLOAD Oct 28 23:20:24.109000 audit[746]: AVC avc: denied { associate } for pid=746 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Oct 28 23:20:25.992000 audit: BPF prog-id=21 op=LOAD Oct 28 23:20:25.993000 audit: BPF prog-id=22 op=LOAD Oct 28 23:20:25.994000 audit: BPF prog-id=23 op=LOAD Oct 28 23:20:25.994000 audit: BPF prog-id=12 op=UNLOAD Oct 28 23:20:25.994000 audit: BPF prog-id=13 op=UNLOAD Oct 28 23:20:26.011000 audit: BPF prog-id=24 op=LOAD Oct 28 23:20:26.011000 audit: BPF prog-id=21 op=UNLOAD Oct 28 23:20:26.012000 audit: BPF prog-id=25 op=LOAD Oct 28 23:20:26.013000 audit: BPF prog-id=26 op=LOAD Oct 28 23:20:26.013000 audit: BPF prog-id=22 op=UNLOAD Oct 28 23:20:26.013000 audit: BPF prog-id=23 op=UNLOAD Oct 28 23:20:26.023000 audit: BPF prog-id=24 op=UNLOAD Oct 28 23:20:26.132000 audit: BPF prog-id=27 op=LOAD Oct 28 23:20:26.132000 audit: BPF prog-id=28 op=LOAD Oct 28 23:20:26.132000 audit: BPF prog-id=29 op=LOAD Oct 28 23:20:26.132000 audit: BPF prog-id=25 op=UNLOAD Oct 28 23:20:26.132000 audit: BPF prog-id=26 op=UNLOAD Oct 28 23:20:26.144000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Oct 28 23:20:25.991933 systemd[1]: Queued start job for default target Multi-User System. Oct 28 23:20:24.108406 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-10-28T23:20:24Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3033.2.4 /usr/share/oem/torcx/store /var/lib/torcx/store/3033.2.4 /var/lib/torcx/store]" Oct 28 23:20:26.013693 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 28 23:20:24.108673 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-10-28T23:20:24Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 28 23:20:24.108693 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-10-28T23:20:24Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 28 23:20:24.108800 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-10-28T23:20:24Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 28 23:20:24.108812 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-10-28T23:20:24Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 28 23:20:24.108842 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-10-28T23:20:24Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 28 23:20:24.108855 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-10-28T23:20:24Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 28 23:20:24.109060 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-10-28T23:20:24Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 28 23:20:24.109094 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-10-28T23:20:24Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 28 23:20:24.109120 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-10-28T23:20:24Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 28 23:20:24.109507 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-10-28T23:20:24Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Oct 28 23:20:24.109539 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-10-28T23:20:24Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Oct 28 23:20:24.109560 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-10-28T23:20:24Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3033.2.4: no such file or directory" path=/usr/share/oem/torcx/store/3033.2.4 Oct 28 23:20:24.109575 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-10-28T23:20:24Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Oct 28 23:20:24.109594 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-10-28T23:20:24Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3033.2.4: no such file or directory" path=/var/lib/torcx/store/3033.2.4 Oct 28 23:20:24.109609 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-10-28T23:20:24Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 28 23:20:25.818063 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-10-28T23:20:25Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 28 23:20:25.818365 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-10-28T23:20:25Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 28 23:20:25.818472 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-10-28T23:20:25Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 28 23:20:25.818598 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-10-28T23:20:25Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 28 23:20:25.818654 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-10-28T23:20:25Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 28 23:20:25.818711 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-10-28T23:20:25Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Oct 28 23:20:26.150536 systemd[1]: Started Journal Service. Oct 28 23:20:26.151059 systemd[1]: Mounted Huge Pages File System. Oct 28 23:20:26.152069 systemd[1]: Mounted POSIX Message Queue File System. Oct 28 23:20:26.153231 systemd[1]: Mounted External Media Directory. Oct 28 23:20:26.154204 systemd[1]: Mounted Kernel Debug File System. Oct 28 23:20:26.155178 systemd[1]: Mounted Kernel Trace File System. Oct 28 23:20:26.156154 systemd[1]: Mounted Temporary Directory /tmp. Oct 28 23:20:26.157398 systemd[1]: Finished Create missing system files. Oct 28 23:20:26.158719 systemd[1]: Finished Create List of Static Device Nodes. Oct 28 23:20:26.159940 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 28 23:20:26.160166 systemd[1]: Finished Load Kernel Module configfs. Oct 28 23:20:26.161278 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 28 23:20:26.161471 systemd[1]: Finished Load Kernel Module drm. Oct 28 23:20:26.162544 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 28 23:20:26.162732 systemd[1]: Finished Load Kernel Module fuse. Oct 28 23:20:26.163921 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 28 23:20:26.165333 systemd[1]: Finished Apply Kernel Variables. Oct 28 23:20:26.177587 systemd[1]: Mounting FUSE Control File System... Oct 28 23:20:26.179477 systemd[1]: Mounting Kernel Configuration File System... Oct 28 23:20:26.180470 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 28 23:20:26.182059 systemd[1]: Starting Rebuild Hardware Database... Oct 28 23:20:26.184141 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 28 23:20:26.185205 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 28 23:20:26.186549 systemd[1]: Starting Load/Save Random Seed... Oct 28 23:20:26.189221 systemd-journald[797]: Time spent on flushing to /var/log/journal/aee9fbc22d0f45d9a987af37fdde7c89 is 8.466ms for 778 entries. Oct 28 23:20:26.189221 systemd-journald[797]: System Journal (/var/log/journal/aee9fbc22d0f45d9a987af37fdde7c89) is 8.0M, max 203.0M, 195.0M free. Oct 28 23:20:26.188616 systemd[1]: Starting Create System Users... Oct 28 23:20:26.191811 systemd[1]: Mounted FUSE Control File System. Oct 28 23:20:26.193052 systemd[1]: Mounted Kernel Configuration File System. Oct 28 23:20:26.196826 systemd[1]: Finished Load/Save Random Seed. Oct 28 23:20:26.198748 systemd[1]: Finished Coldplug All udev Devices. Oct 28 23:20:26.199900 systemd[1]: Reached target First Boot Complete. Oct 28 23:20:26.211067 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 28 23:20:26.213209 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 28 23:20:26.214800 systemd-sysusers[809]: Creating group sgx with gid 999. Oct 28 23:20:26.215843 systemd-sysusers[809]: Creating group systemd-oom with gid 998. Oct 28 23:20:26.216407 systemd-sysusers[809]: Creating user systemd-oom (systemd Userspace OOM Killer) with uid 998 and gid 998. Oct 28 23:20:26.217095 systemd-sysusers[809]: Creating group systemd-timesync with gid 997. Oct 28 23:20:26.217645 systemd-sysusers[809]: Creating user systemd-timesync (systemd Time Synchronization) with uid 997 and gid 997. Oct 28 23:20:26.218380 systemd-sysusers[809]: Creating group systemd-coredump with gid 996. Oct 28 23:20:26.218911 systemd-sysusers[809]: Creating user systemd-coredump (systemd Core Dumper) with uid 996 and gid 996. Oct 28 23:20:26.228102 systemd[1]: Finished Create System Users. Oct 28 23:20:26.238071 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 28 23:20:26.251711 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 28 23:20:26.448458 systemd[1]: Finished Rebuild Hardware Database. Oct 28 23:20:26.462000 audit: BPF prog-id=30 op=LOAD Oct 28 23:20:26.462000 audit: BPF prog-id=31 op=LOAD Oct 28 23:20:26.462000 audit: BPF prog-id=32 op=LOAD Oct 28 23:20:26.462000 audit: BPF prog-id=17 op=UNLOAD Oct 28 23:20:26.462000 audit: BPF prog-id=18 op=UNLOAD Oct 28 23:20:26.463682 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 28 23:20:26.489043 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 28 23:20:26.501969 systemd[1]: Starting Network Configuration... Oct 28 23:20:26.501000 audit: BPF prog-id=33 op=LOAD Oct 28 23:20:26.527002 systemd-udevd[819]: Using default interface naming scheme 'v249'. Oct 28 23:20:26.533108 systemd-networkd[833]: lo: Link UP Oct 28 23:20:26.533116 systemd-networkd[833]: lo: Gained carrier Oct 28 23:20:26.533485 systemd-networkd[833]: Enumeration completed Oct 28 23:20:26.533580 systemd[1]: Started Network Configuration. Oct 28 23:20:26.537184 systemd-networkd[833]: eth0: Link UP Oct 28 23:20:26.546192 systemd-networkd[833]: eth0: Gained carrier Oct 28 23:20:26.552300 systemd-networkd[833]: eth0: DHCPv4 address 10.0.0.7/16 via 10.0.0.1 Oct 28 23:20:26.571551 udevadm[812]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 28 23:20:26.594386 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 28 23:20:26.606316 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 28 23:20:26.614196 lvm[841]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 28 23:20:26.640161 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 28 23:20:26.641262 systemd[1]: Reached target Local Encrypted Volumes. Oct 28 23:20:26.658217 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 28 23:20:26.661699 lvm[842]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 28 23:20:26.685352 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 28 23:20:26.686480 systemd[1]: Reached target Preparation for Local File Systems. Oct 28 23:20:26.687545 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 28 23:20:26.687571 systemd[1]: Reached target Containers. Oct 28 23:20:26.699378 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 28 23:20:26.712060 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 28 23:20:26.714479 systemd[1]: Mounting /usr/share/oem... Oct 28 23:20:26.721699 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 28 23:20:26.721771 kernel: BTRFS info (device vda6): has skinny extents Oct 28 23:20:26.724036 systemd[1]: Mounted /usr/share/oem. Oct 28 23:20:26.724920 systemd[1]: Reached target Local File Systems. Oct 28 23:20:26.732050 systemd[1]: Starting Rebuild Dynamic Linker Cache... Oct 28 23:20:26.734465 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 28 23:20:26.734517 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 28 23:20:26.735632 systemd[1]: Starting Commit a transient machine-id on disk... Oct 28 23:20:26.737849 systemd[1]: Starting Create Volatile Files and Directories... Oct 28 23:20:26.745957 systemd-tmpfiles[867]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 28 23:20:26.747006 systemd-tmpfiles[867]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 28 23:20:26.760954 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 28 23:20:26.761627 systemd[1]: Finished Commit a transient machine-id on disk. Oct 28 23:20:26.816318 systemd-tmpfiles[867]: Detected autofs mount point /boot during canonicalization of /boot. Oct 28 23:20:26.816519 systemd-tmpfiles[867]: Skipping /boot Oct 28 23:20:26.823539 systemd-tmpfiles[867]: Detected autofs mount point /boot during canonicalization of /boot. Oct 28 23:20:26.823746 systemd-tmpfiles[867]: Skipping /boot Oct 28 23:20:26.837943 systemd[1]: Finished Rebuild Dynamic Linker Cache. Oct 28 23:20:26.862563 systemd[1]: Finished Create Volatile Files and Directories. Oct 28 23:20:26.864861 systemd[1]: Starting Load Security Auditing Rules... Oct 28 23:20:26.866765 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 28 23:20:26.868787 systemd[1]: Starting Rebuild Journal Catalog... Oct 28 23:20:26.870000 audit: BPF prog-id=34 op=LOAD Oct 28 23:20:26.871291 systemd[1]: Starting Network Name Resolution... Oct 28 23:20:26.873000 audit: BPF prog-id=35 op=LOAD Oct 28 23:20:26.874386 systemd[1]: Starting Network Time Synchronization... Oct 28 23:20:26.876315 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 28 23:20:26.877860 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 28 23:20:26.882351 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 28 23:20:26.884070 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 28 23:20:26.888654 systemd[1]: Finished Rebuild Journal Catalog. Oct 28 23:20:26.890621 systemd[1]: Starting Update is Completed... Oct 28 23:20:26.897815 systemd[1]: Finished Update is Completed. Oct 28 23:20:26.900000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Oct 28 23:20:26.901002 augenrules[889]: No rules Oct 28 23:20:26.901879 systemd[1]: Finished Load Security Auditing Rules. Oct 28 23:20:26.916264 systemd[1]: Started Network Time Synchronization. Oct 28 23:20:26.916608 systemd-resolved[874]: Positive Trust Anchors: Oct 28 23:20:26.916621 systemd-resolved[874]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 28 23:20:26.916649 systemd-resolved[874]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Oct 28 23:20:27.395362 systemd-timesyncd[878]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 28 23:20:27.395505 systemd[1]: Reached target System Initialization. Oct 28 23:20:27.396540 systemd[1]: Started Watch for update engine configuration changes. Oct 28 23:20:27.396556 systemd-resolved[874]: Defaulting to hostname 'linux'. Oct 28 23:20:27.397634 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 28 23:20:27.398850 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 28 23:20:27.399845 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 28 23:20:27.399879 systemd[1]: Reached target Path Units. Oct 28 23:20:27.400653 systemd[1]: Reached target System Time Set. Oct 28 23:20:27.401599 systemd[1]: Started Daily Log Rotation. Oct 28 23:20:27.402501 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 28 23:20:27.403623 systemd[1]: Reached target Timer Units. Oct 28 23:20:27.404747 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 28 23:20:27.406617 systemd[1]: Starting Docker Socket for the API... Oct 28 23:20:27.409537 systemd[1]: Listening on OpenSSH Server Socket. Oct 28 23:20:27.410812 systemd[1]: Started Network Name Resolution. Oct 28 23:20:27.411773 systemd[1]: Listening on Docker Socket for the API. Oct 28 23:20:27.412734 systemd[1]: Reached target Network. Oct 28 23:20:27.413490 systemd[1]: Reached target Host and Network Name Lookups. Oct 28 23:20:27.414451 systemd[1]: Reached target Socket Units. Oct 28 23:20:27.415228 systemd[1]: Reached target Basic System. Oct 28 23:20:27.416033 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 28 23:20:27.416063 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 28 23:20:27.417038 systemd[1]: Started D-Bus System Message Bus. Oct 28 23:20:27.420299 systemd[1]: Starting Extend Filesystems... Oct 28 23:20:27.421152 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 28 23:20:27.422102 systemd[1]: Starting Generate /run/flatcar/motd... Oct 28 23:20:27.423856 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 28 23:20:27.425777 systemd[1]: Starting Generate sshd host keys... Oct 28 23:20:27.426570 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 28 23:20:27.426607 systemd[1]: Reached target Load system-provided cloud configs. Oct 28 23:20:27.429513 systemd[1]: Starting User Login Management... Oct 28 23:20:27.430609 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 28 23:20:27.431254 systemd[1]: Starting Update Engine... Oct 28 23:20:27.431994 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 28 23:20:27.432030 systemd[1]: Reached target Load user-provided cloud configs. Oct 28 23:20:27.433621 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 28 23:20:27.433833 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 28 23:20:27.446261 extend-filesystems[899]: Found vda Oct 28 23:20:27.447518 extend-filesystems[899]: Found vda1 Oct 28 23:20:27.447651 systemd[1]: motdgen.service: Deactivated successfully. Oct 28 23:20:27.447851 systemd[1]: Finished Generate /run/flatcar/motd. Oct 28 23:20:27.448394 extend-filesystems[899]: Found vda2 Oct 28 23:20:27.449905 extend-filesystems[899]: Found vda3 Oct 28 23:20:27.450855 extend-filesystems[899]: Found usr Oct 28 23:20:27.450855 extend-filesystems[899]: Found vda4 Oct 28 23:20:27.450855 extend-filesystems[899]: Found vda6 Oct 28 23:20:27.450855 extend-filesystems[899]: Found vda7 Oct 28 23:20:27.450855 extend-filesystems[899]: Found vda9 Oct 28 23:20:27.450855 extend-filesystems[899]: Checking size of /dev/vda9 Oct 28 23:20:27.465994 extend-filesystems[899]: Old size kept for /dev/vda9 Oct 28 23:20:27.465112 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 28 23:20:27.465317 systemd[1]: Finished Extend Filesystems. Oct 28 23:20:27.480426 update_engine[910]: I1028 23:20:27.480144 910 main.cc:89] Flatcar Update Engine starting Oct 28 23:20:27.481345 systemd-logind[907]: Watching system buttons on /dev/input/event0 (Power Button) Oct 28 23:20:27.481587 systemd-logind[907]: New seat seat0. Oct 28 23:20:27.484208 systemd[1]: Started Update Engine. Oct 28 23:20:27.485083 update_engine[910]: I1028 23:20:27.485049 910 update_check_scheduler.cc:74] Next update check in 7m19s Oct 28 23:20:27.493034 systemd[1]: Started Cluster reboot manager. Oct 28 23:20:27.494213 systemd[1]: Started User Login Management. Oct 28 23:20:27.520890 locksmithd[922]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 28 23:20:27.836042 systemd[1]: Created slice Slice /system/sshd. Oct 28 23:20:28.021359 sshd_keygen[915]: ssh-keygen: generating new host keys: RSA DSA ECDSA ED25519 Oct 28 23:20:28.039729 systemd[1]: Finished Generate sshd host keys. Oct 28 23:20:28.051988 systemd[1]: Starting Generate /run/issue... Oct 28 23:20:28.053580 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:58872). Oct 28 23:20:28.056574 systemd[1]: issuegen.service: Deactivated successfully. Oct 28 23:20:28.056799 systemd[1]: Finished Generate /run/issue. Oct 28 23:20:28.058905 systemd[1]: Starting Permit User Sessions... Oct 28 23:20:28.063779 systemd[1]: Finished Permit User Sessions. Oct 28 23:20:28.065818 systemd[1]: Started Getty on tty1. Oct 28 23:20:28.067607 systemd[1]: Started Serial Getty on ttyAMA0. Oct 28 23:20:28.068572 systemd[1]: Reached target Login Prompts. Oct 28 23:20:28.069554 systemd[1]: Reached target Multi-User System. Oct 28 23:20:28.071709 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 28 23:20:28.076334 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 28 23:20:28.076533 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 28 23:20:28.077753 systemd[1]: Startup finished in 743ms (kernel) + 2.451s (initrd) + 3.589s (userspace) = 6.785s. Oct 28 23:20:28.100611 sshd[938]: Accepted publickey for core from 10.0.0.1 port 58872 ssh2: RSA SHA256:HTG7MLlKNvOxJPTb3qwiqi6VI8nPd76okQgzGcvxc1o Oct 28 23:20:28.102272 sshd[938]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 23:20:28.125466 systemd[1]: Created slice User Slice of UID 500. Oct 28 23:20:28.126421 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 28 23:20:28.127830 systemd-logind[907]: New session 1 of user core. Oct 28 23:20:28.131821 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 28 23:20:28.132935 systemd[1]: Starting User Manager for UID 500... Oct 28 23:20:28.135072 systemd[948]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 28 23:20:28.179892 systemd[948]: Queued start job for default target Main User Target. Oct 28 23:20:28.180049 systemd[948]: Reached target Paths. Oct 28 23:20:28.180063 systemd[948]: Reached target Sockets. Oct 28 23:20:28.180073 systemd[948]: Reached target Timers. Oct 28 23:20:28.180083 systemd[948]: Reached target Basic System. Oct 28 23:20:28.180118 systemd[948]: Reached target Main User Target. Oct 28 23:20:28.180131 systemd[948]: Startup finished in 41ms. Oct 28 23:20:28.180578 systemd[1]: Started User Manager for UID 500. Oct 28 23:20:28.192333 systemd[1]: Started Session 1 of User core. Oct 28 23:20:28.256949 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:58878). Oct 28 23:20:28.293047 sshd[957]: Accepted publickey for core from 10.0.0.1 port 58878 ssh2: RSA SHA256:HTG7MLlKNvOxJPTb3qwiqi6VI8nPd76okQgzGcvxc1o Oct 28 23:20:28.294249 sshd[957]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 23:20:28.297286 systemd-logind[907]: New session 2 of user core. Oct 28 23:20:28.312147 systemd[1]: Started Session 2 of User core. Oct 28 23:20:28.363615 sshd[957]: pam_unix(sshd:session): session closed for user core Oct 28 23:20:28.375469 systemd[1]: sshd@1-10.0.0.7:22-10.0.0.1:58878.service: Deactivated successfully. Oct 28 23:20:28.376090 systemd[1]: session-2.scope: Deactivated successfully. Oct 28 23:20:28.376619 systemd-logind[907]: Session 2 logged out. Waiting for processes to exit. Oct 28 23:20:28.377507 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:58886). Oct 28 23:20:28.378051 systemd-logind[907]: Removed session 2. Oct 28 23:20:28.413888 sshd[963]: Accepted publickey for core from 10.0.0.1 port 58886 ssh2: RSA SHA256:HTG7MLlKNvOxJPTb3qwiqi6VI8nPd76okQgzGcvxc1o Oct 28 23:20:28.414790 sshd[963]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 23:20:28.417551 systemd-logind[907]: New session 3 of user core. Oct 28 23:20:28.426157 systemd[1]: Started Session 3 of User core. Oct 28 23:20:28.474212 sshd[963]: pam_unix(sshd:session): session closed for user core Oct 28 23:20:28.487520 systemd[1]: sshd@2-10.0.0.7:22-10.0.0.1:58886.service: Deactivated successfully. Oct 28 23:20:28.488171 systemd[1]: session-3.scope: Deactivated successfully. Oct 28 23:20:28.488756 systemd-logind[907]: Session 3 logged out. Waiting for processes to exit. Oct 28 23:20:28.489694 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:58896). Oct 28 23:20:28.490279 systemd-logind[907]: Removed session 3. Oct 28 23:20:28.526135 sshd[969]: Accepted publickey for core from 10.0.0.1 port 58896 ssh2: RSA SHA256:HTG7MLlKNvOxJPTb3qwiqi6VI8nPd76okQgzGcvxc1o Oct 28 23:20:28.527122 sshd[969]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 23:20:28.529867 systemd-logind[907]: New session 4 of user core. Oct 28 23:20:28.537094 systemd[1]: Started Session 4 of User core. Oct 28 23:20:28.588037 sshd[969]: pam_unix(sshd:session): session closed for user core Oct 28 23:20:28.607812 systemd[1]: sshd@3-10.0.0.7:22-10.0.0.1:58896.service: Deactivated successfully. Oct 28 23:20:28.608694 systemd[1]: session-4.scope: Deactivated successfully. Oct 28 23:20:28.609472 systemd-logind[907]: Session 4 logged out. Waiting for processes to exit. Oct 28 23:20:28.610336 systemd-logind[907]: Removed session 4. Oct 28 23:20:28.611867 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:58898). Oct 28 23:20:28.647454 sshd[975]: Accepted publickey for core from 10.0.0.1 port 58898 ssh2: RSA SHA256:HTG7MLlKNvOxJPTb3qwiqi6VI8nPd76okQgzGcvxc1o Oct 28 23:20:28.648774 sshd[975]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 23:20:28.651128 systemd-logind[907]: New session 5 of user core. Oct 28 23:20:28.661330 systemd[1]: Started Session 5 of User core. Oct 28 23:20:28.715743 sudo[978]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 28 23:20:28.715945 sudo[978]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 28 23:20:28.725427 sudo[978]: pam_unix(sudo:session): session closed for user root Oct 28 23:20:28.728789 sshd[975]: pam_unix(sshd:session): session closed for user core Oct 28 23:20:28.742212 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:58902). Oct 28 23:20:28.742146 dbus-daemon[898]: [system] Reloaded configuration Oct 28 23:20:28.744460 systemd[1]: sshd@4-10.0.0.7:22-10.0.0.1:58898.service: Deactivated successfully. Oct 28 23:20:28.745050 systemd[1]: session-5.scope: Deactivated successfully. Oct 28 23:20:28.745664 systemd-logind[907]: Session 5 logged out. Waiting for processes to exit. Oct 28 23:20:28.746379 systemd-logind[907]: Removed session 5. Oct 28 23:20:28.778833 sshd[981]: Accepted publickey for core from 10.0.0.1 port 58902 ssh2: RSA SHA256:HTG7MLlKNvOxJPTb3qwiqi6VI8nPd76okQgzGcvxc1o Oct 28 23:20:28.779985 sshd[981]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 23:20:28.782326 systemd-logind[907]: New session 6 of user core. Oct 28 23:20:28.793158 systemd[1]: Started Session 6 of User core. Oct 28 23:20:28.812171 systemd-networkd[833]: eth0: Gained IPv6LL Oct 28 23:20:28.842848 sudo[986]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Oct 28 23:20:28.843085 sudo[986]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 28 23:20:28.845257 sudo[986]: pam_unix(sudo:session): session closed for user root Oct 28 23:20:28.848693 sudo[985]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 28 23:20:28.848887 sudo[985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 28 23:20:28.865845 systemd[1]: Stopping Load Security Auditing Rules... Oct 28 23:20:28.866000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 28 23:20:28.867126 auditctl[989]: No rules Oct 28 23:20:28.867347 systemd[1]: audit-rules.service: Deactivated successfully. Oct 28 23:20:28.867570 systemd[1]: Stopped Load Security Auditing Rules. Oct 28 23:20:28.868861 systemd[1]: Starting Load Security Auditing Rules... Oct 28 23:20:28.883574 augenrules[1006]: No rules Oct 28 23:20:28.884488 systemd[1]: Finished Load Security Auditing Rules. Oct 28 23:20:28.885521 sudo[985]: pam_unix(sudo:session): session closed for user root Oct 28 23:20:28.886691 sshd[981]: pam_unix(sshd:session): session closed for user core Oct 28 23:20:28.911638 systemd[1]: sshd@5-10.0.0.7:22-10.0.0.1:58902.service: Deactivated successfully. Oct 28 23:20:28.912196 systemd[1]: session-6.scope: Deactivated successfully. Oct 28 23:20:28.913162 systemd-logind[907]: Session 6 logged out. Waiting for processes to exit. Oct 28 23:20:28.914000 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:58904). Oct 28 23:20:28.914675 systemd-logind[907]: Removed session 6. Oct 28 23:20:28.949934 sshd[1012]: Accepted publickey for core from 10.0.0.1 port 58904 ssh2: RSA SHA256:HTG7MLlKNvOxJPTb3qwiqi6VI8nPd76okQgzGcvxc1o Oct 28 23:20:28.951168 sshd[1012]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 23:20:28.953615 systemd-logind[907]: New session 7 of user core. Oct 28 23:20:28.969101 systemd[1]: Started Session 7 of User core. Oct 28 23:20:29.018789 sudo[1015]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/bash -c cat >/etc/coreos/update.conf.new </etc/coreos/update-payload-key.pub.pem </etc/coreos/update.conf.new </etc/coreos/update-payload-key.pub.pem < Oct 28 23:20:31.802428 update_engine[1261]: Oct 28 23:20:31.802428 update_engine[1261]: Oct 28 23:20:31.802428 update_engine[1261]: Oct 28 23:20:31.802428 update_engine[1261]: Oct 28 23:20:31.802428 update_engine[1261]: Oct 28 23:20:31.802428 update_engine[1261]: Oct 28 23:20:31.802428 update_engine[1261]: Oct 28 23:20:31.802428 update_engine[1261]: Oct 28 23:20:31.802650 update_engine[1261]: I1028 23:20:31.802639 1261 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 28 23:20:31.805111 update_engine[1261]: I1028 23:20:31.805090 1261 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 28 23:20:31.805547 update_engine[1261]: I1028 23:20:31.805526 1261 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 28 23:20:31.808031 update_engine[1261]: I1028 23:20:31.808012 1261 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 28 23:20:31.808195 update_engine[1261]: I1028 23:20:31.808178 1261 libcurl_http_fetcher.cc:305] Transfer completed (200), 791 bytes downloaded Oct 28 23:20:31.808268 update_engine[1261]: I1028 23:20:31.808256 1261 omaha_request_action.cc:619] Omaha request response: Oct 28 23:20:31.808268 update_engine[1261]: Oct 28 23:20:31.808268 update_engine[1261]: Oct 28 23:20:31.808268 update_engine[1261]: Oct 28 23:20:31.808268 update_engine[1261]: Oct 28 23:20:31.808268 update_engine[1261]: Oct 28 23:20:31.808268 update_engine[1261]: Oct 28 23:20:31.808268 update_engine[1261]: Oct 28 23:20:31.808268 update_engine[1261]: Oct 28 23:20:31.808268 update_engine[1261]: Oct 28 23:20:31.808268 update_engine[1261]: Oct 28 23:20:31.808268 update_engine[1261]: Oct 28 23:20:31.808268 update_engine[1261]: Oct 28 23:20:31.808268 update_engine[1261]: Oct 28 23:20:31.808268 update_engine[1261]: Oct 28 23:20:31.808268 update_engine[1261]: Oct 28 23:20:31.808268 update_engine[1261]: Oct 28 23:20:31.808268 update_engine[1261]: Oct 28 23:20:31.808268 update_engine[1261]: Oct 28 23:20:31.808268 update_engine[1261]: Oct 28 23:20:31.808268 update_engine[1261]: Oct 28 23:20:31.811859 update_engine[1261]: I1028 23:20:31.811836 1261 omaha_request_action.cc:447] Omaha Response manifest version = Oct 28 23:20:31.811991 update_engine[1261]: I1028 23:20:31.811972 1261 omaha_request_action.cc:470] Found 1 url(s) Oct 28 23:20:31.812049 update_engine[1261]: I1028 23:20:31.812039 1261 omaha_request_action.cc:506] Processing first of 1 package(s) Oct 28 23:20:31.812092 update_engine[1261]: I1028 23:20:31.812083 1261 omaha_request_action.cc:513] Omaha Response package name = update.gz Oct 28 23:20:31.812138 update_engine[1261]: I1028 23:20:31.812127 1261 omaha_request_action.cc:529] Url0: http://10.0.0.6:34567/packages/update.gz Oct 28 23:20:31.812180 update_engine[1261]: I1028 23:20:31.812171 1261 omaha_request_action.cc:541] Payload size = 439722381 bytes Oct 28 23:20:31.812255 update_engine[1261]: I1028 23:20:31.812243 1261 omaha_request_action.cc:565] Found 1 action(s). Processing the postinstall action. Oct 28 23:20:31.812312 update_engine[1261]: I1028 23:20:31.812301 1261 payload_state.cc:51] Resetting all persisted state as this is a new response Oct 28 23:20:31.812358 update_engine[1261]: I1028 23:20:31.812350 1261 payload_state.cc:360] Current Response Signature = Oct 28 23:20:31.812358 update_engine[1261]: NumURLs = 1 Oct 28 23:20:31.812358 update_engine[1261]: Url0 = http://10.0.0.6:34567/packages/update.gz Oct 28 23:20:31.812358 update_engine[1261]: Payload Size = 439722381 Oct 28 23:20:31.812358 update_engine[1261]: Payload Sha256 Hash = 8hFWwyLnzF+7GFbpjt+cVnzq4hiRyr5fK3xWNMCkyeg= Oct 28 23:20:31.812358 update_engine[1261]: Is Delta Payload = 0 Oct 28 23:20:31.812358 update_engine[1261]: Max Failure Count Per Url = 10 Oct 28 23:20:31.812358 update_engine[1261]: Disable Payload Backoff = 1 Oct 28 23:20:31.812594 update_engine[1261]: I1028 23:20:31.812582 1261 payload_state.cc:381] Payload Attempt Number = 0 Oct 28 23:20:31.812684 update_engine[1261]: I1028 23:20:31.812674 1261 payload_state.cc:404] Current URL Index = 0 Oct 28 23:20:31.812800 update_engine[1261]: I1028 23:20:31.812789 1261 payload_state.cc:425] Current URL (Url0)'s Failure Count = 0 Oct 28 23:20:31.812882 update_engine[1261]: I1028 23:20:31.812871 1261 payload_state.cc:287] Resetting backoff expiry time as payload backoff is disabled Oct 28 23:20:31.812974 update_engine[1261]: I1028 23:20:31.812937 1261 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 28 23:20:31.813070 update_engine[1261]: I1028 23:20:31.813058 1261 payload_state.cc:203] Payload backoff logic is disabled. Can proceed with the download Oct 28 23:20:31.813136 update_engine[1261]: I1028 23:20:31.813125 1261 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction Oct 28 23:20:31.813179 update_engine[1261]: I1028 23:20:31.813170 1261 omaha_response_handler_action.cc:43] Using Url0 as the download url this time Oct 28 23:20:31.813235 update_engine[1261]: I1028 23:20:31.813226 1261 prefs.cc:51] update-state-next-operation not present in /var/lib/update_engine/prefs Oct 28 23:20:31.813286 update_engine[1261]: E1028 23:20:31.813275 1261 payload_processor.cc:488] prefs->GetInt64(kPrefsUpdateStateNextOperation, &next_operation) && next_operation != kUpdateStateOperationInvalid && next_operation > 0 failed. Oct 28 23:20:31.814579 update_engine[1261]: I1028 23:20:31.814554 1261 omaha_response_handler_action.cc:85] Using this install plan: Oct 28 23:20:31.814656 update_engine[1261]: I1028 23:20:31.814644 1261 install_plan.cc:53] InstallPlan: , new_update, url: http://10.0.0.6:34567/packages/update.gz, payload size: 439722381, payload hash: 8hFWwyLnzF+7GFbpjt+cVnzq4hiRyr5fK3xWNMCkyeg=, partition_path: /dev/vda4, kernel_path: /boot/flatcar/vmlinuz-b, pcr_policy_path: /var/lib/update_engine/pcrs-b.zip, old_partition_path: /dev/vda3, old_kernel_path: /boot/flatcar/vmlinuz-a Oct 28 23:20:31.814782 update_engine[1261]: I1028 23:20:31.814769 1261 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaResponseHandlerAction, starting FilesystemCopierAction Oct 28 23:20:31.815844 update_engine[1261]: I1028 23:20:31.815822 1261 filesystem_copier_action.cc:296] Filesystem size: 1073741824 Oct 28 23:20:31.831835 sshd[1282]: Accepted publickey for core from 10.0.0.1 port 40918 ssh2: RSA SHA256:HTG7MLlKNvOxJPTb3qwiqi6VI8nPd76okQgzGcvxc1o Oct 28 23:20:31.832572 sshd[1282]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 23:20:31.834995 kernel: audit: type=1006 audit(1761693631.831:358): pid=1282 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=25 res=1 Oct 28 23:20:31.836719 systemd-logind[907]: New session 25 of user core. Oct 28 23:20:31.843127 systemd[1]: Started Session 25 of User core. Oct 28 23:20:31.900773 sshd[1282]: pam_unix(sshd:session): session closed for user core Oct 28 23:20:31.903351 systemd[1]: sshd@24-10.0.0.7:22-10.0.0.1:40918.service: Deactivated successfully. Oct 28 23:20:31.904006 systemd[1]: session-25.scope: Deactivated successfully. Oct 28 23:20:31.904782 systemd-logind[907]: Session 25 logged out. Waiting for processes to exit. Oct 28 23:20:31.905573 systemd-logind[907]: Removed session 25. Oct 28 23:20:33.540275 update_engine[1261]: I1028 23:20:33.540237 1261 filesystem_copier_action.cc:274] Hash: MqVcpLuSy9a3pkT1U79KicfT+9MRnGj6QbL170vealU= Oct 28 23:20:33.540704 update_engine[1261]: I1028 23:20:33.540686 1261 action_processor.cc:82] ActionProcessor::ActionComplete: finished FilesystemCopierAction, starting KernelCopierAction Oct 28 23:20:33.603728 update_engine[1261]: E1028 23:20:33.603685 1261 kernel_copier_action.cc:60] Failed to copy kernel from /boot/flatcar/vmlinuz-a to /boot/flatcar/vmlinuz-b Oct 28 23:20:33.603940 update_engine[1261]: I1028 23:20:33.603930 1261 action_processor.cc:68] ActionProcessor::ActionComplete: KernelCopierAction action failed. Aborting processing. Oct 28 23:20:33.604007 update_engine[1261]: I1028 23:20:33.603997 1261 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type KernelCopierAction Oct 28 23:20:33.604052 update_engine[1261]: I1028 23:20:33.604043 1261 update_attempter.cc:302] Processing Done. Oct 28 23:20:33.604142 update_engine[1261]: E1028 23:20:33.604132 1261 update_attempter.cc:615] Update failed. Oct 28 23:20:33.604189 update_engine[1261]: I1028 23:20:33.604178 1261 payload_state.cc:97] Updating payload state for error code: 1 (kActionCodeError) Oct 28 23:20:33.604240 update_engine[1261]: I1028 23:20:33.604231 1261 payload_state.cc:276] Incrementing the URL failure count Oct 28 23:20:33.604286 update_engine[1261]: I1028 23:20:33.604276 1261 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 28 23:20:33.604540 update_engine[1261]: I1028 23:20:33.604523 1261 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Oct 28 23:20:33.604619 update_engine[1261]: I1028 23:20:33.604609 1261 omaha_request_action.cc:268] Posting an Omaha request to http://10.0.0.6:34567/v1/update Oct 28 23:20:33.604665 update_engine[1261]: I1028 23:20:33.604656 1261 omaha_request_action.cc:269] Request: Oct 28 23:20:33.604665 update_engine[1261]: Oct 28 23:20:33.604665 update_engine[1261]: Oct 28 23:20:33.604665 update_engine[1261]: Oct 28 23:20:33.604665 update_engine[1261]: Oct 28 23:20:33.604665 update_engine[1261]: Oct 28 23:20:33.604665 update_engine[1261]: Oct 28 23:20:33.604812 update_engine[1261]: I1028 23:20:33.604802 1261 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 28 23:20:33.604927 update_engine[1261]: I1028 23:20:33.604915 1261 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 28 23:20:33.605107 update_engine[1261]: I1028 23:20:33.605093 1261 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 28 23:20:33.725788 update_engine[1261]: I1028 23:20:33.725754 1261 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 28 23:20:33.726161 update_engine[1261]: I1028 23:20:33.726145 1261 libcurl_http_fetcher.cc:305] Transfer completed (200), 241 bytes downloaded Oct 28 23:20:33.726235 update_engine[1261]: I1028 23:20:33.726223 1261 omaha_request_action.cc:619] Omaha request response: Oct 28 23:20:33.726235 update_engine[1261]: Oct 28 23:20:33.726235 update_engine[1261]: Oct 28 23:20:33.726235 update_engine[1261]: Oct 28 23:20:33.726235 update_engine[1261]: Oct 28 23:20:33.726235 update_engine[1261]: Oct 28 23:20:33.726235 update_engine[1261]: Oct 28 23:20:33.726391 update_engine[1261]: E1028 23:20:33.726382 1261 omaha_request_action.cc:626] HTTP reported success but Omaha reports an error. Oct 28 23:20:33.726440 update_engine[1261]: I1028 23:20:33.726430 1261 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 28 23:20:33.726482 update_engine[1261]: I1028 23:20:33.726473 1261 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 28 23:20:33.726527 update_engine[1261]: I1028 23:20:33.726517 1261 update_attempter.cc:302] Processing Done. Oct 28 23:20:33.726567 update_engine[1261]: I1028 23:20:33.726559 1261 update_attempter.cc:306] Error event sent. Oct 28 23:20:41.909286 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:48096). Oct 28 23:20:41.947686 sshd[1297]: Accepted publickey for core from 10.0.0.1 port 48096 ssh2: RSA SHA256:HTG7MLlKNvOxJPTb3qwiqi6VI8nPd76okQgzGcvxc1o Oct 28 23:20:41.948876 sshd[1297]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 23:20:41.950979 kernel: audit: type=1006 audit(1761693641.947:359): pid=1297 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=26 res=1 Oct 28 23:20:41.952361 systemd-logind[907]: New session 26 of user core. Oct 28 23:20:41.966113 systemd[1]: Started Session 26 of User core. Oct 28 23:20:42.021761 sshd[1297]: pam_unix(sshd:session): session closed for user core Oct 28 23:20:42.030571 systemd[1]: sshd@25-10.0.0.7:22-10.0.0.1:48096.service: Deactivated successfully. Oct 28 23:20:42.031137 systemd[1]: session-26.scope: Deactivated successfully. Oct 28 23:20:42.031710 systemd-logind[907]: Session 26 logged out. Waiting for processes to exit. Oct 28 23:20:42.032691 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:48112). Oct 28 23:20:42.033349 systemd-logind[907]: Removed session 26. Oct 28 23:20:42.068686 sshd[1304]: Accepted publickey for core from 10.0.0.1 port 48112 ssh2: RSA SHA256:HTG7MLlKNvOxJPTb3qwiqi6VI8nPd76okQgzGcvxc1o Oct 28 23:20:42.069876 sshd[1304]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 23:20:42.071978 kernel: audit: type=1006 audit(1761693642.068:360): pid=1304 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=27 res=1 Oct 28 23:20:42.074068 systemd-logind[907]: New session 27 of user core. Oct 28 23:20:42.080102 systemd[1]: Started Session 27 of User core. Oct 28 23:20:42.137129 sshd[1304]: pam_unix(sshd:session): session closed for user core Oct 28 23:20:42.147651 systemd[1]: sshd@26-10.0.0.7:22-10.0.0.1:48112.service: Deactivated successfully. Oct 28 23:20:42.148177 systemd[1]: session-27.scope: Deactivated successfully. Oct 28 23:20:42.148691 systemd-logind[907]: Session 27 logged out. Waiting for processes to exit. Oct 28 23:20:42.149652 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:48114). Oct 28 23:20:42.150261 systemd-logind[907]: Removed session 27. Oct 28 23:20:42.187724 sshd[1312]: Accepted publickey for core from 10.0.0.1 port 48114 ssh2: RSA SHA256:HTG7MLlKNvOxJPTb3qwiqi6VI8nPd76okQgzGcvxc1o Oct 28 23:20:42.188449 sshd[1312]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 23:20:42.190981 kernel: audit: type=1006 audit(1761693642.187:361): pid=1312 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=28 res=1 Oct 28 23:20:42.192452 systemd-logind[907]: New session 28 of user core. Oct 28 23:20:42.198124 systemd[1]: Started Session 28 of User core. Oct 28 23:20:42.246747 sshd[1312]: pam_unix(sshd:session): session closed for user core Oct 28 23:20:42.255785 systemd[1]: sshd@27-10.0.0.7:22-10.0.0.1:48114.service: Deactivated successfully. Oct 28 23:20:42.256416 systemd[1]: session-28.scope: Deactivated successfully. Oct 28 23:20:42.256972 systemd-logind[907]: Session 28 logged out. Waiting for processes to exit. Oct 28 23:20:42.258066 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:48116). Oct 28 23:20:42.258720 systemd-logind[907]: Removed session 28. Oct 28 23:20:42.294676 sshd[1318]: Accepted publickey for core from 10.0.0.1 port 48116 ssh2: RSA SHA256:HTG7MLlKNvOxJPTb3qwiqi6VI8nPd76okQgzGcvxc1o Oct 28 23:20:42.295574 sshd[1318]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 23:20:42.297972 kernel: audit: type=1006 audit(1761693642.294:362): pid=1318 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=29 res=1 Oct 28 23:20:42.299579 systemd-logind[907]: New session 29 of user core. Oct 28 23:20:42.306097 systemd[1]: Started Session 29 of User core. Oct 28 23:20:42.356178 sshd[1318]: pam_unix(sshd:session): session closed for user core Oct 28 23:20:42.368946 systemd[1]: sshd@28-10.0.0.7:22-10.0.0.1:48116.service: Deactivated successfully. Oct 28 23:20:42.369561 systemd[1]: session-29.scope: Deactivated successfully. Oct 28 23:20:42.370084 systemd-logind[907]: Session 29 logged out. Waiting for processes to exit. Oct 28 23:20:42.371206 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:48120). Oct 28 23:20:42.371791 systemd-logind[907]: Removed session 29. Oct 28 23:20:42.407041 sshd[1324]: Accepted publickey for core from 10.0.0.1 port 48120 ssh2: RSA SHA256:HTG7MLlKNvOxJPTb3qwiqi6VI8nPd76okQgzGcvxc1o Oct 28 23:20:42.408159 sshd[1324]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 23:20:42.410990 kernel: audit: type=1006 audit(1761693642.406:363): pid=1324 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=30 res=1 Oct 28 23:20:42.412207 systemd-logind[907]: New session 30 of user core. Oct 28 23:20:42.420105 systemd[1]: Started Session 30 of User core. Oct 28 23:20:42.470917 sudo[1328]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl stop sshd.socket Oct 28 23:20:42.471460 sudo[1328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 28 23:20:42.477611 systemd[1]: sshd.socket: Deactivated successfully. Oct 28 23:20:42.477979 systemd[1]: Closed OpenSSH Server Socket. Oct 28 23:20:42.479593 sudo[1328]: pam_unix(sudo:session): session closed for user root Oct 28 23:20:42.483417 sudo[1327]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/reboot Oct 28 23:20:42.483623 sudo[1327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 28 23:20:42.497789 systemd[1]: Stopping Session 1 of User core... Oct 28 23:20:42.498546 sshd[938]: pam_unix(sshd:session): session closed for user core Oct 28 23:20:42.499377 systemd[1]: Stopping Session 30 of User core... Oct 28 23:20:42.499753 sshd[1324]: pam_unix(sshd:session): session closed for user core Oct 28 23:20:42.500215 sudo[1327]: pam_unix(sudo:session): session closed for user root Oct 28 23:20:42.500834 systemd[1]: Removed slice Slice /system/addon-config. Oct 28 23:20:42.502278 systemd[1]: Removed slice Slice /system/addon-run. Oct 28 23:20:42.503655 systemd[1]: Removed slice Slice /system/modprobe. Oct 28 23:20:42.505035 systemd[1]: Removed slice Slice /system/system-cloudinit. Oct 28 23:20:42.506215 systemd[1]: Stopped target Multi-User System. Oct 28 23:20:42.507291 systemd[1]: Stopped target Login Prompts. Oct 28 23:20:42.508308 systemd[1]: Stopped target Containers. Oct 28 23:20:42.509220 systemd[1]: Stopped target Host and Network Name Lookups. Oct 28 23:20:42.510347 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 28 23:20:42.511451 systemd[1]: Stopped target Timer Units. Oct 28 23:20:42.512475 systemd[1]: logrotate.timer: Deactivated successfully. Oct 28 23:20:42.512638 systemd[1]: Stopped Daily Log Rotation. Oct 28 23:20:42.513533 systemd[1]: mdadm.timer: Deactivated successfully. Oct 28 23:20:42.513681 systemd[1]: Stopped Weekly check for MD array's redundancy information.. Oct 28 23:20:42.514933 systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. Oct 28 23:20:42.515100 systemd[1]: Stopped Daily Cleanup of Temporary Directories. Oct 28 23:20:42.516200 systemd[1]: Stopped target System Time Set. Oct 28 23:20:42.517158 systemd[1]: Stopped target Load user-provided cloud configs. Oct 28 23:20:42.518285 systemd[1]: Stopped target Load system-provided cloud configs. Oct 28 23:20:42.519479 systemd[1]: systemd-coredump.socket: Deactivated successfully. Oct 28 23:20:42.519696 systemd[1]: Closed Process Core Dump Socket. Oct 28 23:20:42.521602 systemd[1]: Unmounting Boot partition... Oct 28 23:20:42.522883 systemd[1]: Stopping Getty on tty1... Oct 28 23:20:42.524063 systemd[1]: Stopping Serial Getty on ttyAMA0... Oct 28 23:20:42.525063 systemd[1]: Stopping OpenSSH per-connection server daemon... Oct 28 23:20:42.526088 sshd[1324]: pam_systemd(sshd:session): Failed to release session: Interrupted system call Oct 28 23:20:42.526255 systemd[1]: Stopping OpenSSH per-connection server daemon (10.0.0.1:48120)... Oct 28 23:20:42.527438 systemd[1]: systemd-machine-id-commit.service: Deactivated successfully. Oct 28 23:20:42.527659 systemd[1]: Stopped Commit a transient machine-id on disk. Oct 28 23:20:42.528887 systemd[1]: Stopped target First Boot Complete. Oct 28 23:20:42.531153 systemd[1]: Stopping Load/Save Random Seed... Oct 28 23:20:42.532174 systemd[1]: Stopping Update Engine... Oct 28 23:20:42.535420 systemd[1]: sshd@0-10.0.0.7:22-10.0.0.1:58872.service: Deactivated successfully. Oct 28 23:20:42.535625 systemd[1]: Stopped OpenSSH per-connection server daemon. Oct 28 23:20:42.537348 systemd[1]: getty@tty1.service: Deactivated successfully. Oct 28 23:20:42.537547 systemd[1]: Stopped Getty on tty1. Oct 28 23:20:42.538740 systemd[1]: serial-getty@ttyAMA0.service: Deactivated successfully. Oct 28 23:20:42.538942 systemd[1]: Stopped Serial Getty on ttyAMA0. Oct 28 23:20:42.540203 systemd[1]: update-engine.service: Main process exited, code=exited, status=1/FAILURE Oct 28 23:20:42.540272 systemd[1]: update-engine.service: Failed with result 'exit-code'. Oct 28 23:20:42.540512 systemd[1]: Stopped Update Engine. Oct 28 23:20:42.541379 systemd[1]: update-engine.service: Consumed 2.368s CPU time. Oct 28 23:20:42.541603 systemd[1]: sshd@29-10.0.0.7:22-10.0.0.1:48120.service: Deactivated successfully. Oct 28 23:20:42.541784 systemd[1]: Stopped OpenSSH per-connection server daemon (10.0.0.1:48120). Oct 28 23:20:42.554531 systemd[1]: boot.mount: Deactivated successfully. Oct 28 23:20:42.554785 systemd[1]: Unmounted Boot partition. Oct 28 23:20:42.556078 systemd[1]: systemd-random-seed.service: Deactivated successfully. Oct 28 23:20:42.556289 systemd[1]: Stopped Load/Save Random Seed. Oct 28 23:20:42.557345 systemd[1]: session-30.scope: Deactivated successfully. Oct 28 23:20:42.557570 systemd[1]: Stopped Session 30 of User core. Oct 28 23:20:42.558633 systemd[1]: session-1.scope: Deactivated successfully. Oct 28 23:20:42.558877 systemd[1]: Stopped Session 1 of User core. Oct 28 23:20:42.560386 systemd-logind[907]: Session 1 logged out. Waiting for processes to exit. Oct 28 23:20:42.561321 systemd-logind[907]: Session 30 logged out. Waiting for processes to exit. Oct 28 23:20:42.561572 systemd[1]: Removed slice Slice /system/getty. Oct 28 23:20:42.562913 systemd[1]: Removed slice Slice /system/serial-getty. Oct 28 23:20:42.564218 systemd[1]: Removed slice Slice /system/sshd. Oct 28 23:20:42.565202 systemd[1]: sshd-keygen.service: Deactivated successfully. Oct 28 23:20:42.565306 systemd[1]: Stopped Generate sshd host keys. Oct 28 23:20:42.566257 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service: Deactivated successfully. Oct 28 23:20:42.566376 systemd[1]: Stopped File System Check on /dev/disk/by-label/EFI-SYSTEM. Oct 28 23:20:42.567741 systemd[1]: Stopping User Login Management... Oct 28 23:20:42.568596 systemd[1]: Stopping User Manager for UID 500... Oct 28 23:20:42.569566 systemd[948]: Stopped target Main User Target. Oct 28 23:20:42.569583 systemd[948]: Stopped target Basic System. Oct 28 23:20:42.569592 systemd[948]: Stopped target Paths. Oct 28 23:20:42.569601 systemd[948]: Stopped target Sockets. Oct 28 23:20:42.569617 systemd[948]: Reached target Shutdown. Oct 28 23:20:42.569623 systemd[948]: Stopped target Timers. Oct 28 23:20:42.569728 systemd[948]: Finished Exit the Session. Oct 28 23:20:42.569752 systemd[948]: Reached target Exit the Session. Oct 28 23:20:42.571231 systemd-logind[907]: Removed session 30. Oct 28 23:20:42.572058 systemd[1]: user@500.service: Deactivated successfully. Oct 28 23:20:42.572230 systemd[1]: Stopped User Manager for UID 500. Oct 28 23:20:42.573995 systemd[1]: Stopping User Runtime Directory /run/user/500... Oct 28 23:20:42.579347 systemd[1]: run-user-500.mount: Deactivated successfully. Oct 28 23:20:42.579416 systemd[1]: Unmounted /run/user/500. Oct 28 23:20:42.580591 systemd[1]: user-runtime-dir@500.service: Deactivated successfully. Oct 28 23:20:42.580806 systemd[1]: Stopped User Runtime Directory /run/user/500. Oct 28 23:20:42.582546 systemd[1]: Removed slice User Slice of UID 500. Oct 28 23:20:42.584318 systemd[1]: Stopping D-Bus System Message Bus... Oct 28 23:20:42.585914 systemd[1]: Stopping Permit User Sessions... Oct 28 23:20:42.587317 systemd[1]: dbus.service: Deactivated successfully. Oct 28 23:20:42.587566 systemd[1]: Stopped D-Bus System Message Bus. Oct 28 23:20:42.590223 systemd[1]: systemd-user-sessions.service: Deactivated successfully. Oct 28 23:20:42.590454 systemd[1]: Stopped Permit User Sessions. Oct 28 23:20:42.591511 systemd[1]: systemd-logind.service: Deactivated successfully. Oct 28 23:20:42.591748 systemd[1]: Stopped User Login Management. Oct 28 23:20:42.592770 systemd[1]: Stopped target Basic System. Oct 28 23:20:42.593580 systemd[1]: Stopped target Network. Oct 28 23:20:42.594331 systemd[1]: Stopped target Path Units. Oct 28 23:20:42.595050 systemd[1]: motdgen.path: Deactivated successfully. Oct 28 23:20:42.596072 systemd[1]: Stopped Watch for update engine configuration changes. Oct 28 23:20:42.597104 systemd[1]: user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path: Deactivated successfully. Oct 28 23:20:42.600000 audit: BPF prog-id=53 op=UNLOAD Oct 28 23:20:42.602016 kernel: audit: type=1334 audit(1761693642.600:364): prog-id=53 op=UNLOAD Oct 28 23:20:42.603011 systemd[1]: Stopped Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 28 23:20:42.604094 systemd[1]: Stopped target Remote File Systems. Oct 28 23:20:42.604909 systemd[1]: Stopped target Slice Units. Oct 28 23:20:42.606013 systemd[1]: Removed slice User and Session Slice. Oct 28 23:20:42.606854 systemd[1]: Stopped target Socket Units. Oct 28 23:20:42.607685 systemd[1]: dbus.socket: Deactivated successfully. Oct 28 23:20:42.607738 systemd[1]: Closed D-Bus System Message Bus Socket. Oct 28 23:20:42.608768 systemd[1]: docker.socket: Deactivated successfully. Oct 28 23:20:42.609014 systemd[1]: Closed Docker Socket for the API. Oct 28 23:20:42.609839 systemd[1]: Stopped target System Initialization. Oct 28 23:20:42.610759 systemd[1]: proc-sys-fs-binfmt_misc.automount: Deactivated successfully. Oct 28 23:20:42.610907 systemd[1]: Unset automount Arbitrary Executable File Formats File System Automount Point. Oct 28 23:20:42.612072 systemd[1]: Stopped target Local Verity Protected Volumes. Oct 28 23:20:42.613755 systemd[1]: Stopping Load Security Auditing Rules... Oct 28 23:20:42.615396 auditctl[1342]: No rules Oct 28 23:20:42.616099 systemd[1]: Stopping Network Name Resolution... Oct 28 23:20:42.616916 systemd[1]: Stopping Network Time Synchronization... Oct 28 23:20:42.617748 systemd[1]: systemd-update-done.service: Deactivated successfully. Oct 28 23:20:42.617814 systemd[1]: Stopped Update is Completed. Oct 28 23:20:42.618622 systemd[1]: ldconfig.service: Deactivated successfully. Oct 28 23:20:42.618678 systemd[1]: Stopped Rebuild Dynamic Linker Cache. Oct 28 23:20:42.619570 systemd[1]: systemd-hwdb-update.service: Deactivated successfully. Oct 28 23:20:42.619627 systemd[1]: Stopped Rebuild Hardware Database. Oct 28 23:20:42.620510 systemd[1]: systemd-journal-catalog-update.service: Deactivated successfully. Oct 28 23:20:42.620568 systemd[1]: Stopped Rebuild Journal Catalog. Oct 28 23:20:42.622209 systemd[1]: Stopping Record System Boot/Shutdown in UTMP... Oct 28 23:20:42.624762 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 28 23:20:42.625018 systemd[1]: Stopped Network Name Resolution. Oct 28 23:20:42.626108 systemd[1]: systemd-timesyncd.service: Deactivated successfully. Oct 28 23:20:42.626337 systemd[1]: Stopped Network Time Synchronization. Oct 28 23:20:42.627554 systemd[1]: audit-rules.service: Deactivated successfully. Oct 28 23:20:42.627759 systemd[1]: Stopped Load Security Auditing Rules. Oct 28 23:20:42.630230 systemd[1]: Stopping Network Configuration... Oct 28 23:20:42.631135 systemd[1]: systemd-update-utmp.service: Deactivated successfully. Oct 28 23:20:42.631340 systemd[1]: Stopped Record System Boot/Shutdown in UTMP. Oct 28 23:20:42.632898 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 28 23:20:42.632999 systemd[1]: Stopped Create Volatile Files and Directories. Oct 28 23:20:42.633944 systemd[1]: Stopped target Local File Systems. Oct 28 23:20:42.644009 systemd-networkd[833]: eth0: DHCPv6 lease lost Oct 28 23:20:42.652000 audit: BPF prog-id=61 op=UNLOAD Oct 28 23:20:42.653922 systemd[1]: Unmounting /etc/flatcar/update-payload-key.pub.pem... Oct 28 23:20:42.652000 audit: BPF prog-id=60 op=UNLOAD Oct 28 23:20:42.654985 kernel: audit: type=1334 audit(1761693642.652:365): prog-id=61 op=UNLOAD Oct 28 23:20:42.655025 kernel: audit: type=1334 audit(1761693642.652:366): prog-id=60 op=UNLOAD Oct 28 23:20:42.656443 systemd[1]: Unmounting External Media Directory... Oct 28 23:20:42.658030 systemd[1]: Unmounting /run/credentials/systemd-sysusers.service... Oct 28 23:20:42.659763 systemd[1]: Unmounting /run/torcx/unpack... Oct 28 23:20:42.661122 systemd[1]: Unmounting Temporary Directory /tmp... Oct 28 23:20:42.662442 systemd[1]: Unmounting /usr/share/oem... Oct 28 23:20:42.663984 systemd[1]: Unmounting /usr/share/update_engine/update-payload-key.pub.pem... Oct 28 23:20:42.666691 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 28 23:20:42.666945 systemd[1]: Stopped Network Configuration. Oct 28 23:20:42.667948 systemd[1]: etc-flatcar-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 28 23:20:42.668185 systemd[1]: Unmounted /etc/flatcar/update-payload-key.pub.pem. Oct 28 23:20:42.669495 systemd[1]: media.mount: Deactivated successfully. Oct 28 23:20:42.669742 systemd[1]: Unmounted External Media Directory. Oct 28 23:20:42.681160 systemd[1]: run-credentials-systemd\x2dsysusers.service.mount: Deactivated successfully. Oct 28 23:20:42.681391 systemd[1]: Unmounted /run/credentials/systemd-sysusers.service. Oct 28 23:20:42.682736 systemd[1]: run-torcx-unpack.mount: Deactivated successfully. Oct 28 23:20:42.682973 systemd[1]: Unmounted /run/torcx/unpack. Oct 28 23:20:42.684001 systemd[1]: tmp.mount: Deactivated successfully. Oct 28 23:20:42.684238 systemd[1]: Unmounted Temporary Directory /tmp. Oct 28 23:20:42.685231 systemd[1]: usr-share-oem.mount: Deactivated successfully. Oct 28 23:20:42.685475 systemd[1]: Unmounted /usr/share/oem. Oct 28 23:20:42.686384 systemd[1]: usr-share-update_engine-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 28 23:20:42.686611 systemd[1]: Unmounted /usr/share/update_engine/update-payload-key.pub.pem. Oct 28 23:20:42.687000 audit: BPF prog-id=59 op=UNLOAD Oct 28 23:20:42.688105 systemd[1]: Stopped target Swaps. Oct 28 23:20:42.688976 kernel: audit: type=1334 audit(1761693642.687:367): prog-id=59 op=UNLOAD Oct 28 23:20:42.689615 systemd[1]: Reached target Unmount All Filesystems. Oct 28 23:20:42.690531 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 28 23:20:42.690591 systemd[1]: Closed Network Service Netlink Socket. Oct 28 23:20:42.691492 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-OEM.service: Deactivated successfully. Oct 28 23:20:42.691558 systemd[1]: Stopped File System Check on /dev/disk/by-label/OEM. Oct 28 23:20:42.693155 systemd[1]: Removed slice Slice /system/systemd-fsck. Oct 28 23:20:42.694033 systemd[1]: Stopped target Preparation for Local File Systems. Oct 28 23:20:42.695008 systemd[1]: lvm2-activation.service: Deactivated successfully. Oct 28 23:20:42.695073 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 28 23:20:42.695996 systemd[1]: Stopped target Local Encrypted Volumes. Oct 28 23:20:42.696837 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 28 23:20:42.696902 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 28 23:20:42.698201 systemd[1]: systemd-ask-password-wall.path: Deactivated successfully. Oct 28 23:20:42.701992 systemd[1]: Stopped Forward Password Requests to Wall Directory Watch. Oct 28 23:20:42.703085 systemd[1]: lvm2-activation-early.service: Deactivated successfully. Oct 28 23:20:42.703154 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 28 23:20:42.704340 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 28 23:20:42.704398 systemd[1]: Stopped Apply Kernel Variables. Oct 28 23:20:42.705230 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 28 23:20:42.705284 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 28 23:20:42.706223 systemd[1]: systemd-sysusers.service: Deactivated successfully. Oct 28 23:20:42.706279 systemd[1]: Stopped Create System Users. Oct 28 23:20:42.707059 systemd[1]: systemd-remount-fs.service: Deactivated successfully. Oct 28 23:20:42.707113 systemd[1]: Stopped Remount Root and Kernel File Systems. Oct 28 23:20:42.708051 systemd[1]: Reached target System Shutdown. Oct 28 23:20:42.708816 systemd[1]: Reached target Late Shutdown Services. Oct 28 23:20:42.709853 systemd[1]: systemd-reboot.service: Deactivated successfully. Oct 28 23:20:42.709887 systemd[1]: Finished System Reboot. Oct 28 23:20:42.710641 systemd[1]: Reached target System Reboot. Oct 28 23:20:42.711418 systemd[1]: Shutting down. Oct 28 23:20:42.711000 audit: BPF prog-id=55 op=UNLOAD Oct 28 23:20:42.711000 audit: BPF prog-id=54 op=UNLOAD Oct 28 23:20:42.713978 kernel: audit: type=1334 audit(1761693642.711:368): prog-id=55 op=UNLOAD Oct 28 23:20:42.713000 audit: BPF prog-id=58 op=UNLOAD Oct 28 23:20:42.713000 audit: BPF prog-id=57 op=UNLOAD Oct 28 23:20:42.713000 audit: BPF prog-id=56 op=UNLOAD Oct 28 23:20:42.716000 audit: BPF prog-id=64 op=UNLOAD Oct 28 23:20:42.716000 audit: BPF prog-id=63 op=UNLOAD Oct 28 23:20:42.716000 audit: BPF prog-id=62 op=UNLOAD Oct 28 23:20:42.756001 systemd-shutdown[1]: Syncing filesystems and block devices. Oct 28 23:20:42.763871 systemd-shutdown[1]: Sending SIGTERM to remaining processes... Oct 28 23:20:42.763975 systemd-journald[797]: Journal stopped -- Reboot -- Oct 28 23:20:50.675918 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 28 23:20:50.675934 kernel: Linux version 5.10.107-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo 9.3.0-r1 p3) 9.3.0, GNU ld (Gentoo 2.36.1 p5) 2.36.1) #1 SMP PREEMPT Tue Mar 22 19:39:09 -00 2022 Oct 28 23:20:50.675943 kernel: efi: EFI v2.70 by EDK II Oct 28 23:20:50.675948 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82d018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 28 23:20:50.675953 kernel: efi: seeding entropy pool Oct 28 23:20:50.675958 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 28 23:20:50.675964 kernel: ACPI: Early table checksum verification disabled Oct 28 23:20:50.675969 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 28 23:20:50.675974 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 28 23:20:50.675979 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 23:20:50.675986 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 23:20:50.675991 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 23:20:50.675996 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 23:20:50.676001 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 23:20:50.676007 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 23:20:50.676012 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 23:20:50.676018 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 23:20:50.676024 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 23:20:50.676029 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 28 23:20:50.676034 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 28 23:20:50.676040 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 28 23:20:50.676045 kernel: NUMA: NODE_DATA [mem 0xdc95a900-0xdc95ffff] Oct 28 23:20:50.676050 kernel: Zone ranges: Oct 28 23:20:50.676056 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 28 23:20:50.676061 kernel: DMA32 empty Oct 28 23:20:50.676066 kernel: Normal empty Oct 28 23:20:50.676071 kernel: Movable zone start for each node Oct 28 23:20:50.676077 kernel: Early memory node ranges Oct 28 23:20:50.676083 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 28 23:20:50.676088 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 28 23:20:50.676093 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 28 23:20:50.676098 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 28 23:20:50.676103 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 28 23:20:50.676108 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 28 23:20:50.676114 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 28 23:20:50.676119 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 28 23:20:50.676124 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 28 23:20:50.676129 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 28 23:20:50.676134 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 28 23:20:50.676140 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 28 23:20:50.676148 kernel: On node 0 totalpages: 643072 Oct 28 23:20:50.676153 kernel: DMA zone: 10048 pages used for memmap Oct 28 23:20:50.676159 kernel: DMA zone: 0 pages reserved Oct 28 23:20:50.676165 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 28 23:20:50.676170 kernel: psci: probing for conduit method from ACPI. Oct 28 23:20:50.676175 kernel: psci: PSCIv1.1 detected in firmware. Oct 28 23:20:50.676181 kernel: psci: Using standard PSCI v0.2 function IDs Oct 28 23:20:50.676186 kernel: psci: Trusted OS migration not required Oct 28 23:20:50.676193 kernel: psci: SMC Calling Convention v1.1 Oct 28 23:20:50.676198 kernel: ACPI: SRAT not present Oct 28 23:20:50.676204 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 28 23:20:50.676210 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 28 23:20:50.676215 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 28 23:20:50.676221 kernel: Detected PIPT I-cache on CPU0 Oct 28 23:20:50.676226 kernel: CPU features: detected: GIC system register CPU interface Oct 28 23:20:50.676232 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 28 23:20:50.676237 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 28 23:20:50.676243 kernel: CPU features: detected: Hardware dirty bit management Oct 28 23:20:50.676248 kernel: CPU features: detected: Spectre-v4 Oct 28 23:20:50.676255 kernel: CPU features: detected: ARM erratum 1418040 Oct 28 23:20:50.676260 kernel: CPU features: detected: Spectre-BHB Oct 28 23:20:50.676266 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 28 23:20:50.676271 kernel: Policy zone: DMA Oct 28 23:20:50.676277 kernel: Kernel command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 28 23:20:50.676283 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 28 23:20:50.676289 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 28 23:20:50.676295 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 28 23:20:50.676301 kernel: Memory: 2452100K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120188K reserved, 0K cma-reserved) Oct 28 23:20:50.676307 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 28 23:20:50.676312 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 28 23:20:50.676319 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 28 23:20:50.676325 kernel: rcu: RCU event tracing is enabled. Oct 28 23:20:50.676331 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 28 23:20:50.676336 kernel: Trampoline variant of Tasks RCU enabled. Oct 28 23:20:50.676342 kernel: Tracing variant of Tasks RCU enabled. Oct 28 23:20:50.676348 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 28 23:20:50.676354 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 28 23:20:50.676359 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 28 23:20:50.676365 kernel: GICv3: 256 SPIs implemented Oct 28 23:20:50.676370 kernel: GICv3: 0 Extended SPIs implemented Oct 28 23:20:50.676376 kernel: GICv3: Distributor has no Range Selector support Oct 28 23:20:50.676381 kernel: GICv3: 16 PPIs implemented Oct 28 23:20:50.676388 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 28 23:20:50.676393 kernel: ACPI: SRAT not present Oct 28 23:20:50.676399 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 28 23:20:50.676404 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 28 23:20:50.676410 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 28 23:20:50.676416 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 28 23:20:50.676421 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 28 23:20:50.676427 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 23:20:50.676433 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 28 23:20:50.676438 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 28 23:20:50.676444 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 28 23:20:50.676450 kernel: arm-pv: using stolen time PV Oct 28 23:20:50.676457 kernel: Console: colour dummy device 80x25 Oct 28 23:20:50.676462 kernel: ACPI: Core revision 20200925 Oct 28 23:20:50.676468 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 28 23:20:50.676474 kernel: pid_max: default: 32768 minimum: 301 Oct 28 23:20:50.676480 kernel: LSM: Security Framework initializing Oct 28 23:20:50.676486 kernel: SELinux: Initializing. Oct 28 23:20:50.676492 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 28 23:20:50.676498 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 28 23:20:50.676503 kernel: rcu: Hierarchical SRCU implementation. Oct 28 23:20:50.676510 kernel: Platform MSI: ITS@0x8080000 domain created Oct 28 23:20:50.676516 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 28 23:20:50.676521 kernel: Remapping and enabling EFI services. Oct 28 23:20:50.676527 kernel: smp: Bringing up secondary CPUs ... Oct 28 23:20:50.676533 kernel: Detected PIPT I-cache on CPU1 Oct 28 23:20:50.676539 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 28 23:20:50.676559 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 28 23:20:50.676565 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 23:20:50.676571 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 28 23:20:50.676577 kernel: Detected PIPT I-cache on CPU2 Oct 28 23:20:50.676584 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 28 23:20:50.676590 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 28 23:20:50.676596 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 23:20:50.676602 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 28 23:20:50.676608 kernel: Detected PIPT I-cache on CPU3 Oct 28 23:20:50.676618 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 28 23:20:50.676624 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 28 23:20:50.676631 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 23:20:50.676637 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 28 23:20:50.676643 kernel: smp: Brought up 1 node, 4 CPUs Oct 28 23:20:50.676649 kernel: SMP: Total of 4 processors activated. Oct 28 23:20:50.676661 kernel: CPU features: detected: Privileged Access Never Oct 28 23:20:50.676667 kernel: CPU features: detected: LSE atomic instructions Oct 28 23:20:50.676673 kernel: CPU features: detected: User Access Override Oct 28 23:20:50.676679 kernel: CPU features: detected: 32-bit EL0 Support Oct 28 23:20:50.676685 kernel: CPU features: detected: Common not Private translations Oct 28 23:20:50.676691 kernel: CPU features: detected: RAS Extension Support Oct 28 23:20:50.676699 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 28 23:20:50.676705 kernel: CPU features: detected: CRC32 instructions Oct 28 23:20:50.676711 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 28 23:20:50.676717 kernel: CPU: All CPU(s) started at EL1 Oct 28 23:20:50.676723 kernel: alternatives: patching kernel code Oct 28 23:20:50.676730 kernel: devtmpfs: initialized Oct 28 23:20:50.676736 kernel: KASLR enabled Oct 28 23:20:50.676742 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 28 23:20:50.676748 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 28 23:20:50.676754 kernel: pinctrl core: initialized pinctrl subsystem Oct 28 23:20:50.676760 kernel: SMBIOS 3.0.0 present. Oct 28 23:20:50.676766 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 28 23:20:50.676772 kernel: NET: Registered protocol family 16 Oct 28 23:20:50.676778 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 28 23:20:50.676784 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 28 23:20:50.676791 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 28 23:20:50.676798 kernel: audit: initializing netlink subsys (disabled) Oct 28 23:20:50.676804 kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1 Oct 28 23:20:50.676810 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 28 23:20:50.676816 kernel: cpuidle: using governor menu Oct 28 23:20:50.676822 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 28 23:20:50.676828 kernel: ASID allocator initialised with 32768 entries Oct 28 23:20:50.676834 kernel: ACPI: bus type PCI registered Oct 28 23:20:50.676840 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 28 23:20:50.676848 kernel: Serial: AMBA PL011 UART driver Oct 28 23:20:50.676854 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 28 23:20:50.676860 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 28 23:20:50.676866 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 28 23:20:50.676872 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 28 23:20:50.676878 kernel: cryptd: max_cpu_qlen set to 1000 Oct 28 23:20:50.676884 kernel: ACPI: Added _OSI(Module Device) Oct 28 23:20:50.676890 kernel: ACPI: Added _OSI(Processor Device) Oct 28 23:20:50.676896 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 28 23:20:50.676903 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 28 23:20:50.676909 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 28 23:20:50.676915 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 28 23:20:50.676921 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 28 23:20:50.676929 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 28 23:20:50.676936 kernel: ACPI: Interpreter enabled Oct 28 23:20:50.676942 kernel: ACPI: Using GIC for interrupt routing Oct 28 23:20:50.676948 kernel: ACPI: MCFG table detected, 1 entries Oct 28 23:20:50.676954 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 28 23:20:50.676960 kernel: printk: console [ttyAMA0] enabled Oct 28 23:20:50.676996 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 28 23:20:50.677132 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 28 23:20:50.677204 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 28 23:20:50.677265 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 28 23:20:50.677317 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 28 23:20:50.677369 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 28 23:20:50.677377 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 28 23:20:50.677386 kernel: PCI host bridge to bus 0000:00 Oct 28 23:20:50.677443 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 28 23:20:50.677492 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 28 23:20:50.677553 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 28 23:20:50.677607 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 28 23:20:50.677685 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 28 23:20:50.677751 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 28 23:20:50.677810 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 28 23:20:50.677874 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 28 23:20:50.677930 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 28 23:20:50.677984 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 28 23:20:50.678038 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 28 23:20:50.678092 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 28 23:20:50.678140 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 28 23:20:50.678190 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 28 23:20:50.678238 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 28 23:20:50.678246 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 28 23:20:50.678252 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 28 23:20:50.678259 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 28 23:20:50.678265 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 28 23:20:50.678271 kernel: vgaarb: loaded Oct 28 23:20:50.678277 kernel: Registered efivars operations Oct 28 23:20:50.678283 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 28 23:20:50.678290 kernel: VFS: Disk quotas dquot_6.6.0 Oct 28 23:20:50.678296 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 28 23:20:50.678302 kernel: pnp: PnP ACPI init Oct 28 23:20:50.678364 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 28 23:20:50.678414 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 28 23:20:50.678422 kernel: pnp: PnP ACPI: found 1 devices Oct 28 23:20:50.678429 kernel: NET: Registered protocol family 2 Oct 28 23:20:50.678435 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 28 23:20:50.678443 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 28 23:20:50.678449 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 28 23:20:50.678455 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 28 23:20:50.678461 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 28 23:20:50.678467 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 28 23:20:50.678474 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 28 23:20:50.678480 kernel: NET: Registered protocol family 1 Oct 28 23:20:50.678486 kernel: PCI: CLS 0 bytes, default 64 Oct 28 23:20:50.678492 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 28 23:20:50.678499 kernel: kvm [1]: HYP mode not available Oct 28 23:20:50.678505 kernel: Initialise system trusted keyrings Oct 28 23:20:50.678511 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 28 23:20:50.678517 kernel: Key type asymmetric registered Oct 28 23:20:50.678523 kernel: Asymmetric key parser 'x509' registered Oct 28 23:20:50.678529 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 28 23:20:50.678535 kernel: io scheduler mq-deadline registered Oct 28 23:20:50.678556 kernel: io scheduler kyber registered Oct 28 23:20:50.678563 kernel: io scheduler bfq registered Oct 28 23:20:50.678571 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 28 23:20:50.678577 kernel: ACPI: Power Button [PWRB] Oct 28 23:20:50.678583 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 28 23:20:50.678641 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 28 23:20:50.678650 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 28 23:20:50.678664 kernel: msm_serial: driver initialized Oct 28 23:20:50.678670 kernel: random: fast init done Oct 28 23:20:50.678676 kernel: random: crng init done Oct 28 23:20:50.678682 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 28 23:20:50.678689 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 28 23:20:50.678697 kernel: thunder_xcv, ver 1.0 Oct 28 23:20:50.678703 kernel: thunder_bgx, ver 1.0 Oct 28 23:20:50.678708 kernel: nicpf, ver 1.0 Oct 28 23:20:50.678714 kernel: nicvf, ver 1.0 Oct 28 23:20:50.678782 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 28 23:20:50.678834 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-28T23:20:50 UTC (1761693650) Oct 28 23:20:50.678842 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 28 23:20:50.678848 kernel: NET: Registered protocol family 10 Oct 28 23:20:50.678856 kernel: Segment Routing with IPv6 Oct 28 23:20:50.678862 kernel: NET: Registered protocol family 17 Oct 28 23:20:50.678868 kernel: Key type dns_resolver registered Oct 28 23:20:50.678874 kernel: registered taskstats version 1 Oct 28 23:20:50.678880 kernel: Loading compiled-in X.509 certificates Oct 28 23:20:50.678886 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 28 23:20:50.678892 kernel: Key type ._fscrypt registered Oct 28 23:20:50.678898 kernel: Key type .fscrypt registered Oct 28 23:20:50.678904 kernel: Key type fscrypt-provisioning registered Oct 28 23:20:50.678911 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 28 23:20:50.678917 kernel: ima: Allocated hash algorithm: sha1 Oct 28 23:20:50.678924 kernel: ima: No architecture policies found Oct 28 23:20:50.678930 kernel: Freeing unused kernel memory: 34240K Oct 28 23:20:50.678936 kernel: Run /init as init process Oct 28 23:20:50.678942 kernel: with arguments: Oct 28 23:20:50.678947 kernel: /init Oct 28 23:20:50.678954 kernel: with environment: Oct 28 23:20:50.678959 kernel: HOME=/ Oct 28 23:20:50.678965 kernel: TERM=linux Oct 28 23:20:50.678973 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 28 23:20:50.678981 systemd[1]: systemd 249 running in system mode (+PAM -AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Oct 28 23:20:50.678989 systemd[1]: Detected virtualization kvm. Oct 28 23:20:50.678995 systemd[1]: Detected architecture arm64. Oct 28 23:20:50.679002 systemd[1]: Running in initial RAM disk. Oct 28 23:20:50.679009 systemd[1]: No hostname configured, using default hostname. Oct 28 23:20:50.679015 systemd[1]: Hostname set to . Oct 28 23:20:50.679022 systemd[1]: Initializing machine ID from VM UUID. Oct 28 23:20:50.679029 systemd[1]: Queued start job for default target Initrd Default Target. Oct 28 23:20:50.679035 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 28 23:20:50.679042 systemd[1]: Reached target Local Encrypted Volumes. Oct 28 23:20:50.679048 systemd[1]: Reached target Path Units. Oct 28 23:20:50.679054 systemd[1]: Reached target Slice Units. Oct 28 23:20:50.679060 systemd[1]: Reached target Swaps. Oct 28 23:20:50.679066 systemd[1]: Reached target Timer Units. Oct 28 23:20:50.679074 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 28 23:20:50.679081 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 28 23:20:50.679087 systemd[1]: Listening on Journal Audit Socket. Oct 28 23:20:50.679093 systemd[1]: Listening on Journal Socket (/dev/log). Oct 28 23:20:50.679100 systemd[1]: Listening on Journal Socket. Oct 28 23:20:50.679106 systemd[1]: Listening on udev Control Socket. Oct 28 23:20:50.679112 systemd[1]: Listening on udev Kernel Socket. Oct 28 23:20:50.679119 systemd[1]: Reached target Socket Units. Oct 28 23:20:50.679125 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 28 23:20:50.679133 systemd[1]: Starting Create List of Static Device Nodes... Oct 28 23:20:50.679139 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 28 23:20:50.679146 systemd[1]: Starting Journal Service... Oct 28 23:20:50.679152 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 28 23:20:50.679158 systemd[1]: Starting Apply Kernel Variables... Oct 28 23:20:50.679165 systemd[1]: Starting Setup Virtual Console... Oct 28 23:20:50.679171 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 28 23:20:50.679177 kernel: SCSI subsystem initialized Oct 28 23:20:50.679183 systemd[1]: Finished Create List of Static Device Nodes. Oct 28 23:20:50.679190 systemd[1]: Finished Apply Kernel Variables. Oct 28 23:20:50.679197 kernel: Loading iSCSI transport class v2.0-870. Oct 28 23:20:50.679203 systemd[1]: Finished Setup Virtual Console. Oct 28 23:20:50.679212 systemd-journald[270]: Journal started Oct 28 23:20:50.679248 systemd-journald[270]: Runtime Journal (/run/log/journal/aee9fbc22d0f45d9a987af37fdde7c89) is 6.0M, max 48.5M, 42.4M free. Oct 28 23:20:50.680655 systemd[1]: Started Journal Service. Oct 28 23:20:50.681416 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 28 23:20:50.682981 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 28 23:20:50.686122 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 28 23:20:50.694404 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 28 23:20:50.697203 systemd[1]: Starting dracut cmdline hook... Oct 28 23:20:50.705686 dracut-cmdline[290]: dracut-dracut-053 Oct 28 23:20:50.707845 dracut-cmdline[290]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 28 23:20:50.760571 kernel: iscsi: registered transport (tcp) Oct 28 23:20:50.771574 kernel: iscsi: registered transport (qla4xxx) Oct 28 23:20:50.771602 kernel: QLogic iSCSI HBA Driver Oct 28 23:20:50.780528 systemd[1]: Finished dracut cmdline hook. Oct 28 23:20:50.781934 systemd[1]: Starting dracut pre-udev hook... Oct 28 23:20:50.783095 systemd[1]: Starting Open-iSCSI... Oct 28 23:20:50.785260 iscsid[376]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 28 23:20:50.785260 iscsid[376]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Oct 28 23:20:50.785260 iscsid[376]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 28 23:20:50.785260 iscsid[376]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 28 23:20:50.785260 iscsid[376]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 28 23:20:50.785260 iscsid[376]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 28 23:20:50.798897 kernel: device-mapper: uevent: version 1.0.3 Oct 28 23:20:50.798913 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 28 23:20:50.788775 systemd[1]: Started Open-iSCSI. Oct 28 23:20:50.832583 kernel: raid6: neonx8 gen() 13763 MB/s Oct 28 23:20:50.849571 kernel: raid6: neonx8 xor() 10698 MB/s Oct 28 23:20:50.866568 kernel: raid6: neonx4 gen() 13546 MB/s Oct 28 23:20:50.883579 kernel: raid6: neonx4 xor() 11547 MB/s Oct 28 23:20:50.900570 kernel: raid6: neonx2 gen() 12985 MB/s Oct 28 23:20:50.917571 kernel: raid6: neonx2 xor() 9862 MB/s Oct 28 23:20:50.934572 kernel: raid6: neonx1 gen() 10891 MB/s Oct 28 23:20:50.951573 kernel: raid6: neonx1 xor() 8779 MB/s Oct 28 23:20:50.968578 kernel: raid6: int64x8 gen() 6818 MB/s Oct 28 23:20:50.985567 kernel: raid6: int64x8 xor() 3793 MB/s Oct 28 23:20:51.002567 kernel: raid6: int64x4 gen() 6906 MB/s Oct 28 23:20:51.019576 kernel: raid6: int64x4 xor() 3832 MB/s Oct 28 23:20:51.036570 kernel: raid6: int64x2 gen() 6168 MB/s Oct 28 23:20:51.053567 kernel: raid6: int64x2 xor() 3312 MB/s Oct 28 23:20:51.070577 kernel: raid6: int64x1 gen() 5012 MB/s Oct 28 23:20:51.087797 kernel: raid6: int64x1 xor() 2594 MB/s Oct 28 23:20:51.087811 kernel: raid6: using algorithm neonx8 gen() 13763 MB/s Oct 28 23:20:51.087820 kernel: raid6: .... xor() 10698 MB/s, rmw enabled Oct 28 23:20:51.089023 kernel: raid6: using neon recovery algorithm Oct 28 23:20:51.098570 kernel: xor: measuring software checksum speed Oct 28 23:20:51.099568 kernel: 8regs : 17152 MB/sec Oct 28 23:20:51.100567 kernel: 32regs : 20770 MB/sec Oct 28 23:20:51.102166 kernel: arm64_neon : 29153 MB/sec Oct 28 23:20:51.102180 kernel: xor: using function: arm64_neon (29153 MB/sec) Oct 28 23:20:51.146572 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 28 23:20:51.157566 systemd[1]: Finished dracut pre-udev hook. Oct 28 23:20:51.158000 audit: BPF prog-id=6 op=LOAD Oct 28 23:20:51.159000 audit: BPF prog-id=7 op=LOAD Oct 28 23:20:51.160921 kernel: audit: type=1334 audit(1761693651.158:2): prog-id=6 op=LOAD Oct 28 23:20:51.160944 kernel: audit: type=1334 audit(1761693651.159:3): prog-id=7 op=LOAD Oct 28 23:20:51.160953 kernel: audit: type=1334 audit(1761693651.160:4): prog-id=8 op=LOAD Oct 28 23:20:51.160000 audit: BPF prog-id=8 op=LOAD Oct 28 23:20:51.161286 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 28 23:20:51.176734 systemd-udevd[410]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 28 23:20:51.178365 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 28 23:20:51.180064 systemd[1]: Starting dracut pre-trigger hook... Oct 28 23:20:51.193179 dracut-pre-trigger[412]: rd.md=0: removing MD RAID activation Oct 28 23:20:51.221751 systemd[1]: Finished dracut pre-trigger hook. Oct 28 23:20:51.223131 systemd[1]: Starting Coldplug All udev Devices... Oct 28 23:20:51.232439 systemd-udevd[410]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 28 23:20:51.256832 systemd[1]: Finished Coldplug All udev Devices. Oct 28 23:20:51.258308 systemd[1]: Starting dracut initqueue hook... Oct 28 23:20:51.271151 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 28 23:20:51.271260 kernel: vda: detected capacity change from 0 to 4756340736 Oct 28 23:20:51.282118 systemd-udevd[457]: Using default interface naming scheme 'v249'. Oct 28 23:20:51.283338 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 28 23:20:51.299576 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by systemd-udevd (472) Oct 28 23:20:51.301618 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 28 23:20:51.302806 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 28 23:20:51.306596 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 28 23:20:51.313257 systemd[1]: Reached target Initrd Root Device. Oct 28 23:20:51.314749 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 28 23:20:51.318036 systemd[1]: Finished dracut initqueue hook. Oct 28 23:20:51.318996 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 28 23:20:51.319097 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 28 23:20:51.320587 systemd[1]: Reached target Preparation for Local File Systems. Oct 28 23:20:51.322280 systemd[1]: Reached target Preparation for Remote File Systems. Oct 28 23:20:51.324113 systemd[1]: Reached target Remote Encrypted Volumes. Oct 28 23:20:51.325753 systemd[1]: Reached target Remote File Systems. Oct 28 23:20:51.327966 systemd[1]: Starting dracut pre-mount hook... Oct 28 23:20:51.329349 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 28 23:20:51.336084 systemd[1]: Finished dracut pre-mount hook. Oct 28 23:20:51.337610 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 28 23:20:51.342551 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 28 23:20:51.346874 systemd-fsck[505]: ROOT: clean, 640/553792 files, 39961/553472 blocks Oct 28 23:20:51.349933 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 28 23:20:51.352155 systemd[1]: Mounting /sysroot... Oct 28 23:20:51.366908 systemd[1]: Found device /dev/mapper/usr. Oct 28 23:20:51.368792 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 28 23:20:51.370901 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 28 23:20:51.378200 systemd-fsck[516]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 28 23:20:51.378200 systemd-fsck[516]: You must have r/w access to the filesystem or be root Oct 28 23:20:51.378635 systemd-fsck[513]: fsck failed with exit status 8. Oct 28 23:20:51.378639 systemd-fsck[513]: Ignoring error. Oct 28 23:20:51.379231 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 28 23:20:51.381359 systemd[1]: Mounting /sysusr/usr... Oct 28 23:20:51.396468 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 28 23:20:51.396499 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 28 23:20:51.396990 systemd[1]: Mounted /sysroot. Oct 28 23:20:51.397703 systemd[1]: Reached target Initrd Root File System. Oct 28 23:20:51.399640 systemd[1]: Starting Reload Configuration from the Real Root... Oct 28 23:20:51.403663 systemd[1]: Reloading. Oct 28 23:20:51.406000 audit: BPF prog-id=3 op=UNLOAD Oct 28 23:20:51.407562 kernel: audit: type=1334 audit(1761693651.406:5): prog-id=3 op=UNLOAD Oct 28 23:20:51.407581 kernel: audit: type=1334 audit(1761693651.406:6): prog-id=6 op=UNLOAD Oct 28 23:20:51.406000 audit: BPF prog-id=6 op=UNLOAD Oct 28 23:20:51.410567 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 28 23:20:51.483000 audit: BPF prog-id=9 op=LOAD Oct 28 23:20:51.484000 audit: BPF prog-id=10 op=LOAD Oct 28 23:20:51.485829 kernel: audit: type=1334 audit(1761693651.483:7): prog-id=9 op=LOAD Oct 28 23:20:51.485856 kernel: audit: type=1334 audit(1761693651.484:8): prog-id=10 op=LOAD Oct 28 23:20:51.485865 kernel: audit: type=1334 audit(1761693651.485:9): prog-id=11 op=LOAD Oct 28 23:20:51.485000 audit: BPF prog-id=11 op=LOAD Oct 28 23:20:51.485000 audit: BPF prog-id=4 op=UNLOAD Oct 28 23:20:51.485000 audit: BPF prog-id=5 op=UNLOAD Oct 28 23:20:51.487557 kernel: audit: type=1334 audit(1761693651.485:10): prog-id=4 op=UNLOAD Oct 28 23:20:51.486000 audit: BPF prog-id=12 op=LOAD Oct 28 23:20:51.486000 audit: BPF prog-id=13 op=LOAD Oct 28 23:20:51.487000 audit: BPF prog-id=14 op=LOAD Oct 28 23:20:51.487000 audit: BPF prog-id=7 op=UNLOAD Oct 28 23:20:51.487000 audit: BPF prog-id=8 op=UNLOAD Oct 28 23:20:51.488678 systemd[1]: Mounted /sysusr/usr. Oct 28 23:20:51.490594 systemd[1]: Reached target Local File Systems. Oct 28 23:20:51.491406 systemd[1]: Reached target System Initialization. Oct 28 23:20:51.492896 systemd[1]: Reached target Basic System. Oct 28 23:20:51.495090 systemd[1]: Mounting /sysroot/usr... Oct 28 23:20:51.497385 systemd[1]: Mounted /sysroot/usr. Oct 28 23:20:51.506078 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 28 23:20:51.506191 systemd[1]: Finished Reload Configuration from the Real Root. Oct 28 23:20:51.508121 systemd[1]: Reached target Initrd File Systems. Oct 28 23:20:51.509661 systemd[1]: Reached target Initrd Default Target. Oct 28 23:20:51.511150 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 28 23:20:51.511747 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 28 23:20:51.521306 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 28 23:20:51.522879 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 28 23:20:51.528271 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 28 23:20:51.529286 systemd[1]: Stopped target Timer Units. Oct 28 23:20:51.530718 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 28 23:20:51.530822 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 28 23:20:51.532207 systemd[1]: Stopped target Initrd Default Target. Oct 28 23:20:51.533798 systemd[1]: Stopped target Basic System. Oct 28 23:20:51.535244 systemd[1]: Stopped target Initrd Root Device. Oct 28 23:20:51.536685 systemd[1]: Stopped target Path Units. Oct 28 23:20:51.537932 systemd[1]: Stopped target Remote File Systems. Oct 28 23:20:51.539375 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 28 23:20:51.540904 systemd[1]: Stopped target Slice Units. Oct 28 23:20:51.542273 systemd[1]: Stopped target Socket Units. Oct 28 23:20:51.543823 systemd[1]: Stopped target System Initialization. Oct 28 23:20:51.545210 systemd[1]: Stopped target Local File Systems. Oct 28 23:20:51.547335 systemd[1]: Stopped target Preparation for Local File Systems. Oct 28 23:20:51.548900 systemd[1]: Stopped target Swaps. Oct 28 23:20:51.550119 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 28 23:20:51.550219 systemd[1]: Stopped dracut pre-mount hook. Oct 28 23:20:51.551686 systemd[1]: Stopped target Local Encrypted Volumes. Oct 28 23:20:51.552933 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 28 23:20:51.558599 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 28 23:20:51.559674 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 28 23:20:51.559767 systemd[1]: Stopped dracut initqueue hook. Oct 28 23:20:51.562914 iscsid[376]: iscsid shutting down. Oct 28 23:20:51.562689 systemd[1]: Stopping Open-iSCSI... Oct 28 23:20:51.563382 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 28 23:20:51.563498 systemd[1]: Stopped Apply Kernel Variables. Oct 28 23:20:51.564704 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 28 23:20:51.564788 systemd[1]: Stopped Coldplug All udev Devices. Oct 28 23:20:51.565951 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 28 23:20:51.566035 systemd[1]: Stopped dracut pre-trigger hook. Oct 28 23:20:51.567486 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 28 23:20:51.571521 systemd[1]: iscsid.service: Deactivated successfully. Oct 28 23:20:51.571643 systemd[1]: Stopped Open-iSCSI. Oct 28 23:20:51.573126 systemd[1]: iscsid.socket: Deactivated successfully. Oct 28 23:20:51.573170 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 28 23:20:51.574367 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 28 23:20:51.575879 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 28 23:20:51.575981 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 28 23:20:51.577636 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 28 23:20:51.587000 audit: BPF prog-id=12 op=UNLOAD Oct 28 23:20:51.577750 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 28 23:20:51.579242 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 28 23:20:51.579337 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 28 23:20:51.581228 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 28 23:20:51.581273 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 28 23:20:51.582151 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 28 23:20:51.582187 systemd[1]: Closed udev Control Socket. Oct 28 23:20:51.583483 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 28 23:20:51.583513 systemd[1]: Closed udev Kernel Socket. Oct 28 23:20:51.584886 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 28 23:20:51.584926 systemd[1]: Stopped dracut pre-udev hook. Oct 28 23:20:51.586308 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 28 23:20:51.586347 systemd[1]: Stopped dracut cmdline hook. Oct 28 23:20:51.587893 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 28 23:20:51.587932 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 28 23:20:51.590162 systemd[1]: Starting Cleanup udev Database... Oct 28 23:20:51.590982 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 28 23:20:51.610000 audit: BPF prog-id=9 op=UNLOAD Oct 28 23:20:51.592658 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 28 23:20:51.592710 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 28 23:20:51.594370 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 28 23:20:51.594411 systemd[1]: Stopped Create List of Static Device Nodes. Oct 28 23:20:51.596003 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 28 23:20:51.596042 systemd[1]: Stopped Setup Virtual Console. Oct 28 23:20:51.597573 systemd[1]: rngd.service: Deactivated successfully. Oct 28 23:20:51.597680 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 28 23:20:51.599363 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 28 23:20:51.599455 systemd[1]: Finished Cleanup udev Database. Oct 28 23:20:51.600667 systemd[1]: Reached target Switch Root. Oct 28 23:20:51.602811 systemd[1]: Starting Switch Root... Oct 28 23:20:51.609121 systemd[1]: Switching root. Oct 28 23:20:51.624816 systemd-journald[270]: Journal stopped Oct 28 23:20:53.678045 systemd-journald[270]: Received SIGTERM from PID 1 (systemd). Oct 28 23:20:53.678086 kernel: SELinux: policy capability network_peer_controls=1 Oct 28 23:20:53.678098 kernel: SELinux: policy capability open_perms=1 Oct 28 23:20:53.678110 kernel: SELinux: policy capability extended_socket_class=1 Oct 28 23:20:53.678119 kernel: SELinux: policy capability always_check_network=0 Oct 28 23:20:53.678129 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 28 23:20:53.678141 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 28 23:20:53.678150 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 28 23:20:53.678160 systemd[1]: Successfully loaded SELinux policy in 30.503ms. Oct 28 23:20:53.678173 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.074ms. Oct 28 23:20:53.678184 systemd[1]: systemd 249 running in system mode (+PAM -AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Oct 28 23:20:53.678194 systemd[1]: Detected virtualization kvm. Oct 28 23:20:53.678204 systemd[1]: Detected architecture arm64. Oct 28 23:20:53.678215 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 28 23:20:53.678227 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 28 23:20:53.678237 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 28 23:20:53.678247 systemd[1]: Stopped Switch Root. Oct 28 23:20:53.678257 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 28 23:20:53.678266 systemd[1]: Created slice Slice /system/addon-config. Oct 28 23:20:53.678276 systemd[1]: Created slice Slice /system/addon-run. Oct 28 23:20:53.678286 systemd[1]: Created slice Slice /system/getty. Oct 28 23:20:53.678297 systemd[1]: Created slice Slice /system/modprobe. Oct 28 23:20:53.678306 systemd[1]: Created slice Slice /system/serial-getty. Oct 28 23:20:53.678316 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 28 23:20:53.678326 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 28 23:20:53.678335 systemd[1]: Created slice User and Session Slice. Oct 28 23:20:53.678345 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 28 23:20:53.678355 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 28 23:20:53.678365 systemd[1]: Set up automount Boot partition Automount Point. Oct 28 23:20:53.678375 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 28 23:20:53.678386 systemd[1]: Stopped target Switch Root. Oct 28 23:20:53.678396 systemd[1]: Stopped target Initrd File Systems. Oct 28 23:20:53.678406 systemd[1]: Stopped target Initrd Root File System. Oct 28 23:20:53.678416 systemd[1]: Reached target Remote Encrypted Volumes. Oct 28 23:20:53.678428 systemd[1]: Reached target Remote File Systems. Oct 28 23:20:53.678438 systemd[1]: Reached target Slice Units. Oct 28 23:20:53.678448 systemd[1]: Reached target Swaps. Oct 28 23:20:53.678458 systemd[1]: Reached target Verify torcx succeeded. Oct 28 23:20:53.678468 systemd[1]: Reached target Local Verity Protected Volumes. Oct 28 23:20:53.678478 systemd[1]: Listening on Process Core Dump Socket. Oct 28 23:20:53.678489 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 28 23:20:53.678500 systemd[1]: Listening on Network Service Netlink Socket. Oct 28 23:20:53.678509 systemd[1]: Listening on udev Control Socket. Oct 28 23:20:53.678519 systemd[1]: Listening on udev Kernel Socket. Oct 28 23:20:53.678528 systemd[1]: Mounting Huge Pages File System... Oct 28 23:20:53.678538 systemd[1]: Mounting POSIX Message Queue File System... Oct 28 23:20:53.678559 systemd[1]: Mounting External Media Directory... Oct 28 23:20:53.678570 systemd[1]: Mounting Kernel Debug File System... Oct 28 23:20:53.678580 systemd[1]: Mounting Kernel Trace File System... Oct 28 23:20:53.678590 systemd[1]: Mounting Temporary Directory /tmp... Oct 28 23:20:53.678601 systemd[1]: Starting Create missing system files... Oct 28 23:20:53.678612 systemd[1]: Starting Create List of Static Device Nodes... Oct 28 23:20:53.678622 systemd[1]: Starting Load Kernel Module configfs... Oct 28 23:20:53.678637 systemd[1]: Starting Load Kernel Module drm... Oct 28 23:20:53.678650 systemd[1]: Starting Load Kernel Module fuse... Oct 28 23:20:53.678669 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 28 23:20:53.678684 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 28 23:20:53.678694 systemd[1]: Stopped File System Check on Root Device. Oct 28 23:20:53.678704 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 28 23:20:53.678715 systemd[1]: Stopped systemd-fsck-usr.service. Oct 28 23:20:53.678725 kernel: fuse: init (API version 7.32) Oct 28 23:20:53.678734 systemd[1]: Stopped Journal Service. Oct 28 23:20:53.678744 systemd[1]: Starting Journal Service... Oct 28 23:20:53.678754 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 28 23:20:53.678763 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 28 23:20:53.678773 systemd[1]: Starting Apply Kernel Variables... Oct 28 23:20:53.678783 systemd[1]: Starting Coldplug All udev Devices... Oct 28 23:20:53.678793 systemd[1]: verity-setup.service: Deactivated successfully. Oct 28 23:20:53.678802 systemd[1]: Stopped verity-setup.service. Oct 28 23:20:53.678814 systemd-journald[694]: Journal started Oct 28 23:20:53.678851 systemd-journald[694]: Runtime Journal (/run/log/journal/aee9fbc22d0f45d9a987af37fdde7c89) is 6.0M, max 48.5M, 42.4M free. Oct 28 23:20:51.694000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 28 23:20:51.720000 audit[1]: AVC avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 28 23:20:51.720000 audit[1]: AVC avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 28 23:20:51.720000 audit: BPF prog-id=15 op=LOAD Oct 28 23:20:51.720000 audit: BPF prog-id=15 op=UNLOAD Oct 28 23:20:51.720000 audit: BPF prog-id=16 op=LOAD Oct 28 23:20:51.720000 audit: BPF prog-id=16 op=UNLOAD Oct 28 23:20:51.752000 audit[646]: AVC avc: denied { associate } for pid=646 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Oct 28 23:20:53.554000 audit: BPF prog-id=17 op=LOAD Oct 28 23:20:53.555000 audit: BPF prog-id=18 op=LOAD Oct 28 23:20:53.555000 audit: BPF prog-id=19 op=LOAD Oct 28 23:20:53.555000 audit: BPF prog-id=10 op=UNLOAD Oct 28 23:20:53.555000 audit: BPF prog-id=11 op=UNLOAD Oct 28 23:20:53.564000 audit: BPF prog-id=20 op=LOAD Oct 28 23:20:53.565000 audit: BPF prog-id=17 op=UNLOAD Oct 28 23:20:53.565000 audit: BPF prog-id=21 op=LOAD Oct 28 23:20:53.565000 audit: BPF prog-id=22 op=LOAD Oct 28 23:20:53.565000 audit: BPF prog-id=18 op=UNLOAD Oct 28 23:20:53.565000 audit: BPF prog-id=19 op=UNLOAD Oct 28 23:20:53.565000 audit: BPF prog-id=23 op=LOAD Oct 28 23:20:53.565000 audit: BPF prog-id=20 op=UNLOAD Oct 28 23:20:53.565000 audit: BPF prog-id=24 op=LOAD Oct 28 23:20:53.565000 audit: BPF prog-id=25 op=LOAD Oct 28 23:20:53.565000 audit: BPF prog-id=21 op=UNLOAD Oct 28 23:20:53.565000 audit: BPF prog-id=22 op=UNLOAD Oct 28 23:20:53.586000 audit: BPF prog-id=23 op=UNLOAD Oct 28 23:20:53.663000 audit: BPF prog-id=26 op=LOAD Oct 28 23:20:53.664000 audit: BPF prog-id=27 op=LOAD Oct 28 23:20:53.664000 audit: BPF prog-id=28 op=LOAD Oct 28 23:20:53.664000 audit: BPF prog-id=24 op=UNLOAD Oct 28 23:20:53.664000 audit: BPF prog-id=25 op=UNLOAD Oct 28 23:20:53.676000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Oct 28 23:20:53.554689 systemd[1]: Queued start job for default target Multi-User System. Oct 28 23:20:51.751311 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-28T23:20:51Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3033.2.4 /usr/share/oem/torcx/store /var/lib/torcx/store/3033.2.4 /var/lib/torcx/store]" Oct 28 23:20:53.566771 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 28 23:20:51.751572 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-28T23:20:51Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 28 23:20:51.751593 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-28T23:20:51Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 28 23:20:51.751878 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-28T23:20:51Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 28 23:20:51.751891 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-28T23:20:51Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 28 23:20:51.751923 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-28T23:20:51Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 28 23:20:51.751937 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-28T23:20:51Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 28 23:20:51.752231 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-28T23:20:51Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 28 23:20:51.752266 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-28T23:20:51Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 28 23:20:51.752278 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-28T23:20:51Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 28 23:20:51.752630 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-28T23:20:51Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Oct 28 23:20:51.752670 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-28T23:20:51Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Oct 28 23:20:51.752691 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-28T23:20:51Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3033.2.4: no such file or directory" path=/usr/share/oem/torcx/store/3033.2.4 Oct 28 23:20:51.752707 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-28T23:20:51Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Oct 28 23:20:51.752812 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-28T23:20:51Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3033.2.4: no such file or directory" path=/var/lib/torcx/store/3033.2.4 Oct 28 23:20:51.752828 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-28T23:20:51Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 28 23:20:53.469721 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-28T23:20:53Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 28 23:20:53.469995 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-28T23:20:53Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 28 23:20:53.470098 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-28T23:20:53Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 28 23:20:53.470219 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-28T23:20:53Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 28 23:20:53.470317 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-28T23:20:53Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 28 23:20:53.470377 /usr/lib/systemd/system-generators/torcx-generator[646]: time="2025-10-28T23:20:53Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Oct 28 23:20:53.681049 systemd[1]: Started Journal Service. Oct 28 23:20:53.681494 systemd[1]: Mounted Huge Pages File System. Oct 28 23:20:53.682432 systemd[1]: Mounted POSIX Message Queue File System. Oct 28 23:20:53.683496 systemd[1]: Mounted External Media Directory. Oct 28 23:20:53.684464 systemd[1]: Mounted Kernel Debug File System. Oct 28 23:20:53.685417 systemd[1]: Mounted Kernel Trace File System. Oct 28 23:20:53.686406 systemd[1]: Mounted Temporary Directory /tmp. Oct 28 23:20:53.687597 systemd[1]: Finished Create missing system files. Oct 28 23:20:53.688808 systemd[1]: Finished Create List of Static Device Nodes. Oct 28 23:20:53.689946 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 28 23:20:53.690118 systemd[1]: Finished Load Kernel Module configfs. Oct 28 23:20:53.691171 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 28 23:20:53.691346 systemd[1]: Finished Load Kernel Module drm. Oct 28 23:20:53.692451 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 28 23:20:53.692648 systemd[1]: Finished Load Kernel Module fuse. Oct 28 23:20:53.693755 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 28 23:20:53.711041 systemd[1]: Finished Apply Kernel Variables. Oct 28 23:20:53.713827 systemd[1]: Mounting FUSE Control File System... Oct 28 23:20:53.715539 systemd[1]: Mounting Kernel Configuration File System... Oct 28 23:20:53.716391 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 28 23:20:53.717315 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Oct 28 23:20:53.718194 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 28 23:20:53.719235 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 28 23:20:53.720262 systemd[1]: Starting Load/Save Random Seed... Oct 28 23:20:53.721326 systemd[1]: Condition check resulted in Create System Users being skipped. Oct 28 23:20:53.723798 systemd-journald[694]: Time spent on flushing to /var/log/journal/aee9fbc22d0f45d9a987af37fdde7c89 is 11.855ms for 667 entries. Oct 28 23:20:53.723798 systemd-journald[694]: System Journal (/var/log/journal/aee9fbc22d0f45d9a987af37fdde7c89) is 8.0M, max 203.0M, 195.0M free. Oct 28 23:20:53.746000 audit: BPF prog-id=29 op=LOAD Oct 28 23:20:53.746000 audit: BPF prog-id=30 op=LOAD Oct 28 23:20:53.746000 audit: BPF prog-id=31 op=LOAD Oct 28 23:20:53.746000 audit: BPF prog-id=13 op=UNLOAD Oct 28 23:20:53.746000 audit: BPF prog-id=14 op=UNLOAD Oct 28 23:20:53.722195 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 28 23:20:53.723986 systemd[1]: Mounted FUSE Control File System. Oct 28 23:20:53.726290 systemd[1]: Mounted Kernel Configuration File System. Oct 28 23:20:53.728909 systemd[1]: Finished Load/Save Random Seed. Oct 28 23:20:53.730096 systemd[1]: Condition check resulted in First Boot Complete being skipped. Oct 28 23:20:53.732022 systemd[1]: Finished Coldplug All udev Devices. Oct 28 23:20:53.743405 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 28 23:20:53.744977 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 28 23:20:53.747348 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 28 23:20:53.750575 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 28 23:20:53.770437 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 28 23:20:53.772000 audit: BPF prog-id=32 op=LOAD Oct 28 23:20:53.773140 systemd[1]: Starting Network Configuration... Oct 28 23:20:53.803150 systemd-networkd[716]: lo: Link UP Oct 28 23:20:53.803157 systemd-networkd[716]: lo: Gained carrier Oct 28 23:20:53.803440 systemd-networkd[716]: Enumeration completed Oct 28 23:20:53.803527 systemd[1]: Started Network Configuration. Oct 28 23:20:53.818979 udevadm[707]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 28 23:20:53.822158 systemd-udevd[725]: Using default interface naming scheme 'v249'. Oct 28 23:20:53.839285 systemd-networkd[716]: eth0: Link UP Oct 28 23:20:53.843797 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 28 23:20:53.846608 systemd-networkd[716]: eth0: Gained carrier Oct 28 23:20:53.857408 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 28 23:20:53.859663 systemd-networkd[716]: eth0: DHCPv4 address 10.0.0.7/16 via 10.0.0.1 Oct 28 23:20:53.866213 lvm[736]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 28 23:20:53.891495 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 28 23:20:53.892598 systemd[1]: Reached target Local Encrypted Volumes. Oct 28 23:20:53.905366 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 28 23:20:53.908688 lvm[737]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 28 23:20:53.938467 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 28 23:20:53.939573 systemd[1]: Reached target Preparation for Local File Systems. Oct 28 23:20:53.940582 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 28 23:20:53.940605 systemd[1]: Reached target Containers. Oct 28 23:20:53.950706 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 28 23:20:53.962532 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 28 23:20:53.964623 systemd[1]: Mounting /usr/share/oem... Oct 28 23:20:53.973768 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 28 23:20:53.973829 kernel: BTRFS info (device vda6): has skinny extents Oct 28 23:20:53.975978 systemd[1]: Mounted /usr/share/oem. Oct 28 23:20:53.976778 systemd[1]: Reached target Local File Systems. Oct 28 23:20:53.977664 systemd[1]: Condition check resulted in Rebuild Dynamic Linker Cache being skipped. Oct 28 23:20:53.978212 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 28 23:20:53.978280 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 28 23:20:53.978331 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Oct 28 23:20:53.979342 systemd[1]: Starting Create Volatile Files and Directories... Oct 28 23:20:53.985354 systemd-tmpfiles[760]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 28 23:20:53.986511 systemd-tmpfiles[760]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 28 23:20:54.015041 systemd-tmpfiles[760]: Detected autofs mount point /boot during canonicalization of /boot. Oct 28 23:20:54.015052 systemd-tmpfiles[760]: Skipping /boot Oct 28 23:20:54.020676 systemd-tmpfiles[760]: Detected autofs mount point /boot during canonicalization of /boot. Oct 28 23:20:54.020691 systemd-tmpfiles[760]: Skipping /boot Oct 28 23:20:54.050620 systemd[1]: Finished Create Volatile Files and Directories. Oct 28 23:20:54.059773 systemd[1]: Starting Load Security Auditing Rules... Oct 28 23:20:54.061766 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 28 23:20:54.062880 systemd[1]: Condition check resulted in Rebuild Journal Catalog being skipped. Oct 28 23:20:54.063000 audit: BPF prog-id=33 op=LOAD Oct 28 23:20:54.064456 systemd[1]: Starting Network Name Resolution... Oct 28 23:20:54.065000 audit: BPF prog-id=34 op=LOAD Oct 28 23:20:54.067179 systemd[1]: Starting Network Time Synchronization... Oct 28 23:20:54.068155 systemd[1]: Condition check resulted in Update is Completed being skipped. Oct 28 23:20:54.069668 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 28 23:20:54.071187 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 28 23:20:54.072579 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 28 23:20:54.081916 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 28 23:20:54.089521 augenrules[783]: No rules Oct 28 23:20:54.088000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Oct 28 23:20:54.098954 systemd[1]: Finished Load Security Auditing Rules. Oct 28 23:20:54.107040 systemd-resolved[765]: Positive Trust Anchors: Oct 28 23:20:54.107052 systemd-resolved[765]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 28 23:20:53.683909 systemd-resolved[765]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Oct 28 23:20:53.683970 systemd-timesyncd[771]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 28 23:20:53.684753 systemd-resolved[765]: Defaulting to hostname 'linux'. Oct 28 23:20:53.693554 systemd[1]: Started Network Time Synchronization. Oct 28 23:20:53.694798 systemd[1]: Started Network Name Resolution. Oct 28 23:20:53.695860 systemd[1]: Reached target Network. Oct 28 23:20:53.696675 systemd[1]: Reached target Host and Network Name Lookups. Oct 28 23:20:53.697850 systemd[1]: Reached target System Initialization. Oct 28 23:20:53.698925 systemd[1]: Started Watch for update engine configuration changes. Oct 28 23:20:53.700119 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 28 23:20:53.703026 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 28 23:20:53.704165 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 28 23:20:53.704193 systemd[1]: Reached target Path Units. Oct 28 23:20:53.704987 systemd[1]: Reached target System Time Set. Oct 28 23:20:53.706812 systemd[1]: Started Daily Log Rotation. Oct 28 23:20:53.707791 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 28 23:20:53.709012 systemd[1]: Reached target Timer Units. Oct 28 23:20:53.710341 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 28 23:20:53.720015 systemd[1]: Starting Docker Socket for the API... Oct 28 23:20:53.723193 systemd[1]: Listening on OpenSSH Server Socket. Oct 28 23:20:53.724559 systemd[1]: Listening on Docker Socket for the API. Oct 28 23:20:53.725502 systemd[1]: Reached target Socket Units. Oct 28 23:20:53.726277 systemd[1]: Reached target Basic System. Oct 28 23:20:53.727187 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 28 23:20:53.727221 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 28 23:20:53.728181 systemd[1]: Started D-Bus System Message Bus. Oct 28 23:20:53.731777 systemd[1]: Starting Extend Filesystems... Oct 28 23:20:53.732669 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 28 23:20:53.733736 systemd[1]: Starting Generate /run/flatcar/motd... Oct 28 23:20:53.735689 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 28 23:20:53.738579 systemd[1]: Starting Generate sshd host keys... Oct 28 23:20:53.739493 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 28 23:20:53.739534 systemd[1]: Reached target Load system-provided cloud configs. Oct 28 23:20:53.742497 systemd[1]: Starting User Login Management... Oct 28 23:20:53.743508 extend-filesystems[793]: Found vda Oct 28 23:20:53.743770 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 28 23:20:53.744332 systemd[1]: Starting Update Engine... Oct 28 23:20:53.744780 extend-filesystems[793]: Found vda1 Oct 28 23:20:53.746030 extend-filesystems[793]: Found vda2 Oct 28 23:20:53.745142 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 28 23:20:53.745166 systemd[1]: Reached target Load user-provided cloud configs. Oct 28 23:20:53.747451 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 28 23:20:53.747725 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 28 23:20:53.747927 extend-filesystems[793]: Found vda3 Oct 28 23:20:53.750272 extend-filesystems[793]: Found usr Oct 28 23:20:53.751410 extend-filesystems[793]: Found vda4 Oct 28 23:20:53.756304 extend-filesystems[793]: Found vda6 Oct 28 23:20:53.756304 extend-filesystems[793]: Found vda7 Oct 28 23:20:53.756304 extend-filesystems[793]: Found vda9 Oct 28 23:20:53.756304 extend-filesystems[793]: Checking size of /dev/vda9 Oct 28 23:20:53.761741 systemd[1]: motdgen.service: Deactivated successfully. Oct 28 23:20:53.762022 systemd[1]: Finished Generate /run/flatcar/motd. Oct 28 23:20:53.779641 systemd-logind[802]: Watching system buttons on /dev/input/event0 (Power Button) Oct 28 23:20:53.781852 systemd-logind[802]: New seat seat0. Oct 28 23:20:53.784705 extend-filesystems[793]: Old size kept for /dev/vda9 Oct 28 23:20:53.784950 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 28 23:20:53.785267 systemd[1]: Finished Extend Filesystems. Oct 28 23:20:53.795275 systemd[1]: Started User Login Management. Oct 28 23:20:53.806144 update_engine[804]: I1028 23:20:53.805740 804 main.cc:89] Flatcar Update Engine starting Oct 28 23:20:53.806670 update_engine[804]: I1028 23:20:53.806653 804 payload_state.cc:360] Current Response Signature = Oct 28 23:20:53.806670 update_engine[804]: NumURLs = 1 Oct 28 23:20:53.806670 update_engine[804]: Url0 = http://10.0.0.6:34567/packages/update.gz Oct 28 23:20:53.806670 update_engine[804]: Payload Size = 439722381 Oct 28 23:20:53.806670 update_engine[804]: Payload Sha256 Hash = 8hFWwyLnzF+7GFbpjt+cVnzq4hiRyr5fK3xWNMCkyeg= Oct 28 23:20:53.806670 update_engine[804]: Is Delta Payload = 0 Oct 28 23:20:53.806670 update_engine[804]: Max Failure Count Per Url = 10 Oct 28 23:20:53.806670 update_engine[804]: Disable Payload Backoff = 1 Oct 28 23:20:53.806847 update_engine[804]: I1028 23:20:53.806834 804 payload_state.cc:381] Payload Attempt Number = 0 Oct 28 23:20:53.806989 update_engine[804]: I1028 23:20:53.806978 804 payload_state.cc:404] Current URL Index = 0 Oct 28 23:20:53.807124 update_engine[804]: I1028 23:20:53.807109 804 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 28 23:20:53.807262 update_engine[804]: I1028 23:20:53.807236 804 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 28 23:20:53.808994 systemd[1]: Started Update Engine. Oct 28 23:20:53.809846 update_engine[804]: I1028 23:20:53.809816 804 update_check_scheduler.cc:74] Next update check in 7m18s Oct 28 23:20:53.813131 systemd[1]: Finished Generate sshd host keys. Oct 28 23:20:53.831449 systemd[1]: Starting Generate /run/issue... Oct 28 23:20:53.835777 systemd[1]: issuegen.service: Deactivated successfully. Oct 28 23:20:53.836011 systemd[1]: Finished Generate /run/issue. Oct 28 23:20:53.838188 systemd[1]: Starting Permit User Sessions... Oct 28 23:20:53.843123 systemd[1]: Finished Permit User Sessions. Oct 28 23:20:53.845333 systemd[1]: Started Getty on tty1. Oct 28 23:20:53.847247 systemd[1]: Started Serial Getty on ttyAMA0. Oct 28 23:20:53.848299 systemd[1]: Reached target Login Prompts. Oct 28 23:20:53.849239 systemd[1]: Reached target Multi-User System. Oct 28 23:20:53.851404 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 28 23:20:53.856345 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 28 23:20:53.856594 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 28 23:20:53.857655 systemd[1]: Startup finished in 694ms (kernel) + 1.101s (initrd) + 2.618s (userspace) = 4.414s. Oct 28 23:20:54.658671 systemd-networkd[716]: eth0: Gained IPv6LL Oct 28 23:21:03.565170 systemd[1]: Created slice Slice /system/sshd. Oct 28 23:21:03.566163 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:50294). Oct 28 23:21:03.605866 sshd[832]: Accepted publickey for core from 10.0.0.1 port 50294 ssh2: RSA SHA256:HTG7MLlKNvOxJPTb3qwiqi6VI8nPd76okQgzGcvxc1o Oct 28 23:21:03.607692 sshd[832]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 23:21:03.624845 systemd[1]: Created slice User Slice of UID 500. Oct 28 23:21:03.625842 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 28 23:21:03.627508 systemd-logind[802]: New session 1 of user core. Oct 28 23:21:03.631681 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 28 23:21:03.632806 systemd[1]: Starting User Manager for UID 500... Oct 28 23:21:03.634928 systemd[835]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 28 23:21:03.685446 systemd[835]: Queued start job for default target Main User Target. Oct 28 23:21:03.685594 systemd[835]: Reached target Paths. Oct 28 23:21:03.685607 systemd[835]: Reached target Sockets. Oct 28 23:21:03.685618 systemd[835]: Reached target Timers. Oct 28 23:21:03.685628 systemd[835]: Reached target Basic System. Oct 28 23:21:03.685669 systemd[835]: Reached target Main User Target. Oct 28 23:21:03.685681 systemd[835]: Startup finished in 47ms. Oct 28 23:21:03.685761 systemd[1]: Started User Manager for UID 500. Oct 28 23:21:03.692522 systemd[1]: Started Session 1 of User core. Oct 28 23:21:03.753903 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:50306). Oct 28 23:21:03.790475 sshd[844]: Accepted publickey for core from 10.0.0.1 port 50306 ssh2: RSA SHA256:HTG7MLlKNvOxJPTb3qwiqi6VI8nPd76okQgzGcvxc1o Oct 28 23:21:03.791598 sshd[844]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 23:21:03.794057 systemd-logind[802]: New session 2 of user core. Oct 28 23:21:03.816522 systemd[1]: Started Session 2 of User core. Oct 28 23:21:03.868378 sshd[844]: pam_unix(sshd:session): session closed for user core Oct 28 23:21:03.877722 systemd[1]: sshd@1-10.0.0.7:22-10.0.0.1:50306.service: Deactivated successfully. Oct 28 23:21:03.878264 systemd[1]: session-2.scope: Deactivated successfully. Oct 28 23:21:03.878882 systemd-logind[802]: Session 2 logged out. Waiting for processes to exit. Oct 28 23:21:03.879864 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:50308). Oct 28 23:21:03.880719 systemd-logind[802]: Removed session 2. Oct 28 23:21:03.916560 sshd[850]: Accepted publickey for core from 10.0.0.1 port 50308 ssh2: RSA SHA256:HTG7MLlKNvOxJPTb3qwiqi6VI8nPd76okQgzGcvxc1o Oct 28 23:21:03.917492 sshd[850]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 23:21:03.919889 systemd-logind[802]: New session 3 of user core. Oct 28 23:21:03.928581 systemd[1]: Started Session 3 of User core. Oct 28 23:21:03.979979 sshd[850]: pam_unix(sshd:session): session closed for user core Oct 28 23:21:03.995332 systemd[1]: sshd@2-10.0.0.7:22-10.0.0.1:50308.service: Deactivated successfully. Oct 28 23:21:03.995903 systemd[1]: session-3.scope: Deactivated successfully. Oct 28 23:21:03.996503 systemd-logind[802]: Session 3 logged out. Waiting for processes to exit. Oct 28 23:21:03.997422 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:50324). Oct 28 23:21:03.998021 systemd-logind[802]: Removed session 3. Oct 28 23:21:04.033317 sshd[856]: Accepted publickey for core from 10.0.0.1 port 50324 ssh2: RSA SHA256:HTG7MLlKNvOxJPTb3qwiqi6VI8nPd76okQgzGcvxc1o Oct 28 23:21:04.034441 sshd[856]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 23:21:04.036891 systemd-logind[802]: New session 4 of user core. Oct 28 23:21:04.050521 systemd[1]: Started Session 4 of User core. Oct 28 23:21:04.101108 sshd[856]: pam_unix(sshd:session): session closed for user core Oct 28 23:21:04.113465 systemd[1]: sshd@3-10.0.0.7:22-10.0.0.1:50324.service: Deactivated successfully. Oct 28 23:21:04.114136 systemd[1]: session-4.scope: Deactivated successfully. Oct 28 23:21:04.114834 systemd-logind[802]: Session 4 logged out. Waiting for processes to exit. Oct 28 23:21:04.116338 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:50340). Oct 28 23:21:04.116990 systemd-logind[802]: Removed session 4. Oct 28 23:21:04.151906 sshd[862]: Accepted publickey for core from 10.0.0.1 port 50340 ssh2: RSA SHA256:HTG7MLlKNvOxJPTb3qwiqi6VI8nPd76okQgzGcvxc1o Oct 28 23:21:04.152715 sshd[862]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 23:21:04.155217 systemd-logind[802]: New session 5 of user core. Oct 28 23:21:04.163519 systemd[1]: Started Session 5 of User core. Oct 28 23:21:04.218705 sudo[865]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 28 23:21:04.218914 sudo[865]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 28 23:21:04.235955 sudo[865]: pam_unix(sudo:session): session closed for user root Oct 28 23:21:04.237746 sshd[862]: pam_unix(sshd:session): session closed for user core Oct 28 23:21:04.248888 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:50352). Oct 28 23:21:04.251576 dbus-daemon[792]: [system] Reloaded configuration Oct 28 23:21:04.253676 systemd[1]: sshd@4-10.0.0.7:22-10.0.0.1:50340.service: Deactivated successfully. Oct 28 23:21:04.254239 systemd[1]: session-5.scope: Deactivated successfully. Oct 28 23:21:04.254798 systemd-logind[802]: Session 5 logged out. Waiting for processes to exit. Oct 28 23:21:04.255421 systemd-logind[802]: Removed session 5. Oct 28 23:21:04.286203 sshd[868]: Accepted publickey for core from 10.0.0.1 port 50352 ssh2: RSA SHA256:HTG7MLlKNvOxJPTb3qwiqi6VI8nPd76okQgzGcvxc1o Oct 28 23:21:04.287318 sshd[868]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 23:21:04.289604 systemd-logind[802]: New session 6 of user core. Oct 28 23:21:04.298613 systemd[1]: Started Session 6 of User core. Oct 28 23:21:04.349296 sudo[873]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Oct 28 23:21:04.349532 sudo[873]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 28 23:21:04.351607 sudo[873]: pam_unix(sudo:session): session closed for user root Oct 28 23:21:04.355950 sudo[872]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 28 23:21:04.356151 sudo[872]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 28 23:21:04.380252 systemd[1]: Stopping Load Security Auditing Rules... Oct 28 23:21:04.379000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 28 23:21:04.381530 auditctl[876]: No rules Oct 28 23:21:04.382045 kernel: kauditd_printk_skb: 49 callbacks suppressed Oct 28 23:21:04.382073 kernel: audit: type=1305 audit(1761693664.379:60): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 28 23:21:04.382289 systemd[1]: audit-rules.service: Deactivated successfully. Oct 28 23:21:04.382565 systemd[1]: Stopped Load Security Auditing Rules. Oct 28 23:21:04.383776 systemd[1]: Starting Load Security Auditing Rules... Oct 28 23:21:04.397580 augenrules[893]: No rules Oct 28 23:21:04.398436 systemd[1]: Finished Load Security Auditing Rules. Oct 28 23:21:04.400084 sudo[872]: pam_unix(sudo:session): session closed for user root Oct 28 23:21:04.401154 sshd[868]: pam_unix(sshd:session): session closed for user core Oct 28 23:21:04.415207 systemd[1]: sshd@5-10.0.0.7:22-10.0.0.1:50352.service: Deactivated successfully. Oct 28 23:21:04.415815 systemd[1]: session-6.scope: Deactivated successfully. Oct 28 23:21:04.416378 systemd-logind[802]: Session 6 logged out. Waiting for processes to exit. Oct 28 23:21:04.417273 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:50364). Oct 28 23:21:04.417785 systemd-logind[802]: Removed session 6. Oct 28 23:21:04.452901 sshd[899]: Accepted publickey for core from 10.0.0.1 port 50364 ssh2: RSA SHA256:HTG7MLlKNvOxJPTb3qwiqi6VI8nPd76okQgzGcvxc1o Oct 28 23:21:04.453751 sshd[899]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 23:21:04.456989 kernel: audit: type=1006 audit(1761693664.452:61): pid=899 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=7 res=1 Oct 28 23:21:04.457178 systemd-logind[802]: New session 7 of user core. Oct 28 23:21:04.465595 systemd[1]: Started Session 7 of User core. Oct 28 23:21:04.512463 sshd[899]: pam_unix(sshd:session): session closed for user core Oct 28 23:21:04.521772 systemd[1]: sshd@6-10.0.0.7:22-10.0.0.1:50364.service: Deactivated successfully. Oct 28 23:21:04.522380 systemd[1]: session-7.scope: Deactivated successfully. Oct 28 23:21:04.522945 systemd-logind[802]: Session 7 logged out. Waiting for processes to exit. Oct 28 23:21:04.523870 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:50366). Oct 28 23:21:04.524529 systemd-logind[802]: Removed session 7. Oct 28 23:21:04.561623 sshd[905]: Accepted publickey for core from 10.0.0.1 port 50366 ssh2: RSA SHA256:HTG7MLlKNvOxJPTb3qwiqi6VI8nPd76okQgzGcvxc1o Oct 28 23:21:04.564394 kernel: audit: type=1006 audit(1761693664.560:62): pid=905 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=8 res=1 Oct 28 23:21:04.564558 sshd[905]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 28 23:21:04.567431 systemd-logind[802]: New session 8 of user core. Oct 28 23:21:04.580517 systemd[1]: Started Session 8 of User core. Oct 28 23:21:04.628953 sshd[905]: pam_unix(sshd:session): session closed for user core Oct 28 23:21:04.631314 systemd[1]: sshd@7-10.0.0.7:22-10.0.0.1:50366.service: Deactivated successfully. Oct 28 23:21:04.632121 systemd[1]: session-8.scope: Deactivated successfully. Oct 28 23:21:04.632787 systemd-logind[802]: Session 8 logged out. Waiting for processes to exit. Oct 28 23:21:04.633493 systemd-logind[802]: Removed session 8.