Sep 8 23:39:18.809918 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Sep 8 23:39:18.809939 kernel: Linux version 6.12.45-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 Mon Sep 8 22:16:14 -00 2025 Sep 8 23:39:18.809948 kernel: KASLR enabled Sep 8 23:39:18.809954 kernel: efi: EFI v2.7 by EDK II Sep 8 23:39:18.809960 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb832018 ACPI 2.0=0xdbfd0018 RNG=0xdbfd0a18 MEMRESERVE=0xdb838218 Sep 8 23:39:18.809965 kernel: random: crng init done Sep 8 23:39:18.809972 kernel: secureboot: Secure boot disabled Sep 8 23:39:18.809977 kernel: ACPI: Early table checksum verification disabled Sep 8 23:39:18.809983 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Sep 8 23:39:18.809990 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Sep 8 23:39:18.809996 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Sep 8 23:39:18.810002 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 8 23:39:18.810007 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Sep 8 23:39:18.810013 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 8 23:39:18.810020 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 8 23:39:18.810027 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 8 23:39:18.810033 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 8 23:39:18.810039 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Sep 8 23:39:18.810045 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 8 23:39:18.810051 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Sep 8 23:39:18.810057 kernel: ACPI: Use ACPI SPCR as default console: No Sep 8 23:39:18.810063 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Sep 8 23:39:18.810068 kernel: NODE_DATA(0) allocated [mem 0xdc965a00-0xdc96cfff] Sep 8 23:39:18.810074 kernel: Zone ranges: Sep 8 23:39:18.810080 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Sep 8 23:39:18.810087 kernel: DMA32 empty Sep 8 23:39:18.810093 kernel: Normal empty Sep 8 23:39:18.810099 kernel: Device empty Sep 8 23:39:18.810104 kernel: Movable zone start for each node Sep 8 23:39:18.810110 kernel: Early memory node ranges Sep 8 23:39:18.810116 kernel: node 0: [mem 0x0000000040000000-0x00000000db81ffff] Sep 8 23:39:18.810122 kernel: node 0: [mem 0x00000000db820000-0x00000000db82ffff] Sep 8 23:39:18.810128 kernel: node 0: [mem 0x00000000db830000-0x00000000dc09ffff] Sep 8 23:39:18.810133 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Sep 8 23:39:18.810139 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Sep 8 23:39:18.810145 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Sep 8 23:39:18.810151 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Sep 8 23:39:18.810158 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Sep 8 23:39:18.810164 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Sep 8 23:39:18.810170 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Sep 8 23:39:18.810178 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Sep 8 23:39:18.810185 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Sep 8 23:39:18.810191 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Sep 8 23:39:18.810199 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Sep 8 23:39:18.810205 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Sep 8 23:39:18.810211 kernel: cma: Reserved 16 MiB at 0x00000000d8000000 on node -1 Sep 8 23:39:18.810217 kernel: psci: probing for conduit method from ACPI. Sep 8 23:39:18.810223 kernel: psci: PSCIv1.1 detected in firmware. Sep 8 23:39:18.810230 kernel: psci: Using standard PSCI v0.2 function IDs Sep 8 23:39:18.810236 kernel: psci: Trusted OS migration not required Sep 8 23:39:18.810242 kernel: psci: SMC Calling Convention v1.1 Sep 8 23:39:18.810248 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Sep 8 23:39:18.810255 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Sep 8 23:39:18.810263 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Sep 8 23:39:18.810269 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Sep 8 23:39:18.810275 kernel: Detected PIPT I-cache on CPU0 Sep 8 23:39:18.810282 kernel: CPU features: detected: GIC system register CPU interface Sep 8 23:39:18.810288 kernel: CPU features: detected: Spectre-v4 Sep 8 23:39:18.810294 kernel: CPU features: detected: Spectre-BHB Sep 8 23:39:18.810315 kernel: CPU features: kernel page table isolation forced ON by KASLR Sep 8 23:39:18.810322 kernel: CPU features: detected: Kernel page table isolation (KPTI) Sep 8 23:39:18.810328 kernel: CPU features: detected: ARM erratum 1418040 Sep 8 23:39:18.810335 kernel: CPU features: detected: SSBS not fully self-synchronizing Sep 8 23:39:18.810341 kernel: alternatives: applying boot alternatives Sep 8 23:39:18.810348 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=9b288da65d4b75f0b3fa549b2137666f5efe4c54bbf9c99d6059072c88732f23 Sep 8 23:39:18.810357 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 8 23:39:18.810363 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 8 23:39:18.810370 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 8 23:39:18.810376 kernel: Fallback order for Node 0: 0 Sep 8 23:39:18.810382 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 Sep 8 23:39:18.810388 kernel: Policy zone: DMA Sep 8 23:39:18.810394 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 8 23:39:18.810401 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB Sep 8 23:39:18.810407 kernel: software IO TLB: area num 4. Sep 8 23:39:18.810413 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB Sep 8 23:39:18.810420 kernel: software IO TLB: mapped [mem 0x00000000d7c00000-0x00000000d8000000] (4MB) Sep 8 23:39:18.810427 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 8 23:39:18.810434 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 8 23:39:18.810440 kernel: rcu: RCU event tracing is enabled. Sep 8 23:39:18.810447 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Sep 8 23:39:18.810456 kernel: Trampoline variant of Tasks RCU enabled. Sep 8 23:39:18.810463 kernel: Tracing variant of Tasks RCU enabled. Sep 8 23:39:18.810469 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 8 23:39:18.810475 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 8 23:39:18.810482 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 8 23:39:18.810488 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 8 23:39:18.810495 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Sep 8 23:39:18.810502 kernel: GICv3: 256 SPIs implemented Sep 8 23:39:18.810509 kernel: GICv3: 0 Extended SPIs implemented Sep 8 23:39:18.810515 kernel: Root IRQ handler: gic_handle_irq Sep 8 23:39:18.810521 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Sep 8 23:39:18.810527 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 Sep 8 23:39:18.810533 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Sep 8 23:39:18.810540 kernel: ITS [mem 0x08080000-0x0809ffff] Sep 8 23:39:18.810546 kernel: ITS@0x0000000008080000: allocated 8192 Devices @40110000 (indirect, esz 8, psz 64K, shr 1) Sep 8 23:39:18.810553 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @40120000 (flat, esz 8, psz 64K, shr 1) Sep 8 23:39:18.810563 kernel: GICv3: using LPI property table @0x0000000040130000 Sep 8 23:39:18.810630 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040140000 Sep 8 23:39:18.810994 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 8 23:39:18.811281 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 8 23:39:18.811295 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Sep 8 23:39:18.811860 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Sep 8 23:39:18.811968 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Sep 8 23:39:18.811979 kernel: arm-pv: using stolen time PV Sep 8 23:39:18.811987 kernel: Console: colour dummy device 80x25 Sep 8 23:39:18.811993 kernel: ACPI: Core revision 20240827 Sep 8 23:39:18.812000 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Sep 8 23:39:18.812007 kernel: pid_max: default: 32768 minimum: 301 Sep 8 23:39:18.812013 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 8 23:39:18.812038 kernel: landlock: Up and running. Sep 8 23:39:18.812045 kernel: SELinux: Initializing. Sep 8 23:39:18.812051 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 8 23:39:18.812058 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 8 23:39:18.812140 kernel: rcu: Hierarchical SRCU implementation. Sep 8 23:39:18.812246 kernel: rcu: Max phase no-delay instances is 400. Sep 8 23:39:18.812255 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 8 23:39:18.812262 kernel: Remapping and enabling EFI services. Sep 8 23:39:18.812268 kernel: smp: Bringing up secondary CPUs ... Sep 8 23:39:18.812283 kernel: Detected PIPT I-cache on CPU1 Sep 8 23:39:18.812290 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Sep 8 23:39:18.812321 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040150000 Sep 8 23:39:18.812330 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 8 23:39:18.812337 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Sep 8 23:39:18.812344 kernel: Detected PIPT I-cache on CPU2 Sep 8 23:39:18.812351 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Sep 8 23:39:18.812358 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040160000 Sep 8 23:39:18.812366 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 8 23:39:18.812373 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Sep 8 23:39:18.812380 kernel: Detected PIPT I-cache on CPU3 Sep 8 23:39:18.812387 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Sep 8 23:39:18.812394 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040170000 Sep 8 23:39:18.812401 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 8 23:39:18.812408 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Sep 8 23:39:18.812414 kernel: smp: Brought up 1 node, 4 CPUs Sep 8 23:39:18.812421 kernel: SMP: Total of 4 processors activated. Sep 8 23:39:18.812429 kernel: CPU: All CPU(s) started at EL1 Sep 8 23:39:18.812437 kernel: CPU features: detected: 32-bit EL0 Support Sep 8 23:39:18.812443 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Sep 8 23:39:18.812450 kernel: CPU features: detected: Common not Private translations Sep 8 23:39:18.812457 kernel: CPU features: detected: CRC32 instructions Sep 8 23:39:18.812464 kernel: CPU features: detected: Enhanced Virtualization Traps Sep 8 23:39:18.812471 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Sep 8 23:39:18.812478 kernel: CPU features: detected: LSE atomic instructions Sep 8 23:39:18.812484 kernel: CPU features: detected: Privileged Access Never Sep 8 23:39:18.812492 kernel: CPU features: detected: RAS Extension Support Sep 8 23:39:18.812499 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Sep 8 23:39:18.812506 kernel: alternatives: applying system-wide alternatives Sep 8 23:39:18.812513 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Sep 8 23:39:18.812520 kernel: Memory: 2424160K/2572288K available (11136K kernel code, 2436K rwdata, 9076K rodata, 38976K init, 1038K bss, 125472K reserved, 16384K cma-reserved) Sep 8 23:39:18.812527 kernel: devtmpfs: initialized Sep 8 23:39:18.812534 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 8 23:39:18.812541 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 8 23:39:18.812548 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Sep 8 23:39:18.812564 kernel: 0 pages in range for non-PLT usage Sep 8 23:39:18.812571 kernel: 508560 pages in range for PLT usage Sep 8 23:39:18.812578 kernel: pinctrl core: initialized pinctrl subsystem Sep 8 23:39:18.812584 kernel: SMBIOS 3.0.0 present. Sep 8 23:39:18.812591 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Sep 8 23:39:18.812598 kernel: DMI: Memory slots populated: 1/1 Sep 8 23:39:18.812605 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 8 23:39:18.812612 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Sep 8 23:39:18.812622 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Sep 8 23:39:18.812631 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Sep 8 23:39:18.812638 kernel: audit: initializing netlink subsys (disabled) Sep 8 23:39:18.812644 kernel: audit: type=2000 audit(0.023:1): state=initialized audit_enabled=0 res=1 Sep 8 23:39:18.812651 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 8 23:39:18.812658 kernel: cpuidle: using governor menu Sep 8 23:39:18.812665 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Sep 8 23:39:18.812672 kernel: ASID allocator initialised with 32768 entries Sep 8 23:39:18.812678 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 8 23:39:18.812685 kernel: Serial: AMBA PL011 UART driver Sep 8 23:39:18.812693 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 8 23:39:18.812701 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Sep 8 23:39:18.812707 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Sep 8 23:39:18.812716 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Sep 8 23:39:18.812723 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 8 23:39:18.812730 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Sep 8 23:39:18.812742 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Sep 8 23:39:18.812750 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Sep 8 23:39:18.812757 kernel: ACPI: Added _OSI(Module Device) Sep 8 23:39:18.812764 kernel: ACPI: Added _OSI(Processor Device) Sep 8 23:39:18.812775 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 8 23:39:18.812782 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 8 23:39:18.812789 kernel: ACPI: Interpreter enabled Sep 8 23:39:18.812795 kernel: ACPI: Using GIC for interrupt routing Sep 8 23:39:18.812802 kernel: ACPI: MCFG table detected, 1 entries Sep 8 23:39:18.812871 kernel: ACPI: CPU0 has been hot-added Sep 8 23:39:18.812879 kernel: ACPI: CPU1 has been hot-added Sep 8 23:39:18.812886 kernel: ACPI: CPU2 has been hot-added Sep 8 23:39:18.812901 kernel: ACPI: CPU3 has been hot-added Sep 8 23:39:18.812911 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Sep 8 23:39:18.812918 kernel: printk: legacy console [ttyAMA0] enabled Sep 8 23:39:18.812964 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 8 23:39:18.813375 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 8 23:39:18.813448 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Sep 8 23:39:18.813509 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Sep 8 23:39:18.813577 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Sep 8 23:39:18.813647 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Sep 8 23:39:18.813657 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Sep 8 23:39:18.813664 kernel: PCI host bridge to bus 0000:00 Sep 8 23:39:18.813729 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Sep 8 23:39:18.813815 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Sep 8 23:39:18.813877 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Sep 8 23:39:18.813942 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 8 23:39:18.814023 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint Sep 8 23:39:18.814093 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Sep 8 23:39:18.814154 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] Sep 8 23:39:18.814214 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] Sep 8 23:39:18.814272 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] Sep 8 23:39:18.814350 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned Sep 8 23:39:18.814411 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned Sep 8 23:39:18.814474 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned Sep 8 23:39:18.814529 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Sep 8 23:39:18.814588 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Sep 8 23:39:18.814645 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Sep 8 23:39:18.814654 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Sep 8 23:39:18.814661 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Sep 8 23:39:18.814668 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Sep 8 23:39:18.814677 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Sep 8 23:39:18.814683 kernel: iommu: Default domain type: Translated Sep 8 23:39:18.814690 kernel: iommu: DMA domain TLB invalidation policy: strict mode Sep 8 23:39:18.814697 kernel: efivars: Registered efivars operations Sep 8 23:39:18.814704 kernel: vgaarb: loaded Sep 8 23:39:18.814714 kernel: clocksource: Switched to clocksource arch_sys_counter Sep 8 23:39:18.814721 kernel: VFS: Disk quotas dquot_6.6.0 Sep 8 23:39:18.814728 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 8 23:39:18.814734 kernel: pnp: PnP ACPI init Sep 8 23:39:18.814831 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Sep 8 23:39:18.814843 kernel: pnp: PnP ACPI: found 1 devices Sep 8 23:39:18.814850 kernel: NET: Registered PF_INET protocol family Sep 8 23:39:18.814857 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 8 23:39:18.814864 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 8 23:39:18.814871 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 8 23:39:18.814878 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 8 23:39:18.814885 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 8 23:39:18.814893 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 8 23:39:18.814900 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 8 23:39:18.814907 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 8 23:39:18.814914 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 8 23:39:18.814921 kernel: PCI: CLS 0 bytes, default 64 Sep 8 23:39:18.814928 kernel: kvm [1]: HYP mode not available Sep 8 23:39:18.814934 kernel: Initialise system trusted keyrings Sep 8 23:39:18.814941 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 8 23:39:18.814948 kernel: Key type asymmetric registered Sep 8 23:39:18.814955 kernel: Asymmetric key parser 'x509' registered Sep 8 23:39:18.814963 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Sep 8 23:39:18.814970 kernel: io scheduler mq-deadline registered Sep 8 23:39:18.814977 kernel: io scheduler kyber registered Sep 8 23:39:18.814984 kernel: io scheduler bfq registered Sep 8 23:39:18.814991 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Sep 8 23:39:18.814998 kernel: ACPI: button: Power Button [PWRB] Sep 8 23:39:18.815006 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Sep 8 23:39:18.815068 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Sep 8 23:39:18.815078 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 8 23:39:18.815087 kernel: thunder_xcv, ver 1.0 Sep 8 23:39:18.815093 kernel: thunder_bgx, ver 1.0 Sep 8 23:39:18.815100 kernel: nicpf, ver 1.0 Sep 8 23:39:18.815107 kernel: nicvf, ver 1.0 Sep 8 23:39:18.815174 kernel: rtc-efi rtc-efi.0: registered as rtc0 Sep 8 23:39:18.815229 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-09-08T23:39:18 UTC (1757374758) Sep 8 23:39:18.815239 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 8 23:39:18.815246 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Sep 8 23:39:18.815254 kernel: watchdog: NMI not fully supported Sep 8 23:39:18.815261 kernel: watchdog: Hard watchdog permanently disabled Sep 8 23:39:18.815268 kernel: NET: Registered PF_INET6 protocol family Sep 8 23:39:18.815275 kernel: Segment Routing with IPv6 Sep 8 23:39:18.815282 kernel: In-situ OAM (IOAM) with IPv6 Sep 8 23:39:18.815289 kernel: NET: Registered PF_PACKET protocol family Sep 8 23:39:18.815296 kernel: Key type dns_resolver registered Sep 8 23:39:18.815457 kernel: registered taskstats version 1 Sep 8 23:39:18.815483 kernel: Loading compiled-in X.509 certificates Sep 8 23:39:18.815494 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.45-flatcar: 47cbee3f94dbdda6bd0b2aeb4a40d87813458eab' Sep 8 23:39:18.815501 kernel: Demotion targets for Node 0: null Sep 8 23:39:18.815508 kernel: Key type .fscrypt registered Sep 8 23:39:18.815514 kernel: Key type fscrypt-provisioning registered Sep 8 23:39:18.815521 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 8 23:39:18.815528 kernel: ima: Allocated hash algorithm: sha1 Sep 8 23:39:18.815535 kernel: ima: No architecture policies found Sep 8 23:39:18.815542 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Sep 8 23:39:18.815550 kernel: clk: Disabling unused clocks Sep 8 23:39:18.815562 kernel: PM: genpd: Disabling unused power domains Sep 8 23:39:18.815569 kernel: Warning: unable to open an initial console. Sep 8 23:39:18.815576 kernel: Freeing unused kernel memory: 38976K Sep 8 23:39:18.815582 kernel: Run /init as init process Sep 8 23:39:18.815589 kernel: with arguments: Sep 8 23:39:18.815596 kernel: /init Sep 8 23:39:18.815602 kernel: with environment: Sep 8 23:39:18.815609 kernel: HOME=/ Sep 8 23:39:18.815616 kernel: TERM=linux Sep 8 23:39:18.815627 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 8 23:39:18.815635 systemd[1]: Successfully made /usr/ read-only. Sep 8 23:39:18.815646 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) Sep 8 23:39:18.815654 systemd[1]: Detected virtualization kvm. Sep 8 23:39:18.815661 systemd[1]: Detected architecture arm64. Sep 8 23:39:18.815668 systemd[1]: Running in initrd. Sep 8 23:39:18.815675 systemd[1]: No hostname configured, using default hostname. Sep 8 23:39:18.815684 systemd[1]: Hostname set to . Sep 8 23:39:18.815691 systemd[1]: Initializing machine ID from VM UUID. Sep 8 23:39:18.815699 systemd[1]: Queued start job for default target initrd.target. Sep 8 23:39:18.815706 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 8 23:39:18.815716 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 8 23:39:18.815725 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 8 23:39:18.815732 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 8 23:39:18.815746 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 8 23:39:18.815756 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 8 23:39:18.815765 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 8 23:39:18.815772 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 8 23:39:18.815780 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 8 23:39:18.815787 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 8 23:39:18.815795 systemd[1]: Reached target paths.target - Path Units. Sep 8 23:39:18.815803 systemd[1]: Reached target slices.target - Slice Units. Sep 8 23:39:18.815811 systemd[1]: Reached target swap.target - Swaps. Sep 8 23:39:18.815818 systemd[1]: Reached target timers.target - Timer Units. Sep 8 23:39:18.815826 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 8 23:39:18.815833 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 8 23:39:18.815841 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 8 23:39:18.815848 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 8 23:39:18.815856 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 8 23:39:18.815863 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 8 23:39:18.815873 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 8 23:39:18.815880 systemd[1]: Reached target sockets.target - Socket Units. Sep 8 23:39:18.815888 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 8 23:39:18.815895 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 8 23:39:18.815903 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 8 23:39:18.815911 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Sep 8 23:39:18.815919 systemd[1]: Starting systemd-fsck-usr.service... Sep 8 23:39:18.815926 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 8 23:39:18.815934 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 8 23:39:18.815943 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 8 23:39:18.815950 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 8 23:39:18.815958 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 8 23:39:18.815966 systemd[1]: Finished systemd-fsck-usr.service. Sep 8 23:39:18.815975 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 8 23:39:18.815983 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 8 23:39:18.815990 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 8 23:39:18.815998 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 8 23:39:18.816005 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 8 23:39:18.816034 systemd-journald[244]: Collecting audit messages is disabled. Sep 8 23:39:18.816056 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 8 23:39:18.816064 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 8 23:39:18.816071 kernel: Bridge firewalling registered Sep 8 23:39:18.816079 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 8 23:39:18.816087 systemd-journald[244]: Journal started Sep 8 23:39:18.816107 systemd-journald[244]: Runtime Journal (/run/log/journal/10329d1e97c741d7a2f1077a4bb39f71) is 6M, max 48.5M, 42.4M free. Sep 8 23:39:18.783208 systemd-modules-load[246]: Inserted module 'overlay' Sep 8 23:39:18.813789 systemd-modules-load[246]: Inserted module 'br_netfilter' Sep 8 23:39:18.823926 systemd[1]: Started systemd-journald.service - Journal Service. Sep 8 23:39:18.824379 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 8 23:39:18.829814 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 8 23:39:18.831630 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 8 23:39:18.833434 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 8 23:39:18.850853 systemd-tmpfiles[280]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 8 23:39:18.851744 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 8 23:39:18.854390 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 8 23:39:18.859623 dracut-cmdline[278]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 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=9b288da65d4b75f0b3fa549b2137666f5efe4c54bbf9c99d6059072c88732f23 Sep 8 23:39:18.859374 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 8 23:39:18.894713 systemd-resolved[303]: Positive Trust Anchors: Sep 8 23:39:18.894734 systemd-resolved[303]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 8 23:39:18.894773 systemd-resolved[303]: 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 Sep 8 23:39:18.900612 systemd-resolved[303]: Defaulting to hostname 'linux'. Sep 8 23:39:18.901851 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 8 23:39:18.905367 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 8 23:39:18.936332 kernel: SCSI subsystem initialized Sep 8 23:39:18.941316 kernel: Loading iSCSI transport class v2.0-870. Sep 8 23:39:18.948378 kernel: iscsi: registered transport (tcp) Sep 8 23:39:18.962328 kernel: iscsi: registered transport (qla4xxx) Sep 8 23:39:18.962383 kernel: QLogic iSCSI HBA Driver Sep 8 23:39:18.978540 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 8 23:39:18.999346 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 8 23:39:19.002056 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 8 23:39:19.044452 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 8 23:39:19.047424 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 8 23:39:19.107337 kernel: raid6: neonx8 gen() 15700 MB/s Sep 8 23:39:19.124338 kernel: raid6: neonx4 gen() 15238 MB/s Sep 8 23:39:19.141332 kernel: raid6: neonx2 gen() 13278 MB/s Sep 8 23:39:19.158318 kernel: raid6: neonx1 gen() 10417 MB/s Sep 8 23:39:19.175316 kernel: raid6: int64x8 gen() 6896 MB/s Sep 8 23:39:19.192322 kernel: raid6: int64x4 gen() 7283 MB/s Sep 8 23:39:19.209318 kernel: raid6: int64x2 gen() 5991 MB/s Sep 8 23:39:19.226326 kernel: raid6: int64x1 gen() 5046 MB/s Sep 8 23:39:19.226362 kernel: raid6: using algorithm neonx8 gen() 15700 MB/s Sep 8 23:39:19.243332 kernel: raid6: .... xor() 11768 MB/s, rmw enabled Sep 8 23:39:19.243361 kernel: raid6: using neon recovery algorithm Sep 8 23:39:19.248559 kernel: xor: measuring software checksum speed Sep 8 23:39:19.248582 kernel: 8regs : 21624 MB/sec Sep 8 23:39:19.249730 kernel: 32regs : 21681 MB/sec Sep 8 23:39:19.249748 kernel: arm64_neon : 28109 MB/sec Sep 8 23:39:19.249758 kernel: xor: using function: arm64_neon (28109 MB/sec) Sep 8 23:39:19.302330 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 8 23:39:19.308679 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 8 23:39:19.311199 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 8 23:39:19.336847 systemd-udevd[500]: Using default interface naming scheme 'v255'. Sep 8 23:39:19.340893 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 8 23:39:19.342984 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 8 23:39:19.365733 dracut-pre-trigger[507]: rd.md=0: removing MD RAID activation Sep 8 23:39:19.388359 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 8 23:39:19.390670 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 8 23:39:19.444103 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 8 23:39:19.447694 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 8 23:39:19.493512 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Sep 8 23:39:19.493690 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Sep 8 23:39:19.501989 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Sep 8 23:39:19.502029 kernel: GPT:9289727 != 19775487 Sep 8 23:39:19.503765 kernel: GPT:Alternate GPT header not at the end of the disk. Sep 8 23:39:19.503800 kernel: GPT:9289727 != 19775487 Sep 8 23:39:19.503810 kernel: GPT: Use GNU Parted to correct GPT errors. Sep 8 23:39:19.504477 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 8 23:39:19.509729 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 8 23:39:19.509809 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 8 23:39:19.512383 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 8 23:39:19.515682 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 8 23:39:19.544096 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 8 23:39:19.545578 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 8 23:39:19.547765 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 8 23:39:19.555700 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Sep 8 23:39:19.556995 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 8 23:39:19.567382 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 8 23:39:19.574906 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 8 23:39:19.576126 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 8 23:39:19.578319 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 8 23:39:19.580451 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 8 23:39:19.583112 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 8 23:39:19.585115 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 8 23:39:19.597241 disk-uuid[590]: Primary Header is updated. Sep 8 23:39:19.597241 disk-uuid[590]: Secondary Entries is updated. Sep 8 23:39:19.597241 disk-uuid[590]: Secondary Header is updated. Sep 8 23:39:19.601585 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 8 23:39:19.604361 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 8 23:39:20.611328 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 8 23:39:20.611603 disk-uuid[597]: The operation has completed successfully. Sep 8 23:39:20.641283 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 8 23:39:20.642447 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 8 23:39:20.663425 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 8 23:39:20.678443 sh[610]: Success Sep 8 23:39:20.692287 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 8 23:39:20.692352 kernel: device-mapper: uevent: version 1.0.3 Sep 8 23:39:20.692363 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 8 23:39:20.700451 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Sep 8 23:39:20.740148 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 8 23:39:20.744122 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 8 23:39:20.759367 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 8 23:39:20.767946 kernel: BTRFS: device fsid 034f8af6-cbd9-419d-a71e-a7d9edddc941 devid 1 transid 39 /dev/mapper/usr (253:0) scanned by mount (622) Sep 8 23:39:20.767988 kernel: BTRFS info (device dm-0): first mount of filesystem 034f8af6-cbd9-419d-a71e-a7d9edddc941 Sep 8 23:39:20.767999 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Sep 8 23:39:20.773328 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 8 23:39:20.773369 kernel: BTRFS info (device dm-0): enabling free space tree Sep 8 23:39:20.774048 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 8 23:39:20.775502 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 8 23:39:20.777111 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 8 23:39:20.777981 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 8 23:39:20.779745 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 8 23:39:20.803322 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (655) Sep 8 23:39:20.805920 kernel: BTRFS info (device vda6): first mount of filesystem 0737dc78-a948-430a-939d-c5a2ab8b0159 Sep 8 23:39:20.805953 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 8 23:39:20.809319 kernel: BTRFS info (device vda6): turning on async discard Sep 8 23:39:20.809354 kernel: BTRFS info (device vda6): enabling free space tree Sep 8 23:39:20.813329 kernel: BTRFS info (device vda6): last unmount of filesystem 0737dc78-a948-430a-939d-c5a2ab8b0159 Sep 8 23:39:20.814416 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 8 23:39:20.816723 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 8 23:39:20.890529 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 8 23:39:20.893967 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 8 23:39:20.933877 systemd-networkd[801]: lo: Link UP Sep 8 23:39:20.933887 systemd-networkd[801]: lo: Gained carrier Sep 8 23:39:20.934917 systemd-networkd[801]: Enumeration completed Sep 8 23:39:20.936496 ignition[698]: Ignition 2.21.0 Sep 8 23:39:20.935789 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 8 23:39:20.936502 ignition[698]: Stage: fetch-offline Sep 8 23:39:20.935791 systemd-networkd[801]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 8 23:39:20.936531 ignition[698]: no configs at "/usr/lib/ignition/base.d" Sep 8 23:39:20.935795 systemd-networkd[801]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 8 23:39:20.936537 ignition[698]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 8 23:39:20.937180 systemd-networkd[801]: eth0: Link UP Sep 8 23:39:20.937007 ignition[698]: parsed url from cmdline: "" Sep 8 23:39:20.937278 systemd-networkd[801]: eth0: Gained carrier Sep 8 23:39:20.937011 ignition[698]: no config URL provided Sep 8 23:39:20.937289 systemd-networkd[801]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 8 23:39:20.937016 ignition[698]: reading system config file "/usr/lib/ignition/user.ign" Sep 8 23:39:20.937468 systemd[1]: Reached target network.target - Network. Sep 8 23:39:20.937023 ignition[698]: no config at "/usr/lib/ignition/user.ign" Sep 8 23:39:20.937043 ignition[698]: op(1): [started] loading QEMU firmware config module Sep 8 23:39:20.937048 ignition[698]: op(1): executing: "modprobe" "qemu_fw_cfg" Sep 8 23:39:20.943912 ignition[698]: op(1): [finished] loading QEMU firmware config module Sep 8 23:39:20.960190 ignition[698]: parsing config with SHA512: c01c633d3e325fac4e95d8dadaa7767cc1a5befe13e76e3310c329208ade527f8ac041fb855b48a6881df209fc687e1d3eb2d1e49dafa9aecc3ad9b08837a635 Sep 8 23:39:20.960359 systemd-networkd[801]: eth0: DHCPv4 address 10.0.0.59/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 8 23:39:20.964321 unknown[698]: fetched base config from "system" Sep 8 23:39:20.964578 ignition[698]: fetch-offline: fetch-offline passed Sep 8 23:39:20.964331 unknown[698]: fetched user config from "qemu" Sep 8 23:39:20.964702 ignition[698]: Ignition finished successfully Sep 8 23:39:20.966839 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 8 23:39:20.969123 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Sep 8 23:39:20.969989 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 8 23:39:21.012263 ignition[811]: Ignition 2.21.0 Sep 8 23:39:21.012283 ignition[811]: Stage: kargs Sep 8 23:39:21.012447 ignition[811]: no configs at "/usr/lib/ignition/base.d" Sep 8 23:39:21.012458 ignition[811]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 8 23:39:21.013208 ignition[811]: kargs: kargs passed Sep 8 23:39:21.013275 ignition[811]: Ignition finished successfully Sep 8 23:39:21.018973 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 8 23:39:21.021072 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 8 23:39:21.049762 ignition[819]: Ignition 2.21.0 Sep 8 23:39:21.049776 ignition[819]: Stage: disks Sep 8 23:39:21.049912 ignition[819]: no configs at "/usr/lib/ignition/base.d" Sep 8 23:39:21.049921 ignition[819]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 8 23:39:21.051689 ignition[819]: disks: disks passed Sep 8 23:39:21.053716 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 8 23:39:21.051773 ignition[819]: Ignition finished successfully Sep 8 23:39:21.055496 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 8 23:39:21.057325 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 8 23:39:21.059171 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 8 23:39:21.061832 systemd[1]: Reached target sysinit.target - System Initialization. Sep 8 23:39:21.064251 systemd[1]: Reached target basic.target - Basic System. Sep 8 23:39:21.067196 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 8 23:39:21.092578 systemd-fsck[829]: ROOT: clean, 15/553520 files, 52789/553472 blocks Sep 8 23:39:21.096741 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 8 23:39:21.099579 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 8 23:39:21.185330 kernel: EXT4-fs (vda9): mounted filesystem 1106ba00-3c53-4741-ace6-b77ffd1f2115 r/w with ordered data mode. Quota mode: none. Sep 8 23:39:21.186275 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 8 23:39:21.187639 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 8 23:39:21.190387 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 8 23:39:21.212632 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 8 23:39:21.213755 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 8 23:39:21.213821 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 8 23:39:21.213885 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 8 23:39:21.223718 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (837) Sep 8 23:39:21.218655 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 8 23:39:21.223491 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 8 23:39:21.229162 kernel: BTRFS info (device vda6): first mount of filesystem 0737dc78-a948-430a-939d-c5a2ab8b0159 Sep 8 23:39:21.229190 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 8 23:39:21.229199 kernel: BTRFS info (device vda6): turning on async discard Sep 8 23:39:21.230402 kernel: BTRFS info (device vda6): enabling free space tree Sep 8 23:39:21.232178 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 8 23:39:21.273220 initrd-setup-root[861]: cut: /sysroot/etc/passwd: No such file or directory Sep 8 23:39:21.278648 initrd-setup-root[868]: cut: /sysroot/etc/group: No such file or directory Sep 8 23:39:21.282061 initrd-setup-root[875]: cut: /sysroot/etc/shadow: No such file or directory Sep 8 23:39:21.285104 initrd-setup-root[882]: cut: /sysroot/etc/gshadow: No such file or directory Sep 8 23:39:21.360187 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 8 23:39:21.363759 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 8 23:39:21.374648 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 8 23:39:21.381333 kernel: BTRFS info (device vda6): last unmount of filesystem 0737dc78-a948-430a-939d-c5a2ab8b0159 Sep 8 23:39:21.396347 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 8 23:39:21.405553 ignition[949]: INFO : Ignition 2.21.0 Sep 8 23:39:21.405553 ignition[949]: INFO : Stage: mount Sep 8 23:39:21.407215 ignition[949]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 8 23:39:21.407215 ignition[949]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 8 23:39:21.407215 ignition[949]: INFO : mount: mount passed Sep 8 23:39:21.407215 ignition[949]: INFO : Ignition finished successfully Sep 8 23:39:21.409148 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 8 23:39:21.411574 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 8 23:39:21.766809 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 8 23:39:21.768386 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 8 23:39:21.790530 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (963) Sep 8 23:39:21.790580 kernel: BTRFS info (device vda6): first mount of filesystem 0737dc78-a948-430a-939d-c5a2ab8b0159 Sep 8 23:39:21.790600 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 8 23:39:21.793680 kernel: BTRFS info (device vda6): turning on async discard Sep 8 23:39:21.793722 kernel: BTRFS info (device vda6): enabling free space tree Sep 8 23:39:21.795295 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 8 23:39:21.831962 ignition[980]: INFO : Ignition 2.21.0 Sep 8 23:39:21.831962 ignition[980]: INFO : Stage: files Sep 8 23:39:21.833759 ignition[980]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 8 23:39:21.833759 ignition[980]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 8 23:39:21.833759 ignition[980]: DEBUG : files: compiled without relabeling support, skipping Sep 8 23:39:21.837495 ignition[980]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 8 23:39:21.837495 ignition[980]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 8 23:39:21.840813 ignition[980]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 8 23:39:21.842415 ignition[980]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 8 23:39:21.842415 ignition[980]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 8 23:39:21.841612 unknown[980]: wrote ssh authorized keys file for user: core Sep 8 23:39:21.846934 ignition[980]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Sep 8 23:39:21.846934 ignition[980]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Sep 8 23:39:21.851101 ignition[980]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 8 23:39:21.852918 ignition[980]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 8 23:39:21.852918 ignition[980]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Sep 8 23:39:21.859981 ignition[980]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Sep 8 23:39:21.859981 ignition[980]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Sep 8 23:39:21.865024 ignition[980]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.32.4-arm64.raw: attempt #1 Sep 8 23:39:22.036479 systemd-networkd[801]: eth0: Gained IPv6LL Sep 8 23:39:22.247419 ignition[980]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Sep 8 23:39:22.676109 ignition[980]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Sep 8 23:39:22.676109 ignition[980]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Sep 8 23:39:22.679650 ignition[980]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 8 23:39:22.683371 ignition[980]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 8 23:39:22.683371 ignition[980]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Sep 8 23:39:22.683371 ignition[980]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Sep 8 23:39:22.696222 ignition[980]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Sep 8 23:39:22.699644 ignition[980]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Sep 8 23:39:22.701274 ignition[980]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Sep 8 23:39:22.701274 ignition[980]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 8 23:39:22.701274 ignition[980]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 8 23:39:22.701274 ignition[980]: INFO : files: files passed Sep 8 23:39:22.701274 ignition[980]: INFO : Ignition finished successfully Sep 8 23:39:22.702803 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 8 23:39:22.705460 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 8 23:39:22.715743 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 8 23:39:22.718448 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 8 23:39:22.719352 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 8 23:39:22.722788 initrd-setup-root-after-ignition[1009]: grep: /sysroot/oem/oem-release: No such file or directory Sep 8 23:39:22.725296 initrd-setup-root-after-ignition[1011]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 8 23:39:22.725296 initrd-setup-root-after-ignition[1011]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 8 23:39:22.729214 initrd-setup-root-after-ignition[1015]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 8 23:39:22.730755 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 8 23:39:22.732108 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 8 23:39:22.735417 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 8 23:39:22.794231 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 8 23:39:22.795386 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 8 23:39:22.796801 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 8 23:39:22.798634 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 8 23:39:22.800441 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 8 23:39:22.801232 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 8 23:39:22.837752 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 8 23:39:22.840173 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 8 23:39:22.866010 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 8 23:39:22.867280 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 8 23:39:22.869452 systemd[1]: Stopped target timers.target - Timer Units. Sep 8 23:39:22.871259 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 8 23:39:22.871385 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 8 23:39:22.874069 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 8 23:39:22.876220 systemd[1]: Stopped target basic.target - Basic System. Sep 8 23:39:22.878163 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 8 23:39:22.879981 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 8 23:39:22.881983 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 8 23:39:22.884119 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 8 23:39:22.886181 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 8 23:39:22.888131 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 8 23:39:22.890187 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 8 23:39:22.892271 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 8 23:39:22.894109 systemd[1]: Stopped target swap.target - Swaps. Sep 8 23:39:22.895676 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 8 23:39:22.895819 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 8 23:39:22.898177 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 8 23:39:22.899372 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 8 23:39:22.901422 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 8 23:39:22.902401 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 8 23:39:22.904583 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 8 23:39:22.904692 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 8 23:39:22.907612 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 8 23:39:22.907735 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 8 23:39:22.909689 systemd[1]: Stopped target paths.target - Path Units. Sep 8 23:39:22.911521 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 8 23:39:22.914360 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 8 23:39:22.916528 systemd[1]: Stopped target slices.target - Slice Units. Sep 8 23:39:22.918873 systemd[1]: Stopped target sockets.target - Socket Units. Sep 8 23:39:22.920418 systemd[1]: iscsid.socket: Deactivated successfully. Sep 8 23:39:22.920540 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 8 23:39:22.922191 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 8 23:39:22.922323 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 8 23:39:22.924001 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 8 23:39:22.924156 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 8 23:39:22.925965 systemd[1]: ignition-files.service: Deactivated successfully. Sep 8 23:39:22.926109 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 8 23:39:22.928537 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 8 23:39:22.930937 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 8 23:39:22.932197 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 8 23:39:22.932393 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 8 23:39:22.934398 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 8 23:39:22.934541 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 8 23:39:22.941552 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 8 23:39:22.948455 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 8 23:39:22.958968 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 8 23:39:22.968630 ignition[1036]: INFO : Ignition 2.21.0 Sep 8 23:39:22.968630 ignition[1036]: INFO : Stage: umount Sep 8 23:39:22.970382 ignition[1036]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 8 23:39:22.970382 ignition[1036]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 8 23:39:22.970382 ignition[1036]: INFO : umount: umount passed Sep 8 23:39:22.970382 ignition[1036]: INFO : Ignition finished successfully Sep 8 23:39:22.971970 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 8 23:39:22.972066 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 8 23:39:22.973922 systemd[1]: Stopped target network.target - Network. Sep 8 23:39:22.975229 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 8 23:39:22.975290 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 8 23:39:22.976989 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 8 23:39:22.977037 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 8 23:39:22.978734 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 8 23:39:22.978784 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 8 23:39:22.980389 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 8 23:39:22.980431 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 8 23:39:22.982245 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 8 23:39:22.983998 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 8 23:39:22.992804 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 8 23:39:22.992896 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 8 23:39:22.996476 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 8 23:39:22.996657 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 8 23:39:22.996774 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 8 23:39:23.001524 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 8 23:39:23.002274 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 8 23:39:23.004450 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 8 23:39:23.004490 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 8 23:39:23.007505 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 8 23:39:23.008622 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 8 23:39:23.008687 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 8 23:39:23.011713 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 8 23:39:23.011775 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 8 23:39:23.014038 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 8 23:39:23.014084 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 8 23:39:23.015257 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 8 23:39:23.015314 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 8 23:39:23.018439 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 8 23:39:23.024804 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 8 23:39:23.024877 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 8 23:39:23.025205 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 8 23:39:23.025292 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 8 23:39:23.027857 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 8 23:39:23.027939 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 8 23:39:23.031981 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 8 23:39:23.043565 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 8 23:39:23.045206 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 8 23:39:23.045323 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 8 23:39:23.047933 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 8 23:39:23.047996 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 8 23:39:23.049379 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 8 23:39:23.049413 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 8 23:39:23.051285 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 8 23:39:23.051344 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 8 23:39:23.054362 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 8 23:39:23.054417 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 8 23:39:23.057220 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 8 23:39:23.057271 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 8 23:39:23.060248 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 8 23:39:23.061435 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 8 23:39:23.061497 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Sep 8 23:39:23.064570 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 8 23:39:23.064617 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 8 23:39:23.067916 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Sep 8 23:39:23.067960 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 8 23:39:23.071269 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 8 23:39:23.071362 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 8 23:39:23.075400 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 8 23:39:23.075448 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 8 23:39:23.079560 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Sep 8 23:39:23.079609 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Sep 8 23:39:23.079637 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Sep 8 23:39:23.079668 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 8 23:39:23.079996 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 8 23:39:23.080104 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 8 23:39:23.082962 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 8 23:39:23.085239 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 8 23:39:23.104384 systemd[1]: Switching root. Sep 8 23:39:23.123416 systemd-journald[244]: Journal stopped Sep 8 23:39:23.861067 systemd-journald[244]: Received SIGTERM from PID 1 (systemd). Sep 8 23:39:23.861132 kernel: SELinux: policy capability network_peer_controls=1 Sep 8 23:39:23.861148 kernel: SELinux: policy capability open_perms=1 Sep 8 23:39:23.861158 kernel: SELinux: policy capability extended_socket_class=1 Sep 8 23:39:23.861167 kernel: SELinux: policy capability always_check_network=0 Sep 8 23:39:23.861175 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 8 23:39:23.861187 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 8 23:39:23.861199 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 8 23:39:23.861208 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 8 23:39:23.861218 kernel: SELinux: policy capability userspace_initial_context=0 Sep 8 23:39:23.861228 kernel: audit: type=1403 audit(1757374763.244:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 8 23:39:23.861241 systemd[1]: Successfully loaded SELinux policy in 41.664ms. Sep 8 23:39:23.861260 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 10.026ms. Sep 8 23:39:23.861271 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) Sep 8 23:39:23.861282 systemd[1]: Detected virtualization kvm. Sep 8 23:39:23.861292 systemd[1]: Detected architecture arm64. Sep 8 23:39:23.861315 systemd[1]: Detected first boot. Sep 8 23:39:23.861326 systemd[1]: Initializing machine ID from VM UUID. Sep 8 23:39:23.861337 zram_generator::config[1086]: No configuration found. Sep 8 23:39:23.861347 kernel: NET: Registered PF_VSOCK protocol family Sep 8 23:39:23.861357 systemd[1]: Populated /etc with preset unit settings. Sep 8 23:39:23.861367 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 8 23:39:23.861377 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 8 23:39:23.861389 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 8 23:39:23.861399 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 8 23:39:23.861409 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 8 23:39:23.861419 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 8 23:39:23.861429 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 8 23:39:23.861440 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 8 23:39:23.861449 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 8 23:39:23.861459 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 8 23:39:23.861471 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 8 23:39:23.861481 systemd[1]: Created slice user.slice - User and Session Slice. Sep 8 23:39:23.861491 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 8 23:39:23.861501 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 8 23:39:23.861511 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 8 23:39:23.861520 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 8 23:39:23.861531 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 8 23:39:23.861540 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 8 23:39:23.861550 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Sep 8 23:39:23.861562 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 8 23:39:23.861572 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 8 23:39:23.861581 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 8 23:39:23.861591 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 8 23:39:23.861605 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 8 23:39:23.861615 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 8 23:39:23.861625 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 8 23:39:23.861635 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 8 23:39:23.861646 systemd[1]: Reached target slices.target - Slice Units. Sep 8 23:39:23.861656 systemd[1]: Reached target swap.target - Swaps. Sep 8 23:39:23.861666 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 8 23:39:23.861676 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 8 23:39:23.861686 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 8 23:39:23.861696 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 8 23:39:23.861705 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 8 23:39:23.861716 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 8 23:39:23.861732 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 8 23:39:23.861746 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 8 23:39:23.861756 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 8 23:39:23.861766 systemd[1]: Mounting media.mount - External Media Directory... Sep 8 23:39:23.861776 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 8 23:39:23.861786 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 8 23:39:23.861796 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 8 23:39:23.861806 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). Sep 8 23:39:23.861816 systemd[1]: Reached target machines.target - Containers. Sep 8 23:39:23.861826 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 8 23:39:23.861838 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 8 23:39:23.861848 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 8 23:39:23.861858 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 8 23:39:23.861868 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 8 23:39:23.861878 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 8 23:39:23.861888 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 8 23:39:23.861898 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 8 23:39:23.861908 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 8 23:39:23.861920 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 8 23:39:23.861930 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 8 23:39:23.861940 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 8 23:39:23.861950 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 8 23:39:23.861960 systemd[1]: Stopped systemd-fsck-usr.service. Sep 8 23:39:23.861970 kernel: fuse: init (API version 7.41) Sep 8 23:39:23.861981 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). Sep 8 23:39:23.861991 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 8 23:39:23.862003 kernel: ACPI: bus type drm_connector registered Sep 8 23:39:23.862013 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 8 23:39:23.862025 kernel: loop: module loaded Sep 8 23:39:23.862035 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 8 23:39:23.862045 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 8 23:39:23.862056 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 8 23:39:23.862068 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 8 23:39:23.862078 systemd[1]: verity-setup.service: Deactivated successfully. Sep 8 23:39:23.862088 systemd[1]: Stopped verity-setup.service. Sep 8 23:39:23.862098 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 8 23:39:23.862109 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 8 23:39:23.862152 systemd-journald[1151]: Collecting audit messages is disabled. Sep 8 23:39:23.862177 systemd[1]: Mounted media.mount - External Media Directory. Sep 8 23:39:23.862188 systemd-journald[1151]: Journal started Sep 8 23:39:23.862209 systemd-journald[1151]: Runtime Journal (/run/log/journal/10329d1e97c741d7a2f1077a4bb39f71) is 6M, max 48.5M, 42.4M free. Sep 8 23:39:23.628586 systemd[1]: Queued start job for default target multi-user.target. Sep 8 23:39:23.653197 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 8 23:39:23.653565 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 8 23:39:23.865093 systemd[1]: Started systemd-journald.service - Journal Service. Sep 8 23:39:23.865826 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 8 23:39:23.867223 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 8 23:39:23.868553 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 8 23:39:23.871336 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 8 23:39:23.872833 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 8 23:39:23.874570 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 8 23:39:23.875402 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 8 23:39:23.876930 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 8 23:39:23.877105 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 8 23:39:23.878570 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 8 23:39:23.878744 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 8 23:39:23.880067 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 8 23:39:23.880221 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 8 23:39:23.881813 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 8 23:39:23.881970 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 8 23:39:23.883479 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 8 23:39:23.883634 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 8 23:39:23.885260 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 8 23:39:23.886795 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 8 23:39:23.888464 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 8 23:39:23.890073 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 8 23:39:23.902674 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 8 23:39:23.905366 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 8 23:39:23.907509 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 8 23:39:23.908813 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 8 23:39:23.908852 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 8 23:39:23.910886 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 8 23:39:23.915157 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 8 23:39:23.916865 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 8 23:39:23.918116 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 8 23:39:23.920241 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 8 23:39:23.921589 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 8 23:39:23.924443 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 8 23:39:23.925544 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 8 23:39:23.926579 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 8 23:39:23.931830 systemd-journald[1151]: Time spent on flushing to /var/log/journal/10329d1e97c741d7a2f1077a4bb39f71 is 18.325ms for 873 entries. Sep 8 23:39:23.931830 systemd-journald[1151]: System Journal (/var/log/journal/10329d1e97c741d7a2f1077a4bb39f71) is 8M, max 195.6M, 187.6M free. Sep 8 23:39:23.965106 systemd-journald[1151]: Received client request to flush runtime journal. Sep 8 23:39:23.965155 kernel: loop0: detected capacity change from 0 to 207008 Sep 8 23:39:23.965172 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 8 23:39:23.931438 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 8 23:39:23.934993 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 8 23:39:23.938143 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 8 23:39:23.939793 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 8 23:39:23.941287 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 8 23:39:23.943595 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 8 23:39:23.948256 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 8 23:39:23.958513 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 8 23:39:23.960457 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 8 23:39:23.970531 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 8 23:39:23.975426 systemd-tmpfiles[1200]: ACLs are not supported, ignoring. Sep 8 23:39:23.975447 systemd-tmpfiles[1200]: ACLs are not supported, ignoring. Sep 8 23:39:23.981356 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 8 23:39:23.984457 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 8 23:39:23.985334 kernel: loop1: detected capacity change from 0 to 138376 Sep 8 23:39:23.993804 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 8 23:39:24.020569 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 8 23:39:24.026391 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 8 23:39:24.031364 kernel: loop2: detected capacity change from 0 to 107312 Sep 8 23:39:24.044776 systemd-tmpfiles[1221]: ACLs are not supported, ignoring. Sep 8 23:39:24.045072 systemd-tmpfiles[1221]: ACLs are not supported, ignoring. Sep 8 23:39:24.049621 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 8 23:39:24.055333 kernel: loop3: detected capacity change from 0 to 207008 Sep 8 23:39:24.061328 kernel: loop4: detected capacity change from 0 to 138376 Sep 8 23:39:24.071360 kernel: loop5: detected capacity change from 0 to 107312 Sep 8 23:39:24.075438 (sd-merge)[1225]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Sep 8 23:39:24.075830 (sd-merge)[1225]: Merged extensions into '/usr'. Sep 8 23:39:24.080231 systemd[1]: Reload requested from client PID 1199 ('systemd-sysext') (unit systemd-sysext.service)... Sep 8 23:39:24.080249 systemd[1]: Reloading... Sep 8 23:39:24.146343 zram_generator::config[1251]: No configuration found. Sep 8 23:39:24.221908 ldconfig[1194]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 8 23:39:24.225288 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. Sep 8 23:39:24.289992 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 8 23:39:24.290113 systemd[1]: Reloading finished in 209 ms. Sep 8 23:39:24.305914 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 8 23:39:24.307464 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 8 23:39:24.323606 systemd[1]: Starting ensure-sysext.service... Sep 8 23:39:24.325567 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 8 23:39:24.336343 systemd[1]: Reload requested from client PID 1285 ('systemctl') (unit ensure-sysext.service)... Sep 8 23:39:24.336360 systemd[1]: Reloading... Sep 8 23:39:24.341897 systemd-tmpfiles[1286]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 8 23:39:24.342217 systemd-tmpfiles[1286]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 8 23:39:24.342543 systemd-tmpfiles[1286]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 8 23:39:24.342850 systemd-tmpfiles[1286]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 8 23:39:24.343549 systemd-tmpfiles[1286]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 8 23:39:24.343877 systemd-tmpfiles[1286]: ACLs are not supported, ignoring. Sep 8 23:39:24.344005 systemd-tmpfiles[1286]: ACLs are not supported, ignoring. Sep 8 23:39:24.348728 systemd-tmpfiles[1286]: Detected autofs mount point /boot during canonicalization of boot. Sep 8 23:39:24.348833 systemd-tmpfiles[1286]: Skipping /boot Sep 8 23:39:24.358048 systemd-tmpfiles[1286]: Detected autofs mount point /boot during canonicalization of boot. Sep 8 23:39:24.358162 systemd-tmpfiles[1286]: Skipping /boot Sep 8 23:39:24.389390 zram_generator::config[1313]: No configuration found. Sep 8 23:39:24.468915 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. Sep 8 23:39:24.533483 systemd[1]: Reloading finished in 196 ms. Sep 8 23:39:24.556567 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 8 23:39:24.562781 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 8 23:39:24.578911 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 8 23:39:24.581881 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 8 23:39:24.584258 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 8 23:39:24.586973 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 8 23:39:24.591548 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 8 23:39:24.596323 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 8 23:39:24.602690 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 8 23:39:24.603942 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 8 23:39:24.608536 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 8 23:39:24.619711 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 8 23:39:24.620952 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 8 23:39:24.621128 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). Sep 8 23:39:24.622959 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 8 23:39:24.624518 systemd-udevd[1354]: Using default interface naming scheme 'v255'. Sep 8 23:39:24.626352 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 8 23:39:24.629139 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 8 23:39:24.629638 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 8 23:39:24.632202 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 8 23:39:24.632750 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 8 23:39:24.636877 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 8 23:39:24.637044 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 8 23:39:24.643422 augenrules[1379]: No rules Sep 8 23:39:24.644681 systemd[1]: audit-rules.service: Deactivated successfully. Sep 8 23:39:24.644916 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 8 23:39:24.653576 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 8 23:39:24.658156 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 8 23:39:24.669411 systemd[1]: Finished ensure-sysext.service. Sep 8 23:39:24.670605 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 8 23:39:24.675202 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 8 23:39:24.677100 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 8 23:39:24.681690 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 8 23:39:24.688089 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 8 23:39:24.700229 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 8 23:39:24.704502 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 8 23:39:24.706475 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 8 23:39:24.706523 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). Sep 8 23:39:24.708561 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 8 23:39:24.713772 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 8 23:39:24.718580 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 8 23:39:24.720456 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). Sep 8 23:39:24.721051 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 8 23:39:24.723949 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 8 23:39:24.724118 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 8 23:39:24.727787 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 8 23:39:24.727949 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 8 23:39:24.730433 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 8 23:39:24.730590 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 8 23:39:24.740692 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 8 23:39:24.754515 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 8 23:39:24.754966 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 8 23:39:24.760833 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 8 23:39:24.762905 augenrules[1411]: /sbin/augenrules: No change Sep 8 23:39:24.771937 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Sep 8 23:39:24.777118 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 8 23:39:24.779335 augenrules[1459]: No rules Sep 8 23:39:24.780697 systemd[1]: audit-rules.service: Deactivated successfully. Sep 8 23:39:24.782355 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 8 23:39:24.800057 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 8 23:39:24.803129 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 8 23:39:24.830482 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 8 23:39:24.843116 systemd-networkd[1423]: lo: Link UP Sep 8 23:39:24.843122 systemd-networkd[1423]: lo: Gained carrier Sep 8 23:39:24.844103 systemd-networkd[1423]: Enumeration completed Sep 8 23:39:24.844267 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 8 23:39:24.844661 systemd-networkd[1423]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 8 23:39:24.844664 systemd-networkd[1423]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 8 23:39:24.845253 systemd-networkd[1423]: eth0: Link UP Sep 8 23:39:24.845403 systemd-networkd[1423]: eth0: Gained carrier Sep 8 23:39:24.845418 systemd-networkd[1423]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 8 23:39:24.847863 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 8 23:39:24.850943 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 8 23:39:24.852530 systemd-resolved[1353]: Positive Trust Anchors: Sep 8 23:39:24.852549 systemd-resolved[1353]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 8 23:39:24.852580 systemd-resolved[1353]: 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 Sep 8 23:39:24.852962 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 8 23:39:24.855620 systemd[1]: Reached target time-set.target - System Time Set. Sep 8 23:39:24.858410 systemd-resolved[1353]: Defaulting to hostname 'linux'. Sep 8 23:39:24.860044 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 8 23:39:24.861950 systemd[1]: Reached target network.target - Network. Sep 8 23:39:24.862883 systemd-networkd[1423]: eth0: DHCPv4 address 10.0.0.59/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 8 23:39:24.865396 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 8 23:39:24.866637 systemd-timesyncd[1428]: Network configuration changed, trying to establish connection. Sep 8 23:39:24.867071 systemd[1]: Reached target sysinit.target - System Initialization. Sep 8 23:39:24.868467 systemd-timesyncd[1428]: Contacted time server 10.0.0.1:123 (10.0.0.1). Sep 8 23:39:24.868500 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 8 23:39:24.868521 systemd-timesyncd[1428]: Initial clock synchronization to Mon 2025-09-08 23:39:24.830049 UTC. Sep 8 23:39:24.869816 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 8 23:39:24.871267 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 8 23:39:24.872462 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 8 23:39:24.873693 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 8 23:39:24.874937 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 8 23:39:24.874976 systemd[1]: Reached target paths.target - Path Units. Sep 8 23:39:24.875931 systemd[1]: Reached target timers.target - Timer Units. Sep 8 23:39:24.878492 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 8 23:39:24.880920 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 8 23:39:24.884410 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 8 23:39:24.885944 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 8 23:39:24.887279 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 8 23:39:24.891676 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 8 23:39:24.893241 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 8 23:39:24.897376 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 8 23:39:24.898885 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 8 23:39:24.900576 systemd[1]: Reached target sockets.target - Socket Units. Sep 8 23:39:24.901694 systemd[1]: Reached target basic.target - Basic System. Sep 8 23:39:24.902741 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 8 23:39:24.902770 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 8 23:39:24.904494 systemd[1]: Starting containerd.service - containerd container runtime... Sep 8 23:39:24.906554 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 8 23:39:24.909537 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 8 23:39:24.923145 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 8 23:39:24.925524 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 8 23:39:24.926554 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). Sep 8 23:39:24.928377 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 8 23:39:24.930413 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 8 23:39:24.934313 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 8 23:39:24.937645 jq[1492]: false Sep 8 23:39:24.941838 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 8 23:39:24.943825 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 8 23:39:24.944251 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 8 23:39:24.944931 systemd[1]: Starting update-engine.service - Update Engine... Sep 8 23:39:24.947321 extend-filesystems[1493]: Found /dev/vda6 Sep 8 23:39:24.948802 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 8 23:39:24.950989 extend-filesystems[1493]: Found /dev/vda9 Sep 8 23:39:24.956211 extend-filesystems[1493]: Checking size of /dev/vda9 Sep 8 23:39:24.956647 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 8 23:39:24.960823 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 8 23:39:24.961056 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 8 23:39:24.961365 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 8 23:39:24.961555 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 8 23:39:24.963152 jq[1504]: true Sep 8 23:39:24.969839 systemd[1]: motdgen.service: Deactivated successfully. Sep 8 23:39:24.971477 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 8 23:39:24.973952 extend-filesystems[1493]: Resized partition /dev/vda9 Sep 8 23:39:24.976788 extend-filesystems[1523]: resize2fs 1.47.2 (1-Jan-2025) Sep 8 23:39:24.982395 update_engine[1502]: I20250908 23:39:24.982220 1502 main.cc:92] Flatcar Update Engine starting Sep 8 23:39:24.995079 dbus-daemon[1490]: [system] SELinux support is enabled Sep 8 23:39:24.995257 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 8 23:39:24.996632 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Sep 8 23:39:24.998569 jq[1518]: true Sep 8 23:39:25.002176 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). Sep 8 23:39:25.002355 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 8 23:39:25.002563 update_engine[1502]: I20250908 23:39:25.002507 1502 update_check_scheduler.cc:74] Next update check in 8m4s Sep 8 23:39:25.005643 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). Sep 8 23:39:25.005776 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 8 23:39:25.009449 systemd[1]: Started update-engine.service - Update Engine. Sep 8 23:39:25.015464 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 8 23:39:25.017203 (ntainerd)[1528]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 8 23:39:25.020731 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 8 23:39:25.024501 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Sep 8 23:39:25.048018 extend-filesystems[1523]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Sep 8 23:39:25.048018 extend-filesystems[1523]: old_desc_blocks = 1, new_desc_blocks = 1 Sep 8 23:39:25.048018 extend-filesystems[1523]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Sep 8 23:39:25.056171 extend-filesystems[1493]: Resized filesystem in /dev/vda9 Sep 8 23:39:25.048503 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 8 23:39:25.050345 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 8 23:39:25.069707 bash[1548]: Updated "/home/core/.ssh/authorized_keys" Sep 8 23:39:25.080976 systemd-logind[1498]: Watching system buttons on /dev/input/event0 (Power Button) Sep 8 23:39:25.083132 systemd-logind[1498]: New seat seat0. Sep 8 23:39:25.103294 systemd[1]: Started systemd-logind.service - User Login Management. Sep 8 23:39:25.106330 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 8 23:39:25.109775 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 8 23:39:25.128997 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 8 23:39:25.139074 locksmithd[1532]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 8 23:39:25.208978 containerd[1528]: time="2025-09-08T23: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 Sep 8 23:39:25.210703 containerd[1528]: time="2025-09-08T23:39:25.210660926Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Sep 8 23:39:25.220883 containerd[1528]: time="2025-09-08T23:39:25.219875057Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.58µs" Sep 8 23:39:25.220883 containerd[1528]: time="2025-09-08T23:39:25.219909614Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 8 23:39:25.220883 containerd[1528]: time="2025-09-08T23:39:25.219926693Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 8 23:39:25.220883 containerd[1528]: time="2025-09-08T23:39:25.220092655Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 8 23:39:25.220883 containerd[1528]: time="2025-09-08T23:39:25.220108417Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 8 23:39:25.220883 containerd[1528]: time="2025-09-08T23:39:25.220130484Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 8 23:39:25.220883 containerd[1528]: time="2025-09-08T23:39:25.220175895Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 8 23:39:25.220883 containerd[1528]: time="2025-09-08T23:39:25.220186869Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 8 23:39:25.220883 containerd[1528]: time="2025-09-08T23:39:25.220431123Z" 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 Sep 8 23:39:25.220883 containerd[1528]: time="2025-09-08T23:39:25.220446645Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 8 23:39:25.220883 containerd[1528]: time="2025-09-08T23:39:25.220457220Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 8 23:39:25.220883 containerd[1528]: time="2025-09-08T23:39:25.220464762Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 8 23:39:25.221133 containerd[1528]: time="2025-09-08T23:39:25.220535193Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 8 23:39:25.221133 containerd[1528]: time="2025-09-08T23:39:25.220715999Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 8 23:39:25.221133 containerd[1528]: time="2025-09-08T23:39:25.220742735Z" 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 Sep 8 23:39:25.221133 containerd[1528]: time="2025-09-08T23:39:25.220751913Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 8 23:39:25.221133 containerd[1528]: time="2025-09-08T23:39:25.220791378Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 8 23:39:25.221133 containerd[1528]: time="2025-09-08T23:39:25.221007739Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 8 23:39:25.221133 containerd[1528]: time="2025-09-08T23:39:25.221068992Z" level=info msg="metadata content store policy set" policy=shared Sep 8 23:39:25.225198 containerd[1528]: time="2025-09-08T23:39:25.225151835Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 8 23:39:25.225245 containerd[1528]: time="2025-09-08T23:39:25.225213527Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 8 23:39:25.225264 containerd[1528]: time="2025-09-08T23:39:25.225247047Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 8 23:39:25.225288 containerd[1528]: time="2025-09-08T23:39:25.225261492Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 8 23:39:25.225288 containerd[1528]: time="2025-09-08T23:39:25.225274062Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 8 23:39:25.225360 containerd[1528]: time="2025-09-08T23:39:25.225287350Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 8 23:39:25.225360 containerd[1528]: time="2025-09-08T23:39:25.225310016Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 8 23:39:25.225360 containerd[1528]: time="2025-09-08T23:39:25.225325060Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 8 23:39:25.225360 containerd[1528]: time="2025-09-08T23:39:25.225335914Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 8 23:39:25.225360 containerd[1528]: time="2025-09-08T23:39:25.225356824Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 8 23:39:25.225439 containerd[1528]: time="2025-09-08T23:39:25.225366640Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 8 23:39:25.225439 containerd[1528]: time="2025-09-08T23:39:25.225379649Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 8 23:39:25.225551 containerd[1528]: time="2025-09-08T23:39:25.225517957Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 8 23:39:25.225551 containerd[1528]: time="2025-09-08T23:39:25.225547885Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 8 23:39:25.225594 containerd[1528]: time="2025-09-08T23:39:25.225565563Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 8 23:39:25.225594 containerd[1528]: time="2025-09-08T23:39:25.225576935Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 8 23:39:25.225594 containerd[1528]: time="2025-09-08T23:39:25.225586712Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 8 23:39:25.225648 containerd[1528]: time="2025-09-08T23:39:25.225596608Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 8 23:39:25.225648 containerd[1528]: time="2025-09-08T23:39:25.225607741Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 8 23:39:25.225648 containerd[1528]: time="2025-09-08T23:39:25.225618516Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 8 23:39:25.225648 containerd[1528]: time="2025-09-08T23:39:25.225630686Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 8 23:39:25.225648 containerd[1528]: time="2025-09-08T23:39:25.225641261Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 8 23:39:25.225731 containerd[1528]: time="2025-09-08T23:39:25.225651836Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 8 23:39:25.225938 containerd[1528]: time="2025-09-08T23:39:25.225914126Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 8 23:39:25.225981 containerd[1528]: time="2025-09-08T23:39:25.225940862Z" level=info msg="Start snapshots syncer" Sep 8 23:39:25.225981 containerd[1528]: time="2025-09-08T23:39:25.225969314Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 8 23:39:25.226925 containerd[1528]: time="2025-09-08T23:39:25.226348045Z" 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\"}" Sep 8 23:39:25.226925 containerd[1528]: time="2025-09-08T23:39:25.226421629Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 8 23:39:25.227223 containerd[1528]: time="2025-09-08T23:39:25.227180568Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 8 23:39:25.227387 containerd[1528]: time="2025-09-08T23:39:25.227360177Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 8 23:39:25.227415 containerd[1528]: time="2025-09-08T23:39:25.227400759Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 8 23:39:25.227433 containerd[1528]: time="2025-09-08T23:39:25.227419035Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 8 23:39:25.227452 containerd[1528]: time="2025-09-08T23:39:25.227434877Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 8 23:39:25.227469 containerd[1528]: time="2025-09-08T23:39:25.227451916Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 8 23:39:25.227486 containerd[1528]: time="2025-09-08T23:39:25.227465125Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 8 23:39:25.227486 containerd[1528]: time="2025-09-08T23:39:25.227480767Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 8 23:39:25.227539 containerd[1528]: time="2025-09-08T23:39:25.227517759Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 8 23:39:25.227563 containerd[1528]: time="2025-09-08T23:39:25.227541262Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 8 23:39:25.227563 containerd[1528]: time="2025-09-08T23:39:25.227558182Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 8 23:39:25.227630 containerd[1528]: time="2025-09-08T23:39:25.227616362Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 8 23:39:25.227653 containerd[1528]: time="2025-09-08T23:39:25.227637910Z" 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 Sep 8 23:39:25.227678 containerd[1528]: time="2025-09-08T23:39:25.227649203Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 8 23:39:25.227678 containerd[1528]: time="2025-09-08T23:39:25.227663569Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 8 23:39:25.227717 containerd[1528]: time="2025-09-08T23:39:25.227675779Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 8 23:39:25.227717 containerd[1528]: time="2025-09-08T23:39:25.227688269Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 8 23:39:25.227717 containerd[1528]: time="2025-09-08T23:39:25.227704231Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 8 23:39:25.227799 containerd[1528]: time="2025-09-08T23:39:25.227784518Z" level=info msg="runtime interface created" Sep 8 23:39:25.227799 containerd[1528]: time="2025-09-08T23:39:25.227793457Z" level=info msg="created NRI interface" Sep 8 23:39:25.227840 containerd[1528]: time="2025-09-08T23:39:25.227806466Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 8 23:39:25.227840 containerd[1528]: time="2025-09-08T23:39:25.227820791Z" level=info msg="Connect containerd service" Sep 8 23:39:25.227878 containerd[1528]: time="2025-09-08T23:39:25.227858022Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 8 23:39:25.228993 containerd[1528]: time="2025-09-08T23:39:25.228959220Z" 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" Sep 8 23:39:25.312135 containerd[1528]: time="2025-09-08T23:39:25.311711420Z" level=info msg="Start subscribing containerd event" Sep 8 23:39:25.312135 containerd[1528]: time="2025-09-08T23:39:25.311794261Z" level=info msg="Start recovering state" Sep 8 23:39:25.312135 containerd[1528]: time="2025-09-08T23:39:25.311885562Z" level=info msg="Start event monitor" Sep 8 23:39:25.312135 containerd[1528]: time="2025-09-08T23:39:25.311907110Z" level=info msg="Start cni network conf syncer for default" Sep 8 23:39:25.312135 containerd[1528]: time="2025-09-08T23:39:25.311916248Z" level=info msg="Start streaming server" Sep 8 23:39:25.312135 containerd[1528]: time="2025-09-08T23:39:25.311925945Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 8 23:39:25.312135 containerd[1528]: time="2025-09-08T23:39:25.311933327Z" level=info msg="runtime interface starting up..." Sep 8 23:39:25.312135 containerd[1528]: time="2025-09-08T23:39:25.311940111Z" level=info msg="starting plugins..." Sep 8 23:39:25.312135 containerd[1528]: time="2025-09-08T23:39:25.311952402Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 8 23:39:25.312389 containerd[1528]: time="2025-09-08T23:39:25.312154597Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 8 23:39:25.312389 containerd[1528]: time="2025-09-08T23:39:25.312215331Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 8 23:39:25.316755 containerd[1528]: time="2025-09-08T23:39:25.315374231Z" level=info msg="containerd successfully booted in 0.106726s" Sep 8 23:39:25.315490 systemd[1]: Started containerd.service - containerd container runtime. Sep 8 23:39:26.132422 systemd-networkd[1423]: eth0: Gained IPv6LL Sep 8 23:39:26.134884 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 8 23:39:26.137462 systemd[1]: Reached target network-online.target - Network is Online. Sep 8 23:39:26.141583 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 8 23:39:26.145491 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 8 23:39:26.150519 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 8 23:39:26.178488 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 8 23:39:26.196539 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 8 23:39:26.198351 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 8 23:39:26.199888 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 8 23:39:26.815646 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 8 23:39:26.819992 (kubelet)[1604]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Sep 8 23:39:26.913349 sshd_keygen[1526]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 8 23:39:26.933855 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 8 23:39:26.937349 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 8 23:39:26.953156 systemd[1]: issuegen.service: Deactivated successfully. Sep 8 23:39:26.953384 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 8 23:39:26.957858 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 8 23:39:26.978166 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 8 23:39:26.981384 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 8 23:39:26.983576 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Sep 8 23:39:26.985216 systemd[1]: Reached target getty.target - Login Prompts. Sep 8 23:39:26.986392 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 8 23:39:26.987734 systemd[1]: Startup finished in 1.986s (kernel) + 4.650s (initrd) + 3.784s (userspace) = 10.422s. Sep 8 23:39:27.178922 kubelet[1604]: E0908 23:39:27.178807 1604 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Sep 8 23:39:27.181439 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Sep 8 23:39:27.181791 systemd[1]: kubelet.service: Failed with result 'exit-code'. Sep 8 23:39:27.182408 systemd[1]: kubelet.service: Consumed 737ms CPU time, 254.9M memory peak. Sep 8 23:39:31.663113 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 8 23:39:31.664222 systemd[1]: Started sshd@0-10.0.0.59:22-10.0.0.1:48028.service - OpenSSH per-connection server daemon (10.0.0.1:48028). Sep 8 23:39:31.720349 sshd[1633]: Accepted publickey for core from 10.0.0.1 port 48028 ssh2: RSA SHA256:LTMgZj3AhUbvMnCK/3D915he0nK2GexwG9p0y0Iy9qc Sep 8 23:39:31.722086 sshd-session[1633]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 8 23:39:31.729889 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 8 23:39:31.730985 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 8 23:39:31.736630 systemd-logind[1498]: New session 1 of user core. Sep 8 23:39:31.761338 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 8 23:39:31.763813 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 8 23:39:31.777174 (systemd)[1637]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 8 23:39:31.779024 systemd-logind[1498]: New session c1 of user core. Sep 8 23:39:31.880662 systemd[1637]: Queued start job for default target default.target. Sep 8 23:39:31.892245 systemd[1637]: Created slice app.slice - User Application Slice. Sep 8 23:39:31.892276 systemd[1637]: Reached target paths.target - Paths. Sep 8 23:39:31.892336 systemd[1637]: Reached target timers.target - Timers. Sep 8 23:39:31.893584 systemd[1637]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 8 23:39:31.906162 systemd[1637]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 8 23:39:31.906271 systemd[1637]: Reached target sockets.target - Sockets. Sep 8 23:39:31.906330 systemd[1637]: Reached target basic.target - Basic System. Sep 8 23:39:31.906364 systemd[1637]: Reached target default.target - Main User Target. Sep 8 23:39:31.906390 systemd[1637]: Startup finished in 122ms. Sep 8 23:39:31.907165 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 8 23:39:31.911651 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 8 23:39:31.913066 kernel: hrtimer: interrupt took 2492782 ns Sep 8 23:39:31.989096 systemd[1]: Started sshd@1-10.0.0.59:22-10.0.0.1:48034.service - OpenSSH per-connection server daemon (10.0.0.1:48034). Sep 8 23:39:32.052549 sshd[1648]: Accepted publickey for core from 10.0.0.1 port 48034 ssh2: RSA SHA256:LTMgZj3AhUbvMnCK/3D915he0nK2GexwG9p0y0Iy9qc Sep 8 23:39:32.053910 sshd-session[1648]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 8 23:39:32.058707 systemd-logind[1498]: New session 2 of user core. Sep 8 23:39:32.071477 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 8 23:39:32.123439 sshd[1650]: Connection closed by 10.0.0.1 port 48034 Sep 8 23:39:32.124158 sshd-session[1648]: pam_unix(sshd:session): session closed for user core Sep 8 23:39:32.146423 systemd[1]: sshd@1-10.0.0.59:22-10.0.0.1:48034.service: Deactivated successfully. Sep 8 23:39:32.147915 systemd[1]: session-2.scope: Deactivated successfully. Sep 8 23:39:32.150459 systemd-logind[1498]: Session 2 logged out. Waiting for processes to exit. Sep 8 23:39:32.152141 systemd[1]: Started sshd@2-10.0.0.59:22-10.0.0.1:48044.service - OpenSSH per-connection server daemon (10.0.0.1:48044). Sep 8 23:39:32.153282 systemd-logind[1498]: Removed session 2. Sep 8 23:39:32.203887 sshd[1656]: Accepted publickey for core from 10.0.0.1 port 48044 ssh2: RSA SHA256:LTMgZj3AhUbvMnCK/3D915he0nK2GexwG9p0y0Iy9qc Sep 8 23:39:32.207203 sshd-session[1656]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 8 23:39:32.211355 systemd-logind[1498]: New session 3 of user core. Sep 8 23:39:32.226505 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 8 23:39:32.276204 sshd[1658]: Connection closed by 10.0.0.1 port 48044 Sep 8 23:39:32.276136 sshd-session[1656]: pam_unix(sshd:session): session closed for user core Sep 8 23:39:32.292891 systemd[1]: sshd@2-10.0.0.59:22-10.0.0.1:48044.service: Deactivated successfully. Sep 8 23:39:32.294757 systemd[1]: session-3.scope: Deactivated successfully. Sep 8 23:39:32.296544 systemd-logind[1498]: Session 3 logged out. Waiting for processes to exit. Sep 8 23:39:32.299611 systemd[1]: Started sshd@3-10.0.0.59:22-10.0.0.1:48056.service - OpenSSH per-connection server daemon (10.0.0.1:48056). Sep 8 23:39:32.301740 systemd-logind[1498]: Removed session 3. Sep 8 23:39:32.354913 sshd[1664]: Accepted publickey for core from 10.0.0.1 port 48056 ssh2: RSA SHA256:LTMgZj3AhUbvMnCK/3D915he0nK2GexwG9p0y0Iy9qc Sep 8 23:39:32.356152 sshd-session[1664]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 8 23:39:32.360083 systemd-logind[1498]: New session 4 of user core. Sep 8 23:39:32.371504 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 8 23:39:32.425525 sshd[1666]: Connection closed by 10.0.0.1 port 48056 Sep 8 23:39:32.426054 sshd-session[1664]: pam_unix(sshd:session): session closed for user core Sep 8 23:39:32.434411 systemd[1]: sshd@3-10.0.0.59:22-10.0.0.1:48056.service: Deactivated successfully. Sep 8 23:39:32.436156 systemd[1]: session-4.scope: Deactivated successfully. Sep 8 23:39:32.437012 systemd-logind[1498]: Session 4 logged out. Waiting for processes to exit. Sep 8 23:39:32.443577 systemd[1]: Started sshd@4-10.0.0.59:22-10.0.0.1:48072.service - OpenSSH per-connection server daemon (10.0.0.1:48072). Sep 8 23:39:32.444212 systemd-logind[1498]: Removed session 4. Sep 8 23:39:32.495938 sshd[1672]: Accepted publickey for core from 10.0.0.1 port 48072 ssh2: RSA SHA256:LTMgZj3AhUbvMnCK/3D915he0nK2GexwG9p0y0Iy9qc Sep 8 23:39:32.497214 sshd-session[1672]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 8 23:39:32.501659 systemd-logind[1498]: New session 5 of user core. Sep 8 23:39:32.510518 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 8 23:39:32.568355 sudo[1675]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Sep 8 23:39:32.568620 sudo[1675]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 8 23:39:32.580563 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 8 23:39:32.636070 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 8 23:39:32.636274 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 8 23:39:33.051621 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 8 23:39:33.052188 systemd[1]: kubelet.service: Consumed 737ms CPU time, 254.9M memory peak. Sep 8 23:39:33.054093 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 8 23:39:33.073654 systemd[1]: Reload requested from client PID 1715 ('systemctl') (unit session-5.scope)... Sep 8 23:39:33.073671 systemd[1]: Reloading... Sep 8 23:39:33.149321 zram_generator::config[1761]: No configuration found. Sep 8 23:39:33.315881 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. Sep 8 23:39:33.400269 systemd[1]: Reloading finished in 326 ms. Sep 8 23:39:33.474812 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Sep 8 23:39:33.474892 systemd[1]: kubelet.service: Failed with result 'signal'. Sep 8 23:39:33.475120 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 8 23:39:33.475165 systemd[1]: kubelet.service: Consumed 92ms CPU time, 95M memory peak. Sep 8 23:39:33.476842 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 8 23:39:33.586888 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 8 23:39:33.590284 (kubelet)[1803]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Sep 8 23:39:33.623752 kubelet[1803]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Sep 8 23:39:33.623752 kubelet[1803]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Sep 8 23:39:33.623752 kubelet[1803]: Flag --volume-plugin-dir has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Sep 8 23:39:33.624072 kubelet[1803]: I0908 23:39:33.623803 1803 server.go:215] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Sep 8 23:39:34.089221 kubelet[1803]: I0908 23:39:34.088600 1803 server.go:520] "Kubelet version" kubeletVersion="v1.32.4" Sep 8 23:39:34.089221 kubelet[1803]: I0908 23:39:34.088635 1803 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Sep 8 23:39:34.089221 kubelet[1803]: I0908 23:39:34.088908 1803 server.go:954] "Client rotation is on, will bootstrap in background" Sep 8 23:39:34.118903 kubelet[1803]: I0908 23:39:34.118796 1803 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Sep 8 23:39:34.124642 kubelet[1803]: I0908 23:39:34.124030 1803 server.go:1444] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Sep 8 23:39:34.126953 kubelet[1803]: I0908 23:39:34.126595 1803 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Sep 8 23:39:34.131049 kubelet[1803]: I0908 23:39:34.131000 1803 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Sep 8 23:39:34.131365 kubelet[1803]: I0908 23:39:34.131147 1803 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.59","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Sep 8 23:39:34.131564 kubelet[1803]: I0908 23:39:34.131551 1803 topology_manager.go:138] "Creating topology manager with none policy" Sep 8 23:39:34.131626 kubelet[1803]: I0908 23:39:34.131617 1803 container_manager_linux.go:304] "Creating device plugin manager" Sep 8 23:39:34.131875 kubelet[1803]: I0908 23:39:34.131859 1803 state_mem.go:36] "Initialized new in-memory state store" Sep 8 23:39:34.137948 kubelet[1803]: I0908 23:39:34.137919 1803 kubelet.go:446] "Attempting to sync node with API server" Sep 8 23:39:34.138076 kubelet[1803]: I0908 23:39:34.138065 1803 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests" Sep 8 23:39:34.138158 kubelet[1803]: I0908 23:39:34.138148 1803 kubelet.go:352] "Adding apiserver pod source" Sep 8 23:39:34.138212 kubelet[1803]: I0908 23:39:34.138204 1803 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Sep 8 23:39:34.138412 kubelet[1803]: E0908 23:39:34.138369 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:34.139445 kubelet[1803]: E0908 23:39:34.139416 1803 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:34.144031 kubelet[1803]: I0908 23:39:34.143968 1803 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="v2.0.4" apiVersion="v1" Sep 8 23:39:34.144808 kubelet[1803]: I0908 23:39:34.144771 1803 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Sep 8 23:39:34.144914 kubelet[1803]: W0908 23:39:34.144890 1803 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Sep 8 23:39:34.147798 kubelet[1803]: I0908 23:39:34.147187 1803 watchdog_linux.go:99] "Systemd watchdog is not enabled" Sep 8 23:39:34.147798 kubelet[1803]: I0908 23:39:34.147227 1803 server.go:1287] "Started kubelet" Sep 8 23:39:34.153620 kubelet[1803]: I0908 23:39:34.150368 1803 server.go:169] "Starting to listen" address="0.0.0.0" port=10250 Sep 8 23:39:34.153620 kubelet[1803]: I0908 23:39:34.151404 1803 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Sep 8 23:39:34.153620 kubelet[1803]: I0908 23:39:34.151669 1803 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Sep 8 23:39:34.153620 kubelet[1803]: I0908 23:39:34.151712 1803 server.go:479] "Adding debug handlers to kubelet server" Sep 8 23:39:34.156596 kubelet[1803]: I0908 23:39:34.156318 1803 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Sep 8 23:39:34.156847 kubelet[1803]: I0908 23:39:34.156813 1803 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Sep 8 23:39:34.157260 kubelet[1803]: E0908 23:39:34.157140 1803 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.59\" not found" Sep 8 23:39:34.157439 kubelet[1803]: I0908 23:39:34.157416 1803 volume_manager.go:297] "Starting Kubelet Volume Manager" Sep 8 23:39:34.157739 kubelet[1803]: I0908 23:39:34.157706 1803 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Sep 8 23:39:34.157946 kubelet[1803]: I0908 23:39:34.157843 1803 reconciler.go:26] "Reconciler: start to sync state" Sep 8 23:39:34.158929 kubelet[1803]: I0908 23:39:34.158521 1803 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Sep 8 23:39:34.159276 kubelet[1803]: E0908 23:39:34.158768 1803 kubelet.go:1555] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Sep 8 23:39:34.160993 kubelet[1803]: I0908 23:39:34.160883 1803 factory.go:221] Registration of the containerd container factory successfully Sep 8 23:39:34.160993 kubelet[1803]: I0908 23:39:34.160899 1803 factory.go:221] Registration of the systemd container factory successfully Sep 8 23:39:34.169630 kubelet[1803]: I0908 23:39:34.169598 1803 cpu_manager.go:221] "Starting CPU manager" policy="none" Sep 8 23:39:34.169630 kubelet[1803]: I0908 23:39:34.169617 1803 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Sep 8 23:39:34.169630 kubelet[1803]: I0908 23:39:34.169637 1803 state_mem.go:36] "Initialized new in-memory state store" Sep 8 23:39:34.174387 kubelet[1803]: E0908 23:39:34.174350 1803 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.59\" not found" node="10.0.0.59" Sep 8 23:39:34.258140 kubelet[1803]: E0908 23:39:34.258110 1803 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.59\" not found" Sep 8 23:39:34.258476 kubelet[1803]: I0908 23:39:34.258447 1803 policy_none.go:49] "None policy: Start" Sep 8 23:39:34.258518 kubelet[1803]: I0908 23:39:34.258483 1803 memory_manager.go:186] "Starting memorymanager" policy="None" Sep 8 23:39:34.258518 kubelet[1803]: I0908 23:39:34.258499 1803 state_mem.go:35] "Initializing new in-memory state store" Sep 8 23:39:34.267773 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Sep 8 23:39:34.276243 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Sep 8 23:39:34.281996 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Sep 8 23:39:34.288189 kubelet[1803]: I0908 23:39:34.288157 1803 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Sep 8 23:39:34.288800 kubelet[1803]: I0908 23:39:34.288356 1803 eviction_manager.go:189] "Eviction manager: starting control loop" Sep 8 23:39:34.288800 kubelet[1803]: I0908 23:39:34.288373 1803 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Sep 8 23:39:34.288800 kubelet[1803]: I0908 23:39:34.288701 1803 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Sep 8 23:39:34.290329 kubelet[1803]: E0908 23:39:34.290080 1803 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Sep 8 23:39:34.290417 kubelet[1803]: E0908 23:39:34.290350 1803 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.59\" not found" Sep 8 23:39:34.297983 kubelet[1803]: I0908 23:39:34.297943 1803 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Sep 8 23:39:34.299068 kubelet[1803]: I0908 23:39:34.299021 1803 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Sep 8 23:39:34.299068 kubelet[1803]: I0908 23:39:34.299045 1803 status_manager.go:227] "Starting to sync pod status with apiserver" Sep 8 23:39:34.299068 kubelet[1803]: I0908 23:39:34.299062 1803 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Sep 8 23:39:34.299068 kubelet[1803]: I0908 23:39:34.299069 1803 kubelet.go:2382] "Starting kubelet main sync loop" Sep 8 23:39:34.299171 kubelet[1803]: E0908 23:39:34.299106 1803 kubelet.go:2406] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Sep 8 23:39:34.389860 kubelet[1803]: I0908 23:39:34.389742 1803 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.59" Sep 8 23:39:34.398322 kubelet[1803]: I0908 23:39:34.398275 1803 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.59" Sep 8 23:39:34.423768 kubelet[1803]: I0908 23:39:34.423248 1803 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Sep 8 23:39:34.424029 kubelet[1803]: I0908 23:39:34.424011 1803 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Sep 8 23:39:34.424096 containerd[1528]: time="2025-09-08T23:39:34.423840961Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Sep 8 23:39:34.618523 sudo[1675]: pam_unix(sudo:session): session closed for user root Sep 8 23:39:34.620362 sshd[1674]: Connection closed by 10.0.0.1 port 48072 Sep 8 23:39:34.620699 sshd-session[1672]: pam_unix(sshd:session): session closed for user core Sep 8 23:39:34.624072 systemd[1]: sshd@4-10.0.0.59:22-10.0.0.1:48072.service: Deactivated successfully. Sep 8 23:39:34.625885 systemd[1]: session-5.scope: Deactivated successfully. Sep 8 23:39:34.626066 systemd[1]: session-5.scope: Consumed 398ms CPU time, 75.7M memory peak. Sep 8 23:39:34.627359 systemd-logind[1498]: Session 5 logged out. Waiting for processes to exit. Sep 8 23:39:34.628869 systemd-logind[1498]: Removed session 5. Sep 8 23:39:35.091637 kubelet[1803]: I0908 23:39:35.091597 1803 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Sep 8 23:39:35.092018 kubelet[1803]: W0908 23:39:35.091811 1803 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Sep 8 23:39:35.092018 kubelet[1803]: W0908 23:39:35.091834 1803 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Sep 8 23:39:35.092018 kubelet[1803]: W0908 23:39:35.091814 1803 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.Service ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Sep 8 23:39:35.138965 kubelet[1803]: E0908 23:39:35.138927 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:35.141371 kubelet[1803]: I0908 23:39:35.141130 1803 apiserver.go:52] "Watching apiserver" Sep 8 23:39:35.152173 systemd[1]: Created slice kubepods-besteffort-pod099e3522_d8d9_4708_88e2_4f4e9cafd3db.slice - libcontainer container kubepods-besteffort-pod099e3522_d8d9_4708_88e2_4f4e9cafd3db.slice. Sep 8 23:39:35.158584 kubelet[1803]: I0908 23:39:35.158538 1803 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Sep 8 23:39:35.163167 kubelet[1803]: I0908 23:39:35.163049 1803 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/93444d41-1fd4-4f16-8728-b6d02f628500-run\") pod \"kube-flannel-ds-48j7z\" (UID: \"93444d41-1fd4-4f16-8728-b6d02f628500\") " pod="kube-flannel/kube-flannel-ds-48j7z" Sep 8 23:39:35.163167 kubelet[1803]: I0908 23:39:35.163084 1803 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/099e3522-d8d9-4708-88e2-4f4e9cafd3db-kube-proxy\") pod \"kube-proxy-k69fw\" (UID: \"099e3522-d8d9-4708-88e2-4f4e9cafd3db\") " pod="kube-system/kube-proxy-k69fw" Sep 8 23:39:35.163167 kubelet[1803]: I0908 23:39:35.163099 1803 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/099e3522-d8d9-4708-88e2-4f4e9cafd3db-xtables-lock\") pod \"kube-proxy-k69fw\" (UID: \"099e3522-d8d9-4708-88e2-4f4e9cafd3db\") " pod="kube-system/kube-proxy-k69fw" Sep 8 23:39:35.163167 kubelet[1803]: I0908 23:39:35.163115 1803 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-rshr6\" (UniqueName: \"kubernetes.io/projected/099e3522-d8d9-4708-88e2-4f4e9cafd3db-kube-api-access-rshr6\") pod \"kube-proxy-k69fw\" (UID: \"099e3522-d8d9-4708-88e2-4f4e9cafd3db\") " pod="kube-system/kube-proxy-k69fw" Sep 8 23:39:35.163167 kubelet[1803]: I0908 23:39:35.163140 1803 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/93444d41-1fd4-4f16-8728-b6d02f628500-flannel-cfg\") pod \"kube-flannel-ds-48j7z\" (UID: \"93444d41-1fd4-4f16-8728-b6d02f628500\") " pod="kube-flannel/kube-flannel-ds-48j7z" Sep 8 23:39:35.163367 kubelet[1803]: I0908 23:39:35.163180 1803 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/93444d41-1fd4-4f16-8728-b6d02f628500-xtables-lock\") pod \"kube-flannel-ds-48j7z\" (UID: \"93444d41-1fd4-4f16-8728-b6d02f628500\") " pod="kube-flannel/kube-flannel-ds-48j7z" Sep 8 23:39:35.163367 kubelet[1803]: I0908 23:39:35.163218 1803 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-2fw4h\" (UniqueName: \"kubernetes.io/projected/93444d41-1fd4-4f16-8728-b6d02f628500-kube-api-access-2fw4h\") pod \"kube-flannel-ds-48j7z\" (UID: \"93444d41-1fd4-4f16-8728-b6d02f628500\") " pod="kube-flannel/kube-flannel-ds-48j7z" Sep 8 23:39:35.163367 kubelet[1803]: I0908 23:39:35.163247 1803 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/099e3522-d8d9-4708-88e2-4f4e9cafd3db-lib-modules\") pod \"kube-proxy-k69fw\" (UID: \"099e3522-d8d9-4708-88e2-4f4e9cafd3db\") " pod="kube-system/kube-proxy-k69fw" Sep 8 23:39:35.163367 kubelet[1803]: I0908 23:39:35.163276 1803 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/93444d41-1fd4-4f16-8728-b6d02f628500-cni-plugin\") pod \"kube-flannel-ds-48j7z\" (UID: \"93444d41-1fd4-4f16-8728-b6d02f628500\") " pod="kube-flannel/kube-flannel-ds-48j7z" Sep 8 23:39:35.163367 kubelet[1803]: I0908 23:39:35.163311 1803 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/93444d41-1fd4-4f16-8728-b6d02f628500-cni\") pod \"kube-flannel-ds-48j7z\" (UID: \"93444d41-1fd4-4f16-8728-b6d02f628500\") " pod="kube-flannel/kube-flannel-ds-48j7z" Sep 8 23:39:35.170731 systemd[1]: Created slice kubepods-burstable-pod93444d41_1fd4_4f16_8728_b6d02f628500.slice - libcontainer container kubepods-burstable-pod93444d41_1fd4_4f16_8728_b6d02f628500.slice. Sep 8 23:39:35.470131 containerd[1528]: time="2025-09-08T23:39:35.470020876Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-k69fw,Uid:099e3522-d8d9-4708-88e2-4f4e9cafd3db,Namespace:kube-system,Attempt:0,}" Sep 8 23:39:35.483766 containerd[1528]: time="2025-09-08T23:39:35.483707512Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-48j7z,Uid:93444d41-1fd4-4f16-8728-b6d02f628500,Namespace:kube-flannel,Attempt:0,}" Sep 8 23:39:36.056694 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount804314661.mount: Deactivated successfully. Sep 8 23:39:36.062996 containerd[1528]: time="2025-09-08T23:39:36.062886477Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 8 23:39:36.066001 containerd[1528]: time="2025-09-08T23:39:36.065935124Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268705" Sep 8 23:39:36.070290 containerd[1528]: time="2025-09-08T23:39:36.070245445Z" level=info msg="ImageCreate event name:\"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 8 23:39:36.075889 containerd[1528]: time="2025-09-08T23:39:36.075843170Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 8 23:39:36.076558 containerd[1528]: time="2025-09-08T23:39:36.076435072Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Sep 8 23:39:36.078888 containerd[1528]: time="2025-09-08T23:39:36.078842076Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 8 23:39:36.080535 containerd[1528]: time="2025-09-08T23:39:36.080482743Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"267933\" in 601.888763ms" Sep 8 23:39:36.081045 containerd[1528]: time="2025-09-08T23:39:36.080823661Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"267933\" in 592.279628ms" Sep 8 23:39:36.107949 containerd[1528]: time="2025-09-08T23:39:36.107909185Z" level=info msg="connecting to shim a72572eaa4b11f139dcb578b2b2e9350075bdd58de8c23d7013ebaa51f355dbb" address="unix:///run/containerd/s/363f22b60f206c4e721ef17cb6d8f258547f858a12320c955deb3a635c5a4257" namespace=k8s.io protocol=ttrpc version=3 Sep 8 23:39:36.109925 containerd[1528]: time="2025-09-08T23:39:36.109517410Z" level=info msg="connecting to shim fc455feef97aea3de4455b0cb136b38806846e92f2966c9bea2762d4203f7c15" address="unix:///run/containerd/s/2c37c47cd2331a4a714178ac3a64ccec52b317a6e9b15496a532cb467e38a51f" namespace=k8s.io protocol=ttrpc version=3 Sep 8 23:39:36.129465 systemd[1]: Started cri-containerd-a72572eaa4b11f139dcb578b2b2e9350075bdd58de8c23d7013ebaa51f355dbb.scope - libcontainer container a72572eaa4b11f139dcb578b2b2e9350075bdd58de8c23d7013ebaa51f355dbb. Sep 8 23:39:36.132367 systemd[1]: Started cri-containerd-fc455feef97aea3de4455b0cb136b38806846e92f2966c9bea2762d4203f7c15.scope - libcontainer container fc455feef97aea3de4455b0cb136b38806846e92f2966c9bea2762d4203f7c15. Sep 8 23:39:36.139421 kubelet[1803]: E0908 23:39:36.139291 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:36.159426 containerd[1528]: time="2025-09-08T23:39:36.159389165Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-k69fw,Uid:099e3522-d8d9-4708-88e2-4f4e9cafd3db,Namespace:kube-system,Attempt:0,} returns sandbox id \"a72572eaa4b11f139dcb578b2b2e9350075bdd58de8c23d7013ebaa51f355dbb\"" Sep 8 23:39:36.161609 containerd[1528]: time="2025-09-08T23:39:36.161515819Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.8\"" Sep 8 23:39:36.166330 containerd[1528]: time="2025-09-08T23:39:36.166286997Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-48j7z,Uid:93444d41-1fd4-4f16-8728-b6d02f628500,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"fc455feef97aea3de4455b0cb136b38806846e92f2966c9bea2762d4203f7c15\"" Sep 8 23:39:37.122618 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount573088147.mount: Deactivated successfully. Sep 8 23:39:37.140666 kubelet[1803]: E0908 23:39:37.140600 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:37.629031 containerd[1528]: time="2025-09-08T23:39:37.628935150Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.32.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 23:39:37.643813 containerd[1528]: time="2025-09-08T23:39:37.643755020Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.32.8: active requests=0, bytes read=27376726" Sep 8 23:39:37.657086 containerd[1528]: time="2025-09-08T23:39:37.656990440Z" level=info msg="ImageCreate event name:\"sha256:2cf30e39f99f8f4ee1a736a4f3175cc2d8d3f58936d8fa83ec5523658fdc7b8b\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 23:39:37.671399 containerd[1528]: time="2025-09-08T23:39:37.671343945Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:adc1335b480ddd833aac3b0bd20f68ff0f3c3cf7a0bd337933b006d9f5cec40a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 23:39:37.672145 containerd[1528]: time="2025-09-08T23:39:37.671959225Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.32.8\" with image id \"sha256:2cf30e39f99f8f4ee1a736a4f3175cc2d8d3f58936d8fa83ec5523658fdc7b8b\", repo tag \"registry.k8s.io/kube-proxy:v1.32.8\", repo digest \"registry.k8s.io/kube-proxy@sha256:adc1335b480ddd833aac3b0bd20f68ff0f3c3cf7a0bd337933b006d9f5cec40a\", size \"27375743\" in 1.51039686s" Sep 8 23:39:37.672145 containerd[1528]: time="2025-09-08T23:39:37.671995745Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.8\" returns image reference \"sha256:2cf30e39f99f8f4ee1a736a4f3175cc2d8d3f58936d8fa83ec5523658fdc7b8b\"" Sep 8 23:39:37.673113 containerd[1528]: time="2025-09-08T23:39:37.673078908Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Sep 8 23:39:37.675677 containerd[1528]: time="2025-09-08T23:39:37.675563005Z" level=info msg="CreateContainer within sandbox \"a72572eaa4b11f139dcb578b2b2e9350075bdd58de8c23d7013ebaa51f355dbb\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Sep 8 23:39:37.714785 containerd[1528]: time="2025-09-08T23:39:37.713883156Z" level=info msg="Container 48bbe29b03d908ee815f2459322b30c824fa0ad6e08727371ad47333ef40f70c: CDI devices from CRI Config.CDIDevices: []" Sep 8 23:39:37.715939 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1372687571.mount: Deactivated successfully. Sep 8 23:39:37.722954 containerd[1528]: time="2025-09-08T23:39:37.722900396Z" level=info msg="CreateContainer within sandbox \"a72572eaa4b11f139dcb578b2b2e9350075bdd58de8c23d7013ebaa51f355dbb\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"48bbe29b03d908ee815f2459322b30c824fa0ad6e08727371ad47333ef40f70c\"" Sep 8 23:39:37.723618 containerd[1528]: time="2025-09-08T23:39:37.723580764Z" level=info msg="StartContainer for \"48bbe29b03d908ee815f2459322b30c824fa0ad6e08727371ad47333ef40f70c\"" Sep 8 23:39:37.727618 containerd[1528]: time="2025-09-08T23:39:37.727568879Z" level=info msg="connecting to shim 48bbe29b03d908ee815f2459322b30c824fa0ad6e08727371ad47333ef40f70c" address="unix:///run/containerd/s/363f22b60f206c4e721ef17cb6d8f258547f858a12320c955deb3a635c5a4257" protocol=ttrpc version=3 Sep 8 23:39:37.749478 systemd[1]: Started cri-containerd-48bbe29b03d908ee815f2459322b30c824fa0ad6e08727371ad47333ef40f70c.scope - libcontainer container 48bbe29b03d908ee815f2459322b30c824fa0ad6e08727371ad47333ef40f70c. Sep 8 23:39:37.783403 containerd[1528]: time="2025-09-08T23:39:37.783358653Z" level=info msg="StartContainer for \"48bbe29b03d908ee815f2459322b30c824fa0ad6e08727371ad47333ef40f70c\" returns successfully" Sep 8 23:39:38.142163 kubelet[1803]: E0908 23:39:38.140843 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:38.777217 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount118288008.mount: Deactivated successfully. Sep 8 23:39:38.821942 containerd[1528]: time="2025-09-08T23:39:38.821836523Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 23:39:38.822691 containerd[1528]: time="2025-09-08T23:39:38.822595018Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Sep 8 23:39:38.823611 containerd[1528]: time="2025-09-08T23:39:38.823555903Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 23:39:38.828593 containerd[1528]: time="2025-09-08T23:39:38.828529593Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 23:39:38.829199 containerd[1528]: time="2025-09-08T23:39:38.829170689Z" level=info msg="Pulled image \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" with image id \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\", repo tag \"docker.io/flannel/flannel-cni-plugin:v1.1.2\", repo digest \"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\", size \"3662650\" in 1.156051744s" Sep 8 23:39:38.829199 containerd[1528]: time="2025-09-08T23:39:38.829196382Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Sep 8 23:39:38.831826 containerd[1528]: time="2025-09-08T23:39:38.831786101Z" level=info msg="CreateContainer within sandbox \"fc455feef97aea3de4455b0cb136b38806846e92f2966c9bea2762d4203f7c15\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Sep 8 23:39:38.840939 containerd[1528]: time="2025-09-08T23:39:38.840894709Z" level=info msg="Container 232f3daeac339dbfb92fcbce88d1d5a9cc9a2f659661665361e0d4faccdd281f: CDI devices from CRI Config.CDIDevices: []" Sep 8 23:39:38.842471 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3913807488.mount: Deactivated successfully. Sep 8 23:39:38.846878 containerd[1528]: time="2025-09-08T23:39:38.846799035Z" level=info msg="CreateContainer within sandbox \"fc455feef97aea3de4455b0cb136b38806846e92f2966c9bea2762d4203f7c15\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"232f3daeac339dbfb92fcbce88d1d5a9cc9a2f659661665361e0d4faccdd281f\"" Sep 8 23:39:38.847635 containerd[1528]: time="2025-09-08T23:39:38.847603882Z" level=info msg="StartContainer for \"232f3daeac339dbfb92fcbce88d1d5a9cc9a2f659661665361e0d4faccdd281f\"" Sep 8 23:39:38.848411 containerd[1528]: time="2025-09-08T23:39:38.848387390Z" level=info msg="connecting to shim 232f3daeac339dbfb92fcbce88d1d5a9cc9a2f659661665361e0d4faccdd281f" address="unix:///run/containerd/s/2c37c47cd2331a4a714178ac3a64ccec52b317a6e9b15496a532cb467e38a51f" protocol=ttrpc version=3 Sep 8 23:39:38.868482 systemd[1]: Started cri-containerd-232f3daeac339dbfb92fcbce88d1d5a9cc9a2f659661665361e0d4faccdd281f.scope - libcontainer container 232f3daeac339dbfb92fcbce88d1d5a9cc9a2f659661665361e0d4faccdd281f. Sep 8 23:39:38.894150 systemd[1]: cri-containerd-232f3daeac339dbfb92fcbce88d1d5a9cc9a2f659661665361e0d4faccdd281f.scope: Deactivated successfully. Sep 8 23:39:38.895266 containerd[1528]: time="2025-09-08T23:39:38.895229046Z" level=info msg="received exit event container_id:\"232f3daeac339dbfb92fcbce88d1d5a9cc9a2f659661665361e0d4faccdd281f\" id:\"232f3daeac339dbfb92fcbce88d1d5a9cc9a2f659661665361e0d4faccdd281f\" pid:2140 exited_at:{seconds:1757374778 nanos:894874253}" Sep 8 23:39:38.895504 containerd[1528]: time="2025-09-08T23:39:38.895267646Z" level=info msg="TaskExit event in podsandbox handler container_id:\"232f3daeac339dbfb92fcbce88d1d5a9cc9a2f659661665361e0d4faccdd281f\" id:\"232f3daeac339dbfb92fcbce88d1d5a9cc9a2f659661665361e0d4faccdd281f\" pid:2140 exited_at:{seconds:1757374778 nanos:894874253}" Sep 8 23:39:38.896219 containerd[1528]: time="2025-09-08T23:39:38.896196005Z" level=info msg="StartContainer for \"232f3daeac339dbfb92fcbce88d1d5a9cc9a2f659661665361e0d4faccdd281f\" returns successfully" Sep 8 23:39:39.141108 kubelet[1803]: E0908 23:39:39.141046 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:39.311606 containerd[1528]: time="2025-09-08T23:39:39.311569074Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Sep 8 23:39:39.335056 kubelet[1803]: I0908 23:39:39.334988 1803 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-k69fw" podStartSLOduration=3.823104317 podStartE2EDuration="5.334971477s" podCreationTimestamp="2025-09-08 23:39:34 +0000 UTC" firstStartedPulling="2025-09-08 23:39:36.161053164 +0000 UTC m=+2.567712480" lastFinishedPulling="2025-09-08 23:39:37.672920324 +0000 UTC m=+4.079579640" observedRunningTime="2025-09-08 23:39:38.318955293 +0000 UTC m=+4.725614609" watchObservedRunningTime="2025-09-08 23:39:39.334971477 +0000 UTC m=+5.741630833" Sep 8 23:39:39.719285 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-232f3daeac339dbfb92fcbce88d1d5a9cc9a2f659661665361e0d4faccdd281f-rootfs.mount: Deactivated successfully. Sep 8 23:39:40.141784 kubelet[1803]: E0908 23:39:40.141687 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:40.452762 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount776167369.mount: Deactivated successfully. Sep 8 23:39:41.087408 containerd[1528]: time="2025-09-08T23:39:41.087359740Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 23:39:41.089155 containerd[1528]: time="2025-09-08T23:39:41.089115442Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" Sep 8 23:39:41.089987 containerd[1528]: time="2025-09-08T23:39:41.089936022Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 23:39:41.094652 containerd[1528]: time="2025-09-08T23:39:41.094593169Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 23:39:41.095532 containerd[1528]: time="2025-09-08T23:39:41.095502354Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26863435\" in 1.783696709s" Sep 8 23:39:41.095638 containerd[1528]: time="2025-09-08T23:39:41.095537164Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Sep 8 23:39:41.098458 containerd[1528]: time="2025-09-08T23:39:41.097968850Z" level=info msg="CreateContainer within sandbox \"fc455feef97aea3de4455b0cb136b38806846e92f2966c9bea2762d4203f7c15\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Sep 8 23:39:41.105178 containerd[1528]: time="2025-09-08T23:39:41.104588363Z" level=info msg="Container a65232feb28bbffd243474c7434891a7bbed02b15810e4dd09d52f0b380fc605: CDI devices from CRI Config.CDIDevices: []" Sep 8 23:39:41.111746 containerd[1528]: time="2025-09-08T23:39:41.111684429Z" level=info msg="CreateContainer within sandbox \"fc455feef97aea3de4455b0cb136b38806846e92f2966c9bea2762d4203f7c15\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"a65232feb28bbffd243474c7434891a7bbed02b15810e4dd09d52f0b380fc605\"" Sep 8 23:39:41.112274 containerd[1528]: time="2025-09-08T23:39:41.112247149Z" level=info msg="StartContainer for \"a65232feb28bbffd243474c7434891a7bbed02b15810e4dd09d52f0b380fc605\"" Sep 8 23:39:41.113123 containerd[1528]: time="2025-09-08T23:39:41.113086753Z" level=info msg="connecting to shim a65232feb28bbffd243474c7434891a7bbed02b15810e4dd09d52f0b380fc605" address="unix:///run/containerd/s/2c37c47cd2331a4a714178ac3a64ccec52b317a6e9b15496a532cb467e38a51f" protocol=ttrpc version=3 Sep 8 23:39:41.134517 systemd[1]: Started cri-containerd-a65232feb28bbffd243474c7434891a7bbed02b15810e4dd09d52f0b380fc605.scope - libcontainer container a65232feb28bbffd243474c7434891a7bbed02b15810e4dd09d52f0b380fc605. Sep 8 23:39:41.142842 kubelet[1803]: E0908 23:39:41.142800 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:41.158668 systemd[1]: cri-containerd-a65232feb28bbffd243474c7434891a7bbed02b15810e4dd09d52f0b380fc605.scope: Deactivated successfully. Sep 8 23:39:41.161057 containerd[1528]: time="2025-09-08T23:39:41.161023420Z" level=info msg="TaskExit event in podsandbox handler container_id:\"a65232feb28bbffd243474c7434891a7bbed02b15810e4dd09d52f0b380fc605\" id:\"a65232feb28bbffd243474c7434891a7bbed02b15810e4dd09d52f0b380fc605\" pid:2216 exited_at:{seconds:1757374781 nanos:160766719}" Sep 8 23:39:41.161057 containerd[1528]: time="2025-09-08T23:39:41.161024499Z" level=info msg="received exit event container_id:\"a65232feb28bbffd243474c7434891a7bbed02b15810e4dd09d52f0b380fc605\" id:\"a65232feb28bbffd243474c7434891a7bbed02b15810e4dd09d52f0b380fc605\" pid:2216 exited_at:{seconds:1757374781 nanos:160766719}" Sep 8 23:39:41.161497 containerd[1528]: time="2025-09-08T23:39:41.161474195Z" level=info msg="StartContainer for \"a65232feb28bbffd243474c7434891a7bbed02b15810e4dd09d52f0b380fc605\" returns successfully" Sep 8 23:39:41.177900 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-a65232feb28bbffd243474c7434891a7bbed02b15810e4dd09d52f0b380fc605-rootfs.mount: Deactivated successfully. Sep 8 23:39:41.254936 kubelet[1803]: I0908 23:39:41.254901 1803 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Sep 8 23:39:41.318556 containerd[1528]: time="2025-09-08T23:39:41.318516668Z" level=info msg="CreateContainer within sandbox \"fc455feef97aea3de4455b0cb136b38806846e92f2966c9bea2762d4203f7c15\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Sep 8 23:39:41.328993 containerd[1528]: time="2025-09-08T23:39:41.328127629Z" level=info msg="Container 6d7c0bf9e92a5ed299a0f44adc763997240be232ab0278365756bdf79718e5d2: CDI devices from CRI Config.CDIDevices: []" Sep 8 23:39:41.335719 containerd[1528]: time="2025-09-08T23:39:41.335674871Z" level=info msg="CreateContainer within sandbox \"fc455feef97aea3de4455b0cb136b38806846e92f2966c9bea2762d4203f7c15\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"6d7c0bf9e92a5ed299a0f44adc763997240be232ab0278365756bdf79718e5d2\"" Sep 8 23:39:41.336542 containerd[1528]: time="2025-09-08T23:39:41.336510638Z" level=info msg="StartContainer for \"6d7c0bf9e92a5ed299a0f44adc763997240be232ab0278365756bdf79718e5d2\"" Sep 8 23:39:41.337485 containerd[1528]: time="2025-09-08T23:39:41.337408112Z" level=info msg="connecting to shim 6d7c0bf9e92a5ed299a0f44adc763997240be232ab0278365756bdf79718e5d2" address="unix:///run/containerd/s/2c37c47cd2331a4a714178ac3a64ccec52b317a6e9b15496a532cb467e38a51f" protocol=ttrpc version=3 Sep 8 23:39:41.355515 systemd[1]: Started cri-containerd-6d7c0bf9e92a5ed299a0f44adc763997240be232ab0278365756bdf79718e5d2.scope - libcontainer container 6d7c0bf9e92a5ed299a0f44adc763997240be232ab0278365756bdf79718e5d2. Sep 8 23:39:41.385290 containerd[1528]: time="2025-09-08T23:39:41.385218967Z" level=info msg="StartContainer for \"6d7c0bf9e92a5ed299a0f44adc763997240be232ab0278365756bdf79718e5d2\" returns successfully" Sep 8 23:39:42.143971 kubelet[1803]: E0908 23:39:42.143921 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:42.338150 kubelet[1803]: I0908 23:39:42.338097 1803 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-48j7z" podStartSLOduration=3.408886068 podStartE2EDuration="8.338081748s" podCreationTimestamp="2025-09-08 23:39:34 +0000 UTC" firstStartedPulling="2025-09-08 23:39:36.167474358 +0000 UTC m=+2.574133674" lastFinishedPulling="2025-09-08 23:39:41.096670038 +0000 UTC m=+7.503329354" observedRunningTime="2025-09-08 23:39:42.337559805 +0000 UTC m=+8.744219121" watchObservedRunningTime="2025-09-08 23:39:42.338081748 +0000 UTC m=+8.744741064" Sep 8 23:39:42.454659 systemd-networkd[1423]: flannel.1: Link UP Sep 8 23:39:42.454666 systemd-networkd[1423]: flannel.1: Gained carrier Sep 8 23:39:43.144106 kubelet[1803]: E0908 23:39:43.144028 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:43.540532 systemd-networkd[1423]: flannel.1: Gained IPv6LL Sep 8 23:39:44.144228 kubelet[1803]: E0908 23:39:44.144167 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:45.145011 kubelet[1803]: E0908 23:39:45.144962 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:45.533420 systemd[1]: Created slice kubepods-besteffort-pod5a3a8ef1_14e8_409b_94a0_90d94efeb847.slice - libcontainer container kubepods-besteffort-pod5a3a8ef1_14e8_409b_94a0_90d94efeb847.slice. Sep 8 23:39:45.625399 kubelet[1803]: I0908 23:39:45.625360 1803 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-tfl46\" (UniqueName: \"kubernetes.io/projected/5a3a8ef1-14e8-409b-94a0-90d94efeb847-kube-api-access-tfl46\") pod \"nginx-deployment-7fcdb87857-kssth\" (UID: \"5a3a8ef1-14e8-409b-94a0-90d94efeb847\") " pod="default/nginx-deployment-7fcdb87857-kssth" Sep 8 23:39:45.835960 containerd[1528]: time="2025-09-08T23:39:45.835892319Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-kssth,Uid:5a3a8ef1-14e8-409b-94a0-90d94efeb847,Namespace:default,Attempt:0,}" Sep 8 23:39:45.847876 systemd-networkd[1423]: cni0: Link UP Sep 8 23:39:45.847882 systemd-networkd[1423]: cni0: Gained carrier Sep 8 23:39:45.850220 systemd-networkd[1423]: cni0: Lost carrier Sep 8 23:39:45.851873 systemd-networkd[1423]: veth055a4eec: Link UP Sep 8 23:39:45.854620 kernel: cni0: port 1(veth055a4eec) entered blocking state Sep 8 23:39:45.854680 kernel: cni0: port 1(veth055a4eec) entered disabled state Sep 8 23:39:45.856319 kernel: veth055a4eec: entered allmulticast mode Sep 8 23:39:45.856386 kernel: veth055a4eec: entered promiscuous mode Sep 8 23:39:45.864327 kernel: cni0: port 1(veth055a4eec) entered blocking state Sep 8 23:39:45.864387 kernel: cni0: port 1(veth055a4eec) entered forwarding state Sep 8 23:39:45.864480 systemd-networkd[1423]: veth055a4eec: Gained carrier Sep 8 23:39:45.865049 systemd-networkd[1423]: cni0: Gained carrier Sep 8 23:39:45.866946 containerd[1528]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000012938), "name":"cbr0", "type":"bridge"} Sep 8 23:39:45.866946 containerd[1528]: delegateAdd: netconf sent to delegate plugin: Sep 8 23:39:45.891757 containerd[1528]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-09-08T23:39:45.891708305Z" level=info msg="connecting to shim 6ca53d402076325a8b4a4a7ad2cc65665429d059babfedce5d959b53ea51c172" address="unix:///run/containerd/s/e3dcea598a41952441688719f525f510bb741201dde084de7ad62d951f59096d" namespace=k8s.io protocol=ttrpc version=3 Sep 8 23:39:45.917507 systemd[1]: Started cri-containerd-6ca53d402076325a8b4a4a7ad2cc65665429d059babfedce5d959b53ea51c172.scope - libcontainer container 6ca53d402076325a8b4a4a7ad2cc65665429d059babfedce5d959b53ea51c172. Sep 8 23:39:45.927237 systemd-resolved[1353]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 8 23:39:45.944387 containerd[1528]: time="2025-09-08T23:39:45.944355139Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-kssth,Uid:5a3a8ef1-14e8-409b-94a0-90d94efeb847,Namespace:default,Attempt:0,} returns sandbox id \"6ca53d402076325a8b4a4a7ad2cc65665429d059babfedce5d959b53ea51c172\"" Sep 8 23:39:45.945529 containerd[1528]: time="2025-09-08T23:39:45.945504902Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 8 23:39:46.145233 kubelet[1803]: E0908 23:39:46.145087 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:47.146127 kubelet[1803]: E0908 23:39:47.146084 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:47.508440 systemd-networkd[1423]: cni0: Gained IPv6LL Sep 8 23:39:47.679174 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2839902510.mount: Deactivated successfully. Sep 8 23:39:47.828416 systemd-networkd[1423]: veth055a4eec: Gained IPv6LL Sep 8 23:39:48.146902 kubelet[1803]: E0908 23:39:48.146792 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:48.408845 containerd[1528]: time="2025-09-08T23:39:48.408516431Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 23:39:48.409220 containerd[1528]: time="2025-09-08T23:39:48.409189945Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=69986522" Sep 8 23:39:48.411213 containerd[1528]: time="2025-09-08T23:39:48.411170270Z" level=info msg="ImageCreate event name:\"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 23:39:48.415908 containerd[1528]: time="2025-09-08T23:39:48.415847132Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 23:39:48.416886 containerd[1528]: time="2025-09-08T23:39:48.416767312Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\", size \"69986400\" in 2.47123083s" Sep 8 23:39:48.416886 containerd[1528]: time="2025-09-08T23:39:48.416797856Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\"" Sep 8 23:39:48.419033 containerd[1528]: time="2025-09-08T23:39:48.419002539Z" level=info msg="CreateContainer within sandbox \"6ca53d402076325a8b4a4a7ad2cc65665429d059babfedce5d959b53ea51c172\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Sep 8 23:39:48.428469 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2188819852.mount: Deactivated successfully. Sep 8 23:39:48.430052 containerd[1528]: time="2025-09-08T23:39:48.428454568Z" level=info msg="Container 11b208aa2802745b5a4354c07b3f7e9ab6fda351785081a46a53c4b855d45f9b: CDI devices from CRI Config.CDIDevices: []" Sep 8 23:39:48.433908 containerd[1528]: time="2025-09-08T23:39:48.433873747Z" level=info msg="CreateContainer within sandbox \"6ca53d402076325a8b4a4a7ad2cc65665429d059babfedce5d959b53ea51c172\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"11b208aa2802745b5a4354c07b3f7e9ab6fda351785081a46a53c4b855d45f9b\"" Sep 8 23:39:48.434542 containerd[1528]: time="2025-09-08T23:39:48.434518317Z" level=info msg="StartContainer for \"11b208aa2802745b5a4354c07b3f7e9ab6fda351785081a46a53c4b855d45f9b\"" Sep 8 23:39:48.435251 containerd[1528]: time="2025-09-08T23:39:48.435228691Z" level=info msg="connecting to shim 11b208aa2802745b5a4354c07b3f7e9ab6fda351785081a46a53c4b855d45f9b" address="unix:///run/containerd/s/e3dcea598a41952441688719f525f510bb741201dde084de7ad62d951f59096d" protocol=ttrpc version=3 Sep 8 23:39:48.459457 systemd[1]: Started cri-containerd-11b208aa2802745b5a4354c07b3f7e9ab6fda351785081a46a53c4b855d45f9b.scope - libcontainer container 11b208aa2802745b5a4354c07b3f7e9ab6fda351785081a46a53c4b855d45f9b. Sep 8 23:39:48.484919 containerd[1528]: time="2025-09-08T23:39:48.484870985Z" level=info msg="StartContainer for \"11b208aa2802745b5a4354c07b3f7e9ab6fda351785081a46a53c4b855d45f9b\" returns successfully" Sep 8 23:39:49.147559 kubelet[1803]: E0908 23:39:49.147502 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:50.150885 kubelet[1803]: E0908 23:39:50.148653 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:51.149757 kubelet[1803]: E0908 23:39:51.149716 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:51.848183 kubelet[1803]: I0908 23:39:51.848037 1803 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-kssth" podStartSLOduration=4.375297479 podStartE2EDuration="6.848019646s" podCreationTimestamp="2025-09-08 23:39:45 +0000 UTC" firstStartedPulling="2025-09-08 23:39:45.945065192 +0000 UTC m=+12.351724468" lastFinishedPulling="2025-09-08 23:39:48.417787319 +0000 UTC m=+14.824446635" observedRunningTime="2025-09-08 23:39:49.344362878 +0000 UTC m=+15.751022193" watchObservedRunningTime="2025-09-08 23:39:51.848019646 +0000 UTC m=+18.254678962" Sep 8 23:39:51.856143 systemd[1]: Created slice kubepods-besteffort-pod1c340233_1dbc_429e_86fd_2e2c66d70013.slice - libcontainer container kubepods-besteffort-pod1c340233_1dbc_429e_86fd_2e2c66d70013.slice. Sep 8 23:39:51.859238 kubelet[1803]: I0908 23:39:51.859198 1803 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/1c340233-1dbc-429e-86fd-2e2c66d70013-data\") pod \"nfs-server-provisioner-0\" (UID: \"1c340233-1dbc-429e-86fd-2e2c66d70013\") " pod="default/nfs-server-provisioner-0" Sep 8 23:39:51.859238 kubelet[1803]: I0908 23:39:51.859237 1803 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-pl5vn\" (UniqueName: \"kubernetes.io/projected/1c340233-1dbc-429e-86fd-2e2c66d70013-kube-api-access-pl5vn\") pod \"nfs-server-provisioner-0\" (UID: \"1c340233-1dbc-429e-86fd-2e2c66d70013\") " pod="default/nfs-server-provisioner-0" Sep 8 23:39:52.150096 kubelet[1803]: E0908 23:39:52.149974 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:52.160175 containerd[1528]: time="2025-09-08T23:39:52.159897204Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:1c340233-1dbc-429e-86fd-2e2c66d70013,Namespace:default,Attempt:0,}" Sep 8 23:39:52.182354 kernel: cni0: port 2(veth742ce84a) entered blocking state Sep 8 23:39:52.182453 kernel: cni0: port 2(veth742ce84a) entered disabled state Sep 8 23:39:52.182470 kernel: veth742ce84a: entered allmulticast mode Sep 8 23:39:52.183325 kernel: veth742ce84a: entered promiscuous mode Sep 8 23:39:52.185384 systemd-networkd[1423]: veth742ce84a: Link UP Sep 8 23:39:52.189365 kernel: cni0: port 2(veth742ce84a) entered blocking state Sep 8 23:39:52.189432 kernel: cni0: port 2(veth742ce84a) entered forwarding state Sep 8 23:39:52.189630 systemd-networkd[1423]: veth742ce84a: Gained carrier Sep 8 23:39:52.198017 containerd[1528]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000a68e8), "name":"cbr0", "type":"bridge"} Sep 8 23:39:52.198017 containerd[1528]: delegateAdd: netconf sent to delegate plugin: Sep 8 23:39:52.230138 containerd[1528]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-09-08T23:39:52.230094693Z" level=info msg="connecting to shim 350b1aa54544cb275d534c21416be5bab178977361bab327ca6138c538900ce2" address="unix:///run/containerd/s/f47bc46ebcb6637bebdd0bd430c1addf28cb9d2d31ce682787f8aeda575a73ab" namespace=k8s.io protocol=ttrpc version=3 Sep 8 23:39:52.257466 systemd[1]: Started cri-containerd-350b1aa54544cb275d534c21416be5bab178977361bab327ca6138c538900ce2.scope - libcontainer container 350b1aa54544cb275d534c21416be5bab178977361bab327ca6138c538900ce2. Sep 8 23:39:52.269452 systemd-resolved[1353]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 8 23:39:52.288901 containerd[1528]: time="2025-09-08T23:39:52.288848220Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:1c340233-1dbc-429e-86fd-2e2c66d70013,Namespace:default,Attempt:0,} returns sandbox id \"350b1aa54544cb275d534c21416be5bab178977361bab327ca6138c538900ce2\"" Sep 8 23:39:52.291606 containerd[1528]: time="2025-09-08T23:39:52.291511664Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Sep 8 23:39:53.150119 kubelet[1803]: E0908 23:39:53.150066 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:53.652499 systemd-networkd[1423]: veth742ce84a: Gained IPv6LL Sep 8 23:39:54.138402 kubelet[1803]: E0908 23:39:54.138359 1803 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:54.150985 kubelet[1803]: E0908 23:39:54.150948 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:54.526641 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1722605871.mount: Deactivated successfully. Sep 8 23:39:55.151974 kubelet[1803]: E0908 23:39:55.151933 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:55.829727 containerd[1528]: time="2025-09-08T23:39:55.829660932Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 23:39:55.830287 containerd[1528]: time="2025-09-08T23:39:55.830242851Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Sep 8 23:39:55.831052 containerd[1528]: time="2025-09-08T23:39:55.831014665Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 23:39:55.833271 containerd[1528]: time="2025-09-08T23:39:55.833227860Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 23:39:55.834161 containerd[1528]: time="2025-09-08T23:39:55.834117673Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"87371201\" in 3.542535598s" Sep 8 23:39:55.834205 containerd[1528]: time="2025-09-08T23:39:55.834160898Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Sep 8 23:39:55.836119 containerd[1528]: time="2025-09-08T23:39:55.836088512Z" level=info msg="CreateContainer within sandbox \"350b1aa54544cb275d534c21416be5bab178977361bab327ca6138c538900ce2\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Sep 8 23:39:55.843780 containerd[1528]: time="2025-09-08T23:39:55.842934147Z" level=info msg="Container 7403184a18f31a04be2f265c4adb4907ea9f5c2d3cbfa95d30f3a688e69169ea: CDI devices from CRI Config.CDIDevices: []" Sep 8 23:39:55.850392 containerd[1528]: time="2025-09-08T23:39:55.850357783Z" level=info msg="CreateContainer within sandbox \"350b1aa54544cb275d534c21416be5bab178977361bab327ca6138c538900ce2\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"7403184a18f31a04be2f265c4adb4907ea9f5c2d3cbfa95d30f3a688e69169ea\"" Sep 8 23:39:55.851004 containerd[1528]: time="2025-09-08T23:39:55.850940182Z" level=info msg="StartContainer for \"7403184a18f31a04be2f265c4adb4907ea9f5c2d3cbfa95d30f3a688e69169ea\"" Sep 8 23:39:55.851884 containerd[1528]: time="2025-09-08T23:39:55.851855945Z" level=info msg="connecting to shim 7403184a18f31a04be2f265c4adb4907ea9f5c2d3cbfa95d30f3a688e69169ea" address="unix:///run/containerd/s/f47bc46ebcb6637bebdd0bd430c1addf28cb9d2d31ce682787f8aeda575a73ab" protocol=ttrpc version=3 Sep 8 23:39:55.872453 systemd[1]: Started cri-containerd-7403184a18f31a04be2f265c4adb4907ea9f5c2d3cbfa95d30f3a688e69169ea.scope - libcontainer container 7403184a18f31a04be2f265c4adb4907ea9f5c2d3cbfa95d30f3a688e69169ea. Sep 8 23:39:55.895325 containerd[1528]: time="2025-09-08T23:39:55.895241839Z" level=info msg="StartContainer for \"7403184a18f31a04be2f265c4adb4907ea9f5c2d3cbfa95d30f3a688e69169ea\" returns successfully" Sep 8 23:39:56.153071 kubelet[1803]: E0908 23:39:56.152956 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:56.367755 kubelet[1803]: I0908 23:39:56.367684 1803 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.8239344050000001 podStartE2EDuration="5.367667921s" podCreationTimestamp="2025-09-08 23:39:51 +0000 UTC" firstStartedPulling="2025-09-08 23:39:52.291193117 +0000 UTC m=+18.697852433" lastFinishedPulling="2025-09-08 23:39:55.834926633 +0000 UTC m=+22.241585949" observedRunningTime="2025-09-08 23:39:56.366516374 +0000 UTC m=+22.773175690" watchObservedRunningTime="2025-09-08 23:39:56.367667921 +0000 UTC m=+22.774327237" Sep 8 23:39:57.153594 kubelet[1803]: E0908 23:39:57.153553 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:58.154140 kubelet[1803]: E0908 23:39:58.154094 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:39:59.155124 kubelet[1803]: E0908 23:39:59.155087 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:40:00.156204 kubelet[1803]: E0908 23:40:00.156161 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:40:01.157135 kubelet[1803]: E0908 23:40:01.157084 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:40:02.158106 kubelet[1803]: E0908 23:40:02.158057 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:40:03.159239 kubelet[1803]: E0908 23:40:03.159170 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:40:04.159381 kubelet[1803]: E0908 23:40:04.159337 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:40:05.160527 kubelet[1803]: E0908 23:40:05.160469 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:40:05.431591 systemd[1]: Created slice kubepods-besteffort-pod4f9f1f05_2db3_4f1f_af47_b8d7298d1f44.slice - libcontainer container kubepods-besteffort-pod4f9f1f05_2db3_4f1f_af47_b8d7298d1f44.slice. Sep 8 23:40:05.542283 kubelet[1803]: I0908 23:40:05.542244 1803 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-4vb7j\" (UniqueName: \"kubernetes.io/projected/4f9f1f05-2db3-4f1f-af47-b8d7298d1f44-kube-api-access-4vb7j\") pod \"test-pod-1\" (UID: \"4f9f1f05-2db3-4f1f-af47-b8d7298d1f44\") " pod="default/test-pod-1" Sep 8 23:40:05.542495 kubelet[1803]: I0908 23:40:05.542476 1803 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-3cb0ee62-343a-4bb0-a249-b249e2109c63\" (UniqueName: \"kubernetes.io/nfs/4f9f1f05-2db3-4f1f-af47-b8d7298d1f44-pvc-3cb0ee62-343a-4bb0-a249-b249e2109c63\") pod \"test-pod-1\" (UID: \"4f9f1f05-2db3-4f1f-af47-b8d7298d1f44\") " pod="default/test-pod-1" Sep 8 23:40:05.680970 kernel: netfs: FS-Cache loaded Sep 8 23:40:05.733914 kernel: RPC: Registered named UNIX socket transport module. Sep 8 23:40:05.734040 kernel: RPC: Registered udp transport module. Sep 8 23:40:05.734058 kernel: RPC: Registered tcp transport module. Sep 8 23:40:05.734073 kernel: RPC: Registered tcp-with-tls transport module. Sep 8 23:40:05.734087 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Sep 8 23:40:05.918355 kernel: NFS: Registering the id_resolver key type Sep 8 23:40:05.918508 kernel: Key type id_resolver registered Sep 8 23:40:05.918534 kernel: Key type id_legacy registered Sep 8 23:40:05.941780 nfsidmap[2820]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf Sep 8 23:40:05.942469 nfsidmap[2820]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 8 23:40:05.945670 nfsidmap[2823]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf Sep 8 23:40:05.945827 nfsidmap[2823]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 8 23:40:05.954176 nfsrahead[2827]: setting /var/lib/kubelet/pods/4f9f1f05-2db3-4f1f-af47-b8d7298d1f44/volumes/kubernetes.io~nfs/pvc-3cb0ee62-343a-4bb0-a249-b249e2109c63 readahead to 128 Sep 8 23:40:06.036913 containerd[1528]: time="2025-09-08T23:40:06.036654486Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:4f9f1f05-2db3-4f1f-af47-b8d7298d1f44,Namespace:default,Attempt:0,}" Sep 8 23:40:06.046091 systemd-networkd[1423]: veth612fd5dd: Link UP Sep 8 23:40:06.049066 kernel: cni0: port 3(veth612fd5dd) entered blocking state Sep 8 23:40:06.049152 kernel: cni0: port 3(veth612fd5dd) entered disabled state Sep 8 23:40:06.049168 kernel: veth612fd5dd: entered allmulticast mode Sep 8 23:40:06.049182 kernel: veth612fd5dd: entered promiscuous mode Sep 8 23:40:06.054062 kernel: cni0: port 3(veth612fd5dd) entered blocking state Sep 8 23:40:06.054140 kernel: cni0: port 3(veth612fd5dd) entered forwarding state Sep 8 23:40:06.053703 systemd-networkd[1423]: veth612fd5dd: Gained carrier Sep 8 23:40:06.055765 containerd[1528]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000014938), "name":"cbr0", "type":"bridge"} Sep 8 23:40:06.055765 containerd[1528]: delegateAdd: netconf sent to delegate plugin: Sep 8 23:40:06.081047 containerd[1528]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-09-08T23:40:06.080785552Z" level=info msg="connecting to shim 86113c27cb86f52b91bb6c1f5c507dfe9c2843bc2b1a15f833fe1c51b61f19d2" address="unix:///run/containerd/s/2e0d87d73117c2ea2934a15d6b9bb2932c1874bbd28093eedd77bd91a01add3f" namespace=k8s.io protocol=ttrpc version=3 Sep 8 23:40:06.101523 systemd[1]: Started cri-containerd-86113c27cb86f52b91bb6c1f5c507dfe9c2843bc2b1a15f833fe1c51b61f19d2.scope - libcontainer container 86113c27cb86f52b91bb6c1f5c507dfe9c2843bc2b1a15f833fe1c51b61f19d2. Sep 8 23:40:06.114621 systemd-resolved[1353]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 8 23:40:06.135494 containerd[1528]: time="2025-09-08T23:40:06.135394239Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:4f9f1f05-2db3-4f1f-af47-b8d7298d1f44,Namespace:default,Attempt:0,} returns sandbox id \"86113c27cb86f52b91bb6c1f5c507dfe9c2843bc2b1a15f833fe1c51b61f19d2\"" Sep 8 23:40:06.136848 containerd[1528]: time="2025-09-08T23:40:06.136607473Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 8 23:40:06.161351 kubelet[1803]: E0908 23:40:06.161261 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:40:06.452781 containerd[1528]: time="2025-09-08T23:40:06.452292626Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 23:40:06.452960 containerd[1528]: time="2025-09-08T23:40:06.452924998Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Sep 8 23:40:06.455655 containerd[1528]: time="2025-09-08T23:40:06.455612862Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\", size \"69986400\" in 318.953398ms" Sep 8 23:40:06.455655 containerd[1528]: time="2025-09-08T23:40:06.455649336Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\"" Sep 8 23:40:06.458349 containerd[1528]: time="2025-09-08T23:40:06.457535176Z" level=info msg="CreateContainer within sandbox \"86113c27cb86f52b91bb6c1f5c507dfe9c2843bc2b1a15f833fe1c51b61f19d2\" for container &ContainerMetadata{Name:test,Attempt:0,}" Sep 8 23:40:06.465368 containerd[1528]: time="2025-09-08T23:40:06.465290859Z" level=info msg="Container 3ac2024020623016343932e50a52068540c2d5c8b833f092fa8ff6e97e4ccf19: CDI devices from CRI Config.CDIDevices: []" Sep 8 23:40:06.471254 containerd[1528]: time="2025-09-08T23:40:06.471203455Z" level=info msg="CreateContainer within sandbox \"86113c27cb86f52b91bb6c1f5c507dfe9c2843bc2b1a15f833fe1c51b61f19d2\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"3ac2024020623016343932e50a52068540c2d5c8b833f092fa8ff6e97e4ccf19\"" Sep 8 23:40:06.471941 containerd[1528]: time="2025-09-08T23:40:06.471858103Z" level=info msg="StartContainer for \"3ac2024020623016343932e50a52068540c2d5c8b833f092fa8ff6e97e4ccf19\"" Sep 8 23:40:06.472846 containerd[1528]: time="2025-09-08T23:40:06.472804183Z" level=info msg="connecting to shim 3ac2024020623016343932e50a52068540c2d5c8b833f092fa8ff6e97e4ccf19" address="unix:///run/containerd/s/2e0d87d73117c2ea2934a15d6b9bb2932c1874bbd28093eedd77bd91a01add3f" protocol=ttrpc version=3 Sep 8 23:40:06.505526 systemd[1]: Started cri-containerd-3ac2024020623016343932e50a52068540c2d5c8b833f092fa8ff6e97e4ccf19.scope - libcontainer container 3ac2024020623016343932e50a52068540c2d5c8b833f092fa8ff6e97e4ccf19. Sep 8 23:40:06.532076 containerd[1528]: time="2025-09-08T23:40:06.531969176Z" level=info msg="StartContainer for \"3ac2024020623016343932e50a52068540c2d5c8b833f092fa8ff6e97e4ccf19\" returns successfully" Sep 8 23:40:07.162178 kubelet[1803]: E0908 23:40:07.162122 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:40:07.412498 systemd-networkd[1423]: veth612fd5dd: Gained IPv6LL Sep 8 23:40:08.164163 kubelet[1803]: E0908 23:40:08.164099 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 23:40:09.164963 kubelet[1803]: E0908 23:40:09.164907 1803 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"