May 13 23:39:19.871747 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] May 13 23:39:19.871768 kernel: Linux version 6.6.89-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT Tue May 13 22:16:18 -00 2025 May 13 23:39:19.871778 kernel: KASLR enabled May 13 23:39:19.871784 kernel: efi: EFI v2.7 by EDK II May 13 23:39:19.871789 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdbbae018 ACPI 2.0=0xd9b43018 RNG=0xd9b43a18 MEMRESERVE=0xd9b40218 May 13 23:39:19.871795 kernel: random: crng init done May 13 23:39:19.871801 kernel: secureboot: Secure boot disabled May 13 23:39:19.871807 kernel: ACPI: Early table checksum verification disabled May 13 23:39:19.871813 kernel: ACPI: RSDP 0x00000000D9B43018 000024 (v02 BOCHS ) May 13 23:39:19.871820 kernel: ACPI: XSDT 0x00000000D9B43F18 000064 (v01 BOCHS BXPC 00000001 01000013) May 13 23:39:19.871826 kernel: ACPI: FACP 0x00000000D9B43B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) May 13 23:39:19.871832 kernel: ACPI: DSDT 0x00000000D9B41018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 13 23:39:19.871837 kernel: ACPI: APIC 0x00000000D9B43C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) May 13 23:39:19.871843 kernel: ACPI: PPTT 0x00000000D9B43098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) May 13 23:39:19.871850 kernel: ACPI: GTDT 0x00000000D9B43818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 13 23:39:19.871857 kernel: ACPI: MCFG 0x00000000D9B43A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) May 13 23:39:19.871864 kernel: ACPI: SPCR 0x00000000D9B43918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 13 23:39:19.871869 kernel: ACPI: DBG2 0x00000000D9B43998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) May 13 23:39:19.871876 kernel: ACPI: IORT 0x00000000D9B43198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) May 13 23:39:19.871882 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 May 13 23:39:19.871888 kernel: NUMA: Failed to initialise from firmware May 13 23:39:19.871894 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] May 13 23:39:19.871900 kernel: NUMA: NODE_DATA [mem 0xdc958800-0xdc95dfff] May 13 23:39:19.871906 kernel: Zone ranges: May 13 23:39:19.871912 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] May 13 23:39:19.871919 kernel: DMA32 empty May 13 23:39:19.871925 kernel: Normal empty May 13 23:39:19.871931 kernel: Movable zone start for each node May 13 23:39:19.871937 kernel: Early memory node ranges May 13 23:39:19.871943 kernel: node 0: [mem 0x0000000040000000-0x00000000d967ffff] May 13 23:39:19.871949 kernel: node 0: [mem 0x00000000d9680000-0x00000000d968ffff] May 13 23:39:19.871955 kernel: node 0: [mem 0x00000000d9690000-0x00000000d976ffff] May 13 23:39:19.871960 kernel: node 0: [mem 0x00000000d9770000-0x00000000d9b3ffff] May 13 23:39:19.871966 kernel: node 0: [mem 0x00000000d9b40000-0x00000000dce1ffff] May 13 23:39:19.871972 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] May 13 23:39:19.871978 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] May 13 23:39:19.871984 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] May 13 23:39:19.871992 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] May 13 23:39:19.871998 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] May 13 23:39:19.872004 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges May 13 23:39:19.872013 kernel: psci: probing for conduit method from ACPI. May 13 23:39:19.872019 kernel: psci: PSCIv1.1 detected in firmware. May 13 23:39:19.872026 kernel: psci: Using standard PSCI v0.2 function IDs May 13 23:39:19.872033 kernel: psci: Trusted OS migration not required May 13 23:39:19.872040 kernel: psci: SMC Calling Convention v1.1 May 13 23:39:19.872046 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) May 13 23:39:19.872053 kernel: percpu: Embedded 31 pages/cpu s86632 r8192 d32152 u126976 May 13 23:39:19.872059 kernel: pcpu-alloc: s86632 r8192 d32152 u126976 alloc=31*4096 May 13 23:39:19.872072 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 May 13 23:39:19.872079 kernel: Detected PIPT I-cache on CPU0 May 13 23:39:19.872086 kernel: CPU features: detected: GIC system register CPU interface May 13 23:39:19.872092 kernel: CPU features: detected: Hardware dirty bit management May 13 23:39:19.872098 kernel: CPU features: detected: Spectre-v4 May 13 23:39:19.872107 kernel: CPU features: detected: Spectre-BHB May 13 23:39:19.872114 kernel: CPU features: kernel page table isolation forced ON by KASLR May 13 23:39:19.872120 kernel: CPU features: detected: Kernel page table isolation (KPTI) May 13 23:39:19.872126 kernel: CPU features: detected: ARM erratum 1418040 May 13 23:39:19.872133 kernel: CPU features: detected: SSBS not fully self-synchronizing May 13 23:39:19.872139 kernel: alternatives: applying boot alternatives May 13 23:39:19.872146 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=3174b2682629aa8ad4069807ed6fd62c10f62266ee1e150a1104f2a2fb6489b5 May 13 23:39:19.872153 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 13 23:39:19.872160 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 13 23:39:19.872166 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 13 23:39:19.872173 kernel: Fallback order for Node 0: 0 May 13 23:39:19.872181 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 May 13 23:39:19.872187 kernel: Policy zone: DMA May 13 23:39:19.872193 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 13 23:39:19.872199 kernel: software IO TLB: area num 4. May 13 23:39:19.872206 kernel: software IO TLB: mapped [mem 0x00000000d2e00000-0x00000000d6e00000] (64MB) May 13 23:39:19.872213 kernel: Memory: 2387348K/2572288K available (10368K kernel code, 2186K rwdata, 8100K rodata, 38464K init, 897K bss, 184940K reserved, 0K cma-reserved) May 13 23:39:19.872219 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 May 13 23:39:19.872226 kernel: rcu: Preemptible hierarchical RCU implementation. May 13 23:39:19.872232 kernel: rcu: RCU event tracing is enabled. May 13 23:39:19.872239 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. May 13 23:39:19.872246 kernel: Trampoline variant of Tasks RCU enabled. May 13 23:39:19.872252 kernel: Tracing variant of Tasks RCU enabled. May 13 23:39:19.872260 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 13 23:39:19.872267 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 May 13 23:39:19.872273 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 May 13 23:39:19.872279 kernel: GICv3: 256 SPIs implemented May 13 23:39:19.872286 kernel: GICv3: 0 Extended SPIs implemented May 13 23:39:19.872292 kernel: Root IRQ handler: gic_handle_irq May 13 23:39:19.872298 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI May 13 23:39:19.872305 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 May 13 23:39:19.872311 kernel: ITS [mem 0x08080000-0x0809ffff] May 13 23:39:19.872318 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400c0000 (indirect, esz 8, psz 64K, shr 1) May 13 23:39:19.872325 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400d0000 (flat, esz 8, psz 64K, shr 1) May 13 23:39:19.872332 kernel: GICv3: using LPI property table @0x00000000400f0000 May 13 23:39:19.872339 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040100000 May 13 23:39:19.872345 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 13 23:39:19.872351 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 13 23:39:19.872358 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). May 13 23:39:19.872364 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns May 13 23:39:19.872371 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns May 13 23:39:19.872377 kernel: arm-pv: using stolen time PV May 13 23:39:19.872384 kernel: Console: colour dummy device 80x25 May 13 23:39:19.872390 kernel: ACPI: Core revision 20230628 May 13 23:39:19.872397 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) May 13 23:39:19.872405 kernel: pid_max: default: 32768 minimum: 301 May 13 23:39:19.872412 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity May 13 23:39:19.872418 kernel: landlock: Up and running. May 13 23:39:19.872425 kernel: SELinux: Initializing. May 13 23:39:19.872432 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 13 23:39:19.872438 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 13 23:39:19.872445 kernel: ACPI PPTT: PPTT table found, but unable to locate core 3 (3) May 13 23:39:19.872452 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 13 23:39:19.872459 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 13 23:39:19.872470 kernel: rcu: Hierarchical SRCU implementation. May 13 23:39:19.872477 kernel: rcu: Max phase no-delay instances is 400. May 13 23:39:19.872486 kernel: Platform MSI: ITS@0x8080000 domain created May 13 23:39:19.872495 kernel: PCI/MSI: ITS@0x8080000 domain created May 13 23:39:19.872503 kernel: Remapping and enabling EFI services. May 13 23:39:19.872510 kernel: smp: Bringing up secondary CPUs ... May 13 23:39:19.872517 kernel: Detected PIPT I-cache on CPU1 May 13 23:39:19.872524 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 May 13 23:39:19.872531 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040110000 May 13 23:39:19.872539 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 13 23:39:19.872546 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] May 13 23:39:19.872557 kernel: Detected PIPT I-cache on CPU2 May 13 23:39:19.872566 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 May 13 23:39:19.872573 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040120000 May 13 23:39:19.872580 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 13 23:39:19.872587 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] May 13 23:39:19.872594 kernel: Detected PIPT I-cache on CPU3 May 13 23:39:19.872601 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 May 13 23:39:19.872608 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040130000 May 13 23:39:19.872616 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 13 23:39:19.872672 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] May 13 23:39:19.872681 kernel: smp: Brought up 1 node, 4 CPUs May 13 23:39:19.872688 kernel: SMP: Total of 4 processors activated. May 13 23:39:19.872695 kernel: CPU features: detected: 32-bit EL0 Support May 13 23:39:19.872702 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence May 13 23:39:19.872709 kernel: CPU features: detected: Common not Private translations May 13 23:39:19.872719 kernel: CPU features: detected: CRC32 instructions May 13 23:39:19.872726 kernel: CPU features: detected: Enhanced Virtualization Traps May 13 23:39:19.872733 kernel: CPU features: detected: RCpc load-acquire (LDAPR) May 13 23:39:19.872739 kernel: CPU features: detected: LSE atomic instructions May 13 23:39:19.872746 kernel: CPU features: detected: Privileged Access Never May 13 23:39:19.872753 kernel: CPU features: detected: RAS Extension Support May 13 23:39:19.872760 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) May 13 23:39:19.872767 kernel: CPU: All CPU(s) started at EL1 May 13 23:39:19.872774 kernel: alternatives: applying system-wide alternatives May 13 23:39:19.872781 kernel: devtmpfs: initialized May 13 23:39:19.872789 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 13 23:39:19.872796 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) May 13 23:39:19.872803 kernel: pinctrl core: initialized pinctrl subsystem May 13 23:39:19.872810 kernel: SMBIOS 3.0.0 present. May 13 23:39:19.872817 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 May 13 23:39:19.872824 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 13 23:39:19.872831 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations May 13 23:39:19.872838 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations May 13 23:39:19.872846 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations May 13 23:39:19.872853 kernel: audit: initializing netlink subsys (disabled) May 13 23:39:19.872860 kernel: audit: type=2000 audit(0.018:1): state=initialized audit_enabled=0 res=1 May 13 23:39:19.872867 kernel: thermal_sys: Registered thermal governor 'step_wise' May 13 23:39:19.872874 kernel: cpuidle: using governor menu May 13 23:39:19.872881 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. May 13 23:39:19.872888 kernel: ASID allocator initialised with 32768 entries May 13 23:39:19.872895 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 13 23:39:19.872902 kernel: Serial: AMBA PL011 UART driver May 13 23:39:19.872910 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL May 13 23:39:19.872917 kernel: Modules: 0 pages in range for non-PLT usage May 13 23:39:19.872924 kernel: Modules: 509232 pages in range for PLT usage May 13 23:39:19.872931 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 13 23:39:19.872938 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page May 13 23:39:19.872945 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages May 13 23:39:19.872952 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page May 13 23:39:19.872959 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 13 23:39:19.872965 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page May 13 23:39:19.872972 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages May 13 23:39:19.872981 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page May 13 23:39:19.872987 kernel: ACPI: Added _OSI(Module Device) May 13 23:39:19.872994 kernel: ACPI: Added _OSI(Processor Device) May 13 23:39:19.873001 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 13 23:39:19.873008 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 13 23:39:19.873015 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 13 23:39:19.873022 kernel: ACPI: Interpreter enabled May 13 23:39:19.873029 kernel: ACPI: Using GIC for interrupt routing May 13 23:39:19.873036 kernel: ACPI: MCFG table detected, 1 entries May 13 23:39:19.873044 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA May 13 23:39:19.873051 kernel: printk: console [ttyAMA0] enabled May 13 23:39:19.873058 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) May 13 23:39:19.873203 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] May 13 23:39:19.873277 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] May 13 23:39:19.873350 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] May 13 23:39:19.873414 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 May 13 23:39:19.873485 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] May 13 23:39:19.873499 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] May 13 23:39:19.873507 kernel: PCI host bridge to bus 0000:00 May 13 23:39:19.873583 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] May 13 23:39:19.873667 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] May 13 23:39:19.873728 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] May 13 23:39:19.873784 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] May 13 23:39:19.873872 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 May 13 23:39:19.873946 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 May 13 23:39:19.874013 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] May 13 23:39:19.874089 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] May 13 23:39:19.874156 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] May 13 23:39:19.874221 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] May 13 23:39:19.874286 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] May 13 23:39:19.874354 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] May 13 23:39:19.874413 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] May 13 23:39:19.874469 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] May 13 23:39:19.874526 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] May 13 23:39:19.874535 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 May 13 23:39:19.874542 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 May 13 23:39:19.874549 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 May 13 23:39:19.874558 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 May 13 23:39:19.874565 kernel: iommu: Default domain type: Translated May 13 23:39:19.874572 kernel: iommu: DMA domain TLB invalidation policy: strict mode May 13 23:39:19.874579 kernel: efivars: Registered efivars operations May 13 23:39:19.874586 kernel: vgaarb: loaded May 13 23:39:19.874593 kernel: clocksource: Switched to clocksource arch_sys_counter May 13 23:39:19.874600 kernel: VFS: Disk quotas dquot_6.6.0 May 13 23:39:19.874607 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 13 23:39:19.874614 kernel: pnp: PnP ACPI init May 13 23:39:19.874732 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved May 13 23:39:19.874744 kernel: pnp: PnP ACPI: found 1 devices May 13 23:39:19.874751 kernel: NET: Registered PF_INET protocol family May 13 23:39:19.874758 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 13 23:39:19.874766 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 13 23:39:19.874773 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 13 23:39:19.874780 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 13 23:39:19.874787 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) May 13 23:39:19.874797 kernel: TCP: Hash tables configured (established 32768 bind 32768) May 13 23:39:19.874804 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 13 23:39:19.874811 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 13 23:39:19.874818 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 13 23:39:19.874825 kernel: PCI: CLS 0 bytes, default 64 May 13 23:39:19.874832 kernel: kvm [1]: HYP mode not available May 13 23:39:19.874839 kernel: Initialise system trusted keyrings May 13 23:39:19.874846 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 May 13 23:39:19.874853 kernel: Key type asymmetric registered May 13 23:39:19.874861 kernel: Asymmetric key parser 'x509' registered May 13 23:39:19.874868 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) May 13 23:39:19.874875 kernel: io scheduler mq-deadline registered May 13 23:39:19.874882 kernel: io scheduler kyber registered May 13 23:39:19.874889 kernel: io scheduler bfq registered May 13 23:39:19.874896 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 May 13 23:39:19.874903 kernel: ACPI: button: Power Button [PWRB] May 13 23:39:19.874911 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 May 13 23:39:19.874978 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) May 13 23:39:19.874987 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 13 23:39:19.874996 kernel: thunder_xcv, ver 1.0 May 13 23:39:19.875003 kernel: thunder_bgx, ver 1.0 May 13 23:39:19.875010 kernel: nicpf, ver 1.0 May 13 23:39:19.875017 kernel: nicvf, ver 1.0 May 13 23:39:19.875097 kernel: rtc-efi rtc-efi.0: registered as rtc0 May 13 23:39:19.875159 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-05-13T23:39:19 UTC (1747179559) May 13 23:39:19.875168 kernel: hid: raw HID events driver (C) Jiri Kosina May 13 23:39:19.875176 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available May 13 23:39:19.875186 kernel: watchdog: Delayed init of the lockup detector failed: -19 May 13 23:39:19.875192 kernel: watchdog: Hard watchdog permanently disabled May 13 23:39:19.875200 kernel: NET: Registered PF_INET6 protocol family May 13 23:39:19.875207 kernel: Segment Routing with IPv6 May 13 23:39:19.875213 kernel: In-situ OAM (IOAM) with IPv6 May 13 23:39:19.875220 kernel: NET: Registered PF_PACKET protocol family May 13 23:39:19.875227 kernel: Key type dns_resolver registered May 13 23:39:19.875234 kernel: registered taskstats version 1 May 13 23:39:19.875241 kernel: Loading compiled-in X.509 certificates May 13 23:39:19.875250 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.89-flatcar: 568a15bbab977599d8f910f319ba50c03c8a57bd' May 13 23:39:19.875256 kernel: Key type .fscrypt registered May 13 23:39:19.875263 kernel: Key type fscrypt-provisioning registered May 13 23:39:19.875270 kernel: ima: No TPM chip found, activating TPM-bypass! May 13 23:39:19.875277 kernel: ima: Allocated hash algorithm: sha1 May 13 23:39:19.875284 kernel: ima: No architecture policies found May 13 23:39:19.875291 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) May 13 23:39:19.875298 kernel: clk: Disabling unused clocks May 13 23:39:19.875306 kernel: Freeing unused kernel memory: 38464K May 13 23:39:19.875313 kernel: Run /init as init process May 13 23:39:19.875319 kernel: with arguments: May 13 23:39:19.875326 kernel: /init May 13 23:39:19.875333 kernel: with environment: May 13 23:39:19.875340 kernel: HOME=/ May 13 23:39:19.875347 kernel: TERM=linux May 13 23:39:19.875353 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 13 23:39:19.875361 systemd[1]: Successfully made /usr/ read-only. May 13 23:39:19.875372 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) May 13 23:39:19.875380 systemd[1]: Detected virtualization kvm. May 13 23:39:19.875387 systemd[1]: Detected architecture arm64. May 13 23:39:19.875394 systemd[1]: Running in initrd. May 13 23:39:19.875402 systemd[1]: No hostname configured, using default hostname. May 13 23:39:19.875409 systemd[1]: Hostname set to . May 13 23:39:19.875417 systemd[1]: Initializing machine ID from VM UUID. May 13 23:39:19.875424 systemd[1]: Queued start job for default target initrd.target. May 13 23:39:19.875433 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 13 23:39:19.875441 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 13 23:39:19.875449 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 13 23:39:19.875457 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 13 23:39:19.875464 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 13 23:39:19.875473 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 13 23:39:19.875483 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 13 23:39:19.875490 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 13 23:39:19.875498 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 13 23:39:19.875506 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 13 23:39:19.875513 systemd[1]: Reached target paths.target - Path Units. May 13 23:39:19.875520 systemd[1]: Reached target slices.target - Slice Units. May 13 23:39:19.875528 systemd[1]: Reached target swap.target - Swaps. May 13 23:39:19.875535 systemd[1]: Reached target timers.target - Timer Units. May 13 23:39:19.875543 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 13 23:39:19.875552 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 13 23:39:19.875560 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 13 23:39:19.875568 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. May 13 23:39:19.875575 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 13 23:39:19.875583 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 13 23:39:19.875590 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 13 23:39:19.875598 systemd[1]: Reached target sockets.target - Socket Units. May 13 23:39:19.875605 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 13 23:39:19.875615 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 13 23:39:19.875632 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 13 23:39:19.875653 systemd[1]: Starting systemd-fsck-usr.service... May 13 23:39:19.875661 systemd[1]: Starting systemd-journald.service - Journal Service... May 13 23:39:19.875669 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 13 23:39:19.875676 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 13 23:39:19.875684 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 13 23:39:19.875692 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 13 23:39:19.875702 systemd[1]: Finished systemd-fsck-usr.service. May 13 23:39:19.875710 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 13 23:39:19.875736 systemd-journald[237]: Collecting audit messages is disabled. May 13 23:39:19.875757 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:39:19.875765 systemd-journald[237]: Journal started May 13 23:39:19.875783 systemd-journald[237]: Runtime Journal (/run/log/journal/c6e3b6c0446f4c468eb285bf0e5cdc64) is 5.9M, max 47.3M, 41.4M free. May 13 23:39:19.870770 systemd-modules-load[238]: Inserted module 'overlay' May 13 23:39:19.882641 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 13 23:39:19.884636 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. May 13 23:39:19.884654 systemd[1]: Started systemd-journald.service - Journal Service. May 13 23:39:19.885767 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 13 23:39:19.887834 kernel: Bridge firewalling registered May 13 23:39:19.885883 systemd-modules-load[238]: Inserted module 'br_netfilter' May 13 23:39:19.887080 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 13 23:39:19.891226 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 13 23:39:19.893413 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 13 23:39:19.896172 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 13 23:39:19.904029 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 13 23:39:19.905831 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 13 23:39:19.907288 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 13 23:39:19.910693 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 13 23:39:19.911879 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 13 23:39:19.914265 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 13 23:39:19.932433 dracut-cmdline[280]: dracut-dracut-053 May 13 23:39:19.934944 dracut-cmdline[280]: 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=3174b2682629aa8ad4069807ed6fd62c10f62266ee1e150a1104f2a2fb6489b5 May 13 23:39:19.953003 systemd-resolved[279]: Positive Trust Anchors: May 13 23:39:19.953020 systemd-resolved[279]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 13 23:39:19.953051 systemd-resolved[279]: 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 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test May 13 23:39:19.957778 systemd-resolved[279]: Defaulting to hostname 'linux'. May 13 23:39:19.958766 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 13 23:39:19.961775 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 13 23:39:20.006659 kernel: SCSI subsystem initialized May 13 23:39:20.011647 kernel: Loading iSCSI transport class v2.0-870. May 13 23:39:20.018651 kernel: iscsi: registered transport (tcp) May 13 23:39:20.031655 kernel: iscsi: registered transport (qla4xxx) May 13 23:39:20.031669 kernel: QLogic iSCSI HBA Driver May 13 23:39:20.076558 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 13 23:39:20.078977 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 13 23:39:20.107590 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 13 23:39:20.109335 kernel: device-mapper: uevent: version 1.0.3 May 13 23:39:20.109368 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com May 13 23:39:20.157659 kernel: raid6: neonx8 gen() 15760 MB/s May 13 23:39:20.174637 kernel: raid6: neonx4 gen() 15815 MB/s May 13 23:39:20.191639 kernel: raid6: neonx2 gen() 13365 MB/s May 13 23:39:20.208639 kernel: raid6: neonx1 gen() 10494 MB/s May 13 23:39:20.225649 kernel: raid6: int64x8 gen() 6789 MB/s May 13 23:39:20.242642 kernel: raid6: int64x4 gen() 7347 MB/s May 13 23:39:20.259639 kernel: raid6: int64x2 gen() 6115 MB/s May 13 23:39:20.276638 kernel: raid6: int64x1 gen() 5058 MB/s May 13 23:39:20.276652 kernel: raid6: using algorithm neonx4 gen() 15815 MB/s May 13 23:39:20.293658 kernel: raid6: .... xor() 12344 MB/s, rmw enabled May 13 23:39:20.293682 kernel: raid6: using neon recovery algorithm May 13 23:39:20.298641 kernel: xor: measuring software checksum speed May 13 23:39:20.298664 kernel: 8regs : 20780 MB/sec May 13 23:39:20.300110 kernel: 32regs : 20133 MB/sec May 13 23:39:20.300121 kernel: arm64_neon : 27908 MB/sec May 13 23:39:20.300130 kernel: xor: using function: arm64_neon (27908 MB/sec) May 13 23:39:20.351655 kernel: Btrfs loaded, zoned=no, fsverity=no May 13 23:39:20.363279 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 13 23:39:20.366796 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 13 23:39:20.392206 systemd-udevd[464]: Using default interface naming scheme 'v255'. May 13 23:39:20.396736 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 13 23:39:20.399207 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 13 23:39:20.429826 dracut-pre-trigger[471]: rd.md=0: removing MD RAID activation May 13 23:39:20.460941 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 13 23:39:20.463200 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 13 23:39:20.547238 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 13 23:39:20.550345 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 13 23:39:20.572665 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 13 23:39:20.573994 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 13 23:39:20.575605 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 13 23:39:20.577904 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 13 23:39:20.580442 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 13 23:39:20.600823 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 13 23:39:20.608977 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues May 13 23:39:20.609152 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) May 13 23:39:20.613659 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 13 23:39:20.617124 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 13 23:39:20.617260 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 13 23:39:20.620248 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 13 23:39:20.621167 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 13 23:39:20.621320 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:39:20.624363 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 13 23:39:20.626476 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 13 23:39:20.640442 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by (udev-worker) (518) May 13 23:39:20.642652 kernel: BTRFS: device fsid ee830c17-a93d-4109-bd12-3fec8ef6763d devid 1 transid 41 /dev/vda3 scanned by (udev-worker) (519) May 13 23:39:20.648630 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. May 13 23:39:20.649832 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:39:20.667286 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. May 13 23:39:20.679475 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 13 23:39:20.685710 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. May 13 23:39:20.686600 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. May 13 23:39:20.689483 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 13 23:39:20.691850 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 13 23:39:20.708653 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 13 23:39:20.715749 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 13 23:39:21.723663 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 13 23:39:21.724065 disk-uuid[558]: The operation has completed successfully. May 13 23:39:21.764981 systemd[1]: disk-uuid.service: Deactivated successfully. May 13 23:39:21.765099 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 13 23:39:21.788292 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 13 23:39:21.809738 sh[573]: Success May 13 23:39:21.825665 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" May 13 23:39:21.856699 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 13 23:39:21.858334 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 13 23:39:21.864826 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 13 23:39:21.871769 kernel: BTRFS info (device dm-0): first mount of filesystem ee830c17-a93d-4109-bd12-3fec8ef6763d May 13 23:39:21.871810 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm May 13 23:39:21.874091 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead May 13 23:39:21.874121 kernel: BTRFS info (device dm-0): disabling log replay at mount time May 13 23:39:21.874131 kernel: BTRFS info (device dm-0): using free space tree May 13 23:39:21.877996 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 13 23:39:21.879510 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 13 23:39:21.880370 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 13 23:39:21.882317 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 13 23:39:21.911996 kernel: BTRFS info (device vda6): first mount of filesystem e7b30525-8b14-4004-ad68-68a99b3959db May 13 23:39:21.912058 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 13 23:39:21.912069 kernel: BTRFS info (device vda6): using free space tree May 13 23:39:21.914674 kernel: BTRFS info (device vda6): auto enabling async discard May 13 23:39:21.918669 kernel: BTRFS info (device vda6): last unmount of filesystem e7b30525-8b14-4004-ad68-68a99b3959db May 13 23:39:21.923184 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 13 23:39:21.925061 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 13 23:39:21.989283 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 13 23:39:21.991800 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 13 23:39:22.037086 systemd-networkd[758]: lo: Link UP May 13 23:39:22.037095 systemd-networkd[758]: lo: Gained carrier May 13 23:39:22.039110 systemd-networkd[758]: Enumeration completed May 13 23:39:22.039365 systemd[1]: Started systemd-networkd.service - Network Configuration. May 13 23:39:22.042545 ignition[669]: Ignition 2.20.0 May 13 23:39:22.039563 systemd-networkd[758]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 13 23:39:22.042551 ignition[669]: Stage: fetch-offline May 13 23:39:22.039567 systemd-networkd[758]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 13 23:39:22.042584 ignition[669]: no configs at "/usr/lib/ignition/base.d" May 13 23:39:22.040134 systemd-networkd[758]: eth0: Link UP May 13 23:39:22.042592 ignition[669]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 13 23:39:22.040137 systemd-networkd[758]: eth0: Gained carrier May 13 23:39:22.042755 ignition[669]: parsed url from cmdline: "" May 13 23:39:22.040143 systemd-networkd[758]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 13 23:39:22.042758 ignition[669]: no config URL provided May 13 23:39:22.044735 systemd[1]: Reached target network.target - Network. May 13 23:39:22.042763 ignition[669]: reading system config file "/usr/lib/ignition/user.ign" May 13 23:39:22.061793 unknown[669]: fetched base config from "system" May 13 23:39:22.042771 ignition[669]: no config at "/usr/lib/ignition/user.ign" May 13 23:39:22.061800 unknown[669]: fetched user config from "qemu" May 13 23:39:22.042796 ignition[669]: op(1): [started] loading QEMU firmware config module May 13 23:39:22.074698 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 13 23:39:22.042801 ignition[669]: op(1): executing: "modprobe" "qemu_fw_cfg" May 13 23:39:22.076372 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). May 13 23:39:22.050834 ignition[669]: op(1): [finished] loading QEMU firmware config module May 13 23:39:22.077151 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 13 23:39:22.053817 ignition[669]: parsing config with SHA512: 11d722cdd6e986280bbc38633f12759aca236d03466cadccb596dd31692d630c5f83897588d1e9bb25e651cad2c172bf09bba0e51f1249e25fd66586045f6516 May 13 23:39:22.077668 systemd-networkd[758]: eth0: DHCPv4 address 10.0.0.48/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 13 23:39:22.062078 ignition[669]: fetch-offline: fetch-offline passed May 13 23:39:22.062175 ignition[669]: Ignition finished successfully May 13 23:39:22.107886 ignition[768]: Ignition 2.20.0 May 13 23:39:22.107900 ignition[768]: Stage: kargs May 13 23:39:22.108083 ignition[768]: no configs at "/usr/lib/ignition/base.d" May 13 23:39:22.108094 ignition[768]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 13 23:39:22.111289 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 13 23:39:22.108870 ignition[768]: kargs: kargs passed May 13 23:39:22.108920 ignition[768]: Ignition finished successfully May 13 23:39:22.114553 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 13 23:39:22.135831 ignition[779]: Ignition 2.20.0 May 13 23:39:22.135842 ignition[779]: Stage: disks May 13 23:39:22.135992 ignition[779]: no configs at "/usr/lib/ignition/base.d" May 13 23:39:22.136012 ignition[779]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 13 23:39:22.136662 ignition[779]: disks: disks passed May 13 23:39:22.138602 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 13 23:39:22.136705 ignition[779]: Ignition finished successfully May 13 23:39:22.139811 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 13 23:39:22.140948 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 13 23:39:22.142363 systemd[1]: Reached target local-fs.target - Local File Systems. May 13 23:39:22.143564 systemd[1]: Reached target sysinit.target - System Initialization. May 13 23:39:22.145339 systemd[1]: Reached target basic.target - Basic System. May 13 23:39:22.147742 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 13 23:39:22.172536 systemd-fsck[791]: ROOT: clean, 14/553520 files, 52654/553472 blocks May 13 23:39:22.177236 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 13 23:39:22.180748 systemd[1]: Mounting sysroot.mount - /sysroot... May 13 23:39:22.237643 kernel: EXT4-fs (vda9): mounted filesystem 9f8d74e6-c079-469f-823a-18a62077a2c7 r/w with ordered data mode. Quota mode: none. May 13 23:39:22.238468 systemd[1]: Mounted sysroot.mount - /sysroot. May 13 23:39:22.239694 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 13 23:39:22.242326 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 13 23:39:22.244397 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 13 23:39:22.245322 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. May 13 23:39:22.245365 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 13 23:39:22.245388 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 13 23:39:22.262239 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 13 23:39:22.264845 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 13 23:39:22.269077 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by mount (799) May 13 23:39:22.269108 kernel: BTRFS info (device vda6): first mount of filesystem e7b30525-8b14-4004-ad68-68a99b3959db May 13 23:39:22.269119 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 13 23:39:22.270636 kernel: BTRFS info (device vda6): using free space tree May 13 23:39:22.274652 kernel: BTRFS info (device vda6): auto enabling async discard May 13 23:39:22.273690 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 13 23:39:22.304816 initrd-setup-root[823]: cut: /sysroot/etc/passwd: No such file or directory May 13 23:39:22.309383 initrd-setup-root[830]: cut: /sysroot/etc/group: No such file or directory May 13 23:39:22.314137 initrd-setup-root[837]: cut: /sysroot/etc/shadow: No such file or directory May 13 23:39:22.317830 initrd-setup-root[844]: cut: /sysroot/etc/gshadow: No such file or directory May 13 23:39:22.391909 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 13 23:39:22.394770 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 13 23:39:22.396206 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 13 23:39:22.420666 kernel: BTRFS info (device vda6): last unmount of filesystem e7b30525-8b14-4004-ad68-68a99b3959db May 13 23:39:22.435928 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 13 23:39:22.456115 ignition[915]: INFO : Ignition 2.20.0 May 13 23:39:22.456115 ignition[915]: INFO : Stage: mount May 13 23:39:22.457389 ignition[915]: INFO : no configs at "/usr/lib/ignition/base.d" May 13 23:39:22.457389 ignition[915]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 13 23:39:22.457389 ignition[915]: INFO : mount: mount passed May 13 23:39:22.457389 ignition[915]: INFO : Ignition finished successfully May 13 23:39:22.459685 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 13 23:39:22.461546 systemd[1]: Starting ignition-files.service - Ignition (files)... May 13 23:39:22.871370 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 13 23:39:22.872854 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 13 23:39:22.891662 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (927) May 13 23:39:22.891708 kernel: BTRFS info (device vda6): first mount of filesystem e7b30525-8b14-4004-ad68-68a99b3959db May 13 23:39:22.893158 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 13 23:39:22.893183 kernel: BTRFS info (device vda6): using free space tree May 13 23:39:22.895649 kernel: BTRFS info (device vda6): auto enabling async discard May 13 23:39:22.896706 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 13 23:39:22.919919 ignition[944]: INFO : Ignition 2.20.0 May 13 23:39:22.919919 ignition[944]: INFO : Stage: files May 13 23:39:22.921218 ignition[944]: INFO : no configs at "/usr/lib/ignition/base.d" May 13 23:39:22.921218 ignition[944]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 13 23:39:22.921218 ignition[944]: DEBUG : files: compiled without relabeling support, skipping May 13 23:39:22.926456 ignition[944]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "dockremap" May 13 23:39:22.926456 ignition[944]: DEBUG : files: ensureUsers: op(1): executing: "useradd" "--root" "/sysroot" "--create-home" "--password" "*" "dockremap" May 13 23:39:22.998117 ignition[944]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "dockremap" May 13 23:39:23.000137 ignition[944]: INFO : files: ensureUsers: op(2): [started] creating or modifying user "core" May 13 23:39:23.000137 ignition[944]: DEBUG : files: ensureUsers: op(2): executing: "usermod" "--root" "/sysroot" "core" May 13 23:39:23.000137 ignition[944]: INFO : files: ensureUsers: op(2): [finished] creating or modifying user "core" May 13 23:39:23.000137 ignition[944]: INFO : files: ensureUsers: op(3): [started] adding ssh keys to user "core" May 13 23:39:23.005783 ignition[944]: INFO : files: ensureUsers: op(3): [finished] adding ssh keys to user "core" May 13 23:39:23.005783 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/subuid" May 13 23:39:23.005783 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/subuid" May 13 23:39:23.005783 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/etc/subgid" May 13 23:39:23.005783 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/etc/subgid" May 13 23:39:23.005783 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/etc/flatcar/update.conf" May 13 23:39:23.005783 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 13 23:39:23.005783 ignition[944]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" May 13 23:39:23.005783 ignition[944]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 13 23:39:23.005783 ignition[944]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 13 23:39:23.005783 ignition[944]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" May 13 23:39:23.005783 ignition[944]: INFO : files: op(9): [started] processing unit "docker.service" May 13 23:39:23.005783 ignition[944]: INFO : files: op(9): op(a): [started] writing systemd drop-in "10-userns.conf" at "/sysroot/etc/systemd/system/docker.service.d/10-userns.conf" May 13 23:39:23.005783 ignition[944]: INFO : files: op(9): op(a): [finished] writing systemd drop-in "10-userns.conf" at "/sysroot/etc/systemd/system/docker.service.d/10-userns.conf" May 13 23:39:23.005783 ignition[944]: INFO : files: op(9): [finished] processing unit "docker.service" May 13 23:39:23.005783 ignition[944]: INFO : files: op(b): [started] setting preset to disabled for "coreos-metadata.service" May 13 23:39:23.001615 unknown[944]: wrote ssh authorized keys file for user: core May 13 23:39:23.035889 ignition[944]: INFO : files: op(b): op(c): [started] removing enablement symlink(s) for "coreos-metadata.service" May 13 23:39:23.035889 ignition[944]: INFO : files: op(b): op(c): [finished] removing enablement symlink(s) for "coreos-metadata.service" May 13 23:39:23.035889 ignition[944]: INFO : files: op(b): [finished] setting preset to disabled for "coreos-metadata.service" May 13 23:39:23.035889 ignition[944]: INFO : files: op(d): [started] setting preset to enabled for "docker.service" May 13 23:39:23.035889 ignition[944]: INFO : files: op(d): [finished] setting preset to enabled for "docker.service" May 13 23:39:23.035889 ignition[944]: INFO : files: createResultFile: createFiles: op(e): [started] writing file "/sysroot/etc/.ignition-result.json" May 13 23:39:23.035889 ignition[944]: INFO : files: createResultFile: createFiles: op(e): [finished] writing file "/sysroot/etc/.ignition-result.json" May 13 23:39:23.035889 ignition[944]: INFO : files: files passed May 13 23:39:23.035889 ignition[944]: INFO : Ignition finished successfully May 13 23:39:23.025811 systemd[1]: Finished ignition-files.service - Ignition (files). May 13 23:39:23.029637 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 13 23:39:23.032242 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 13 23:39:23.056384 initrd-setup-root-after-ignition[983]: grep: /sysroot/oem/oem-release: No such file or directory May 13 23:39:23.054619 systemd[1]: ignition-quench.service: Deactivated successfully. May 13 23:39:23.054746 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 13 23:39:23.060029 initrd-setup-root-after-ignition[986]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 13 23:39:23.060029 initrd-setup-root-after-ignition[986]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 13 23:39:23.063113 initrd-setup-root-after-ignition[990]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 13 23:39:23.061306 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 13 23:39:23.064279 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 13 23:39:23.067037 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 13 23:39:23.102312 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 13 23:39:23.102431 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 13 23:39:23.104205 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 13 23:39:23.105510 systemd[1]: Reached target initrd.target - Initrd Default Target. May 13 23:39:23.106862 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 13 23:39:23.107690 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 13 23:39:23.128504 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 13 23:39:23.131036 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 13 23:39:23.152699 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 13 23:39:23.153664 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 13 23:39:23.155358 systemd[1]: Stopped target timers.target - Timer Units. May 13 23:39:23.156608 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 13 23:39:23.156758 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 13 23:39:23.158619 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 13 23:39:23.160133 systemd[1]: Stopped target basic.target - Basic System. May 13 23:39:23.161386 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 13 23:39:23.162640 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 13 23:39:23.164165 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 13 23:39:23.165589 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 13 23:39:23.166993 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 13 23:39:23.168475 systemd[1]: Stopped target sysinit.target - System Initialization. May 13 23:39:23.169917 systemd[1]: Stopped target local-fs.target - Local File Systems. May 13 23:39:23.171186 systemd[1]: Stopped target swap.target - Swaps. May 13 23:39:23.172296 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 13 23:39:23.172423 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 13 23:39:23.174156 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 13 23:39:23.175665 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 13 23:39:23.177128 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 13 23:39:23.178540 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 13 23:39:23.179476 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 13 23:39:23.179605 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 13 23:39:23.181686 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 13 23:39:23.181800 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 13 23:39:23.183219 systemd[1]: Stopped target paths.target - Path Units. May 13 23:39:23.184316 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 13 23:39:23.187723 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 13 23:39:23.189664 systemd[1]: Stopped target slices.target - Slice Units. May 13 23:39:23.190372 systemd[1]: Stopped target sockets.target - Socket Units. May 13 23:39:23.191854 systemd[1]: iscsid.socket: Deactivated successfully. May 13 23:39:23.191954 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 13 23:39:23.193017 systemd[1]: iscsiuio.socket: Deactivated successfully. May 13 23:39:23.193099 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 13 23:39:23.194241 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 13 23:39:23.194353 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 13 23:39:23.195601 systemd[1]: ignition-files.service: Deactivated successfully. May 13 23:39:23.195724 systemd[1]: Stopped ignition-files.service - Ignition (files). May 13 23:39:23.197610 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 13 23:39:23.198707 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 13 23:39:23.198842 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 13 23:39:23.201277 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 13 23:39:23.202239 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 13 23:39:23.202392 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 13 23:39:23.203787 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 13 23:39:23.203893 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 13 23:39:23.211854 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 13 23:39:23.211956 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 13 23:39:23.221366 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 13 23:39:23.222265 ignition[1010]: INFO : Ignition 2.20.0 May 13 23:39:23.222265 ignition[1010]: INFO : Stage: umount May 13 23:39:23.222265 ignition[1010]: INFO : no configs at "/usr/lib/ignition/base.d" May 13 23:39:23.224251 ignition[1010]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 13 23:39:23.224251 ignition[1010]: INFO : umount: umount passed May 13 23:39:23.224251 ignition[1010]: INFO : Ignition finished successfully May 13 23:39:23.224153 systemd[1]: sysroot-boot.service: Deactivated successfully. May 13 23:39:23.224251 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 13 23:39:23.226010 systemd[1]: ignition-mount.service: Deactivated successfully. May 13 23:39:23.226103 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 13 23:39:23.227758 systemd[1]: Stopped target network.target - Network. May 13 23:39:23.228748 systemd[1]: ignition-disks.service: Deactivated successfully. May 13 23:39:23.228813 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 13 23:39:23.230027 systemd[1]: ignition-kargs.service: Deactivated successfully. May 13 23:39:23.230068 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 13 23:39:23.231281 systemd[1]: ignition-setup.service: Deactivated successfully. May 13 23:39:23.231325 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 13 23:39:23.232562 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 13 23:39:23.232598 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 13 23:39:23.233939 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 13 23:39:23.233994 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 13 23:39:23.235393 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 13 23:39:23.236664 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 13 23:39:23.245827 systemd[1]: systemd-resolved.service: Deactivated successfully. May 13 23:39:23.245946 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 13 23:39:23.249247 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. May 13 23:39:23.249465 systemd[1]: systemd-networkd.service: Deactivated successfully. May 13 23:39:23.249563 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 13 23:39:23.252476 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. May 13 23:39:23.253185 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 13 23:39:23.253255 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 13 23:39:23.255355 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 13 23:39:23.256508 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 13 23:39:23.256567 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 13 23:39:23.258118 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 13 23:39:23.258173 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 13 23:39:23.260506 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 13 23:39:23.260552 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 13 23:39:23.261982 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 13 23:39:23.262026 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 13 23:39:23.264224 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 13 23:39:23.285865 systemd[1]: systemd-udevd.service: Deactivated successfully. May 13 23:39:23.286032 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 13 23:39:23.288025 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 13 23:39:23.288068 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 13 23:39:23.289458 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 13 23:39:23.289494 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 13 23:39:23.290831 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 13 23:39:23.290880 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 13 23:39:23.292868 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 13 23:39:23.292915 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 13 23:39:23.294842 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 13 23:39:23.294888 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 13 23:39:23.297888 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 13 23:39:23.299225 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 13 23:39:23.299284 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 13 23:39:23.301721 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 13 23:39:23.301763 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:39:23.309826 systemd[1]: network-cleanup.service: Deactivated successfully. May 13 23:39:23.309938 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 13 23:39:23.315213 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 13 23:39:23.315331 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 13 23:39:23.317082 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 13 23:39:23.319149 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 13 23:39:23.339008 systemd[1]: Switching root. May 13 23:39:23.368754 systemd-journald[237]: Journal stopped May 13 23:39:24.054995 systemd-journald[237]: Received SIGTERM from PID 1 (systemd). May 13 23:39:24.055049 kernel: SELinux: policy capability network_peer_controls=1 May 13 23:39:24.055062 kernel: SELinux: policy capability open_perms=1 May 13 23:39:24.055075 kernel: SELinux: policy capability extended_socket_class=1 May 13 23:39:24.055088 kernel: SELinux: policy capability always_check_network=0 May 13 23:39:24.055097 kernel: SELinux: policy capability cgroup_seclabel=1 May 13 23:39:24.055110 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 13 23:39:24.055119 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 13 23:39:24.055128 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 13 23:39:24.055138 kernel: audit: type=1403 audit(1747179563.437:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 13 23:39:24.055148 systemd[1]: Successfully loaded SELinux policy in 35.698ms. May 13 23:39:24.055161 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 10.140ms. May 13 23:39:24.055172 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) May 13 23:39:24.055186 systemd[1]: Detected virtualization kvm. May 13 23:39:24.055198 systemd[1]: Detected architecture arm64. May 13 23:39:24.055210 systemd[1]: Detected first boot. May 13 23:39:24.055220 systemd[1]: Initializing machine ID from VM UUID. May 13 23:39:24.055232 zram_generator::config[1056]: No configuration found. May 13 23:39:24.055243 kernel: NET: Registered PF_VSOCK protocol family May 13 23:39:24.055254 systemd[1]: Populated /etc with preset unit settings. May 13 23:39:24.055265 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. May 13 23:39:24.055275 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 13 23:39:24.055285 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 13 23:39:24.055296 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 13 23:39:24.055306 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 13 23:39:24.055317 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 13 23:39:24.055327 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 13 23:39:24.055338 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 13 23:39:24.055350 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 13 23:39:24.055361 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 13 23:39:24.055372 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 13 23:39:24.055382 systemd[1]: Created slice user.slice - User and Session Slice. May 13 23:39:24.055393 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 13 23:39:24.055416 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 13 23:39:24.055427 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 13 23:39:24.055438 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 13 23:39:24.055450 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 13 23:39:24.055465 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 13 23:39:24.055476 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... May 13 23:39:24.055487 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 13 23:39:24.055498 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 13 23:39:24.055508 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 13 23:39:24.055518 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 13 23:39:24.055530 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 13 23:39:24.055542 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 13 23:39:24.055553 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 13 23:39:24.055564 systemd[1]: Reached target slices.target - Slice Units. May 13 23:39:24.055574 systemd[1]: Reached target swap.target - Swaps. May 13 23:39:24.055584 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 13 23:39:24.055595 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 13 23:39:24.055605 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. May 13 23:39:24.055615 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 13 23:39:24.055636 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 13 23:39:24.055649 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 13 23:39:24.055662 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 13 23:39:24.055673 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 13 23:39:24.055683 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 13 23:39:24.055695 systemd[1]: Mounting media.mount - External Media Directory... May 13 23:39:24.055705 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 13 23:39:24.055716 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 13 23:39:24.055726 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 13 23:39:24.055737 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). May 13 23:39:24.055749 systemd[1]: Reached target machines.target - Containers. May 13 23:39:24.055760 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 13 23:39:24.055771 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 13 23:39:24.055796 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 13 23:39:24.055808 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 13 23:39:24.055818 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 13 23:39:24.055828 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 13 23:39:24.055838 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 13 23:39:24.055849 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 13 23:39:24.055861 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 13 23:39:24.055871 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 13 23:39:24.055882 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 13 23:39:24.055892 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 13 23:39:24.055902 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 13 23:39:24.055912 systemd[1]: Stopped systemd-fsck-usr.service. May 13 23:39:24.055922 kernel: fuse: init (API version 7.39) May 13 23:39:24.055935 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 13 23:39:24.055946 kernel: loop: module loaded May 13 23:39:24.055962 systemd[1]: Starting systemd-journald.service - Journal Service... May 13 23:39:24.055973 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 13 23:39:24.055983 kernel: ACPI: bus type drm_connector registered May 13 23:39:24.055994 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 13 23:39:24.056004 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 13 23:39:24.056015 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... May 13 23:39:24.056025 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 13 23:39:24.056035 systemd[1]: verity-setup.service: Deactivated successfully. May 13 23:39:24.056047 systemd[1]: Stopped verity-setup.service. May 13 23:39:24.056058 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 13 23:39:24.056092 systemd-journald[1121]: Collecting audit messages is disabled. May 13 23:39:24.056116 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 13 23:39:24.056127 systemd[1]: Mounted media.mount - External Media Directory. May 13 23:39:24.056137 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 13 23:39:24.056148 systemd-journald[1121]: Journal started May 13 23:39:24.056169 systemd-journald[1121]: Runtime Journal (/run/log/journal/c6e3b6c0446f4c468eb285bf0e5cdc64) is 5.9M, max 47.3M, 41.4M free. May 13 23:39:23.855568 systemd[1]: Queued start job for default target multi-user.target. May 13 23:39:23.866681 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. May 13 23:39:23.867069 systemd[1]: systemd-journald.service: Deactivated successfully. May 13 23:39:24.058649 systemd[1]: Started systemd-journald.service - Journal Service. May 13 23:39:24.059026 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 13 23:39:24.060035 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 13 23:39:24.063201 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 13 23:39:24.064517 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 13 23:39:24.065938 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 13 23:39:24.066125 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 13 23:39:24.067250 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 13 23:39:24.067409 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 13 23:39:24.068539 systemd[1]: modprobe@drm.service: Deactivated successfully. May 13 23:39:24.068724 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 13 23:39:24.069769 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 13 23:39:24.069938 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 13 23:39:24.071094 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 13 23:39:24.071250 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 13 23:39:24.073059 systemd[1]: modprobe@loop.service: Deactivated successfully. May 13 23:39:24.073341 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 13 23:39:24.074767 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 13 23:39:24.076018 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 13 23:39:24.077372 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 13 23:39:24.079157 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. May 13 23:39:24.092076 systemd[1]: Reached target network-pre.target - Preparation for Network. May 13 23:39:24.094608 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 13 23:39:24.096683 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 13 23:39:24.097539 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 13 23:39:24.097574 systemd[1]: Reached target local-fs.target - Local File Systems. May 13 23:39:24.099340 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. May 13 23:39:24.102586 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 13 23:39:24.106011 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 13 23:39:24.107033 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 13 23:39:24.108553 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 13 23:39:24.111836 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 13 23:39:24.112873 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 13 23:39:24.116831 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 13 23:39:24.118329 systemd-journald[1121]: Time spent on flushing to /var/log/journal/c6e3b6c0446f4c468eb285bf0e5cdc64 is 25.702ms for 841 entries. May 13 23:39:24.118329 systemd-journald[1121]: System Journal (/var/log/journal/c6e3b6c0446f4c468eb285bf0e5cdc64) is 8M, max 195.6M, 187.6M free. May 13 23:39:24.159339 systemd-journald[1121]: Received client request to flush runtime journal. May 13 23:39:24.159403 kernel: loop0: detected capacity change from 0 to 126448 May 13 23:39:24.118220 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 13 23:39:24.120868 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 13 23:39:24.123063 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 13 23:39:24.126354 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 13 23:39:24.133315 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 13 23:39:24.134570 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 13 23:39:24.135896 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 13 23:39:24.137284 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 13 23:39:24.146135 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... May 13 23:39:24.148747 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 13 23:39:24.149823 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 13 23:39:24.154073 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... May 13 23:39:24.162323 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 13 23:39:24.167773 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 13 23:39:24.173719 udevadm[1180]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. May 13 23:39:24.175667 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 13 23:39:24.182165 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 13 23:39:24.185780 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 13 23:39:24.190829 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. May 13 23:39:24.199658 kernel: loop1: detected capacity change from 0 to 103832 May 13 23:39:24.222468 systemd-tmpfiles[1191]: ACLs are not supported, ignoring. May 13 23:39:24.222484 systemd-tmpfiles[1191]: ACLs are not supported, ignoring. May 13 23:39:24.229397 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 13 23:39:24.231804 kernel: loop2: detected capacity change from 0 to 126448 May 13 23:39:24.237668 kernel: loop3: detected capacity change from 0 to 103832 May 13 23:39:24.242069 (sd-merge)[1196]: Using extensions 'containerd-flatcar', 'docker-flatcar'. May 13 23:39:24.242466 (sd-merge)[1196]: Merged extensions into '/usr'. May 13 23:39:24.246677 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 13 23:39:24.249892 systemd[1]: Starting ensure-sysext.service... May 13 23:39:24.252785 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 13 23:39:24.274786 systemd[1]: Reload requested from client PID 1198 ('systemctl') (unit ensure-sysext.service)... May 13 23:39:24.274965 systemd[1]: Reloading... May 13 23:39:24.283037 systemd-tmpfiles[1199]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 13 23:39:24.283248 systemd-tmpfiles[1199]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 13 23:39:24.284044 systemd-tmpfiles[1199]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 13 23:39:24.284244 systemd-tmpfiles[1199]: ACLs are not supported, ignoring. May 13 23:39:24.284288 systemd-tmpfiles[1199]: ACLs are not supported, ignoring. May 13 23:39:24.287395 systemd-tmpfiles[1199]: Detected autofs mount point /boot during canonicalization of boot. May 13 23:39:24.287431 systemd-tmpfiles[1199]: Skipping /boot May 13 23:39:24.301678 systemd-tmpfiles[1199]: Detected autofs mount point /boot during canonicalization of boot. May 13 23:39:24.301694 systemd-tmpfiles[1199]: Skipping /boot May 13 23:39:24.348711 zram_generator::config[1232]: No configuration found. May 13 23:39:24.386096 ldconfig[1168]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 13 23:39:24.449609 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 13 23:39:24.499887 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 13 23:39:24.500431 systemd[1]: Reloading finished in 225 ms. May 13 23:39:24.525501 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 13 23:39:24.546701 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 13 23:39:24.554820 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 13 23:39:24.557252 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 13 23:39:24.565916 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 13 23:39:24.569074 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 13 23:39:24.574262 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 13 23:39:24.578196 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 13 23:39:24.579476 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 13 23:39:24.583536 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 13 23:39:24.596831 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 13 23:39:24.597715 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 13 23:39:24.597836 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 13 23:39:24.601652 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 13 23:39:24.603653 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 13 23:39:24.603863 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 13 23:39:24.605159 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 13 23:39:24.605313 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 13 23:39:24.606676 systemd[1]: modprobe@loop.service: Deactivated successfully. May 13 23:39:24.606854 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 13 23:39:24.609245 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 13 23:39:24.617985 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 13 23:39:24.619904 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 13 23:39:24.622373 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 13 23:39:24.625950 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 13 23:39:24.626859 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 13 23:39:24.627031 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 13 23:39:24.639046 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 13 23:39:24.643493 systemd[1]: modprobe@loop.service: Deactivated successfully. May 13 23:39:24.643742 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 13 23:39:24.646397 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 13 23:39:24.647054 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 13 23:39:24.649153 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 13 23:39:24.649389 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 13 23:39:24.659983 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 13 23:39:24.661714 systemd[1]: Finished ensure-sysext.service. May 13 23:39:24.665219 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 13 23:39:24.674126 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 13 23:39:24.675232 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 13 23:39:24.675277 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 13 23:39:24.675321 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 13 23:39:24.675376 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 13 23:39:24.686409 augenrules[1312]: No rules May 13 23:39:24.684000 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... May 13 23:39:24.686972 systemd[1]: modprobe@drm.service: Deactivated successfully. May 13 23:39:24.688661 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 13 23:39:24.690046 systemd[1]: audit-rules.service: Deactivated successfully. May 13 23:39:24.690270 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 13 23:39:24.696671 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 13 23:39:24.700578 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 13 23:39:24.703081 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 13 23:39:24.704443 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 13 23:39:24.706393 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). May 13 23:39:24.728203 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 13 23:39:24.738350 systemd-udevd[1321]: Using default interface naming scheme 'v255'. May 13 23:39:24.747825 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. May 13 23:39:24.749135 systemd[1]: Reached target time-set.target - System Time Set. May 13 23:39:24.756229 systemd-resolved[1269]: Positive Trust Anchors: May 13 23:39:24.756250 systemd-resolved[1269]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 13 23:39:24.756280 systemd-resolved[1269]: 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 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test May 13 23:39:24.760015 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 13 23:39:24.762982 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 13 23:39:24.764573 systemd-resolved[1269]: Defaulting to hostname 'linux'. May 13 23:39:24.766494 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 13 23:39:24.770379 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 13 23:39:24.805648 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (1341) May 13 23:39:24.811116 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. May 13 23:39:24.842043 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 13 23:39:24.845482 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 13 23:39:24.862925 systemd-networkd[1333]: lo: Link UP May 13 23:39:24.862943 systemd-networkd[1333]: lo: Gained carrier May 13 23:39:24.863890 systemd-networkd[1333]: Enumeration completed May 13 23:39:24.863994 systemd[1]: Started systemd-networkd.service - Network Configuration. May 13 23:39:24.864658 systemd-networkd[1333]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 13 23:39:24.864662 systemd-networkd[1333]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 13 23:39:24.864971 systemd[1]: Reached target network.target - Network. May 13 23:39:24.865166 systemd-networkd[1333]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 13 23:39:24.865189 systemd-networkd[1333]: eth0: Link UP May 13 23:39:24.865191 systemd-networkd[1333]: eth0: Gained carrier May 13 23:39:24.865200 systemd-networkd[1333]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 13 23:39:24.867418 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... May 13 23:39:24.869773 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 13 23:39:24.879822 systemd-networkd[1333]: eth0: DHCPv4 address 10.0.0.48/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 13 23:39:24.880795 systemd-timesyncd[1309]: Network configuration changed, trying to establish connection. May 13 23:39:24.880899 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 13 23:39:24.883781 systemd-timesyncd[1309]: Contacted time server 10.0.0.1:123 (10.0.0.1). May 13 23:39:24.883845 systemd-timesyncd[1309]: Initial clock synchronization to Tue 2025-05-13 23:39:25.197733 UTC. May 13 23:39:24.897018 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. May 13 23:39:24.904743 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 13 23:39:24.916193 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. May 13 23:39:24.923235 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... May 13 23:39:24.961883 lvm[1365]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 13 23:39:24.992538 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:39:24.996102 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. May 13 23:39:24.997269 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 13 23:39:24.998182 systemd[1]: Reached target sysinit.target - System Initialization. May 13 23:39:24.999054 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 13 23:39:24.999967 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. May 13 23:39:25.001046 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 13 23:39:25.002158 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 13 23:39:25.003132 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 13 23:39:25.004053 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 13 23:39:25.004096 systemd[1]: Reached target paths.target - Path Units. May 13 23:39:25.004751 systemd[1]: Reached target timers.target - Timer Units. May 13 23:39:25.006565 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 13 23:39:25.008933 systemd[1]: Starting docker.socket - Docker Socket for the API... May 13 23:39:25.012007 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). May 13 23:39:25.013205 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). May 13 23:39:25.014195 systemd[1]: Reached target ssh-access.target - SSH Access Available. May 13 23:39:25.018692 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 13 23:39:25.019905 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. May 13 23:39:25.021897 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... May 13 23:39:25.023231 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 13 23:39:25.024138 systemd[1]: Reached target sockets.target - Socket Units. May 13 23:39:25.024872 systemd[1]: Reached target basic.target - Basic System. May 13 23:39:25.025573 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 13 23:39:25.025604 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 13 23:39:25.026491 systemd[1]: Starting containerd.service - containerd container runtime... May 13 23:39:25.028520 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 13 23:39:25.030803 lvm[1373]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 13 23:39:25.031772 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 13 23:39:25.033863 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 13 23:39:25.034705 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). May 13 23:39:25.038022 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 13 23:39:25.041404 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 13 23:39:25.045043 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 13 23:39:25.046631 jq[1376]: false May 13 23:39:25.054074 systemd[1]: Starting systemd-logind.service - User Login Management... May 13 23:39:25.056696 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 13 23:39:25.057221 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 13 23:39:25.057886 systemd[1]: Starting update-engine.service - Update Engine... May 13 23:39:25.060875 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 13 23:39:25.065633 extend-filesystems[1377]: Found loop2 May 13 23:39:25.069697 extend-filesystems[1377]: Found loop3 May 13 23:39:25.069697 extend-filesystems[1377]: Found vda May 13 23:39:25.069697 extend-filesystems[1377]: Found vda1 May 13 23:39:25.069697 extend-filesystems[1377]: Found vda2 May 13 23:39:25.069697 extend-filesystems[1377]: Found vda3 May 13 23:39:25.069697 extend-filesystems[1377]: Found usr May 13 23:39:25.069697 extend-filesystems[1377]: Found vda4 May 13 23:39:25.069697 extend-filesystems[1377]: Found vda6 May 13 23:39:25.069697 extend-filesystems[1377]: Found vda7 May 13 23:39:25.069697 extend-filesystems[1377]: Found vda9 May 13 23:39:25.069697 extend-filesystems[1377]: Checking size of /dev/vda9 May 13 23:39:25.069179 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 13 23:39:25.065866 dbus-daemon[1375]: [system] SELinux support is enabled May 13 23:39:25.072586 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. May 13 23:39:25.077164 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 13 23:39:25.088047 jq[1386]: true May 13 23:39:25.077342 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 13 23:39:25.077592 systemd[1]: motdgen.service: Deactivated successfully. May 13 23:39:25.077765 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 13 23:39:25.078930 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 13 23:39:25.079096 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 13 23:39:25.095177 extend-filesystems[1377]: Old size kept for /dev/vda9 May 13 23:39:25.097155 update_engine[1385]: I20250513 23:39:25.096976 1385 main.cc:92] Flatcar Update Engine starting May 13 23:39:25.096380 systemd[1]: extend-filesystems.service: Deactivated successfully. May 13 23:39:25.096580 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 13 23:39:25.107549 update_engine[1385]: I20250513 23:39:25.106146 1385 update_check_scheduler.cc:74] Next update check in 9m21s May 13 23:39:25.106647 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). May 13 23:39:25.106713 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 13 23:39:25.108854 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (1331) May 13 23:39:25.108727 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). May 13 23:39:25.108746 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 13 23:39:25.112266 (ntainerd)[1403]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 13 23:39:25.118942 jq[1402]: true May 13 23:39:25.119592 systemd[1]: Started update-engine.service - Update Engine. May 13 23:39:25.125540 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 13 23:39:25.154235 systemd-logind[1381]: Watching system buttons on /dev/input/event0 (Power Button) May 13 23:39:25.154462 systemd-logind[1381]: New seat seat0. May 13 23:39:25.155177 systemd[1]: Started systemd-logind.service - User Login Management. May 13 23:39:25.207697 locksmithd[1412]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 13 23:39:25.260625 bash[1426]: Updated "/home/core/.ssh/authorized_keys" May 13 23:39:25.265971 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 13 23:39:25.267820 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. May 13 23:39:25.348823 containerd[1403]: time="2025-05-13T23:39:25Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 May 13 23:39:25.351137 containerd[1403]: time="2025-05-13T23:39:25.351093539Z" level=info msg="starting containerd" revision=88aa2f531d6c2922003cc7929e51daf1c14caa0a version=v2.0.1 May 13 23:39:25.363836 containerd[1403]: time="2025-05-13T23:39:25.360117300Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.526µs" May 13 23:39:25.363836 containerd[1403]: time="2025-05-13T23:39:25.360160375Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 May 13 23:39:25.363836 containerd[1403]: time="2025-05-13T23:39:25.360179750Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 May 13 23:39:25.363836 containerd[1403]: time="2025-05-13T23:39:25.360355334Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 May 13 23:39:25.363836 containerd[1403]: time="2025-05-13T23:39:25.360373920Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 May 13 23:39:25.363836 containerd[1403]: time="2025-05-13T23:39:25.360399158Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 13 23:39:25.363836 containerd[1403]: time="2025-05-13T23:39:25.360447845Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 13 23:39:25.363836 containerd[1403]: time="2025-05-13T23:39:25.360459487Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 13 23:39:25.363836 containerd[1403]: time="2025-05-13T23:39:25.360987279Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 13 23:39:25.363836 containerd[1403]: time="2025-05-13T23:39:25.361044116Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 13 23:39:25.363836 containerd[1403]: time="2025-05-13T23:39:25.361068148Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 13 23:39:25.363836 containerd[1403]: time="2025-05-13T23:39:25.361083573Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 May 13 23:39:25.364327 containerd[1403]: time="2025-05-13T23:39:25.361179660Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 May 13 23:39:25.364327 containerd[1403]: time="2025-05-13T23:39:25.361387051Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 13 23:39:25.364327 containerd[1403]: time="2025-05-13T23:39:25.361421603Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 13 23:39:25.364327 containerd[1403]: time="2025-05-13T23:39:25.361432954Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 May 13 23:39:25.364327 containerd[1403]: time="2025-05-13T23:39:25.361461518Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 May 13 23:39:25.364327 containerd[1403]: time="2025-05-13T23:39:25.362182813Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 May 13 23:39:25.364327 containerd[1403]: time="2025-05-13T23:39:25.362275823Z" level=info msg="metadata content store policy set" policy=shared May 13 23:39:25.373250 containerd[1403]: time="2025-05-13T23:39:25.373187725Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 May 13 23:39:25.373367 containerd[1403]: time="2025-05-13T23:39:25.373263355Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 May 13 23:39:25.373367 containerd[1403]: time="2025-05-13T23:39:25.373280777Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 May 13 23:39:25.373367 containerd[1403]: time="2025-05-13T23:39:25.373293707Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 May 13 23:39:25.373367 containerd[1403]: time="2025-05-13T23:39:25.373308343Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 May 13 23:39:25.373367 containerd[1403]: time="2025-05-13T23:39:25.373319818Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 May 13 23:39:25.373367 containerd[1403]: time="2025-05-13T23:39:25.373332583Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 May 13 23:39:25.373367 containerd[1403]: time="2025-05-13T23:39:25.373345223Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 May 13 23:39:25.373367 containerd[1403]: time="2025-05-13T23:39:25.373357322Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 May 13 23:39:25.373367 containerd[1403]: time="2025-05-13T23:39:25.373368714Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 May 13 23:39:25.373514 containerd[1403]: time="2025-05-13T23:39:25.373378693Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 May 13 23:39:25.373514 containerd[1403]: time="2025-05-13T23:39:25.373391291Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 May 13 23:39:25.373572 containerd[1403]: time="2025-05-13T23:39:25.373546626Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 May 13 23:39:25.373597 containerd[1403]: time="2025-05-13T23:39:25.373575024Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 May 13 23:39:25.373597 containerd[1403]: time="2025-05-13T23:39:25.373589660Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 May 13 23:39:25.373646 containerd[1403]: time="2025-05-13T23:39:25.373601551Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 May 13 23:39:25.373646 containerd[1403]: time="2025-05-13T23:39:25.373613276Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 May 13 23:39:25.373646 containerd[1403]: time="2025-05-13T23:39:25.373623878Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 May 13 23:39:25.373646 containerd[1403]: time="2025-05-13T23:39:25.373637017Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 May 13 23:39:25.373726 containerd[1403]: time="2025-05-13T23:39:25.373648118Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 May 13 23:39:25.373726 containerd[1403]: time="2025-05-13T23:39:25.373696931Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 May 13 23:39:25.373726 containerd[1403]: time="2025-05-13T23:39:25.373712689Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 May 13 23:39:25.373726 containerd[1403]: time="2025-05-13T23:39:25.373724081Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 May 13 23:39:25.374048 containerd[1403]: time="2025-05-13T23:39:25.374000908Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" May 13 23:39:25.374048 containerd[1403]: time="2025-05-13T23:39:25.374026312Z" level=info msg="Start snapshots syncer" May 13 23:39:25.374119 containerd[1403]: time="2025-05-13T23:39:25.374059117Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 May 13 23:39:25.374385 containerd[1403]: time="2025-05-13T23:39:25.374328709Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" May 13 23:39:25.374494 containerd[1403]: time="2025-05-13T23:39:25.374396315Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 May 13 23:39:25.374494 containerd[1403]: time="2025-05-13T23:39:25.374468037Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 May 13 23:39:25.374616 containerd[1403]: time="2025-05-13T23:39:25.374583749Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 May 13 23:39:25.374616 containerd[1403]: time="2025-05-13T23:39:25.374614807Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 May 13 23:39:25.374679 containerd[1403]: time="2025-05-13T23:39:25.374638049Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 May 13 23:39:25.374679 containerd[1403]: time="2025-05-13T23:39:25.374650440Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 May 13 23:39:25.374728 containerd[1403]: time="2025-05-13T23:39:25.374680584Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 May 13 23:39:25.374728 containerd[1403]: time="2025-05-13T23:39:25.374694512Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 May 13 23:39:25.374728 containerd[1403]: time="2025-05-13T23:39:25.374706237Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 May 13 23:39:25.374777 containerd[1403]: time="2025-05-13T23:39:25.374735342Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 May 13 23:39:25.374777 containerd[1403]: time="2025-05-13T23:39:25.374749188Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 May 13 23:39:25.374777 containerd[1403]: time="2025-05-13T23:39:25.374759333Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 May 13 23:39:25.374841 containerd[1403]: time="2025-05-13T23:39:25.374800869Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 13 23:39:25.374841 containerd[1403]: time="2025-05-13T23:39:25.374818041Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 13 23:39:25.374841 containerd[1403]: time="2025-05-13T23:39:25.374829890Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 13 23:39:25.374894 containerd[1403]: time="2025-05-13T23:39:25.374848767Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 13 23:39:25.374894 containerd[1403]: time="2025-05-13T23:39:25.374857623Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 May 13 23:39:25.374894 containerd[1403]: time="2025-05-13T23:39:25.374873132Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 May 13 23:39:25.374894 containerd[1403]: time="2025-05-13T23:39:25.374885688Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 May 13 23:39:25.375700 containerd[1403]: time="2025-05-13T23:39:25.374965310Z" level=info msg="runtime interface created" May 13 23:39:25.375700 containerd[1403]: time="2025-05-13T23:39:25.374974665Z" level=info msg="created NRI interface" May 13 23:39:25.375700 containerd[1403]: time="2025-05-13T23:39:25.374984727Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 May 13 23:39:25.375700 containerd[1403]: time="2025-05-13T23:39:25.374997533Z" level=info msg="Connect containerd service" May 13 23:39:25.375700 containerd[1403]: time="2025-05-13T23:39:25.375060482Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 13 23:39:25.375967 containerd[1403]: time="2025-05-13T23:39:25.375941811Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" May 13 23:39:25.481003 containerd[1403]: time="2025-05-13T23:39:25.480824321Z" level=info msg="Start subscribing containerd event" May 13 23:39:25.481003 containerd[1403]: time="2025-05-13T23:39:25.480919992Z" level=info msg="Start recovering state" May 13 23:39:25.481109 containerd[1403]: time="2025-05-13T23:39:25.481021193Z" level=info msg="Start event monitor" May 13 23:39:25.481109 containerd[1403]: time="2025-05-13T23:39:25.481036743Z" level=info msg="Start cni network conf syncer for default" May 13 23:39:25.481109 containerd[1403]: time="2025-05-13T23:39:25.481045308Z" level=info msg="Start streaming server" May 13 23:39:25.481109 containerd[1403]: time="2025-05-13T23:39:25.481053374Z" level=info msg="Registered namespace \"k8s.io\" with NRI" May 13 23:39:25.481109 containerd[1403]: time="2025-05-13T23:39:25.481060442Z" level=info msg="runtime interface starting up..." May 13 23:39:25.481109 containerd[1403]: time="2025-05-13T23:39:25.481066180Z" level=info msg="starting plugins..." May 13 23:39:25.481109 containerd[1403]: time="2025-05-13T23:39:25.481077739Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" May 13 23:39:25.481619 containerd[1403]: time="2025-05-13T23:39:25.481582621Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 13 23:39:25.481810 containerd[1403]: time="2025-05-13T23:39:25.481744026Z" level=info msg=serving... address=/run/containerd/containerd.sock May 13 23:39:25.481957 containerd[1403]: time="2025-05-13T23:39:25.481927386Z" level=info msg="containerd successfully booted in 0.133626s" May 13 23:39:25.483505 systemd[1]: Started containerd.service - containerd container runtime. May 13 23:39:26.054853 sshd_keygen[1398]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 13 23:39:26.075731 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 13 23:39:26.078842 systemd[1]: Starting issuegen.service - Generate /run/issue... May 13 23:39:26.097468 systemd[1]: issuegen.service: Deactivated successfully. May 13 23:39:26.097707 systemd[1]: Finished issuegen.service - Generate /run/issue. May 13 23:39:26.100323 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 13 23:39:26.124885 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 13 23:39:26.128591 systemd[1]: Started getty@tty1.service - Getty on tty1. May 13 23:39:26.130800 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. May 13 23:39:26.131802 systemd[1]: Reached target getty.target - Login Prompts. May 13 23:39:26.444248 systemd-networkd[1333]: eth0: Gained IPv6LL May 13 23:39:26.447027 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 13 23:39:26.448459 systemd[1]: Reached target network-online.target - Network is Online. May 13 23:39:26.452153 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... May 13 23:39:26.454067 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 13 23:39:26.477815 systemd[1]: coreos-metadata.service: Deactivated successfully. May 13 23:39:26.478776 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. May 13 23:39:26.480227 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 13 23:39:26.482188 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 13 23:39:26.482477 systemd[1]: Reached target multi-user.target - Multi-User System. May 13 23:39:26.483725 systemd[1]: Startup finished in 542ms (kernel) + 3.740s (initrd) + 3.083s (userspace) = 7.367s. May 13 23:39:32.775304 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 13 23:39:32.776509 systemd[1]: Started sshd@0-10.0.0.48:22-10.0.0.1:60486.service - OpenSSH per-connection server daemon (10.0.0.1:60486). May 13 23:39:32.862865 sshd[1498]: Accepted publickey for core from 10.0.0.1 port 60486 ssh2: RSA SHA256:mw68dZYQU0J8UXjv1qvX457MoBIWfYiH3KbOSP4fCfE May 13 23:39:32.864740 sshd-session[1498]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:39:32.872337 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 13 23:39:32.873402 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 13 23:39:32.878191 systemd-logind[1381]: New session 1 of user core. May 13 23:39:32.892862 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 13 23:39:32.895454 systemd[1]: Starting user@500.service - User Manager for UID 500... May 13 23:39:32.910746 (systemd)[1502]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 13 23:39:32.913030 systemd-logind[1381]: New session c1 of user core. May 13 23:39:33.020008 systemd[1502]: Queued start job for default target default.target. May 13 23:39:33.030668 systemd[1502]: Created slice app.slice - User Application Slice. May 13 23:39:33.030699 systemd[1502]: Reached target paths.target - Paths. May 13 23:39:33.030736 systemd[1502]: Reached target timers.target - Timers. May 13 23:39:33.032027 systemd[1502]: Starting dbus.socket - D-Bus User Message Bus Socket... May 13 23:39:33.041735 systemd[1502]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 13 23:39:33.041806 systemd[1502]: Reached target sockets.target - Sockets. May 13 23:39:33.041846 systemd[1502]: Reached target basic.target - Basic System. May 13 23:39:33.041887 systemd[1502]: Reached target default.target - Main User Target. May 13 23:39:33.041917 systemd[1502]: Startup finished in 123ms. May 13 23:39:33.042171 systemd[1]: Started user@500.service - User Manager for UID 500. May 13 23:39:33.043475 systemd[1]: Started session-1.scope - Session 1 of User core. May 13 23:39:33.112143 systemd[1]: Started sshd@1-10.0.0.48:22-10.0.0.1:60490.service - OpenSSH per-connection server daemon (10.0.0.1:60490). May 13 23:39:33.169571 sshd[1513]: Accepted publickey for core from 10.0.0.1 port 60490 ssh2: RSA SHA256:mw68dZYQU0J8UXjv1qvX457MoBIWfYiH3KbOSP4fCfE May 13 23:39:33.170807 sshd-session[1513]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:39:33.175765 systemd-logind[1381]: New session 2 of user core. May 13 23:39:33.184795 systemd[1]: Started session-2.scope - Session 2 of User core. May 13 23:39:33.237595 sshd[1515]: Connection closed by 10.0.0.1 port 60490 May 13 23:39:33.238076 sshd-session[1513]: pam_unix(sshd:session): session closed for user core May 13 23:39:33.256574 systemd[1]: sshd@1-10.0.0.48:22-10.0.0.1:60490.service: Deactivated successfully. May 13 23:39:33.258267 systemd[1]: session-2.scope: Deactivated successfully. May 13 23:39:33.259914 systemd-logind[1381]: Session 2 logged out. Waiting for processes to exit. May 13 23:39:33.261338 systemd[1]: Started sshd@2-10.0.0.48:22-10.0.0.1:60500.service - OpenSSH per-connection server daemon (10.0.0.1:60500). May 13 23:39:33.262160 systemd-logind[1381]: Removed session 2. May 13 23:39:33.315304 sshd[1520]: Accepted publickey for core from 10.0.0.1 port 60500 ssh2: RSA SHA256:mw68dZYQU0J8UXjv1qvX457MoBIWfYiH3KbOSP4fCfE May 13 23:39:33.316940 sshd-session[1520]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:39:33.322510 systemd-logind[1381]: New session 3 of user core. May 13 23:39:33.333856 systemd[1]: Started session-3.scope - Session 3 of User core. May 13 23:39:33.382094 sshd[1523]: Connection closed by 10.0.0.1 port 60500 May 13 23:39:33.382620 sshd-session[1520]: pam_unix(sshd:session): session closed for user core May 13 23:39:33.401173 systemd[1]: sshd@2-10.0.0.48:22-10.0.0.1:60500.service: Deactivated successfully. May 13 23:39:33.404078 systemd[1]: session-3.scope: Deactivated successfully. May 13 23:39:33.404748 systemd-logind[1381]: Session 3 logged out. Waiting for processes to exit. May 13 23:39:33.406634 systemd[1]: Started sshd@3-10.0.0.48:22-10.0.0.1:60506.service - OpenSSH per-connection server daemon (10.0.0.1:60506). May 13 23:39:33.407523 systemd-logind[1381]: Removed session 3. May 13 23:39:33.465525 sshd[1528]: Accepted publickey for core from 10.0.0.1 port 60506 ssh2: RSA SHA256:mw68dZYQU0J8UXjv1qvX457MoBIWfYiH3KbOSP4fCfE May 13 23:39:33.466966 sshd-session[1528]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:39:33.471651 systemd-logind[1381]: New session 4 of user core. May 13 23:39:33.486846 systemd[1]: Started session-4.scope - Session 4 of User core. May 13 23:39:33.539843 sshd[1531]: Connection closed by 10.0.0.1 port 60506 May 13 23:39:33.540319 sshd-session[1528]: pam_unix(sshd:session): session closed for user core May 13 23:39:33.549173 systemd[1]: sshd@3-10.0.0.48:22-10.0.0.1:60506.service: Deactivated successfully. May 13 23:39:33.550969 systemd[1]: session-4.scope: Deactivated successfully. May 13 23:39:33.553372 systemd-logind[1381]: Session 4 logged out. Waiting for processes to exit. May 13 23:39:33.554278 systemd[1]: Started sshd@4-10.0.0.48:22-10.0.0.1:60518.service - OpenSSH per-connection server daemon (10.0.0.1:60518). May 13 23:39:33.555240 systemd-logind[1381]: Removed session 4. May 13 23:39:33.601016 sshd[1536]: Accepted publickey for core from 10.0.0.1 port 60518 ssh2: RSA SHA256:mw68dZYQU0J8UXjv1qvX457MoBIWfYiH3KbOSP4fCfE May 13 23:39:33.602229 sshd-session[1536]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:39:33.606657 systemd-logind[1381]: New session 5 of user core. May 13 23:39:33.617822 systemd[1]: Started session-5.scope - Session 5 of User core. May 13 23:39:33.687527 sudo[1540]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 May 13 23:39:33.687861 sudo[1540]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 13 23:39:33.701751 sudo[1540]: pam_unix(sudo:session): session closed for user root May 13 23:39:33.703434 sshd[1539]: Connection closed by 10.0.0.1 port 60518 May 13 23:39:33.703926 sshd-session[1536]: pam_unix(sshd:session): session closed for user core May 13 23:39:33.715105 systemd[1]: Started sshd@5-10.0.0.48:22-10.0.0.1:60524.service - OpenSSH per-connection server daemon (10.0.0.1:60524). May 13 23:39:33.715550 systemd[1]: sshd@4-10.0.0.48:22-10.0.0.1:60518.service: Deactivated successfully. May 13 23:39:33.717032 systemd[1]: session-5.scope: Deactivated successfully. May 13 23:39:33.718452 systemd-logind[1381]: Session 5 logged out. Waiting for processes to exit. May 13 23:39:33.719683 systemd-logind[1381]: Removed session 5. May 13 23:39:33.764187 sshd[1543]: Accepted publickey for core from 10.0.0.1 port 60524 ssh2: RSA SHA256:mw68dZYQU0J8UXjv1qvX457MoBIWfYiH3KbOSP4fCfE May 13 23:39:33.765536 sshd-session[1543]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:39:33.771303 systemd-logind[1381]: New session 6 of user core. May 13 23:39:33.781822 systemd[1]: Started session-6.scope - Session 6 of User core. May 13 23:39:33.835810 sudo[1550]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules May 13 23:39:33.836101 sudo[1550]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 13 23:39:33.839410 sudo[1550]: pam_unix(sudo:session): session closed for user root May 13 23:39:33.844196 sudo[1549]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules May 13 23:39:33.844475 sudo[1549]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 13 23:39:33.853041 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 13 23:39:33.890336 augenrules[1572]: No rules May 13 23:39:33.891842 systemd[1]: audit-rules.service: Deactivated successfully. May 13 23:39:33.892071 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 13 23:39:33.893572 sudo[1549]: pam_unix(sudo:session): session closed for user root May 13 23:39:33.894919 sshd[1548]: Connection closed by 10.0.0.1 port 60524 May 13 23:39:33.895146 sshd-session[1543]: pam_unix(sshd:session): session closed for user core May 13 23:39:33.906446 systemd[1]: sshd@5-10.0.0.48:22-10.0.0.1:60524.service: Deactivated successfully. May 13 23:39:33.908054 systemd[1]: session-6.scope: Deactivated successfully. May 13 23:39:33.908786 systemd-logind[1381]: Session 6 logged out. Waiting for processes to exit. May 13 23:39:33.911018 systemd[1]: Started sshd@6-10.0.0.48:22-10.0.0.1:60536.service - OpenSSH per-connection server daemon (10.0.0.1:60536). May 13 23:39:33.913062 systemd-logind[1381]: Removed session 6. May 13 23:39:33.977055 sshd[1580]: Accepted publickey for core from 10.0.0.1 port 60536 ssh2: RSA SHA256:mw68dZYQU0J8UXjv1qvX457MoBIWfYiH3KbOSP4fCfE May 13 23:39:33.978397 sshd-session[1580]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:39:33.982701 systemd-logind[1381]: New session 7 of user core. May 13 23:39:33.995845 systemd[1]: Started session-7.scope - Session 7 of User core. May 13 23:39:34.052912 sudo[1588]: core : PWD=/tmp/tmp.Aw5xFOVlT6 ; USER=root ; COMMAND=/usr/sbin/ldd /usr/bin/echo /usr/bin/sleep May 13 23:39:34.053211 sudo[1588]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 13 23:39:34.065350 sudo[1588]: pam_unix(sudo:session): session closed for user root May 13 23:39:34.074840 sudo[1600]: core : PWD=/tmp/tmp.Aw5xFOVlT6 ; USER=root ; COMMAND=/usr/sbin/rsync -av --relative --copy-links /usr/bin/echo /usr/bin/sleep /lib/ld-linux-aarch64.so.1 /lib64/ld-linux-aarch64.so.1 /lib64/libc.so.6 ./ May 13 23:39:34.075119 sudo[1600]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 13 23:39:34.139188 sudo[1600]: pam_unix(sudo:session): session closed for user root May 13 23:39:34.144402 sudo[1584]: core : PWD=/tmp/tmp.Aw5xFOVlT6 ; USER=root ; COMMAND=/usr/sbin/docker build -t userns-test . May 13 23:39:34.144711 sudo[1584]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 13 23:39:34.255112 systemd[1]: Starting docker.service - Docker Application Container Engine... May 13 23:39:34.269965 (dockerd)[1613]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU May 13 23:39:34.527656 dockerd[1613]: time="2025-05-13T23:39:34.527504476Z" level=info msg="User namespaces: ID ranges will be mapped to subuid/subgid ranges of: dockremap" May 13 23:39:34.527656 dockerd[1613]: time="2025-05-13T23:39:34.527625049Z" level=info msg="Starting up" May 13 23:39:34.527934 dockerd[1613]: time="2025-05-13T23:39:34.527742587Z" level=info msg="User namespaces: ID ranges will be mapped to subuid/subgid ranges of: dockremap" May 13 23:39:34.529643 dockerd[1613]: time="2025-05-13T23:39:34.529103257Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" May 13 23:39:34.529643 dockerd[1613]: time="2025-05-13T23:39:34.529344161Z" level=info msg="User namespaces: ID ranges will be mapped to subuid/subgid ranges of: dockremap" May 13 23:39:34.574345 dockerd[1613]: time="2025-05-13T23:39:34.574296940Z" level=info msg="Loading containers: start." May 13 23:39:34.737664 kernel: Initializing XFRM netlink socket May 13 23:39:34.815693 systemd-networkd[1333]: docker0: Link UP May 13 23:39:34.886547 dockerd[1613]: time="2025-05-13T23:39:34.886495066Z" level=info msg="Loading containers: done." May 13 23:39:34.904540 dockerd[1613]: time="2025-05-13T23:39:34.904467945Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 May 13 23:39:34.904715 dockerd[1613]: time="2025-05-13T23:39:34.904564396Z" level=info msg="Docker daemon" commit=c710b88579fcb5e0d53f96dcae976d79323b9166 containerd-snapshotter=false storage-driver=overlay2 version=27.4.1 May 13 23:39:34.904879 dockerd[1613]: time="2025-05-13T23:39:34.904846179Z" level=info msg="Daemon has completed initialization" May 13 23:39:34.973377 dockerd[1613]: time="2025-05-13T23:39:34.973282676Z" level=info msg="API listen on /run/docker.sock" May 13 23:39:34.973482 systemd[1]: Started docker.service - Docker Application Container Engine. May 13 23:39:35.286837 dockerd[1613]: 2025/05/13 23:39:35 http2: server: error reading preface from client @: read unix /run/docker.sock->@: read: connection reset by peer May 13 23:39:35.459826 sudo[1584]: pam_unix(sudo:session): session closed for user root May 13 23:39:35.461271 sshd[1583]: Connection closed by 10.0.0.1 port 60536 May 13 23:39:35.461613 sshd-session[1580]: pam_unix(sshd:session): session closed for user core May 13 23:39:35.471675 systemd[1]: sshd@6-10.0.0.48:22-10.0.0.1:60536.service: Deactivated successfully. May 13 23:39:35.472964 systemd[1]: session-7.scope: Deactivated successfully. May 13 23:39:35.473250 systemd[1]: session-7.scope: Consumed 484ms CPU time, 135.6M memory peak. May 13 23:39:35.473756 systemd-logind[1381]: Session 7 logged out. Waiting for processes to exit. May 13 23:39:35.475509 systemd[1]: Started sshd@7-10.0.0.48:22-10.0.0.1:60542.service - OpenSSH per-connection server daemon (10.0.0.1:60542). May 13 23:39:35.476327 systemd-logind[1381]: Removed session 7. May 13 23:39:35.535300 sshd[1840]: Accepted publickey for core from 10.0.0.1 port 60542 ssh2: RSA SHA256:mw68dZYQU0J8UXjv1qvX457MoBIWfYiH3KbOSP4fCfE May 13 23:39:35.536525 sshd-session[1840]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:39:35.540815 systemd-logind[1381]: New session 8 of user core. May 13 23:39:35.552812 systemd[1]: Started session-8.scope - Session 8 of User core. May 13 23:39:35.667987 kernel: docker0: port 1(veth750b107) entered blocking state May 13 23:39:35.668103 kernel: docker0: port 1(veth750b107) entered disabled state May 13 23:39:35.668123 kernel: veth750b107: entered allmulticast mode May 13 23:39:35.668139 kernel: veth750b107: entered promiscuous mode May 13 23:39:35.669404 kernel: docker0: port 1(veth750b107) entered blocking state May 13 23:39:35.669468 kernel: docker0: port 1(veth750b107) entered forwarding state May 13 23:39:35.670028 systemd-networkd[1333]: veth750b107: Link UP May 13 23:39:35.671238 kernel: docker0: port 1(veth750b107) entered disabled state May 13 23:39:35.712854 containerd[1403]: time="2025-05-13T23:39:35.712749239Z" level=info msg="connecting to shim 7701ef9b35ae5d7aace98e876989e2187d84fb81c624b1c770d5ac8bdfa351c1" address="unix:///run/containerd/s/863750438ca6d070da149149d431aa56eba757db7c19f96a06cccb9791b0f1e9" namespace=moby-100000.100000 protocol=ttrpc version=3 May 13 23:39:35.736829 systemd[1]: Started docker-7701ef9b35ae5d7aace98e876989e2187d84fb81c624b1c770d5ac8bdfa351c1.scope - libcontainer container 7701ef9b35ae5d7aace98e876989e2187d84fb81c624b1c770d5ac8bdfa351c1. May 13 23:39:35.785674 kernel: eth0: renamed from vethb52afed May 13 23:39:35.795718 kernel: docker0: port 1(veth750b107) entered blocking state May 13 23:39:35.795827 kernel: docker0: port 1(veth750b107) entered forwarding state May 13 23:39:35.795860 systemd-networkd[1333]: veth750b107: Gained carrier May 13 23:39:35.796128 systemd-networkd[1333]: docker0: Gained carrier May 13 23:39:35.798116 systemd-resolved[1269]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 13 23:39:35.825827 systemd[1]: docker-7701ef9b35ae5d7aace98e876989e2187d84fb81c624b1c770d5ac8bdfa351c1.scope: Deactivated successfully. May 13 23:39:35.839350 dockerd[1613]: time="2025-05-13T23:39:35.839226637Z" level=info msg="ignoring event" container=7701ef9b35ae5d7aace98e876989e2187d84fb81c624b1c770d5ac8bdfa351c1 module=libcontainerd namespace=moby-100000.100000 topic=/tasks/delete type="*events.TaskDelete" May 13 23:39:35.839735 containerd[1403]: time="2025-05-13T23:39:35.839316035Z" level=info msg="shim disconnected" id=7701ef9b35ae5d7aace98e876989e2187d84fb81c624b1c770d5ac8bdfa351c1 namespace=moby-100000.100000 May 13 23:39:35.839735 containerd[1403]: time="2025-05-13T23:39:35.839351883Z" level=warning msg="cleaning up after shim disconnected" id=7701ef9b35ae5d7aace98e876989e2187d84fb81c624b1c770d5ac8bdfa351c1 namespace=moby-100000.100000 May 13 23:39:35.839735 containerd[1403]: time="2025-05-13T23:39:35.839383205Z" level=info msg="cleaning up dead shim" namespace=moby-100000.100000 May 13 23:39:35.854669 kernel: vethb52afed: renamed from eth0 May 13 23:39:35.869351 systemd-networkd[1333]: veth750b107: Lost carrier May 13 23:39:35.869688 kernel: docker0: port 1(veth750b107) entered disabled state May 13 23:39:35.872370 systemd-networkd[1333]: veth750b107: Link DOWN May 13 23:39:35.873170 kernel: docker0: port 1(veth750b107) entered disabled state May 13 23:39:35.873220 kernel: veth750b107 (unregistering): left allmulticast mode May 13 23:39:35.873237 kernel: veth750b107 (unregistering): left promiscuous mode May 13 23:39:35.874669 kernel: docker0: port 1(veth750b107) entered disabled state May 13 23:39:35.926559 sshd[1843]: Connection closed by 10.0.0.1 port 60542 May 13 23:39:35.926994 sshd-session[1840]: pam_unix(sshd:session): session closed for user core May 13 23:39:35.939201 systemd[1]: sshd@7-10.0.0.48:22-10.0.0.1:60542.service: Deactivated successfully. May 13 23:39:35.940881 systemd[1]: session-8.scope: Deactivated successfully. May 13 23:39:35.941529 systemd-logind[1381]: Session 8 logged out. Waiting for processes to exit. May 13 23:39:35.944085 systemd[1]: Started sshd@8-10.0.0.48:22-10.0.0.1:60546.service - OpenSSH per-connection server daemon (10.0.0.1:60546). May 13 23:39:35.945399 systemd-logind[1381]: Removed session 8. May 13 23:39:36.003857 sshd[1944]: Accepted publickey for core from 10.0.0.1 port 60546 ssh2: RSA SHA256:mw68dZYQU0J8UXjv1qvX457MoBIWfYiH3KbOSP4fCfE May 13 23:39:36.005099 sshd-session[1944]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:39:36.009435 systemd-logind[1381]: New session 9 of user core. May 13 23:39:36.018806 systemd[1]: Started session-9.scope - Session 9 of User core. May 13 23:39:36.115755 kernel: docker0: port 1(veth15b73cc) entered blocking state May 13 23:39:36.115867 kernel: docker0: port 1(veth15b73cc) entered disabled state May 13 23:39:36.115890 kernel: veth15b73cc: entered allmulticast mode May 13 23:39:36.116640 kernel: veth15b73cc: entered promiscuous mode May 13 23:39:36.117326 systemd-networkd[1333]: veth15b73cc: Link UP May 13 23:39:36.118694 kernel: docker0: port 1(veth15b73cc) entered blocking state May 13 23:39:36.118929 kernel: docker0: port 1(veth15b73cc) entered forwarding state May 13 23:39:36.146839 containerd[1403]: time="2025-05-13T23:39:36.146778457Z" level=info msg="connecting to shim b5cf9880c01641fa1410c1da25c31f831b418e9051315faad5d76ccde9cc26c9" address="unix:///run/containerd/s/01580d1dccd7ae7cc7eb6e79e241f39464027164d6445ee4fa8e4d15ac3dd566" namespace=moby-100000.100000 protocol=ttrpc version=3 May 13 23:39:36.170786 systemd[1]: Started docker-b5cf9880c01641fa1410c1da25c31f831b418e9051315faad5d76ccde9cc26c9.scope - libcontainer container b5cf9880c01641fa1410c1da25c31f831b418e9051315faad5d76ccde9cc26c9. May 13 23:39:36.211675 kernel: eth0: renamed from vethc7d6ee4 May 13 23:39:36.217450 systemd-networkd[1333]: veth15b73cc: Gained carrier May 13 23:39:36.220538 systemd-resolved[1269]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 13 23:39:36.247072 sshd[1947]: Connection closed by 10.0.0.1 port 60546 May 13 23:39:36.248395 sshd-session[1944]: pam_unix(sshd:session): session closed for user core May 13 23:39:36.262012 systemd[1]: sshd@8-10.0.0.48:22-10.0.0.1:60546.service: Deactivated successfully. May 13 23:39:36.263989 systemd[1]: session-9.scope: Deactivated successfully. May 13 23:39:36.264756 systemd-logind[1381]: Session 9 logged out. Waiting for processes to exit. May 13 23:39:36.266416 systemd[1]: Started sshd@9-10.0.0.48:22-10.0.0.1:60562.service - OpenSSH per-connection server daemon (10.0.0.1:60562). May 13 23:39:36.267909 systemd-logind[1381]: Removed session 9. May 13 23:39:36.328306 sshd[2015]: Accepted publickey for core from 10.0.0.1 port 60562 ssh2: RSA SHA256:mw68dZYQU0J8UXjv1qvX457MoBIWfYiH3KbOSP4fCfE May 13 23:39:36.329654 sshd-session[2015]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:39:36.335556 systemd-logind[1381]: New session 10 of user core. May 13 23:39:36.346832 systemd[1]: Started session-10.scope - Session 10 of User core. May 13 23:39:36.442749 systemd[1]: docker-b5cf9880c01641fa1410c1da25c31f831b418e9051315faad5d76ccde9cc26c9.scope: Deactivated successfully. May 13 23:39:36.454806 containerd[1403]: time="2025-05-13T23:39:36.454584778Z" level=info msg="shim disconnected" id=b5cf9880c01641fa1410c1da25c31f831b418e9051315faad5d76ccde9cc26c9 namespace=moby-100000.100000 May 13 23:39:36.454806 containerd[1403]: time="2025-05-13T23:39:36.454644435Z" level=warning msg="cleaning up after shim disconnected" id=b5cf9880c01641fa1410c1da25c31f831b418e9051315faad5d76ccde9cc26c9 namespace=moby-100000.100000 May 13 23:39:36.454806 containerd[1403]: time="2025-05-13T23:39:36.454655535Z" level=info msg="cleaning up dead shim" namespace=moby-100000.100000 May 13 23:39:36.454962 dockerd[1613]: time="2025-05-13T23:39:36.454709581Z" level=info msg="ignoring event" container=b5cf9880c01641fa1410c1da25c31f831b418e9051315faad5d76ccde9cc26c9 module=libcontainerd namespace=moby-100000.100000 topic=/tasks/delete type="*events.TaskDelete" May 13 23:39:36.469676 kernel: docker0: port 1(veth15b73cc) entered disabled state May 13 23:39:36.469766 kernel: vethc7d6ee4: renamed from eth0 May 13 23:39:36.469319 systemd-networkd[1333]: veth15b73cc: Lost carrier May 13 23:39:36.487738 systemd-networkd[1333]: veth15b73cc: Link DOWN May 13 23:39:36.489136 kernel: docker0: port 1(veth15b73cc) entered disabled state May 13 23:39:36.489189 kernel: veth15b73cc (unregistering): left allmulticast mode May 13 23:39:36.489214 kernel: veth15b73cc (unregistering): left promiscuous mode May 13 23:39:36.489654 kernel: docker0: port 1(veth15b73cc) entered disabled state May 13 23:39:36.524085 sshd[2018]: Connection closed by 10.0.0.1 port 60562 May 13 23:39:36.524560 sshd-session[2015]: pam_unix(sshd:session): session closed for user core May 13 23:39:36.527904 systemd-logind[1381]: Session 10 logged out. Waiting for processes to exit. May 13 23:39:36.528218 systemd[1]: sshd@9-10.0.0.48:22-10.0.0.1:60562.service: Deactivated successfully. May 13 23:39:36.530958 systemd[1]: session-10.scope: Deactivated successfully. May 13 23:39:36.531870 systemd-logind[1381]: Removed session 10. May 13 23:39:36.554219 systemd[1]: var-lib-docker-100000.100000-overlay2-8070cac1350ed90b47e3546791879c4fe35865d78982dbe5492879832a609cf6-merged.mount: Deactivated successfully. May 13 23:39:36.748033 systemd-networkd[1333]: docker0: Lost carrier May 13 23:39:37.516269 systemd-networkd[1333]: docker0: Gained IPv6LL