Oct 28 12:41:21.370947 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 28 12:41:21.370971 kernel: Linux version 6.12.54-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.1_p20250801 p4) 14.3.1 20250801, GNU ld (Gentoo 2.45 p3) 2.45.0) #1 SMP PREEMPT Tue Oct 28 11:22:22 -00 2025 Oct 28 12:41:21.370979 kernel: KASLR enabled Oct 28 12:41:21.370985 kernel: efi: EFI v2.7 by EDK II Oct 28 12:41:21.370991 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb832018 ACPI 2.0=0xdbfd0018 RNG=0xdbfd0a18 MEMRESERVE=0xdb838218 Oct 28 12:41:21.370996 kernel: random: crng init done Oct 28 12:41:21.371003 kernel: secureboot: Secure boot disabled Oct 28 12:41:21.371009 kernel: ACPI: Early table checksum verification disabled Oct 28 12:41:21.371017 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 28 12:41:21.371023 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 28 12:41:21.371029 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:41:21.371043 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:41:21.371050 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:41:21.371056 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:41:21.371065 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:41:21.371072 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:41:21.371079 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:41:21.371085 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:41:21.371092 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 12:41:21.371098 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 28 12:41:21.371104 kernel: ACPI: Use ACPI SPCR as default console: No Oct 28 12:41:21.371111 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 28 12:41:21.371118 kernel: NODE_DATA(0) allocated [mem 0xdc965a00-0xdc96cfff] Oct 28 12:41:21.371125 kernel: Zone ranges: Oct 28 12:41:21.371131 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 28 12:41:21.371137 kernel: DMA32 empty Oct 28 12:41:21.371143 kernel: Normal empty Oct 28 12:41:21.371150 kernel: Device empty Oct 28 12:41:21.371156 kernel: Movable zone start for each node Oct 28 12:41:21.371162 kernel: Early memory node ranges Oct 28 12:41:21.371168 kernel: node 0: [mem 0x0000000040000000-0x00000000db81ffff] Oct 28 12:41:21.371175 kernel: node 0: [mem 0x00000000db820000-0x00000000db82ffff] Oct 28 12:41:21.371181 kernel: node 0: [mem 0x00000000db830000-0x00000000dc09ffff] Oct 28 12:41:21.371187 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 28 12:41:21.371195 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 28 12:41:21.371201 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 28 12:41:21.371208 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 28 12:41:21.371214 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 28 12:41:21.371220 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 28 12:41:21.371227 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 28 12:41:21.371237 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 28 12:41:21.371244 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 28 12:41:21.371251 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 28 12:41:21.371258 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 28 12:41:21.371264 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Oct 28 12:41:21.371271 kernel: cma: Reserved 16 MiB at 0x00000000d8000000 on node -1 Oct 28 12:41:21.371278 kernel: psci: probing for conduit method from ACPI. Oct 28 12:41:21.371285 kernel: psci: PSCIv1.1 detected in firmware. Oct 28 12:41:21.371292 kernel: psci: Using standard PSCI v0.2 function IDs Oct 28 12:41:21.371299 kernel: psci: Trusted OS migration not required Oct 28 12:41:21.371306 kernel: psci: SMC Calling Convention v1.1 Oct 28 12:41:21.371313 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Oct 28 12:41:21.371320 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Oct 28 12:41:21.371327 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Oct 28 12:41:21.371333 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 28 12:41:21.371340 kernel: Detected PIPT I-cache on CPU0 Oct 28 12:41:21.371347 kernel: CPU features: detected: GIC system register CPU interface Oct 28 12:41:21.371354 kernel: CPU features: detected: Spectre-v4 Oct 28 12:41:21.371361 kernel: CPU features: detected: Spectre-BHB Oct 28 12:41:21.371369 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 28 12:41:21.371375 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 28 12:41:21.371382 kernel: CPU features: detected: ARM erratum 1418040 Oct 28 12:41:21.371389 kernel: CPU features: detected: SSBS not fully self-synchronizing Oct 28 12:41:21.371396 kernel: alternatives: applying boot alternatives Oct 28 12:41:21.371403 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=b481a145f1514315d0bd7f9f231ccfb1e7e52e44731a9503681e2ea359fcd6e3 Oct 28 12:41:21.371411 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 28 12:41:21.371418 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 28 12:41:21.371424 kernel: Fallback order for Node 0: 0 Oct 28 12:41:21.371431 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 Oct 28 12:41:21.371439 kernel: Policy zone: DMA Oct 28 12:41:21.371446 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 28 12:41:21.371453 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB Oct 28 12:41:21.371459 kernel: software IO TLB: area num 4. Oct 28 12:41:21.371466 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB Oct 28 12:41:21.371473 kernel: software IO TLB: mapped [mem 0x00000000d7c00000-0x00000000d8000000] (4MB) Oct 28 12:41:21.371480 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 28 12:41:21.371486 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 28 12:41:21.371494 kernel: rcu: RCU event tracing is enabled. Oct 28 12:41:21.371501 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 28 12:41:21.371508 kernel: Trampoline variant of Tasks RCU enabled. Oct 28 12:41:21.371516 kernel: Tracing variant of Tasks RCU enabled. Oct 28 12:41:21.371523 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 28 12:41:21.371530 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 28 12:41:21.371536 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Oct 28 12:41:21.371543 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Oct 28 12:41:21.371550 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 28 12:41:21.371557 kernel: GICv3: 256 SPIs implemented Oct 28 12:41:21.371564 kernel: GICv3: 0 Extended SPIs implemented Oct 28 12:41:21.371570 kernel: Root IRQ handler: gic_handle_irq Oct 28 12:41:21.371577 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Oct 28 12:41:21.371584 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 Oct 28 12:41:21.371592 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 28 12:41:21.371599 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 28 12:41:21.371606 kernel: ITS@0x0000000008080000: allocated 8192 Devices @40110000 (indirect, esz 8, psz 64K, shr 1) Oct 28 12:41:21.371613 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @40120000 (flat, esz 8, psz 64K, shr 1) Oct 28 12:41:21.371620 kernel: GICv3: using LPI property table @0x0000000040130000 Oct 28 12:41:21.371626 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040140000 Oct 28 12:41:21.371633 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 28 12:41:21.371640 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 12:41:21.371647 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 28 12:41:21.371654 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 28 12:41:21.371681 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 28 12:41:21.371691 kernel: arm-pv: using stolen time PV Oct 28 12:41:21.371698 kernel: Console: colour dummy device 80x25 Oct 28 12:41:21.371705 kernel: ACPI: Core revision 20240827 Oct 28 12:41:21.371713 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 28 12:41:21.371720 kernel: pid_max: default: 32768 minimum: 301 Oct 28 12:41:21.371727 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Oct 28 12:41:21.371734 kernel: landlock: Up and running. Oct 28 12:41:21.371741 kernel: SELinux: Initializing. Oct 28 12:41:21.371749 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 28 12:41:21.371757 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 28 12:41:21.371764 kernel: rcu: Hierarchical SRCU implementation. Oct 28 12:41:21.371771 kernel: rcu: Max phase no-delay instances is 400. Oct 28 12:41:21.371778 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Oct 28 12:41:21.371786 kernel: Remapping and enabling EFI services. Oct 28 12:41:21.371793 kernel: smp: Bringing up secondary CPUs ... Oct 28 12:41:21.371801 kernel: Detected PIPT I-cache on CPU1 Oct 28 12:41:21.371813 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 28 12:41:21.371821 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040150000 Oct 28 12:41:21.371829 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 12:41:21.371836 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 28 12:41:21.371844 kernel: Detected PIPT I-cache on CPU2 Oct 28 12:41:21.371851 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 28 12:41:21.371860 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040160000 Oct 28 12:41:21.371868 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 12:41:21.371875 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 28 12:41:21.371882 kernel: Detected PIPT I-cache on CPU3 Oct 28 12:41:21.371890 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 28 12:41:21.371898 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040170000 Oct 28 12:41:21.371906 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 12:41:21.371914 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 28 12:41:21.371922 kernel: smp: Brought up 1 node, 4 CPUs Oct 28 12:41:21.371929 kernel: SMP: Total of 4 processors activated. Oct 28 12:41:21.371937 kernel: CPU: All CPU(s) started at EL1 Oct 28 12:41:21.371945 kernel: CPU features: detected: 32-bit EL0 Support Oct 28 12:41:21.371952 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 28 12:41:21.371960 kernel: CPU features: detected: Common not Private translations Oct 28 12:41:21.371969 kernel: CPU features: detected: CRC32 instructions Oct 28 12:41:21.371977 kernel: CPU features: detected: Enhanced Virtualization Traps Oct 28 12:41:21.371984 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Oct 28 12:41:21.371992 kernel: CPU features: detected: LSE atomic instructions Oct 28 12:41:21.371999 kernel: CPU features: detected: Privileged Access Never Oct 28 12:41:21.372007 kernel: CPU features: detected: RAS Extension Support Oct 28 12:41:21.372014 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 28 12:41:21.372022 kernel: alternatives: applying system-wide alternatives Oct 28 12:41:21.372031 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Oct 28 12:41:21.372044 kernel: Memory: 2450400K/2572288K available (11136K kernel code, 2456K rwdata, 9084K rodata, 12992K init, 1038K bss, 99552K reserved, 16384K cma-reserved) Oct 28 12:41:21.372052 kernel: devtmpfs: initialized Oct 28 12:41:21.372059 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 28 12:41:21.372067 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 28 12:41:21.372074 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Oct 28 12:41:21.372082 kernel: 0 pages in range for non-PLT usage Oct 28 12:41:21.372091 kernel: 515056 pages in range for PLT usage Oct 28 12:41:21.372099 kernel: pinctrl core: initialized pinctrl subsystem Oct 28 12:41:21.372106 kernel: SMBIOS 3.0.0 present. Oct 28 12:41:21.372114 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 28 12:41:21.372121 kernel: DMI: Memory slots populated: 1/1 Oct 28 12:41:21.372129 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 28 12:41:21.372136 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 28 12:41:21.372145 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 28 12:41:21.372153 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 28 12:41:21.372160 kernel: audit: initializing netlink subsys (disabled) Oct 28 12:41:21.372168 kernel: audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 Oct 28 12:41:21.372175 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 28 12:41:21.372183 kernel: cpuidle: using governor menu Oct 28 12:41:21.372190 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 28 12:41:21.372199 kernel: ASID allocator initialised with 32768 entries Oct 28 12:41:21.372206 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 28 12:41:21.372213 kernel: Serial: AMBA PL011 UART driver Oct 28 12:41:21.372221 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 28 12:41:21.372228 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Oct 28 12:41:21.372236 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Oct 28 12:41:21.372243 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Oct 28 12:41:21.372252 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 28 12:41:21.372259 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Oct 28 12:41:21.372267 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Oct 28 12:41:21.372274 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Oct 28 12:41:21.372282 kernel: ACPI: Added _OSI(Module Device) Oct 28 12:41:21.372289 kernel: ACPI: Added _OSI(Processor Device) Oct 28 12:41:21.372297 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 28 12:41:21.372304 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 28 12:41:21.372313 kernel: ACPI: Interpreter enabled Oct 28 12:41:21.372320 kernel: ACPI: Using GIC for interrupt routing Oct 28 12:41:21.372328 kernel: ACPI: MCFG table detected, 1 entries Oct 28 12:41:21.372335 kernel: ACPI: CPU0 has been hot-added Oct 28 12:41:21.372343 kernel: ACPI: CPU1 has been hot-added Oct 28 12:41:21.372350 kernel: ACPI: CPU2 has been hot-added Oct 28 12:41:21.372358 kernel: ACPI: CPU3 has been hot-added Oct 28 12:41:21.372366 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 28 12:41:21.372374 kernel: printk: legacy console [ttyAMA0] enabled Oct 28 12:41:21.372381 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 28 12:41:21.372537 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 28 12:41:21.372623 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 28 12:41:21.372723 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 28 12:41:21.372809 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 28 12:41:21.372888 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 28 12:41:21.372898 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 28 12:41:21.372906 kernel: PCI host bridge to bus 0000:00 Oct 28 12:41:21.372994 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 28 12:41:21.373080 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 28 12:41:21.373157 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 28 12:41:21.373228 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 28 12:41:21.373324 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint Oct 28 12:41:21.373414 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Oct 28 12:41:21.373501 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] Oct 28 12:41:21.373582 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] Oct 28 12:41:21.373676 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] Oct 28 12:41:21.373770 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned Oct 28 12:41:21.373862 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned Oct 28 12:41:21.373959 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned Oct 28 12:41:21.374053 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 28 12:41:21.374141 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 28 12:41:21.374256 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 28 12:41:21.374267 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Oct 28 12:41:21.374275 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Oct 28 12:41:21.374284 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Oct 28 12:41:21.374292 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Oct 28 12:41:21.374300 kernel: iommu: Default domain type: Translated Oct 28 12:41:21.374311 kernel: iommu: DMA domain TLB invalidation policy: strict mode Oct 28 12:41:21.374318 kernel: efivars: Registered efivars operations Oct 28 12:41:21.374326 kernel: vgaarb: loaded Oct 28 12:41:21.374334 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 28 12:41:21.374341 kernel: VFS: Disk quotas dquot_6.6.0 Oct 28 12:41:21.374349 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 28 12:41:21.374357 kernel: pnp: PnP ACPI init Oct 28 12:41:21.374455 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 28 12:41:21.374466 kernel: pnp: PnP ACPI: found 1 devices Oct 28 12:41:21.374474 kernel: NET: Registered PF_INET protocol family Oct 28 12:41:21.374482 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 28 12:41:21.374489 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 28 12:41:21.374497 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 28 12:41:21.374505 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 28 12:41:21.374515 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Oct 28 12:41:21.374522 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 28 12:41:21.374530 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 28 12:41:21.374538 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 28 12:41:21.374545 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 28 12:41:21.374553 kernel: PCI: CLS 0 bytes, default 64 Oct 28 12:41:21.374561 kernel: kvm [1]: HYP mode not available Oct 28 12:41:21.374570 kernel: Initialise system trusted keyrings Oct 28 12:41:21.374578 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 28 12:41:21.374585 kernel: Key type asymmetric registered Oct 28 12:41:21.374593 kernel: Asymmetric key parser 'x509' registered Oct 28 12:41:21.374600 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Oct 28 12:41:21.374608 kernel: io scheduler mq-deadline registered Oct 28 12:41:21.374615 kernel: io scheduler kyber registered Oct 28 12:41:21.374624 kernel: io scheduler bfq registered Oct 28 12:41:21.374632 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 28 12:41:21.374639 kernel: ACPI: button: Power Button [PWRB] Oct 28 12:41:21.374648 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Oct 28 12:41:21.374743 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 28 12:41:21.374753 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 28 12:41:21.374761 kernel: thunder_xcv, ver 1.0 Oct 28 12:41:21.374771 kernel: thunder_bgx, ver 1.0 Oct 28 12:41:21.374779 kernel: nicpf, ver 1.0 Oct 28 12:41:21.374786 kernel: nicvf, ver 1.0 Oct 28 12:41:21.374878 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 28 12:41:21.374955 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-28T12:41:20 UTC (1761655280) Oct 28 12:41:21.374965 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 28 12:41:21.374975 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Oct 28 12:41:21.374983 kernel: watchdog: NMI not fully supported Oct 28 12:41:21.374990 kernel: watchdog: Hard watchdog permanently disabled Oct 28 12:41:21.374998 kernel: NET: Registered PF_INET6 protocol family Oct 28 12:41:21.375006 kernel: Segment Routing with IPv6 Oct 28 12:41:21.375013 kernel: In-situ OAM (IOAM) with IPv6 Oct 28 12:41:21.375021 kernel: NET: Registered PF_PACKET protocol family Oct 28 12:41:21.375030 kernel: Key type dns_resolver registered Oct 28 12:41:21.375045 kernel: registered taskstats version 1 Oct 28 12:41:21.375054 kernel: Loading compiled-in X.509 certificates Oct 28 12:41:21.375061 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: 08c844221364c3c33e6ee0ef78c391f8cc131915' Oct 28 12:41:21.375069 kernel: Demotion targets for Node 0: null Oct 28 12:41:21.375076 kernel: Key type .fscrypt registered Oct 28 12:41:21.375084 kernel: Key type fscrypt-provisioning registered Oct 28 12:41:21.375091 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 28 12:41:21.375101 kernel: ima: Allocated hash algorithm: sha1 Oct 28 12:41:21.375108 kernel: ima: No architecture policies found Oct 28 12:41:21.375116 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Oct 28 12:41:21.375123 kernel: clk: Disabling unused clocks Oct 28 12:41:21.375131 kernel: PM: genpd: Disabling unused power domains Oct 28 12:41:21.375138 kernel: Freeing unused kernel memory: 12992K Oct 28 12:41:21.375145 kernel: Run /init as init process Oct 28 12:41:21.375155 kernel: with arguments: Oct 28 12:41:21.375169 kernel: /init Oct 28 12:41:21.375177 kernel: with environment: Oct 28 12:41:21.375184 kernel: HOME=/ Oct 28 12:41:21.375191 kernel: TERM=linux Oct 28 12:41:21.375293 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Oct 28 12:41:21.375375 kernel: virtio_blk virtio1: [vda] 27000832 512-byte logical blocks (13.8 GB/12.9 GiB) Oct 28 12:41:21.375388 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Oct 28 12:41:21.375396 kernel: GPT:16515071 != 27000831 Oct 28 12:41:21.375404 kernel: GPT:Alternate GPT header not at the end of the disk. Oct 28 12:41:21.375411 kernel: GPT:16515071 != 27000831 Oct 28 12:41:21.375419 kernel: GPT: Use GNU Parted to correct GPT errors. Oct 28 12:41:21.375426 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 28 12:41:21.375436 kernel: Invalid ELF header magic: != \u007fELF Oct 28 12:41:21.375444 kernel: Invalid ELF header magic: != \u007fELF Oct 28 12:41:21.375451 kernel: SCSI subsystem initialized Oct 28 12:41:21.375459 kernel: Invalid ELF header magic: != \u007fELF Oct 28 12:41:21.375466 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 28 12:41:21.375474 kernel: device-mapper: uevent: version 1.0.3 Oct 28 12:41:21.375482 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Oct 28 12:41:21.375491 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Oct 28 12:41:21.375498 kernel: Invalid ELF header magic: != \u007fELF Oct 28 12:41:21.375505 kernel: Invalid ELF header magic: != \u007fELF Oct 28 12:41:21.375513 kernel: Invalid ELF header magic: != \u007fELF Oct 28 12:41:21.375520 kernel: raid6: neonx8 gen() 15769 MB/s Oct 28 12:41:21.375528 kernel: raid6: neonx4 gen() 15688 MB/s Oct 28 12:41:21.375535 kernel: raid6: neonx2 gen() 13208 MB/s Oct 28 12:41:21.375544 kernel: raid6: neonx1 gen() 10536 MB/s Oct 28 12:41:21.375551 kernel: raid6: int64x8 gen() 6719 MB/s Oct 28 12:41:21.375558 kernel: raid6: int64x4 gen() 7316 MB/s Oct 28 12:41:21.375566 kernel: raid6: int64x2 gen() 6108 MB/s Oct 28 12:41:21.375573 kernel: raid6: int64x1 gen() 5033 MB/s Oct 28 12:41:21.375581 kernel: raid6: using algorithm neonx8 gen() 15769 MB/s Oct 28 12:41:21.375589 kernel: raid6: .... xor() 12066 MB/s, rmw enabled Oct 28 12:41:21.375597 kernel: raid6: using neon recovery algorithm Oct 28 12:41:21.375606 kernel: Invalid ELF header magic: != \u007fELF Oct 28 12:41:21.375613 kernel: Invalid ELF header magic: != \u007fELF Oct 28 12:41:21.375621 kernel: Invalid ELF header magic: != \u007fELF Oct 28 12:41:21.375628 kernel: Invalid ELF header magic: != \u007fELF Oct 28 12:41:21.375635 kernel: xor: measuring software checksum speed Oct 28 12:41:21.375643 kernel: 8regs : 19971 MB/sec Oct 28 12:41:21.375650 kernel: 32regs : 21653 MB/sec Oct 28 12:41:21.375666 kernel: arm64_neon : 27965 MB/sec Oct 28 12:41:21.375677 kernel: xor: using function: arm64_neon (27965 MB/sec) Oct 28 12:41:21.375685 kernel: Invalid ELF header magic: != \u007fELF Oct 28 12:41:21.375692 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 28 12:41:21.375700 kernel: BTRFS: device fsid d2b28d9e-1c24-4399-91ca-fef9207a72dc devid 1 transid 39 /dev/mapper/usr (253:0) scanned by mount (203) Oct 28 12:41:21.375708 kernel: BTRFS info (device dm-0): first mount of filesystem d2b28d9e-1c24-4399-91ca-fef9207a72dc Oct 28 12:41:21.375716 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Oct 28 12:41:21.375723 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 28 12:41:21.375732 kernel: BTRFS info (device dm-0): enabling free space tree Oct 28 12:41:21.375740 kernel: Invalid ELF header magic: != \u007fELF Oct 28 12:41:21.375747 kernel: loop: module loaded Oct 28 12:41:21.375755 kernel: loop0: detected capacity change from 0 to 91464 Oct 28 12:41:21.375762 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 28 12:41:21.375771 systemd[1]: Successfully made /usr/ read-only. Oct 28 12:41:21.375782 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +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 -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Oct 28 12:41:21.375792 systemd[1]: Detected virtualization kvm. Oct 28 12:41:21.375800 systemd[1]: Detected architecture arm64. Oct 28 12:41:21.375807 systemd[1]: Running in initrd. Oct 28 12:41:21.375815 systemd[1]: No hostname configured, using default hostname. Oct 28 12:41:21.375823 systemd[1]: Hostname set to . Oct 28 12:41:21.375831 systemd[1]: Initializing machine ID from SMBIOS/DMI UUID. Oct 28 12:41:21.375841 systemd[1]: Queued start job for default target initrd.target. Oct 28 12:41:21.375849 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Oct 28 12:41:21.375857 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 28 12:41:21.375865 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 28 12:41:21.375873 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 28 12:41:21.375881 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 28 12:41:21.375893 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 28 12:41:21.375907 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 28 12:41:21.375917 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 28 12:41:21.375925 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 28 12:41:21.375935 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Oct 28 12:41:21.375943 systemd[1]: Reached target paths.target - Path Units. Oct 28 12:41:21.375951 systemd[1]: Reached target slices.target - Slice Units. Oct 28 12:41:21.375960 systemd[1]: Reached target swap.target - Swaps. Oct 28 12:41:21.375968 systemd[1]: Reached target timers.target - Timer Units. Oct 28 12:41:21.375976 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 28 12:41:21.375984 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 28 12:41:21.375994 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 28 12:41:21.376007 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Oct 28 12:41:21.376018 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 28 12:41:21.376026 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 28 12:41:21.376041 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 28 12:41:21.376050 systemd[1]: Reached target sockets.target - Socket Units. Oct 28 12:41:21.376058 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 28 12:41:21.376069 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 28 12:41:21.376078 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 28 12:41:21.376086 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 28 12:41:21.376095 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). Oct 28 12:41:21.376103 systemd[1]: Starting systemd-fsck-usr.service... Oct 28 12:41:21.376112 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 28 12:41:21.376120 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 28 12:41:21.376130 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 28 12:41:21.376139 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 28 12:41:21.376147 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 28 12:41:21.376157 systemd[1]: Finished systemd-fsck-usr.service. Oct 28 12:41:21.376165 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 28 12:41:21.376194 systemd-journald[343]: Collecting audit messages is disabled. Oct 28 12:41:21.376215 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 28 12:41:21.376225 systemd-journald[343]: Journal started Oct 28 12:41:21.376243 systemd-journald[343]: Runtime Journal (/run/log/journal/7d75dc6a305e4a86b5dde3bae31caede) is 6M, max 48.5M, 42.4M free. Oct 28 12:41:21.379052 systemd[1]: Started systemd-journald.service - Journal Service. Oct 28 12:41:21.379085 kernel: Bridge firewalling registered Oct 28 12:41:21.379642 systemd-modules-load[345]: Inserted module 'br_netfilter' Oct 28 12:41:21.381047 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 28 12:41:21.384596 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 28 12:41:21.386367 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 28 12:41:21.402309 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 28 12:41:21.405493 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 28 12:41:21.408343 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 28 12:41:21.410171 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 28 12:41:21.410344 systemd-tmpfiles[361]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Oct 28 12:41:21.414620 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 28 12:41:21.419979 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 28 12:41:21.423184 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 28 12:41:21.427648 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 28 12:41:21.434112 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 28 12:41:21.437789 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 28 12:41:21.459580 systemd-resolved[373]: Positive Trust Anchors: Oct 28 12:41:21.459595 systemd-resolved[373]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 28 12:41:21.459598 systemd-resolved[373]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Oct 28 12:41:21.463855 dracut-cmdline[386]: 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=b481a145f1514315d0bd7f9f231ccfb1e7e52e44731a9503681e2ea359fcd6e3 Oct 28 12:41:21.459627 systemd-resolved[373]: 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 Oct 28 12:41:21.482114 systemd-resolved[373]: Defaulting to hostname 'linux'. Oct 28 12:41:21.483064 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 28 12:41:21.484310 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 28 12:41:21.530680 kernel: Loading iSCSI transport class v2.0-870. Oct 28 12:41:21.539703 kernel: iscsi: registered transport (tcp) Oct 28 12:41:21.552830 kernel: iscsi: registered transport (qla4xxx) Oct 28 12:41:21.552880 kernel: QLogic iSCSI HBA Driver Oct 28 12:41:21.572251 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 28 12:41:21.590648 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 28 12:41:21.592929 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 28 12:41:21.634187 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 28 12:41:21.636648 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 28 12:41:21.638348 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 28 12:41:21.669646 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 28 12:41:21.673977 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 28 12:41:21.707803 systemd-udevd[627]: Using default interface naming scheme 'v257'. Oct 28 12:41:21.715738 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 28 12:41:21.718153 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 28 12:41:21.731498 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 28 12:41:21.736596 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 28 12:41:21.743129 dracut-pre-trigger[712]: rd.md=0: removing MD RAID activation Oct 28 12:41:21.768294 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 28 12:41:21.770741 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 28 12:41:21.779734 systemd-networkd[728]: lo: Link UP Oct 28 12:41:21.779742 systemd-networkd[728]: lo: Gained carrier Oct 28 12:41:21.780201 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 28 12:41:21.782971 systemd[1]: Reached target network.target - Network. Oct 28 12:41:21.829020 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 28 12:41:21.832333 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 28 12:41:21.868301 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Oct 28 12:41:21.876699 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 28 12:41:21.890330 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 28 12:41:21.900230 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Oct 28 12:41:21.910325 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Oct 28 12:41:21.911882 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 28 12:41:21.915786 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 28 12:41:21.918425 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 28 12:41:21.921995 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 28 12:41:21.924145 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 28 12:41:21.932076 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 28 12:41:21.932204 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 28 12:41:21.936936 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 28 12:41:21.938569 systemd-networkd[728]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 28 12:41:21.938573 systemd-networkd[728]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 28 12:41:21.939874 systemd-networkd[728]: eth0: Link UP Oct 28 12:41:21.940245 systemd-networkd[728]: eth0: Gained carrier Oct 28 12:41:21.940257 systemd-networkd[728]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 28 12:41:21.944277 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 28 12:41:21.952985 disk-uuid[801]: Primary Header is updated. Oct 28 12:41:21.952985 disk-uuid[801]: Secondary Entries is updated. Oct 28 12:41:21.952985 disk-uuid[801]: Secondary Header is updated. Oct 28 12:41:21.956080 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 28 12:41:21.960742 systemd-networkd[728]: eth0: DHCPv4 address 10.0.0.52/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 28 12:41:21.970619 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 28 12:41:22.986879 disk-uuid[805]: Warning: The kernel is still using the old partition table. Oct 28 12:41:22.986879 disk-uuid[805]: The new table will be used at the next reboot or after you Oct 28 12:41:22.986879 disk-uuid[805]: run partprobe(8) or kpartx(8) Oct 28 12:41:22.986879 disk-uuid[805]: The operation has completed successfully. Oct 28 12:41:22.991946 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 28 12:41:22.992055 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 28 12:41:22.994200 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 28 12:41:23.024342 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (826) Oct 28 12:41:23.024397 kernel: BTRFS info (device vda6): first mount of filesystem 65d7d399-e3eb-4f09-80bb-c969f622e233 Oct 28 12:41:23.024408 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 28 12:41:23.028106 kernel: BTRFS info (device vda6): turning on async discard Oct 28 12:41:23.028156 kernel: BTRFS info (device vda6): enabling free space tree Oct 28 12:41:23.033669 kernel: BTRFS info (device vda6): last unmount of filesystem 65d7d399-e3eb-4f09-80bb-c969f622e233 Oct 28 12:41:23.034347 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 28 12:41:23.036370 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 28 12:41:23.128167 ignition[845]: Ignition 2.22.0 Oct 28 12:41:23.128181 ignition[845]: Stage: fetch-offline Oct 28 12:41:23.128214 ignition[845]: no configs at "/usr/lib/ignition/base.d" Oct 28 12:41:23.128224 ignition[845]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 28 12:41:23.128298 ignition[845]: parsed url from cmdline: "" Oct 28 12:41:23.128302 ignition[845]: no config URL provided Oct 28 12:41:23.128306 ignition[845]: reading system config file "/usr/lib/ignition/user.ign" Oct 28 12:41:23.128316 ignition[845]: no config at "/usr/lib/ignition/user.ign" Oct 28 12:41:23.128357 ignition[845]: op(1): [started] loading QEMU firmware config module Oct 28 12:41:23.128362 ignition[845]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 28 12:41:23.134502 ignition[845]: op(1): [finished] loading QEMU firmware config module Oct 28 12:41:23.142633 ignition[845]: parsing config with SHA512: ee598493dbd28121132787a6a5c89d42114f5a3bfe1f073f85148ca063d73c1cea8b7f7ec3e5ce1c78dbeb6cdec1913755c27da3ac0e5706f32f287352491840 Oct 28 12:41:23.145869 unknown[845]: fetched base config from "system" Oct 28 12:41:23.145889 unknown[845]: fetched user config from "qemu" Oct 28 12:41:23.146343 ignition[845]: fetch-offline: fetch-offline passed Oct 28 12:41:23.146421 ignition[845]: Ignition finished successfully Oct 28 12:41:23.148909 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 28 12:41:23.150676 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Oct 28 12:41:23.151431 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 28 12:41:23.194777 ignition[860]: Ignition 2.22.0 Oct 28 12:41:23.194788 ignition[860]: Stage: kargs Oct 28 12:41:23.194908 ignition[860]: no configs at "/usr/lib/ignition/base.d" Oct 28 12:41:23.194916 ignition[860]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 28 12:41:23.195425 ignition[860]: kargs: kargs passed Oct 28 12:41:23.195460 ignition[860]: Ignition finished successfully Oct 28 12:41:23.201358 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 28 12:41:23.203347 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 28 12:41:23.240970 ignition[868]: Ignition 2.22.0 Oct 28 12:41:23.240986 ignition[868]: Stage: disks Oct 28 12:41:23.241124 ignition[868]: no configs at "/usr/lib/ignition/base.d" Oct 28 12:41:23.241133 ignition[868]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 28 12:41:23.244746 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 28 12:41:23.241670 ignition[868]: disks: disks passed Oct 28 12:41:23.246544 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 28 12:41:23.241708 ignition[868]: Ignition finished successfully Oct 28 12:41:23.248356 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 28 12:41:23.250095 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 28 12:41:23.252185 systemd[1]: Reached target sysinit.target - System Initialization. Oct 28 12:41:23.253792 systemd[1]: Reached target basic.target - Basic System. Oct 28 12:41:23.256624 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 28 12:41:23.293981 systemd-fsck[878]: ROOT: clean, 15/456736 files, 38230/456704 blocks Oct 28 12:41:23.298372 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 28 12:41:23.300946 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 28 12:41:23.360683 kernel: EXT4-fs (vda9): mounted filesystem c0e58219-a988-4622-8d4f-7da4e7ecb8e9 r/w with ordered data mode. Quota mode: none. Oct 28 12:41:23.360953 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 28 12:41:23.362279 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 28 12:41:23.364919 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 28 12:41:23.366606 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 28 12:41:23.367729 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Oct 28 12:41:23.367763 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 28 12:41:23.367790 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 28 12:41:23.373987 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 28 12:41:23.376357 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 28 12:41:23.382695 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (887) Oct 28 12:41:23.385541 kernel: BTRFS info (device vda6): first mount of filesystem 65d7d399-e3eb-4f09-80bb-c969f622e233 Oct 28 12:41:23.385575 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 28 12:41:23.388739 kernel: BTRFS info (device vda6): turning on async discard Oct 28 12:41:23.388774 kernel: BTRFS info (device vda6): enabling free space tree Oct 28 12:41:23.389866 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 28 12:41:23.400182 systemd-networkd[728]: eth0: Gained IPv6LL Oct 28 12:41:23.418831 initrd-setup-root[911]: cut: /sysroot/etc/passwd: No such file or directory Oct 28 12:41:23.423087 initrd-setup-root[918]: cut: /sysroot/etc/group: No such file or directory Oct 28 12:41:23.426942 initrd-setup-root[925]: cut: /sysroot/etc/shadow: No such file or directory Oct 28 12:41:23.430500 initrd-setup-root[932]: cut: /sysroot/etc/gshadow: No such file or directory Oct 28 12:41:23.498340 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 28 12:41:23.500814 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 28 12:41:23.502448 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 28 12:41:23.518752 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 28 12:41:23.520840 kernel: BTRFS info (device vda6): last unmount of filesystem 65d7d399-e3eb-4f09-80bb-c969f622e233 Oct 28 12:41:23.537777 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 28 12:41:23.551843 ignition[1001]: INFO : Ignition 2.22.0 Oct 28 12:41:23.551843 ignition[1001]: INFO : Stage: mount Oct 28 12:41:23.554446 ignition[1001]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 28 12:41:23.554446 ignition[1001]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 28 12:41:23.554446 ignition[1001]: INFO : mount: mount passed Oct 28 12:41:23.554446 ignition[1001]: INFO : Ignition finished successfully Oct 28 12:41:23.554390 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 28 12:41:23.556474 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 28 12:41:24.362426 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 28 12:41:24.380721 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (1013) Oct 28 12:41:24.382931 kernel: BTRFS info (device vda6): first mount of filesystem 65d7d399-e3eb-4f09-80bb-c969f622e233 Oct 28 12:41:24.382963 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 28 12:41:24.385942 kernel: BTRFS info (device vda6): turning on async discard Oct 28 12:41:24.385989 kernel: BTRFS info (device vda6): enabling free space tree Oct 28 12:41:24.387300 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 28 12:41:24.414645 ignition[1030]: INFO : Ignition 2.22.0 Oct 28 12:41:24.414645 ignition[1030]: INFO : Stage: files Oct 28 12:41:24.416557 ignition[1030]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 28 12:41:24.416557 ignition[1030]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 28 12:41:24.416557 ignition[1030]: DEBUG : files: compiled without relabeling support, skipping Oct 28 12:41:24.416557 ignition[1030]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 28 12:41:24.416557 ignition[1030]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 28 12:41:24.423589 ignition[1030]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 28 12:41:24.423589 ignition[1030]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 28 12:41:24.423589 ignition[1030]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 28 12:41:24.423589 ignition[1030]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Oct 28 12:41:24.423589 ignition[1030]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Oct 28 12:41:24.420173 unknown[1030]: wrote ssh authorized keys file for user: core Oct 28 12:41:24.432896 ignition[1030]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 28 12:41:24.432896 ignition[1030]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 28 12:41:24.432896 ignition[1030]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 28 12:41:24.432896 ignition[1030]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 28 12:41:24.432896 ignition[1030]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 28 12:41:24.432896 ignition[1030]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.33.0-arm64.raw: attempt #1 Oct 28 12:41:24.837790 ignition[1030]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Oct 28 12:41:25.076049 ignition[1030]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 28 12:41:25.076049 ignition[1030]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Oct 28 12:41:25.080198 ignition[1030]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 28 12:41:25.080198 ignition[1030]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 28 12:41:25.080198 ignition[1030]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Oct 28 12:41:25.080198 ignition[1030]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Oct 28 12:41:25.093011 ignition[1030]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Oct 28 12:41:25.095948 ignition[1030]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Oct 28 12:41:25.098708 ignition[1030]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Oct 28 12:41:25.098708 ignition[1030]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 28 12:41:25.098708 ignition[1030]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 28 12:41:25.098708 ignition[1030]: INFO : files: files passed Oct 28 12:41:25.098708 ignition[1030]: INFO : Ignition finished successfully Oct 28 12:41:25.099448 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 28 12:41:25.102184 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 28 12:41:25.104587 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 28 12:41:25.115962 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 28 12:41:25.117696 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 28 12:41:25.120002 initrd-setup-root-after-ignition[1061]: grep: /sysroot/oem/oem-release: No such file or directory Oct 28 12:41:25.121925 initrd-setup-root-after-ignition[1063]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 28 12:41:25.121925 initrd-setup-root-after-ignition[1063]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 28 12:41:25.125129 initrd-setup-root-after-ignition[1067]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 28 12:41:25.124426 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 28 12:41:25.126741 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 28 12:41:25.129426 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 28 12:41:25.162357 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 28 12:41:25.163471 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 28 12:41:25.165023 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 28 12:41:25.167010 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 28 12:41:25.169139 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 28 12:41:25.169901 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 28 12:41:25.185745 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 28 12:41:25.188157 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 28 12:41:25.218886 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Oct 28 12:41:25.219197 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 28 12:41:25.221408 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 28 12:41:25.223819 systemd[1]: Stopped target timers.target - Timer Units. Oct 28 12:41:25.225702 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 28 12:41:25.225832 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 28 12:41:25.228810 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 28 12:41:25.230862 systemd[1]: Stopped target basic.target - Basic System. Oct 28 12:41:25.232576 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 28 12:41:25.234452 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 28 12:41:25.236750 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 28 12:41:25.239140 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Oct 28 12:41:25.241403 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 28 12:41:25.243607 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 28 12:41:25.245945 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 28 12:41:25.248170 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 28 12:41:25.250131 systemd[1]: Stopped target swap.target - Swaps. Oct 28 12:41:25.251787 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 28 12:41:25.251928 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 28 12:41:25.254544 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 28 12:41:25.255902 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 28 12:41:25.258199 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 28 12:41:25.261701 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 28 12:41:25.263415 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 28 12:41:25.263630 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 28 12:41:25.266769 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 28 12:41:25.266913 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 28 12:41:25.268916 systemd[1]: Stopped target paths.target - Path Units. Oct 28 12:41:25.271302 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 28 12:41:25.276744 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 28 12:41:25.278249 systemd[1]: Stopped target slices.target - Slice Units. Oct 28 12:41:25.280777 systemd[1]: Stopped target sockets.target - Socket Units. Oct 28 12:41:25.282653 systemd[1]: iscsid.socket: Deactivated successfully. Oct 28 12:41:25.282805 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 28 12:41:25.284593 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 28 12:41:25.284735 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 28 12:41:25.286584 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 28 12:41:25.286805 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 28 12:41:25.288700 systemd[1]: ignition-files.service: Deactivated successfully. Oct 28 12:41:25.288842 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 28 12:41:25.291384 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 28 12:41:25.294325 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 28 12:41:25.295775 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 28 12:41:25.295955 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 28 12:41:25.298100 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 28 12:41:25.298255 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 28 12:41:25.300148 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 28 12:41:25.300303 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 28 12:41:25.308467 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 28 12:41:25.308585 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 28 12:41:25.314577 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 28 12:41:25.320808 ignition[1089]: INFO : Ignition 2.22.0 Oct 28 12:41:25.320808 ignition[1089]: INFO : Stage: umount Oct 28 12:41:25.323707 ignition[1089]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 28 12:41:25.323707 ignition[1089]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 28 12:41:25.323707 ignition[1089]: INFO : umount: umount passed Oct 28 12:41:25.323707 ignition[1089]: INFO : Ignition finished successfully Oct 28 12:41:25.323558 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 28 12:41:25.324781 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 28 12:41:25.326401 systemd[1]: Stopped target network.target - Network. Oct 28 12:41:25.328590 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 28 12:41:25.328689 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 28 12:41:25.330512 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 28 12:41:25.330566 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 28 12:41:25.332789 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 28 12:41:25.332847 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 28 12:41:25.334648 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 28 12:41:25.334705 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 28 12:41:25.337111 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 28 12:41:25.338862 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 28 12:41:25.341027 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 28 12:41:25.341116 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 28 12:41:25.343182 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 28 12:41:25.343267 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 28 12:41:25.346901 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 28 12:41:25.346998 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 28 12:41:25.350817 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 28 12:41:25.350895 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 28 12:41:25.355463 systemd[1]: Stopped target network-pre.target - Preparation for Network. Oct 28 12:41:25.357122 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 28 12:41:25.357160 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 28 12:41:25.359852 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 28 12:41:25.360925 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 28 12:41:25.360985 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 28 12:41:25.363133 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 28 12:41:25.363178 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 28 12:41:25.365116 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 28 12:41:25.365158 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 28 12:41:25.367058 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 28 12:41:25.377495 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 28 12:41:25.378707 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 28 12:41:25.381377 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 28 12:41:25.381442 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 28 12:41:25.383579 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 28 12:41:25.383609 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 28 12:41:25.385916 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 28 12:41:25.385962 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 28 12:41:25.389039 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 28 12:41:25.389091 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 28 12:41:25.392047 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 28 12:41:25.392111 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 28 12:41:25.398264 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 28 12:41:25.400111 systemd[1]: systemd-network-generator.service: Deactivated successfully. Oct 28 12:41:25.400175 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Oct 28 12:41:25.402316 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 28 12:41:25.402362 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 28 12:41:25.405102 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Oct 28 12:41:25.405150 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 28 12:41:25.407314 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 28 12:41:25.407357 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 28 12:41:25.409633 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 28 12:41:25.409700 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 28 12:41:25.412802 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 28 12:41:25.412919 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 28 12:41:25.414414 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 28 12:41:25.415743 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 28 12:41:25.418574 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 28 12:41:25.421718 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 28 12:41:25.438868 systemd[1]: Switching root. Oct 28 12:41:25.485755 systemd-journald[343]: Journal stopped Oct 28 12:41:26.267059 systemd-journald[343]: Received SIGTERM from PID 1 (systemd). Oct 28 12:41:26.267114 kernel: SELinux: policy capability network_peer_controls=1 Oct 28 12:41:26.267134 kernel: SELinux: policy capability open_perms=1 Oct 28 12:41:26.267145 kernel: SELinux: policy capability extended_socket_class=1 Oct 28 12:41:26.267155 kernel: SELinux: policy capability always_check_network=0 Oct 28 12:41:26.267166 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 28 12:41:26.267176 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 28 12:41:26.267202 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 28 12:41:26.267213 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 28 12:41:26.267224 kernel: SELinux: policy capability userspace_initial_context=0 Oct 28 12:41:26.267233 kernel: audit: type=1403 audit(1761655285.664:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 28 12:41:26.267245 systemd[1]: Successfully loaded SELinux policy in 58.769ms. Oct 28 12:41:26.267259 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 5.339ms. Oct 28 12:41:26.267271 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +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 -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Oct 28 12:41:26.267284 systemd[1]: Detected virtualization kvm. Oct 28 12:41:26.267294 systemd[1]: Detected architecture arm64. Oct 28 12:41:26.267305 systemd[1]: Detected first boot. Oct 28 12:41:26.267316 systemd[1]: Initializing machine ID from SMBIOS/DMI UUID. Oct 28 12:41:26.267326 zram_generator::config[1135]: No configuration found. Oct 28 12:41:26.267343 kernel: NET: Registered PF_VSOCK protocol family Oct 28 12:41:26.267354 systemd[1]: Populated /etc with preset unit settings. Oct 28 12:41:26.267365 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 28 12:41:26.267375 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 28 12:41:26.267387 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 28 12:41:26.267399 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 28 12:41:26.267411 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 28 12:41:26.267421 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 28 12:41:26.267436 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 28 12:41:26.267447 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 28 12:41:26.267458 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 28 12:41:26.267469 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 28 12:41:26.267481 systemd[1]: Created slice user.slice - User and Session Slice. Oct 28 12:41:26.267491 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 28 12:41:26.267503 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 28 12:41:26.267514 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 28 12:41:26.267524 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 28 12:41:26.267535 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 28 12:41:26.267546 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 28 12:41:26.267557 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Oct 28 12:41:26.267569 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 28 12:41:26.267580 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 28 12:41:26.267590 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 28 12:41:26.267600 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 28 12:41:26.267611 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 28 12:41:26.267621 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 28 12:41:26.267633 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 28 12:41:26.267644 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 28 12:41:26.267655 systemd[1]: Reached target slices.target - Slice Units. Oct 28 12:41:26.267675 systemd[1]: Reached target swap.target - Swaps. Oct 28 12:41:26.267686 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 28 12:41:26.267697 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 28 12:41:26.267707 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Oct 28 12:41:26.267721 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 28 12:41:26.267732 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 28 12:41:26.267743 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 28 12:41:26.267754 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 28 12:41:26.267765 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 28 12:41:26.267776 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 28 12:41:26.267786 systemd[1]: Mounting media.mount - External Media Directory... Oct 28 12:41:26.267799 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 28 12:41:26.267814 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 28 12:41:26.267825 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 28 12:41:26.267837 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). Oct 28 12:41:26.267848 systemd[1]: Reached target machines.target - Containers. Oct 28 12:41:26.267860 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 28 12:41:26.267872 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 28 12:41:26.267884 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 28 12:41:26.267896 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 28 12:41:26.267906 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 28 12:41:26.267917 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 28 12:41:26.267928 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 28 12:41:26.267940 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 28 12:41:26.267951 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 28 12:41:26.267964 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 28 12:41:26.267975 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 28 12:41:26.267986 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 28 12:41:26.267997 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 28 12:41:26.268013 systemd[1]: Stopped systemd-fsck-usr.service. Oct 28 12:41:26.268026 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). Oct 28 12:41:26.268039 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 28 12:41:26.268051 kernel: ACPI: bus type drm_connector registered Oct 28 12:41:26.268061 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 28 12:41:26.268071 kernel: fuse: init (API version 7.41) Oct 28 12:41:26.268081 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 28 12:41:26.268092 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 28 12:41:26.268103 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Oct 28 12:41:26.268115 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 28 12:41:26.268127 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 28 12:41:26.268137 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 28 12:41:26.268148 systemd[1]: Mounted media.mount - External Media Directory. Oct 28 12:41:26.268160 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 28 12:41:26.268171 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 28 12:41:26.268202 systemd-journald[1207]: Collecting audit messages is disabled. Oct 28 12:41:26.268223 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 28 12:41:26.268235 systemd-journald[1207]: Journal started Oct 28 12:41:26.268257 systemd-journald[1207]: Runtime Journal (/run/log/journal/7d75dc6a305e4a86b5dde3bae31caede) is 6M, max 48.5M, 42.4M free. Oct 28 12:41:26.027359 systemd[1]: Queued start job for default target multi-user.target. Oct 28 12:41:26.051636 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Oct 28 12:41:26.052078 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 28 12:41:26.271263 systemd[1]: Started systemd-journald.service - Journal Service. Oct 28 12:41:26.272352 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 28 12:41:26.273933 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 28 12:41:26.275503 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 28 12:41:26.275677 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 28 12:41:26.277136 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 28 12:41:26.277302 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 28 12:41:26.278725 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 28 12:41:26.278895 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 28 12:41:26.280293 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 28 12:41:26.280449 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 28 12:41:26.282001 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 28 12:41:26.282176 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 28 12:41:26.283569 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 28 12:41:26.283733 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 28 12:41:26.285246 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 28 12:41:26.286812 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 28 12:41:26.288977 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 28 12:41:26.290730 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Oct 28 12:41:26.301733 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 28 12:41:26.303222 systemd[1]: Listening on systemd-importd.socket - Disk Image Download Service Socket. Oct 28 12:41:26.305563 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 28 12:41:26.307686 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 28 12:41:26.308941 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 28 12:41:26.308979 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 28 12:41:26.310936 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Oct 28 12:41:26.312355 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 28 12:41:26.314423 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 28 12:41:26.316623 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 28 12:41:26.317957 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 28 12:41:26.318894 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 28 12:41:26.320145 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 28 12:41:26.322786 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 28 12:41:26.324595 systemd-journald[1207]: Time spent on flushing to /var/log/journal/7d75dc6a305e4a86b5dde3bae31caede is 16.081ms for 866 entries. Oct 28 12:41:26.324595 systemd-journald[1207]: System Journal (/var/log/journal/7d75dc6a305e4a86b5dde3bae31caede) is 8M, max 163.5M, 155.5M free. Oct 28 12:41:26.347831 systemd-journald[1207]: Received client request to flush runtime journal. Oct 28 12:41:26.347872 kernel: loop1: detected capacity change from 0 to 211168 Oct 28 12:41:26.325800 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 28 12:41:26.328166 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 28 12:41:26.332688 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 28 12:41:26.334219 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 28 12:41:26.335585 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 28 12:41:26.339002 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 28 12:41:26.343487 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 28 12:41:26.347281 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Oct 28 12:41:26.350748 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 28 12:41:26.352712 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 28 12:41:26.364624 systemd-tmpfiles[1253]: ACLs are not supported, ignoring. Oct 28 12:41:26.366216 kernel: loop2: detected capacity change from 0 to 100624 Oct 28 12:41:26.364643 systemd-tmpfiles[1253]: ACLs are not supported, ignoring. Oct 28 12:41:26.369265 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 28 12:41:26.374266 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 28 12:41:26.375759 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Oct 28 12:41:26.388205 kernel: loop3: detected capacity change from 0 to 109744 Oct 28 12:41:26.404211 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 28 12:41:26.407055 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 28 12:41:26.408987 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 28 12:41:26.412692 kernel: loop4: detected capacity change from 0 to 211168 Oct 28 12:41:26.418688 kernel: loop5: detected capacity change from 0 to 100624 Oct 28 12:41:26.423800 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 28 12:41:26.424684 kernel: loop6: detected capacity change from 0 to 109744 Oct 28 12:41:26.431081 (sd-merge)[1277]: Using extensions 'containerd-flatcar.raw', 'docker-flatcar.raw', 'kubernetes.raw'. Oct 28 12:41:26.435780 (sd-merge)[1277]: Merged extensions into '/usr'. Oct 28 12:41:26.438205 systemd-tmpfiles[1276]: ACLs are not supported, ignoring. Oct 28 12:41:26.438215 systemd-tmpfiles[1276]: ACLs are not supported, ignoring. Oct 28 12:41:26.443136 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 28 12:41:26.445753 systemd[1]: Reload requested from client PID 1252 ('systemd-sysext') (unit systemd-sysext.service)... Oct 28 12:41:26.445766 systemd[1]: Reloading... Oct 28 12:41:26.505706 zram_generator::config[1317]: No configuration found. Oct 28 12:41:26.537181 systemd-resolved[1275]: Positive Trust Anchors: Oct 28 12:41:26.537199 systemd-resolved[1275]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 28 12:41:26.537202 systemd-resolved[1275]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Oct 28 12:41:26.537234 systemd-resolved[1275]: 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 Oct 28 12:41:26.544266 systemd-resolved[1275]: Defaulting to hostname 'linux'. Oct 28 12:41:26.639820 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 28 12:41:26.640054 systemd[1]: Reloading finished in 193 ms. Oct 28 12:41:26.675370 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 28 12:41:26.676836 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 28 12:41:26.678323 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 28 12:41:26.681565 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 28 12:41:26.696846 systemd[1]: Starting ensure-sysext.service... Oct 28 12:41:26.698886 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 28 12:41:26.706776 systemd[1]: Reload requested from client PID 1347 ('systemctl') (unit ensure-sysext.service)... Oct 28 12:41:26.706790 systemd[1]: Reloading... Oct 28 12:41:26.713554 systemd-tmpfiles[1348]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Oct 28 12:41:26.713873 systemd-tmpfiles[1348]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Oct 28 12:41:26.714141 systemd-tmpfiles[1348]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 28 12:41:26.714434 systemd-tmpfiles[1348]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 28 12:41:26.715061 systemd-tmpfiles[1348]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 28 12:41:26.715321 systemd-tmpfiles[1348]: ACLs are not supported, ignoring. Oct 28 12:41:26.715432 systemd-tmpfiles[1348]: ACLs are not supported, ignoring. Oct 28 12:41:26.719606 systemd-tmpfiles[1348]: Detected autofs mount point /boot during canonicalization of boot. Oct 28 12:41:26.719619 systemd-tmpfiles[1348]: Skipping /boot Oct 28 12:41:26.725455 systemd-tmpfiles[1348]: Detected autofs mount point /boot during canonicalization of boot. Oct 28 12:41:26.725469 systemd-tmpfiles[1348]: Skipping /boot Oct 28 12:41:26.757690 zram_generator::config[1378]: No configuration found. Oct 28 12:41:26.885215 systemd[1]: Reloading finished in 178 ms. Oct 28 12:41:26.904130 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 28 12:41:26.921712 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 28 12:41:26.928856 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 28 12:41:26.930765 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 28 12:41:26.942685 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 28 12:41:26.945022 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 28 12:41:26.947723 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 28 12:41:26.950947 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 28 12:41:26.955054 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 28 12:41:26.957074 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 28 12:41:26.964618 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 28 12:41:26.967816 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 28 12:41:26.969402 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 28 12:41:26.969562 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). Oct 28 12:41:26.972911 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 28 12:41:26.974607 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 28 12:41:26.974752 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 28 12:41:26.979450 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 28 12:41:26.979806 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 28 12:41:26.984310 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 28 12:41:26.984490 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 28 12:41:26.992709 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 28 12:41:26.993090 systemd-udevd[1419]: Using default interface naming scheme 'v257'. Oct 28 12:41:26.997457 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 28 12:41:26.998621 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 28 12:41:27.002793 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 28 12:41:27.012836 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 28 12:41:27.015442 augenrules[1449]: No rules Oct 28 12:41:27.015768 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 28 12:41:27.017015 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 28 12:41:27.017059 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). Oct 28 12:41:27.017898 systemd[1]: Finished ensure-sysext.service. Oct 28 12:41:27.019095 systemd[1]: audit-rules.service: Deactivated successfully. Oct 28 12:41:27.019309 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 28 12:41:27.020917 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 28 12:41:27.022850 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 28 12:41:27.023017 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 28 12:41:27.024542 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 28 12:41:27.026857 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 28 12:41:27.027032 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 28 12:41:27.028517 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 28 12:41:27.028777 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 28 12:41:27.030354 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 28 12:41:27.030563 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 28 12:41:27.045828 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 28 12:41:27.046956 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 28 12:41:27.047066 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 28 12:41:27.048507 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Oct 28 12:41:27.050852 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). Oct 28 12:41:27.105337 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 28 12:41:27.109841 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 28 12:41:27.131928 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Oct 28 12:41:27.137370 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 28 12:41:27.139812 systemd-networkd[1482]: lo: Link UP Oct 28 12:41:27.139821 systemd-networkd[1482]: lo: Gained carrier Oct 28 12:41:27.141120 systemd-networkd[1482]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 28 12:41:27.141131 systemd-networkd[1482]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 28 12:41:27.142527 systemd-networkd[1482]: eth0: Link UP Oct 28 12:41:27.142698 systemd-networkd[1482]: eth0: Gained carrier Oct 28 12:41:27.142712 systemd-networkd[1482]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 28 12:41:27.143033 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 28 12:41:27.145971 systemd[1]: Reached target network.target - Network. Oct 28 12:41:27.148289 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Oct 28 12:41:27.151777 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 28 12:41:27.159608 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Oct 28 12:41:27.161104 systemd[1]: Reached target time-set.target - System Time Set. Oct 28 12:41:27.161719 systemd-networkd[1482]: eth0: DHCPv4 address 10.0.0.52/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 28 12:41:27.166934 systemd-timesyncd[1483]: Network configuration changed, trying to establish connection. Oct 28 12:41:27.167855 systemd-timesyncd[1483]: Contacted time server 10.0.0.1:123 (10.0.0.1). Oct 28 12:41:27.167980 systemd-timesyncd[1483]: Initial clock synchronization to Tue 2025-10-28 12:41:27.273290 UTC. Oct 28 12:41:27.175920 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Oct 28 12:41:27.246841 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 28 12:41:27.248369 ldconfig[1416]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 28 12:41:27.259839 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 28 12:41:27.263191 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 28 12:41:27.281683 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 28 12:41:27.291296 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 28 12:41:27.293891 systemd[1]: Reached target sysinit.target - System Initialization. Oct 28 12:41:27.295047 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 28 12:41:27.296317 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 28 12:41:27.297749 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 28 12:41:27.298896 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 28 12:41:27.300170 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 28 12:41:27.301437 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 28 12:41:27.301472 systemd[1]: Reached target paths.target - Path Units. Oct 28 12:41:27.302443 systemd[1]: Reached target timers.target - Timer Units. Oct 28 12:41:27.304070 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 28 12:41:27.306289 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 28 12:41:27.309055 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Oct 28 12:41:27.310600 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Oct 28 12:41:27.311928 systemd[1]: Reached target ssh-access.target - SSH Access Available. Oct 28 12:41:27.314773 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 28 12:41:27.316237 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Oct 28 12:41:27.317923 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 28 12:41:27.319091 systemd[1]: Reached target sockets.target - Socket Units. Oct 28 12:41:27.320115 systemd[1]: Reached target basic.target - Basic System. Oct 28 12:41:27.321103 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 28 12:41:27.321135 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 28 12:41:27.321982 systemd[1]: Starting containerd.service - containerd container runtime... Oct 28 12:41:27.323880 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 28 12:41:27.325687 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 28 12:41:27.327693 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 28 12:41:27.329567 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 28 12:41:27.330711 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). Oct 28 12:41:27.331614 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 28 12:41:27.333896 jq[1533]: false Oct 28 12:41:27.334423 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 28 12:41:27.337839 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 28 12:41:27.341132 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 28 12:41:27.342431 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 28 12:41:27.342813 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 28 12:41:27.343917 systemd[1]: Starting update-engine.service - Update Engine... Oct 28 12:41:27.344612 extend-filesystems[1534]: Found /dev/vda6 Oct 28 12:41:27.346617 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 28 12:41:27.346903 extend-filesystems[1534]: Found /dev/vda9 Oct 28 12:41:27.349396 extend-filesystems[1534]: Checking size of /dev/vda9 Oct 28 12:41:27.359565 jq[1549]: true Oct 28 12:41:27.361218 extend-filesystems[1534]: Resized partition /dev/vda9 Oct 28 12:41:27.359760 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 28 12:41:27.362350 extend-filesystems[1558]: resize2fs 1.47.3 (8-Jul-2025) Oct 28 12:41:27.379737 kernel: EXT4-fs (vda9): resizing filesystem from 456704 to 1784827 blocks Oct 28 12:41:27.361425 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 28 12:41:27.361578 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 28 12:41:27.361836 systemd[1]: motdgen.service: Deactivated successfully. Oct 28 12:41:27.361983 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 28 12:41:27.363990 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 28 12:41:27.364161 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 28 12:41:27.384510 jq[1562]: true Oct 28 12:41:27.397142 update_engine[1547]: I20251028 12:41:27.396909 1547 main.cc:92] Flatcar Update Engine starting Oct 28 12:41:27.401691 kernel: EXT4-fs (vda9): resized filesystem to 1784827 Oct 28 12:41:27.411715 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 28 12:41:27.411534 dbus-daemon[1531]: [system] SELinux support is enabled Oct 28 12:41:27.415551 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). Oct 28 12:41:27.415584 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 28 12:41:27.417393 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). Oct 28 12:41:27.417407 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 28 12:41:27.419692 update_engine[1547]: I20251028 12:41:27.419549 1547 update_check_scheduler.cc:74] Next update check in 9m35s Oct 28 12:41:27.419800 systemd[1]: Started update-engine.service - Update Engine. Oct 28 12:41:27.421504 extend-filesystems[1558]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Oct 28 12:41:27.421504 extend-filesystems[1558]: old_desc_blocks = 1, new_desc_blocks = 1 Oct 28 12:41:27.421504 extend-filesystems[1558]: The filesystem on /dev/vda9 is now 1784827 (4k) blocks long. Oct 28 12:41:27.425073 extend-filesystems[1534]: Resized filesystem in /dev/vda9 Oct 28 12:41:27.424814 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 28 12:41:27.426633 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 28 12:41:27.427208 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 28 12:41:27.441737 bash[1595]: Updated "/home/core/.ssh/authorized_keys" Oct 28 12:41:27.446883 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 28 12:41:27.448839 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Oct 28 12:41:27.452191 systemd-logind[1546]: Watching system buttons on /dev/input/event0 (Power Button) Oct 28 12:41:27.452474 systemd-logind[1546]: New seat seat0. Oct 28 12:41:27.454424 systemd[1]: Started systemd-logind.service - User Login Management. Oct 28 12:41:27.482670 locksmithd[1587]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 28 12:41:27.527620 containerd[1566]: time="2025-10-28T12:41:27Z" 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 Oct 28 12:41:27.531214 containerd[1566]: time="2025-10-28T12:41:27.531171480Z" level=info msg="starting containerd" revision=cb1076646aa3740577fafbf3d914198b7fe8e3f7 version=v2.1.4 Oct 28 12:41:27.541688 containerd[1566]: time="2025-10-28T12:41:27.540934200Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9.44µs" Oct 28 12:41:27.541688 containerd[1566]: time="2025-10-28T12:41:27.540964960Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Oct 28 12:41:27.541688 containerd[1566]: time="2025-10-28T12:41:27.541007080Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Oct 28 12:41:27.541688 containerd[1566]: time="2025-10-28T12:41:27.541020800Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Oct 28 12:41:27.541688 containerd[1566]: time="2025-10-28T12:41:27.541139080Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Oct 28 12:41:27.541688 containerd[1566]: time="2025-10-28T12:41:27.541153240Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 28 12:41:27.541688 containerd[1566]: time="2025-10-28T12:41:27.541197800Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 28 12:41:27.541688 containerd[1566]: time="2025-10-28T12:41:27.541208080Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 28 12:41:27.541688 containerd[1566]: time="2025-10-28T12:41:27.541382960Z" 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 Oct 28 12:41:27.541688 containerd[1566]: time="2025-10-28T12:41:27.541397840Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 28 12:41:27.541688 containerd[1566]: time="2025-10-28T12:41:27.541408400Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 28 12:41:27.541688 containerd[1566]: time="2025-10-28T12:41:27.541416000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.erofs type=io.containerd.snapshotter.v1 Oct 28 12:41:27.541963 containerd[1566]: time="2025-10-28T12:41:27.541536920Z" level=info msg="skip loading plugin" error="EROFS unsupported, please `modprobe erofs`: skip plugin" id=io.containerd.snapshotter.v1.erofs type=io.containerd.snapshotter.v1 Oct 28 12:41:27.541963 containerd[1566]: time="2025-10-28T12:41:27.541548720Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Oct 28 12:41:27.541963 containerd[1566]: time="2025-10-28T12:41:27.541611400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Oct 28 12:41:27.542200 containerd[1566]: time="2025-10-28T12:41:27.542179000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 28 12:41:27.542279 containerd[1566]: time="2025-10-28T12:41:27.542264240Z" 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 Oct 28 12:41:27.542343 containerd[1566]: time="2025-10-28T12:41:27.542329400Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Oct 28 12:41:27.542415 containerd[1566]: time="2025-10-28T12:41:27.542402560Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Oct 28 12:41:27.542653 containerd[1566]: time="2025-10-28T12:41:27.542637840Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Oct 28 12:41:27.542784 containerd[1566]: time="2025-10-28T12:41:27.542761280Z" level=info msg="metadata content store policy set" policy=shared Oct 28 12:41:27.546054 containerd[1566]: time="2025-10-28T12:41:27.546031200Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Oct 28 12:41:27.546151 containerd[1566]: time="2025-10-28T12:41:27.546137360Z" level=info msg="loading plugin" id=io.containerd.differ.v1.erofs type=io.containerd.differ.v1 Oct 28 12:41:27.546307 containerd[1566]: time="2025-10-28T12:41:27.546290560Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Oct 28 12:41:27.546389 containerd[1566]: time="2025-10-28T12:41:27.546374880Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Oct 28 12:41:27.546446 containerd[1566]: time="2025-10-28T12:41:27.546433400Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Oct 28 12:41:27.546492 containerd[1566]: time="2025-10-28T12:41:27.546481360Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Oct 28 12:41:27.546539 containerd[1566]: time="2025-10-28T12:41:27.546527280Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Oct 28 12:41:27.546592 containerd[1566]: time="2025-10-28T12:41:27.546578520Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Oct 28 12:41:27.546642 containerd[1566]: time="2025-10-28T12:41:27.546629760Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Oct 28 12:41:27.546720 containerd[1566]: time="2025-10-28T12:41:27.546706200Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Oct 28 12:41:27.546775 containerd[1566]: time="2025-10-28T12:41:27.546763240Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Oct 28 12:41:27.546827 containerd[1566]: time="2025-10-28T12:41:27.546815880Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Oct 28 12:41:27.546876 containerd[1566]: time="2025-10-28T12:41:27.546865200Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Oct 28 12:41:27.547028 containerd[1566]: time="2025-10-28T12:41:27.546999800Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Oct 28 12:41:27.547101 containerd[1566]: time="2025-10-28T12:41:27.547088440Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Oct 28 12:41:27.547153 containerd[1566]: time="2025-10-28T12:41:27.547141960Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Oct 28 12:41:27.547212 containerd[1566]: time="2025-10-28T12:41:27.547199480Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Oct 28 12:41:27.547273 containerd[1566]: time="2025-10-28T12:41:27.547258360Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Oct 28 12:41:27.547333 containerd[1566]: time="2025-10-28T12:41:27.547318720Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Oct 28 12:41:27.547385 containerd[1566]: time="2025-10-28T12:41:27.547373160Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Oct 28 12:41:27.547437 containerd[1566]: time="2025-10-28T12:41:27.547423600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Oct 28 12:41:27.547487 containerd[1566]: time="2025-10-28T12:41:27.547474280Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Oct 28 12:41:27.547539 containerd[1566]: time="2025-10-28T12:41:27.547525440Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Oct 28 12:41:27.547597 containerd[1566]: time="2025-10-28T12:41:27.547584320Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Oct 28 12:41:27.547698 containerd[1566]: time="2025-10-28T12:41:27.547681000Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Oct 28 12:41:27.547803 containerd[1566]: time="2025-10-28T12:41:27.547788560Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Oct 28 12:41:27.547857 containerd[1566]: time="2025-10-28T12:41:27.547846520Z" level=info msg="Start snapshots syncer" Oct 28 12:41:27.547928 containerd[1566]: time="2025-10-28T12:41:27.547913160Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Oct 28 12:41:27.548203 containerd[1566]: time="2025-10-28T12:41:27.548170040Z" 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,\"cgroupWritable\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"\",\"binDirs\":[\"/opt/cni/bin\"],\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogLineSize\":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\"}" Oct 28 12:41:27.548353 containerd[1566]: time="2025-10-28T12:41:27.548337280Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Oct 28 12:41:27.548471 containerd[1566]: time="2025-10-28T12:41:27.548455760Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Oct 28 12:41:27.548616 containerd[1566]: time="2025-10-28T12:41:27.548599360Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Oct 28 12:41:27.548699 containerd[1566]: time="2025-10-28T12:41:27.548685000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Oct 28 12:41:27.548751 containerd[1566]: time="2025-10-28T12:41:27.548738320Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Oct 28 12:41:27.548821 containerd[1566]: time="2025-10-28T12:41:27.548806640Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Oct 28 12:41:27.548874 containerd[1566]: time="2025-10-28T12:41:27.548861000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Oct 28 12:41:27.548928 containerd[1566]: time="2025-10-28T12:41:27.548915120Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Oct 28 12:41:27.548981 containerd[1566]: time="2025-10-28T12:41:27.548967320Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Oct 28 12:41:27.549052 containerd[1566]: time="2025-10-28T12:41:27.549037600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Oct 28 12:41:27.549102 containerd[1566]: time="2025-10-28T12:41:27.549090160Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Oct 28 12:41:27.549184 containerd[1566]: time="2025-10-28T12:41:27.549169720Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 28 12:41:27.549253 containerd[1566]: time="2025-10-28T12:41:27.549235080Z" 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 Oct 28 12:41:27.549298 containerd[1566]: time="2025-10-28T12:41:27.549286200Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 28 12:41:27.549346 containerd[1566]: time="2025-10-28T12:41:27.549333160Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 28 12:41:27.549401 containerd[1566]: time="2025-10-28T12:41:27.549385480Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Oct 28 12:41:27.549448 containerd[1566]: time="2025-10-28T12:41:27.549436000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Oct 28 12:41:27.549496 containerd[1566]: time="2025-10-28T12:41:27.549482680Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Oct 28 12:41:27.549550 containerd[1566]: time="2025-10-28T12:41:27.549539320Z" level=info msg="runtime interface created" Oct 28 12:41:27.550676 containerd[1566]: time="2025-10-28T12:41:27.549585200Z" level=info msg="created NRI interface" Oct 28 12:41:27.550676 containerd[1566]: time="2025-10-28T12:41:27.549599520Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Oct 28 12:41:27.550676 containerd[1566]: time="2025-10-28T12:41:27.549611840Z" level=info msg="Connect containerd service" Oct 28 12:41:27.550676 containerd[1566]: time="2025-10-28T12:41:27.549636240Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 28 12:41:27.550676 containerd[1566]: time="2025-10-28T12:41:27.550327840Z" 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" Oct 28 12:41:27.614454 containerd[1566]: time="2025-10-28T12:41:27.614412400Z" level=info msg="Start subscribing containerd event" Oct 28 12:41:27.614575 containerd[1566]: time="2025-10-28T12:41:27.614471000Z" level=info msg="Start recovering state" Oct 28 12:41:27.614575 containerd[1566]: time="2025-10-28T12:41:27.614550560Z" level=info msg="Start event monitor" Oct 28 12:41:27.614575 containerd[1566]: time="2025-10-28T12:41:27.614562440Z" level=info msg="Start cni network conf syncer for default" Oct 28 12:41:27.614575 containerd[1566]: time="2025-10-28T12:41:27.614569080Z" level=info msg="Start streaming server" Oct 28 12:41:27.614640 containerd[1566]: time="2025-10-28T12:41:27.614577400Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Oct 28 12:41:27.614640 containerd[1566]: time="2025-10-28T12:41:27.614584200Z" level=info msg="runtime interface starting up..." Oct 28 12:41:27.614640 containerd[1566]: time="2025-10-28T12:41:27.614589520Z" level=info msg="starting plugins..." Oct 28 12:41:27.614640 containerd[1566]: time="2025-10-28T12:41:27.614602000Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Oct 28 12:41:27.614738 containerd[1566]: time="2025-10-28T12:41:27.614713680Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 28 12:41:27.614777 containerd[1566]: time="2025-10-28T12:41:27.614765160Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 28 12:41:27.614938 containerd[1566]: time="2025-10-28T12:41:27.614923840Z" level=info msg="containerd successfully booted in 0.087651s" Oct 28 12:41:27.615057 systemd[1]: Started containerd.service - containerd container runtime. Oct 28 12:41:27.952986 sshd_keygen[1573]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 28 12:41:27.971303 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 28 12:41:27.976288 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 28 12:41:27.994521 systemd[1]: issuegen.service: Deactivated successfully. Oct 28 12:41:27.994756 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 28 12:41:27.997198 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 28 12:41:28.006585 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 28 12:41:28.009256 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 28 12:41:28.011364 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Oct 28 12:41:28.012917 systemd[1]: Reached target getty.target - Login Prompts. Oct 28 12:41:28.391866 systemd-networkd[1482]: eth0: Gained IPv6LL Oct 28 12:41:28.394060 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 28 12:41:28.395905 systemd[1]: Reached target network-online.target - Network is Online. Oct 28 12:41:28.398357 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Oct 28 12:41:28.400880 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 28 12:41:28.412013 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 28 12:41:28.426147 systemd[1]: coreos-metadata.service: Deactivated successfully. Oct 28 12:41:28.426380 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Oct 28 12:41:28.428141 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 28 12:41:28.429577 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 28 12:41:28.949208 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 28 12:41:28.950907 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 28 12:41:28.952511 (kubelet)[1663]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Oct 28 12:41:28.956739 systemd[1]: Startup finished in 1.207s (kernel) + 4.548s (initrd) + 3.351s (userspace) = 9.108s. Oct 28 12:41:29.291072 kubelet[1663]: E1028 12:41:29.290969 1663 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" Oct 28 12:41:29.293060 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Oct 28 12:41:29.293190 systemd[1]: kubelet.service: Failed with result 'exit-code'. Oct 28 12:41:29.293877 systemd[1]: kubelet.service: Consumed 733ms CPU time, 258M memory peak. Oct 28 12:41:32.181342 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 28 12:41:32.182530 systemd[1]: Started sshd@0-10.0.0.52:22-10.0.0.1:53436.service - OpenSSH per-connection server daemon (10.0.0.1:53436). Oct 28 12:41:32.245866 sshd[1677]: Accepted publickey for core from 10.0.0.1 port 53436 ssh2: RSA SHA256:y5DILAuoqXjK2JwSJCmNRt+2/SZo5/8WW+uOyH1DepM Oct 28 12:41:32.247626 sshd-session[1677]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 12:41:32.253171 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 28 12:41:32.254108 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 28 12:41:32.258917 systemd-logind[1546]: New session 1 of user core. Oct 28 12:41:32.271451 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 28 12:41:32.273694 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 28 12:41:32.279201 (systemd)[1682]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 28 12:41:32.281193 systemd-logind[1546]: New session c1 of user core. Oct 28 12:41:32.378167 systemd[1682]: Queued start job for default target default.target. Oct 28 12:41:32.388489 systemd[1682]: Created slice app.slice - User Application Slice. Oct 28 12:41:32.388516 systemd[1682]: Reached target paths.target - Paths. Oct 28 12:41:32.388547 systemd[1682]: Reached target timers.target - Timers. Oct 28 12:41:32.389599 systemd[1682]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 28 12:41:32.397918 systemd[1682]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 28 12:41:32.397969 systemd[1682]: Reached target sockets.target - Sockets. Oct 28 12:41:32.398003 systemd[1682]: Reached target basic.target - Basic System. Oct 28 12:41:32.398033 systemd[1682]: Reached target default.target - Main User Target. Oct 28 12:41:32.398057 systemd[1682]: Startup finished in 111ms. Oct 28 12:41:32.398164 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 28 12:41:32.399375 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 28 12:41:32.457149 systemd[1]: Started sshd@1-10.0.0.52:22-10.0.0.1:53444.service - OpenSSH per-connection server daemon (10.0.0.1:53444). Oct 28 12:41:32.519140 sshd[1693]: Accepted publickey for core from 10.0.0.1 port 53444 ssh2: RSA SHA256:y5DILAuoqXjK2JwSJCmNRt+2/SZo5/8WW+uOyH1DepM Oct 28 12:41:32.520214 sshd-session[1693]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 12:41:32.524308 systemd-logind[1546]: New session 2 of user core. Oct 28 12:41:32.540828 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 28 12:41:32.592473 sshd[1696]: Connection closed by 10.0.0.1 port 53444 Oct 28 12:41:32.592766 sshd-session[1693]: pam_unix(sshd:session): session closed for user core Oct 28 12:41:32.601393 systemd[1]: sshd@1-10.0.0.52:22-10.0.0.1:53444.service: Deactivated successfully. Oct 28 12:41:32.603913 systemd[1]: session-2.scope: Deactivated successfully. Oct 28 12:41:32.606425 systemd-logind[1546]: Session 2 logged out. Waiting for processes to exit. Oct 28 12:41:32.608234 systemd[1]: Started sshd@2-10.0.0.52:22-10.0.0.1:53456.service - OpenSSH per-connection server daemon (10.0.0.1:53456). Oct 28 12:41:32.608994 systemd-logind[1546]: Removed session 2. Oct 28 12:41:32.663136 sshd[1702]: Accepted publickey for core from 10.0.0.1 port 53456 ssh2: RSA SHA256:y5DILAuoqXjK2JwSJCmNRt+2/SZo5/8WW+uOyH1DepM Oct 28 12:41:32.664082 sshd-session[1702]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 12:41:32.668128 systemd-logind[1546]: New session 3 of user core. Oct 28 12:41:32.677845 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 28 12:41:32.725191 sshd[1706]: Connection closed by 10.0.0.1 port 53456 Oct 28 12:41:32.725604 sshd-session[1702]: pam_unix(sshd:session): session closed for user core Oct 28 12:41:32.734459 systemd[1]: sshd@2-10.0.0.52:22-10.0.0.1:53456.service: Deactivated successfully. Oct 28 12:41:32.736849 systemd[1]: session-3.scope: Deactivated successfully. Oct 28 12:41:32.738563 systemd-logind[1546]: Session 3 logged out. Waiting for processes to exit. Oct 28 12:41:32.739219 systemd[1]: Started sshd@3-10.0.0.52:22-10.0.0.1:53468.service - OpenSSH per-connection server daemon (10.0.0.1:53468). Oct 28 12:41:32.740068 systemd-logind[1546]: Removed session 3. Oct 28 12:41:32.795849 sshd[1712]: Accepted publickey for core from 10.0.0.1 port 53468 ssh2: RSA SHA256:y5DILAuoqXjK2JwSJCmNRt+2/SZo5/8WW+uOyH1DepM Oct 28 12:41:32.796921 sshd-session[1712]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 12:41:32.801401 systemd-logind[1546]: New session 4 of user core. Oct 28 12:41:32.814805 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 28 12:41:32.865709 sshd[1715]: Connection closed by 10.0.0.1 port 53468 Oct 28 12:41:32.865707 sshd-session[1712]: pam_unix(sshd:session): session closed for user core Oct 28 12:41:32.875493 systemd[1]: sshd@3-10.0.0.52:22-10.0.0.1:53468.service: Deactivated successfully. Oct 28 12:41:32.877836 systemd[1]: session-4.scope: Deactivated successfully. Oct 28 12:41:32.878422 systemd-logind[1546]: Session 4 logged out. Waiting for processes to exit. Oct 28 12:41:32.880064 systemd[1]: Started sshd@4-10.0.0.52:22-10.0.0.1:53480.service - OpenSSH per-connection server daemon (10.0.0.1:53480). Oct 28 12:41:32.880842 systemd-logind[1546]: Removed session 4. Oct 28 12:41:32.931719 sshd[1721]: Accepted publickey for core from 10.0.0.1 port 53480 ssh2: RSA SHA256:y5DILAuoqXjK2JwSJCmNRt+2/SZo5/8WW+uOyH1DepM Oct 28 12:41:32.932743 sshd-session[1721]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 12:41:32.936725 systemd-logind[1546]: New session 5 of user core. Oct 28 12:41:32.946854 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 28 12:41:33.003816 sudo[1725]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Oct 28 12:41:33.004362 sudo[1725]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 28 12:41:33.014756 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Oct 28 12:41:33.040036 systemd[1]: coreos-metadata.service: Deactivated successfully. Oct 28 12:41:33.040811 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Oct 28 12:41:33.427102 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 28 12:41:33.427554 systemd[1]: kubelet.service: Consumed 733ms CPU time, 258M memory peak. Oct 28 12:41:33.429310 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 28 12:41:33.448275 systemd[1]: Reload requested from client PID 1768 ('systemctl') (unit session-5.scope)... Oct 28 12:41:33.448291 systemd[1]: Reloading... Oct 28 12:41:33.529230 zram_generator::config[1813]: No configuration found. Oct 28 12:41:33.812403 systemd[1]: Reloading finished in 363 ms. Oct 28 12:41:33.867909 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... Oct 28 12:41:33.869990 systemd[1]: kubelet.service: Deactivated successfully. Oct 28 12:41:33.870314 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 28 12:41:33.870409 systemd[1]: kubelet.service: Consumed 96ms CPU time, 95.3M memory peak. Oct 28 12:41:33.871886 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 28 12:41:34.003236 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 28 12:41:34.006765 (kubelet)[1857]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Oct 28 12:41:34.040468 kubelet[1857]: 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. Oct 28 12:41:34.040468 kubelet[1857]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Oct 28 12:41:34.040468 kubelet[1857]: 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. Oct 28 12:41:34.040794 kubelet[1857]: I1028 12:41:34.040506 1857 server.go:212] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Oct 28 12:41:34.736333 kubelet[1857]: I1028 12:41:34.736290 1857 server.go:530] "Kubelet version" kubeletVersion="v1.33.0" Oct 28 12:41:34.736333 kubelet[1857]: I1028 12:41:34.736319 1857 server.go:532] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Oct 28 12:41:34.736550 kubelet[1857]: I1028 12:41:34.736522 1857 server.go:956] "Client rotation is on, will bootstrap in background" Oct 28 12:41:34.759498 kubelet[1857]: I1028 12:41:34.759472 1857 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Oct 28 12:41:34.768259 kubelet[1857]: I1028 12:41:34.768222 1857 server.go:1446] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Oct 28 12:41:34.772660 kubelet[1857]: I1028 12:41:34.772624 1857 server.go:782] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Oct 28 12:41:34.774925 kubelet[1857]: I1028 12:41:34.774871 1857 container_manager_linux.go:267] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Oct 28 12:41:34.775098 kubelet[1857]: I1028 12:41:34.774922 1857 container_manager_linux.go:272] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.52","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":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"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}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Oct 28 12:41:34.775228 kubelet[1857]: I1028 12:41:34.775152 1857 topology_manager.go:138] "Creating topology manager with none policy" Oct 28 12:41:34.775228 kubelet[1857]: I1028 12:41:34.775161 1857 container_manager_linux.go:303] "Creating device plugin manager" Oct 28 12:41:34.776009 kubelet[1857]: I1028 12:41:34.775917 1857 state_mem.go:36] "Initialized new in-memory state store" Oct 28 12:41:34.778606 kubelet[1857]: I1028 12:41:34.778566 1857 kubelet.go:480] "Attempting to sync node with API server" Oct 28 12:41:34.778606 kubelet[1857]: I1028 12:41:34.778592 1857 kubelet.go:375] "Adding static pod path" path="/etc/kubernetes/manifests" Oct 28 12:41:34.778655 kubelet[1857]: I1028 12:41:34.778617 1857 kubelet.go:386] "Adding apiserver pod source" Oct 28 12:41:34.779777 kubelet[1857]: I1028 12:41:34.779713 1857 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Oct 28 12:41:34.779777 kubelet[1857]: E1028 12:41:34.779736 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:34.779777 kubelet[1857]: E1028 12:41:34.779742 1857 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:34.781163 kubelet[1857]: I1028 12:41:34.781137 1857 kuberuntime_manager.go:279] "Container runtime initialized" containerRuntime="containerd" version="v2.1.4" apiVersion="v1" Oct 28 12:41:34.781937 kubelet[1857]: I1028 12:41:34.781903 1857 kubelet.go:935] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Oct 28 12:41:34.782109 kubelet[1857]: W1028 12:41:34.782088 1857 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Oct 28 12:41:34.784629 kubelet[1857]: I1028 12:41:34.784605 1857 watchdog_linux.go:99] "Systemd watchdog is not enabled" Oct 28 12:41:34.784697 kubelet[1857]: I1028 12:41:34.784654 1857 server.go:1289] "Started kubelet" Oct 28 12:41:34.785684 kubelet[1857]: I1028 12:41:34.784751 1857 server.go:180] "Starting to listen" address="0.0.0.0" port=10250 Oct 28 12:41:34.785684 kubelet[1857]: I1028 12:41:34.785610 1857 server.go:317] "Adding debug handlers to kubelet server" Oct 28 12:41:34.787191 kubelet[1857]: I1028 12:41:34.787147 1857 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Oct 28 12:41:34.787459 kubelet[1857]: I1028 12:41:34.787443 1857 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Oct 28 12:41:34.788269 kubelet[1857]: I1028 12:41:34.787475 1857 server.go:255] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Oct 28 12:41:34.788387 kubelet[1857]: I1028 12:41:34.788370 1857 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Oct 28 12:41:34.790286 kubelet[1857]: E1028 12:41:34.790242 1857 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.52\" not found" Oct 28 12:41:34.790286 kubelet[1857]: I1028 12:41:34.790291 1857 volume_manager.go:297] "Starting Kubelet Volume Manager" Oct 28 12:41:34.790495 kubelet[1857]: I1028 12:41:34.790480 1857 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Oct 28 12:41:34.790538 kubelet[1857]: I1028 12:41:34.790529 1857 reconciler.go:26] "Reconciler: start to sync state" Oct 28 12:41:34.791458 kubelet[1857]: I1028 12:41:34.791440 1857 factory.go:223] Registration of the systemd container factory successfully Oct 28 12:41:34.791684 kubelet[1857]: I1028 12:41:34.791519 1857 factory.go:221] 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 Oct 28 12:41:34.792271 kubelet[1857]: E1028 12:41:34.792247 1857 kubelet.go:1600] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Oct 28 12:41:34.792868 kubelet[1857]: I1028 12:41:34.792852 1857 factory.go:223] Registration of the containerd container factory successfully Oct 28 12:41:34.798696 kubelet[1857]: E1028 12:41:34.797803 1857 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.52\" not found" node="10.0.0.52" Oct 28 12:41:34.804970 kubelet[1857]: I1028 12:41:34.804937 1857 cpu_manager.go:221] "Starting CPU manager" policy="none" Oct 28 12:41:34.804970 kubelet[1857]: I1028 12:41:34.804956 1857 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Oct 28 12:41:34.804970 kubelet[1857]: I1028 12:41:34.804975 1857 state_mem.go:36] "Initialized new in-memory state store" Oct 28 12:41:34.887991 kubelet[1857]: I1028 12:41:34.887955 1857 policy_none.go:49] "None policy: Start" Oct 28 12:41:34.887991 kubelet[1857]: I1028 12:41:34.887987 1857 memory_manager.go:186] "Starting memorymanager" policy="None" Oct 28 12:41:34.887991 kubelet[1857]: I1028 12:41:34.887999 1857 state_mem.go:35] "Initializing new in-memory state store" Oct 28 12:41:34.890718 kubelet[1857]: E1028 12:41:34.890642 1857 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.52\" not found" Oct 28 12:41:34.893854 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Oct 28 12:41:34.907248 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Oct 28 12:41:34.910584 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Oct 28 12:41:34.915036 kubelet[1857]: I1028 12:41:34.914998 1857 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv4" Oct 28 12:41:34.916100 kubelet[1857]: I1028 12:41:34.916078 1857 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv6" Oct 28 12:41:34.916100 kubelet[1857]: I1028 12:41:34.916104 1857 status_manager.go:230] "Starting to sync pod status with apiserver" Oct 28 12:41:34.916163 kubelet[1857]: I1028 12:41:34.916131 1857 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Oct 28 12:41:34.916163 kubelet[1857]: I1028 12:41:34.916138 1857 kubelet.go:2436] "Starting kubelet main sync loop" Oct 28 12:41:34.916216 kubelet[1857]: E1028 12:41:34.916180 1857 kubelet.go:2460] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Oct 28 12:41:34.921473 kubelet[1857]: E1028 12:41:34.921453 1857 manager.go:517] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Oct 28 12:41:34.921642 kubelet[1857]: I1028 12:41:34.921625 1857 eviction_manager.go:189] "Eviction manager: starting control loop" Oct 28 12:41:34.921787 kubelet[1857]: I1028 12:41:34.921642 1857 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Oct 28 12:41:34.922089 kubelet[1857]: I1028 12:41:34.921990 1857 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Oct 28 12:41:34.923353 kubelet[1857]: E1028 12:41:34.923288 1857 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Oct 28 12:41:34.923353 kubelet[1857]: E1028 12:41:34.923322 1857 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.52\" not found" Oct 28 12:41:35.023510 kubelet[1857]: I1028 12:41:35.023382 1857 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.52" Oct 28 12:41:35.027473 kubelet[1857]: I1028 12:41:35.027263 1857 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.52" Oct 28 12:41:35.042470 kubelet[1857]: I1028 12:41:35.042441 1857 kuberuntime_manager.go:1746] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Oct 28 12:41:35.043146 kubelet[1857]: I1028 12:41:35.042993 1857 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Oct 28 12:41:35.043173 containerd[1566]: time="2025-10-28T12:41:35.042853180Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Oct 28 12:41:35.527187 sudo[1725]: pam_unix(sudo:session): session closed for user root Oct 28 12:41:35.528797 sshd[1724]: Connection closed by 10.0.0.1 port 53480 Oct 28 12:41:35.529141 sshd-session[1721]: pam_unix(sshd:session): session closed for user core Oct 28 12:41:35.532938 systemd[1]: sshd@4-10.0.0.52:22-10.0.0.1:53480.service: Deactivated successfully. Oct 28 12:41:35.534933 systemd[1]: session-5.scope: Deactivated successfully. Oct 28 12:41:35.535225 systemd[1]: session-5.scope: Consumed 392ms CPU time, 74.4M memory peak. Oct 28 12:41:35.536258 systemd-logind[1546]: Session 5 logged out. Waiting for processes to exit. Oct 28 12:41:35.537402 systemd-logind[1546]: Removed session 5. Oct 28 12:41:35.738853 kubelet[1857]: I1028 12:41:35.738822 1857 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Oct 28 12:41:35.739249 kubelet[1857]: I1028 12:41:35.739213 1857 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Service" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Oct 28 12:41:35.739249 kubelet[1857]: I1028 12:41:35.739205 1857 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.RuntimeClass" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Oct 28 12:41:35.739370 kubelet[1857]: I1028 12:41:35.739213 1857 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.CSIDriver" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Oct 28 12:41:35.780465 kubelet[1857]: I1028 12:41:35.780039 1857 apiserver.go:52] "Watching apiserver" Oct 28 12:41:35.780465 kubelet[1857]: E1028 12:41:35.780058 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:35.791391 kubelet[1857]: I1028 12:41:35.791340 1857 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Oct 28 12:41:35.793633 systemd[1]: Created slice kubepods-besteffort-podb262a00f_5d13_4ff9_9617_0cfb30e72b86.slice - libcontainer container kubepods-besteffort-podb262a00f_5d13_4ff9_9617_0cfb30e72b86.slice. Oct 28 12:41:35.796328 kubelet[1857]: I1028 12:41:35.796299 1857 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-6n9bb\" (UniqueName: \"kubernetes.io/projected/b262a00f-5d13-4ff9-9617-0cfb30e72b86-kube-api-access-6n9bb\") pod \"kube-proxy-l6rj7\" (UID: \"b262a00f-5d13-4ff9-9617-0cfb30e72b86\") " pod="kube-system/kube-proxy-l6rj7" Oct 28 12:41:35.796388 kubelet[1857]: I1028 12:41:35.796336 1857 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/f729f0ba-c251-473e-90b8-c3e69e2cbfbe-run\") pod \"kube-flannel-ds-ppjnw\" (UID: \"f729f0ba-c251-473e-90b8-c3e69e2cbfbe\") " pod="kube-flannel/kube-flannel-ds-ppjnw" Oct 28 12:41:35.796388 kubelet[1857]: I1028 12:41:35.796357 1857 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/f729f0ba-c251-473e-90b8-c3e69e2cbfbe-cni-plugin\") pod \"kube-flannel-ds-ppjnw\" (UID: \"f729f0ba-c251-473e-90b8-c3e69e2cbfbe\") " pod="kube-flannel/kube-flannel-ds-ppjnw" Oct 28 12:41:35.796388 kubelet[1857]: I1028 12:41:35.796372 1857 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/f729f0ba-c251-473e-90b8-c3e69e2cbfbe-xtables-lock\") pod \"kube-flannel-ds-ppjnw\" (UID: \"f729f0ba-c251-473e-90b8-c3e69e2cbfbe\") " pod="kube-flannel/kube-flannel-ds-ppjnw" Oct 28 12:41:35.796461 kubelet[1857]: I1028 12:41:35.796387 1857 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-sbsl6\" (UniqueName: \"kubernetes.io/projected/f729f0ba-c251-473e-90b8-c3e69e2cbfbe-kube-api-access-sbsl6\") pod \"kube-flannel-ds-ppjnw\" (UID: \"f729f0ba-c251-473e-90b8-c3e69e2cbfbe\") " pod="kube-flannel/kube-flannel-ds-ppjnw" Oct 28 12:41:35.796461 kubelet[1857]: I1028 12:41:35.796406 1857 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/b262a00f-5d13-4ff9-9617-0cfb30e72b86-lib-modules\") pod \"kube-proxy-l6rj7\" (UID: \"b262a00f-5d13-4ff9-9617-0cfb30e72b86\") " pod="kube-system/kube-proxy-l6rj7" Oct 28 12:41:35.796461 kubelet[1857]: I1028 12:41:35.796421 1857 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/f729f0ba-c251-473e-90b8-c3e69e2cbfbe-cni\") pod \"kube-flannel-ds-ppjnw\" (UID: \"f729f0ba-c251-473e-90b8-c3e69e2cbfbe\") " pod="kube-flannel/kube-flannel-ds-ppjnw" Oct 28 12:41:35.796461 kubelet[1857]: I1028 12:41:35.796434 1857 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/f729f0ba-c251-473e-90b8-c3e69e2cbfbe-flannel-cfg\") pod \"kube-flannel-ds-ppjnw\" (UID: \"f729f0ba-c251-473e-90b8-c3e69e2cbfbe\") " pod="kube-flannel/kube-flannel-ds-ppjnw" Oct 28 12:41:35.796461 kubelet[1857]: I1028 12:41:35.796456 1857 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/b262a00f-5d13-4ff9-9617-0cfb30e72b86-kube-proxy\") pod \"kube-proxy-l6rj7\" (UID: \"b262a00f-5d13-4ff9-9617-0cfb30e72b86\") " pod="kube-system/kube-proxy-l6rj7" Oct 28 12:41:35.796553 kubelet[1857]: I1028 12:41:35.796472 1857 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/b262a00f-5d13-4ff9-9617-0cfb30e72b86-xtables-lock\") pod \"kube-proxy-l6rj7\" (UID: \"b262a00f-5d13-4ff9-9617-0cfb30e72b86\") " pod="kube-system/kube-proxy-l6rj7" Oct 28 12:41:35.812306 systemd[1]: Created slice kubepods-burstable-podf729f0ba_c251_473e_90b8_c3e69e2cbfbe.slice - libcontainer container kubepods-burstable-podf729f0ba_c251_473e_90b8_c3e69e2cbfbe.slice. Oct 28 12:41:36.109840 kubelet[1857]: E1028 12:41:36.109807 1857 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 28 12:41:36.110369 containerd[1566]: time="2025-10-28T12:41:36.110326186Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-l6rj7,Uid:b262a00f-5d13-4ff9-9617-0cfb30e72b86,Namespace:kube-system,Attempt:0,}" Oct 28 12:41:36.130571 kubelet[1857]: E1028 12:41:36.130337 1857 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 28 12:41:36.143011 containerd[1566]: time="2025-10-28T12:41:36.142970885Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-ppjnw,Uid:f729f0ba-c251-473e-90b8-c3e69e2cbfbe,Namespace:kube-flannel,Attempt:0,}" Oct 28 12:41:36.610726 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount846931692.mount: Deactivated successfully. Oct 28 12:41:36.618697 containerd[1566]: time="2025-10-28T12:41:36.618000651Z" 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\"}" Oct 28 12:41:36.618852 containerd[1566]: time="2025-10-28T12:41:36.618831760Z" 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\"}" Oct 28 12:41:36.619325 containerd[1566]: time="2025-10-28T12:41:36.619271384Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Oct 28 12:41:36.619795 containerd[1566]: time="2025-10-28T12:41:36.619754724Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Oct 28 12:41:36.621043 containerd[1566]: time="2025-10-28T12:41:36.621009464Z" level=info msg="ImageCreate event name:\"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 28 12:41:36.623229 containerd[1566]: time="2025-10-28T12:41:36.623181341Z" 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\"}" Oct 28 12:41:36.623978 containerd[1566]: time="2025-10-28T12:41:36.623954670Z" 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 506.679899ms" Oct 28 12:41:36.625232 containerd[1566]: time="2025-10-28T12:41:36.625207762Z" 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 478.002978ms" Oct 28 12:41:36.640251 containerd[1566]: time="2025-10-28T12:41:36.640160936Z" level=info msg="connecting to shim 9dcec90459b9aaea4a4e13be02041a55d6e8bbf13ed1ae19be13a4e319fcab2a" address="unix:///run/containerd/s/e01bec76a2877c16ebfb0366f291d48ebdc7565eca91ae1b3fa1d7c3548d1812" namespace=k8s.io protocol=ttrpc version=3 Oct 28 12:41:36.640329 containerd[1566]: time="2025-10-28T12:41:36.640160976Z" level=info msg="connecting to shim 88a0c22218304ae18246242c9651d38fb6dfedd79a43ecfebbbe4643a40c5791" address="unix:///run/containerd/s/b2ba6b4277d4d8d4e32e87938f7f941d49a4ae7355b6dd1b050ef21d80f26bdc" namespace=k8s.io protocol=ttrpc version=3 Oct 28 12:41:36.660858 systemd[1]: Started cri-containerd-88a0c22218304ae18246242c9651d38fb6dfedd79a43ecfebbbe4643a40c5791.scope - libcontainer container 88a0c22218304ae18246242c9651d38fb6dfedd79a43ecfebbbe4643a40c5791. Oct 28 12:41:36.663532 systemd[1]: Started cri-containerd-9dcec90459b9aaea4a4e13be02041a55d6e8bbf13ed1ae19be13a4e319fcab2a.scope - libcontainer container 9dcec90459b9aaea4a4e13be02041a55d6e8bbf13ed1ae19be13a4e319fcab2a. Oct 28 12:41:36.684358 containerd[1566]: time="2025-10-28T12:41:36.684323150Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-l6rj7,Uid:b262a00f-5d13-4ff9-9617-0cfb30e72b86,Namespace:kube-system,Attempt:0,} returns sandbox id \"88a0c22218304ae18246242c9651d38fb6dfedd79a43ecfebbbe4643a40c5791\"" Oct 28 12:41:36.685974 kubelet[1857]: E1028 12:41:36.685949 1857 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 28 12:41:36.688012 containerd[1566]: time="2025-10-28T12:41:36.687732771Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\"" Oct 28 12:41:36.695481 containerd[1566]: time="2025-10-28T12:41:36.695454644Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-ppjnw,Uid:f729f0ba-c251-473e-90b8-c3e69e2cbfbe,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"9dcec90459b9aaea4a4e13be02041a55d6e8bbf13ed1ae19be13a4e319fcab2a\"" Oct 28 12:41:36.696066 kubelet[1857]: E1028 12:41:36.696045 1857 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 28 12:41:36.780526 kubelet[1857]: E1028 12:41:36.780501 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:37.680620 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount398272212.mount: Deactivated successfully. Oct 28 12:41:37.780826 kubelet[1857]: E1028 12:41:37.780779 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:37.904196 containerd[1566]: time="2025-10-28T12:41:37.904148940Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.33.5\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 12:41:37.904849 containerd[1566]: time="2025-10-28T12:41:37.904812159Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.33.5: active requests=0, bytes read=28236385" Oct 28 12:41:37.905540 containerd[1566]: time="2025-10-28T12:41:37.905496261Z" level=info msg="ImageCreate event name:\"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 12:41:37.907411 containerd[1566]: time="2025-10-28T12:41:37.907373153Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:71445ec84ad98bd52a7784865a9d31b1b50b56092d3f7699edc39eefd71befe1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 12:41:37.908117 containerd[1566]: time="2025-10-28T12:41:37.908082193Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.33.5\" with image id \"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\", repo tag \"registry.k8s.io/kube-proxy:v1.33.5\", repo digest \"registry.k8s.io/kube-proxy@sha256:71445ec84ad98bd52a7784865a9d31b1b50b56092d3f7699edc39eefd71befe1\", size \"28239125\" in 1.220309045s" Oct 28 12:41:37.908152 containerd[1566]: time="2025-10-28T12:41:37.908116529Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\" returns image reference \"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\"" Oct 28 12:41:37.909134 containerd[1566]: time="2025-10-28T12:41:37.908956727Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Oct 28 12:41:37.911575 containerd[1566]: time="2025-10-28T12:41:37.911530853Z" level=info msg="CreateContainer within sandbox \"88a0c22218304ae18246242c9651d38fb6dfedd79a43ecfebbbe4643a40c5791\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Oct 28 12:41:37.919654 containerd[1566]: time="2025-10-28T12:41:37.918377011Z" level=info msg="Container 0c64e1f05983212b8e8fac4c7de2e8318cce53d3bd355257a119ea8df261bbb8: CDI devices from CRI Config.CDIDevices: []" Oct 28 12:41:37.926059 containerd[1566]: time="2025-10-28T12:41:37.926023891Z" level=info msg="CreateContainer within sandbox \"88a0c22218304ae18246242c9651d38fb6dfedd79a43ecfebbbe4643a40c5791\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"0c64e1f05983212b8e8fac4c7de2e8318cce53d3bd355257a119ea8df261bbb8\"" Oct 28 12:41:37.926895 containerd[1566]: time="2025-10-28T12:41:37.926869230Z" level=info msg="StartContainer for \"0c64e1f05983212b8e8fac4c7de2e8318cce53d3bd355257a119ea8df261bbb8\"" Oct 28 12:41:37.928310 containerd[1566]: time="2025-10-28T12:41:37.928285985Z" level=info msg="connecting to shim 0c64e1f05983212b8e8fac4c7de2e8318cce53d3bd355257a119ea8df261bbb8" address="unix:///run/containerd/s/b2ba6b4277d4d8d4e32e87938f7f941d49a4ae7355b6dd1b050ef21d80f26bdc" protocol=ttrpc version=3 Oct 28 12:41:37.946831 systemd[1]: Started cri-containerd-0c64e1f05983212b8e8fac4c7de2e8318cce53d3bd355257a119ea8df261bbb8.scope - libcontainer container 0c64e1f05983212b8e8fac4c7de2e8318cce53d3bd355257a119ea8df261bbb8. Oct 28 12:41:37.974789 containerd[1566]: time="2025-10-28T12:41:37.974658003Z" level=info msg="StartContainer for \"0c64e1f05983212b8e8fac4c7de2e8318cce53d3bd355257a119ea8df261bbb8\" returns successfully" Oct 28 12:41:38.774218 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount667382700.mount: Deactivated successfully. Oct 28 12:41:38.781292 kubelet[1857]: E1028 12:41:38.781265 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:38.804241 containerd[1566]: time="2025-10-28T12:41:38.803376506Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 12:41:38.804241 containerd[1566]: time="2025-10-28T12:41:38.804184660Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=0" Oct 28 12:41:38.804897 containerd[1566]: time="2025-10-28T12:41:38.804871234Z" level=info msg="ImageCreate event name:\"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 12:41:38.807343 containerd[1566]: time="2025-10-28T12:41:38.807303764Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 12:41:38.808564 containerd[1566]: time="2025-10-28T12:41:38.808499659Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" with image id \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\", repo tag \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\", repo digest \"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\", size \"5125394\" in 899.509605ms" Oct 28 12:41:38.808564 containerd[1566]: time="2025-10-28T12:41:38.808530004Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\"" Oct 28 12:41:38.811702 containerd[1566]: time="2025-10-28T12:41:38.811652359Z" level=info msg="CreateContainer within sandbox \"9dcec90459b9aaea4a4e13be02041a55d6e8bbf13ed1ae19be13a4e319fcab2a\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Oct 28 12:41:38.817376 containerd[1566]: time="2025-10-28T12:41:38.817340184Z" level=info msg="Container abec490e5305ed8b89a3da9e8e1c61d45f27eeed8d1ce66d18b5adfea7b64348: CDI devices from CRI Config.CDIDevices: []" Oct 28 12:41:38.822239 containerd[1566]: time="2025-10-28T12:41:38.822211345Z" level=info msg="CreateContainer within sandbox \"9dcec90459b9aaea4a4e13be02041a55d6e8bbf13ed1ae19be13a4e319fcab2a\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"abec490e5305ed8b89a3da9e8e1c61d45f27eeed8d1ce66d18b5adfea7b64348\"" Oct 28 12:41:38.822618 containerd[1566]: time="2025-10-28T12:41:38.822596477Z" level=info msg="StartContainer for \"abec490e5305ed8b89a3da9e8e1c61d45f27eeed8d1ce66d18b5adfea7b64348\"" Oct 28 12:41:38.824983 containerd[1566]: time="2025-10-28T12:41:38.824946040Z" level=info msg="connecting to shim abec490e5305ed8b89a3da9e8e1c61d45f27eeed8d1ce66d18b5adfea7b64348" address="unix:///run/containerd/s/e01bec76a2877c16ebfb0366f291d48ebdc7565eca91ae1b3fa1d7c3548d1812" protocol=ttrpc version=3 Oct 28 12:41:38.849796 systemd[1]: Started cri-containerd-abec490e5305ed8b89a3da9e8e1c61d45f27eeed8d1ce66d18b5adfea7b64348.scope - libcontainer container abec490e5305ed8b89a3da9e8e1c61d45f27eeed8d1ce66d18b5adfea7b64348. Oct 28 12:41:38.871973 containerd[1566]: time="2025-10-28T12:41:38.871922856Z" level=info msg="StartContainer for \"abec490e5305ed8b89a3da9e8e1c61d45f27eeed8d1ce66d18b5adfea7b64348\" returns successfully" Oct 28 12:41:38.872941 systemd[1]: cri-containerd-abec490e5305ed8b89a3da9e8e1c61d45f27eeed8d1ce66d18b5adfea7b64348.scope: Deactivated successfully. Oct 28 12:41:38.874942 containerd[1566]: time="2025-10-28T12:41:38.874912633Z" level=info msg="received exit event container_id:\"abec490e5305ed8b89a3da9e8e1c61d45f27eeed8d1ce66d18b5adfea7b64348\" id:\"abec490e5305ed8b89a3da9e8e1c61d45f27eeed8d1ce66d18b5adfea7b64348\" pid:2197 exited_at:{seconds:1761655298 nanos:874506268}" Oct 28 12:41:38.926017 kubelet[1857]: E1028 12:41:38.925900 1857 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 28 12:41:38.928449 kubelet[1857]: E1028 12:41:38.928395 1857 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 28 12:41:39.781799 kubelet[1857]: E1028 12:41:39.781766 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:39.930861 kubelet[1857]: E1028 12:41:39.930832 1857 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 28 12:41:39.930861 kubelet[1857]: E1028 12:41:39.930856 1857 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 28 12:41:39.931497 containerd[1566]: time="2025-10-28T12:41:39.931466179Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Oct 28 12:41:39.943833 kubelet[1857]: I1028 12:41:39.943782 1857 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-l6rj7" podStartSLOduration=3.721900211 podStartE2EDuration="4.943770016s" podCreationTimestamp="2025-10-28 12:41:35 +0000 UTC" firstStartedPulling="2025-10-28 12:41:36.686915766 +0000 UTC m=+2.676329285" lastFinishedPulling="2025-10-28 12:41:37.908785611 +0000 UTC m=+3.898199090" observedRunningTime="2025-10-28 12:41:38.947789917 +0000 UTC m=+4.937203436" watchObservedRunningTime="2025-10-28 12:41:39.943770016 +0000 UTC m=+5.933183535" Oct 28 12:41:40.782504 kubelet[1857]: E1028 12:41:40.782465 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:41.250483 containerd[1566]: time="2025-10-28T12:41:41.250425260Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 12:41:41.251475 containerd[1566]: time="2025-10-28T12:41:41.251382399Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=6583402" Oct 28 12:41:41.252153 containerd[1566]: time="2025-10-28T12:41:41.252123157Z" level=info msg="ImageCreate event name:\"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 12:41:41.255268 containerd[1566]: time="2025-10-28T12:41:41.255231364Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 12:41:41.256758 containerd[1566]: time="2025-10-28T12:41:41.256727634Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel:v0.26.7\" with image id \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\", repo tag \"ghcr.io/flannel-io/flannel:v0.26.7\", repo digest \"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\", size \"32412118\" in 1.325222385s" Oct 28 12:41:41.256806 containerd[1566]: time="2025-10-28T12:41:41.256757623Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\"" Oct 28 12:41:41.260230 containerd[1566]: time="2025-10-28T12:41:41.260200447Z" level=info msg="CreateContainer within sandbox \"9dcec90459b9aaea4a4e13be02041a55d6e8bbf13ed1ae19be13a4e319fcab2a\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Oct 28 12:41:41.268734 containerd[1566]: time="2025-10-28T12:41:41.268126987Z" level=info msg="Container 9fa54d2030c72d79b7f2725e1eae99b44a0213656c1838365b80c1e57422a2c1: CDI devices from CRI Config.CDIDevices: []" Oct 28 12:41:41.274089 containerd[1566]: time="2025-10-28T12:41:41.274047796Z" level=info msg="CreateContainer within sandbox \"9dcec90459b9aaea4a4e13be02041a55d6e8bbf13ed1ae19be13a4e319fcab2a\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"9fa54d2030c72d79b7f2725e1eae99b44a0213656c1838365b80c1e57422a2c1\"" Oct 28 12:41:41.274572 containerd[1566]: time="2025-10-28T12:41:41.274542182Z" level=info msg="StartContainer for \"9fa54d2030c72d79b7f2725e1eae99b44a0213656c1838365b80c1e57422a2c1\"" Oct 28 12:41:41.275417 containerd[1566]: time="2025-10-28T12:41:41.275395441Z" level=info msg="connecting to shim 9fa54d2030c72d79b7f2725e1eae99b44a0213656c1838365b80c1e57422a2c1" address="unix:///run/containerd/s/e01bec76a2877c16ebfb0366f291d48ebdc7565eca91ae1b3fa1d7c3548d1812" protocol=ttrpc version=3 Oct 28 12:41:41.291818 systemd[1]: Started cri-containerd-9fa54d2030c72d79b7f2725e1eae99b44a0213656c1838365b80c1e57422a2c1.scope - libcontainer container 9fa54d2030c72d79b7f2725e1eae99b44a0213656c1838365b80c1e57422a2c1. Oct 28 12:41:41.312517 systemd[1]: cri-containerd-9fa54d2030c72d79b7f2725e1eae99b44a0213656c1838365b80c1e57422a2c1.scope: Deactivated successfully. Oct 28 12:41:41.313107 containerd[1566]: time="2025-10-28T12:41:41.312953290Z" level=info msg="StartContainer for \"9fa54d2030c72d79b7f2725e1eae99b44a0213656c1838365b80c1e57422a2c1\" returns successfully" Oct 28 12:41:41.314220 containerd[1566]: time="2025-10-28T12:41:41.314142047Z" level=info msg="received exit event container_id:\"9fa54d2030c72d79b7f2725e1eae99b44a0213656c1838365b80c1e57422a2c1\" id:\"9fa54d2030c72d79b7f2725e1eae99b44a0213656c1838365b80c1e57422a2c1\" pid:2275 exited_at:{seconds:1761655301 nanos:313249938}" Oct 28 12:41:41.329645 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-9fa54d2030c72d79b7f2725e1eae99b44a0213656c1838365b80c1e57422a2c1-rootfs.mount: Deactivated successfully. Oct 28 12:41:41.354606 kubelet[1857]: I1028 12:41:41.354580 1857 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Oct 28 12:41:41.782764 kubelet[1857]: E1028 12:41:41.782723 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:41.935392 kubelet[1857]: E1028 12:41:41.935242 1857 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 28 12:41:41.938383 containerd[1566]: time="2025-10-28T12:41:41.938346180Z" level=info msg="CreateContainer within sandbox \"9dcec90459b9aaea4a4e13be02041a55d6e8bbf13ed1ae19be13a4e319fcab2a\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Oct 28 12:41:41.945040 containerd[1566]: time="2025-10-28T12:41:41.945008749Z" level=info msg="Container 98f031b6f3e9ed48364b60690e848dc300ddb27c5eafa2583427204b708817b1: CDI devices from CRI Config.CDIDevices: []" Oct 28 12:41:41.952246 containerd[1566]: time="2025-10-28T12:41:41.952198701Z" level=info msg="CreateContainer within sandbox \"9dcec90459b9aaea4a4e13be02041a55d6e8bbf13ed1ae19be13a4e319fcab2a\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"98f031b6f3e9ed48364b60690e848dc300ddb27c5eafa2583427204b708817b1\"" Oct 28 12:41:41.952981 containerd[1566]: time="2025-10-28T12:41:41.952955456Z" level=info msg="StartContainer for \"98f031b6f3e9ed48364b60690e848dc300ddb27c5eafa2583427204b708817b1\"" Oct 28 12:41:41.954010 containerd[1566]: time="2025-10-28T12:41:41.953978227Z" level=info msg="connecting to shim 98f031b6f3e9ed48364b60690e848dc300ddb27c5eafa2583427204b708817b1" address="unix:///run/containerd/s/e01bec76a2877c16ebfb0366f291d48ebdc7565eca91ae1b3fa1d7c3548d1812" protocol=ttrpc version=3 Oct 28 12:41:41.973857 systemd[1]: Started cri-containerd-98f031b6f3e9ed48364b60690e848dc300ddb27c5eafa2583427204b708817b1.scope - libcontainer container 98f031b6f3e9ed48364b60690e848dc300ddb27c5eafa2583427204b708817b1. Oct 28 12:41:41.997341 containerd[1566]: time="2025-10-28T12:41:41.997307380Z" level=info msg="StartContainer for \"98f031b6f3e9ed48364b60690e848dc300ddb27c5eafa2583427204b708817b1\" returns successfully" Oct 28 12:41:42.267160 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4155836415.mount: Deactivated successfully. Oct 28 12:41:42.783638 kubelet[1857]: E1028 12:41:42.783597 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:42.939692 kubelet[1857]: E1028 12:41:42.939437 1857 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 28 12:41:43.057037 systemd-networkd[1482]: flannel.1: Link UP Oct 28 12:41:43.057043 systemd-networkd[1482]: flannel.1: Gained carrier Oct 28 12:41:43.784072 kubelet[1857]: E1028 12:41:43.784014 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:43.941449 kubelet[1857]: E1028 12:41:43.941416 1857 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 28 12:41:44.071841 systemd-networkd[1482]: flannel.1: Gained IPv6LL Oct 28 12:41:44.784667 kubelet[1857]: E1028 12:41:44.784623 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:45.785798 kubelet[1857]: E1028 12:41:45.785760 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:46.453964 kubelet[1857]: I1028 12:41:46.453895 1857 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-ppjnw" podStartSLOduration=6.8929100519999995 podStartE2EDuration="11.453864053s" podCreationTimestamp="2025-10-28 12:41:35 +0000 UTC" firstStartedPulling="2025-10-28 12:41:36.69662637 +0000 UTC m=+2.686039889" lastFinishedPulling="2025-10-28 12:41:41.257580371 +0000 UTC m=+7.246993890" observedRunningTime="2025-10-28 12:41:42.949407809 +0000 UTC m=+8.938821328" watchObservedRunningTime="2025-10-28 12:41:46.453864053 +0000 UTC m=+12.443277573" Oct 28 12:41:46.462033 systemd[1]: Created slice kubepods-besteffort-pod016fb0b3_35a1_4c36_951f_ccfec6b3955a.slice - libcontainer container kubepods-besteffort-pod016fb0b3_35a1_4c36_951f_ccfec6b3955a.slice. Oct 28 12:41:46.563052 kubelet[1857]: I1028 12:41:46.563002 1857 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-n269d\" (UniqueName: \"kubernetes.io/projected/016fb0b3-35a1-4c36-951f-ccfec6b3955a-kube-api-access-n269d\") pod \"nginx-deployment-7fcdb87857-5gjjq\" (UID: \"016fb0b3-35a1-4c36-951f-ccfec6b3955a\") " pod="default/nginx-deployment-7fcdb87857-5gjjq" Oct 28 12:41:46.764305 containerd[1566]: time="2025-10-28T12:41:46.764209858Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-5gjjq,Uid:016fb0b3-35a1-4c36-951f-ccfec6b3955a,Namespace:default,Attempt:0,}" Oct 28 12:41:46.779072 systemd-networkd[1482]: cni0: Link UP Oct 28 12:41:46.779083 systemd-networkd[1482]: cni0: Gained carrier Oct 28 12:41:46.782469 systemd-networkd[1482]: cni0: Lost carrier Oct 28 12:41:46.784260 systemd-networkd[1482]: veth66a5ad44: Link UP Oct 28 12:41:46.786900 kubelet[1857]: E1028 12:41:46.786871 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:46.788051 kernel: cni0: port 1(veth66a5ad44) entered blocking state Oct 28 12:41:46.788111 kernel: cni0: port 1(veth66a5ad44) entered disabled state Oct 28 12:41:46.788130 kernel: veth66a5ad44: entered allmulticast mode Oct 28 12:41:46.790960 kernel: veth66a5ad44: entered promiscuous mode Oct 28 12:41:46.798827 kernel: cni0: port 1(veth66a5ad44) entered blocking state Oct 28 12:41:46.798895 kernel: cni0: port 1(veth66a5ad44) entered forwarding state Oct 28 12:41:46.798886 systemd-networkd[1482]: veth66a5ad44: Gained carrier Oct 28 12:41:46.799087 systemd-networkd[1482]: cni0: Gained carrier Oct 28 12:41:46.800492 containerd[1566]: 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), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000112950), "name":"cbr0", "type":"bridge"} Oct 28 12:41:46.800492 containerd[1566]: delegateAdd: netconf sent to delegate plugin: Oct 28 12:41:46.820832 containerd[1566]: {"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-10-28T12:41:46.820791148Z" level=info msg="connecting to shim 3982a939e3fe062b64d9645d51ced68dd735ca06ec85efc0e8dca4706cc69849" address="unix:///run/containerd/s/b2ad70cbca064da2a0a8dc04a24543d5236ae8bd51e830aa82745e5492f32926" namespace=k8s.io protocol=ttrpc version=3 Oct 28 12:41:46.845841 systemd[1]: Started cri-containerd-3982a939e3fe062b64d9645d51ced68dd735ca06ec85efc0e8dca4706cc69849.scope - libcontainer container 3982a939e3fe062b64d9645d51ced68dd735ca06ec85efc0e8dca4706cc69849. Oct 28 12:41:46.856892 systemd-resolved[1275]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 28 12:41:46.875313 containerd[1566]: time="2025-10-28T12:41:46.875277544Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-5gjjq,Uid:016fb0b3-35a1-4c36-951f-ccfec6b3955a,Namespace:default,Attempt:0,} returns sandbox id \"3982a939e3fe062b64d9645d51ced68dd735ca06ec85efc0e8dca4706cc69849\"" Oct 28 12:41:46.876772 containerd[1566]: time="2025-10-28T12:41:46.876742448Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 28 12:41:47.787476 kubelet[1857]: E1028 12:41:47.787417 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:48.104790 systemd-networkd[1482]: veth66a5ad44: Gained IPv6LL Oct 28 12:41:48.423826 systemd-networkd[1482]: cni0: Gained IPv6LL Oct 28 12:41:48.788096 kubelet[1857]: E1028 12:41:48.787990 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:48.794798 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2802927660.mount: Deactivated successfully. Oct 28 12:41:49.557078 containerd[1566]: time="2025-10-28T12:41:49.557024377Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 12:41:49.557616 containerd[1566]: time="2025-10-28T12:41:49.557572695Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=70001274" Oct 28 12:41:49.558481 containerd[1566]: time="2025-10-28T12:41:49.558458762Z" level=info msg="ImageCreate event name:\"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 12:41:49.560703 containerd[1566]: time="2025-10-28T12:41:49.560679174Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 12:41:49.561560 containerd[1566]: time="2025-10-28T12:41:49.561538780Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"70017022\" in 2.684764136s" Oct 28 12:41:49.561597 containerd[1566]: time="2025-10-28T12:41:49.561565281Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Oct 28 12:41:49.564889 containerd[1566]: time="2025-10-28T12:41:49.564854826Z" level=info msg="CreateContainer within sandbox \"3982a939e3fe062b64d9645d51ced68dd735ca06ec85efc0e8dca4706cc69849\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Oct 28 12:41:49.573681 containerd[1566]: time="2025-10-28T12:41:49.573252807Z" level=info msg="Container 99bb8b52fb2f108d3af98fd3bf8c847ca33f1d12b2138e6f70e7e8062f14da9c: CDI devices from CRI Config.CDIDevices: []" Oct 28 12:41:49.580815 containerd[1566]: time="2025-10-28T12:41:49.580784978Z" level=info msg="CreateContainer within sandbox \"3982a939e3fe062b64d9645d51ced68dd735ca06ec85efc0e8dca4706cc69849\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"99bb8b52fb2f108d3af98fd3bf8c847ca33f1d12b2138e6f70e7e8062f14da9c\"" Oct 28 12:41:49.581465 containerd[1566]: time="2025-10-28T12:41:49.581441822Z" level=info msg="StartContainer for \"99bb8b52fb2f108d3af98fd3bf8c847ca33f1d12b2138e6f70e7e8062f14da9c\"" Oct 28 12:41:49.582265 containerd[1566]: time="2025-10-28T12:41:49.582236776Z" level=info msg="connecting to shim 99bb8b52fb2f108d3af98fd3bf8c847ca33f1d12b2138e6f70e7e8062f14da9c" address="unix:///run/containerd/s/b2ad70cbca064da2a0a8dc04a24543d5236ae8bd51e830aa82745e5492f32926" protocol=ttrpc version=3 Oct 28 12:41:49.606816 systemd[1]: Started cri-containerd-99bb8b52fb2f108d3af98fd3bf8c847ca33f1d12b2138e6f70e7e8062f14da9c.scope - libcontainer container 99bb8b52fb2f108d3af98fd3bf8c847ca33f1d12b2138e6f70e7e8062f14da9c. Oct 28 12:41:49.628471 containerd[1566]: time="2025-10-28T12:41:49.628438965Z" level=info msg="StartContainer for \"99bb8b52fb2f108d3af98fd3bf8c847ca33f1d12b2138e6f70e7e8062f14da9c\" returns successfully" Oct 28 12:41:49.789064 kubelet[1857]: E1028 12:41:49.788993 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:49.960884 kubelet[1857]: I1028 12:41:49.960814 1857 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-5gjjq" podStartSLOduration=1.274426334 podStartE2EDuration="3.960799301s" podCreationTimestamp="2025-10-28 12:41:46 +0000 UTC" firstStartedPulling="2025-10-28 12:41:46.876188028 +0000 UTC m=+12.865601507" lastFinishedPulling="2025-10-28 12:41:49.562560955 +0000 UTC m=+15.551974474" observedRunningTime="2025-10-28 12:41:49.960776723 +0000 UTC m=+15.950190242" watchObservedRunningTime="2025-10-28 12:41:49.960799301 +0000 UTC m=+15.950212820" Oct 28 12:41:50.790127 kubelet[1857]: E1028 12:41:50.790069 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:51.790377 kubelet[1857]: E1028 12:41:51.790323 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:52.768626 systemd[1]: Created slice kubepods-besteffort-podb5edecda_f550_47eb_be78_9f03668d46bc.slice - libcontainer container kubepods-besteffort-podb5edecda_f550_47eb_be78_9f03668d46bc.slice. Oct 28 12:41:52.790832 kubelet[1857]: E1028 12:41:52.790785 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:52.797067 kubelet[1857]: I1028 12:41:52.797015 1857 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/b5edecda-f550-47eb-be78-9f03668d46bc-data\") pod \"nfs-server-provisioner-0\" (UID: \"b5edecda-f550-47eb-be78-9f03668d46bc\") " pod="default/nfs-server-provisioner-0" Oct 28 12:41:52.797126 kubelet[1857]: I1028 12:41:52.797079 1857 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-pr8dw\" (UniqueName: \"kubernetes.io/projected/b5edecda-f550-47eb-be78-9f03668d46bc-kube-api-access-pr8dw\") pod \"nfs-server-provisioner-0\" (UID: \"b5edecda-f550-47eb-be78-9f03668d46bc\") " pod="default/nfs-server-provisioner-0" Oct 28 12:41:53.071495 containerd[1566]: time="2025-10-28T12:41:53.071382836Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:b5edecda-f550-47eb-be78-9f03668d46bc,Namespace:default,Attempt:0,}" Oct 28 12:41:53.089124 systemd-networkd[1482]: veth1fb8a773: Link UP Oct 28 12:41:53.091687 kernel: cni0: port 2(veth1fb8a773) entered blocking state Oct 28 12:41:53.094048 kernel: cni0: port 2(veth1fb8a773) entered disabled state Oct 28 12:41:53.094127 kernel: veth1fb8a773: entered allmulticast mode Oct 28 12:41:53.094874 kernel: veth1fb8a773: entered promiscuous mode Oct 28 12:41:53.101165 kernel: cni0: port 2(veth1fb8a773) entered blocking state Oct 28 12:41:53.101227 kernel: cni0: port 2(veth1fb8a773) entered forwarding state Oct 28 12:41:53.101262 systemd-networkd[1482]: veth1fb8a773: Gained carrier Oct 28 12:41:53.103958 containerd[1566]: 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), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400000e9a0), "name":"cbr0", "type":"bridge"} Oct 28 12:41:53.103958 containerd[1566]: delegateAdd: netconf sent to delegate plugin: Oct 28 12:41:53.124521 containerd[1566]: {"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-10-28T12:41:53.124074811Z" level=info msg="connecting to shim a6ded058160a3e943be7f65adb414459ce72189967db312f7c5bf8443e44ac76" address="unix:///run/containerd/s/0070afaa5dcbbe8bcd9a1e9456870ac8ebfe3eecdab769aafc8d48da2de6f5ef" namespace=k8s.io protocol=ttrpc version=3 Oct 28 12:41:53.150827 systemd[1]: Started cri-containerd-a6ded058160a3e943be7f65adb414459ce72189967db312f7c5bf8443e44ac76.scope - libcontainer container a6ded058160a3e943be7f65adb414459ce72189967db312f7c5bf8443e44ac76. Oct 28 12:41:53.160443 systemd-resolved[1275]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 28 12:41:53.191287 containerd[1566]: time="2025-10-28T12:41:53.191239399Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:b5edecda-f550-47eb-be78-9f03668d46bc,Namespace:default,Attempt:0,} returns sandbox id \"a6ded058160a3e943be7f65adb414459ce72189967db312f7c5bf8443e44ac76\"" Oct 28 12:41:53.192638 containerd[1566]: time="2025-10-28T12:41:53.192607039Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Oct 28 12:41:53.791932 kubelet[1857]: E1028 12:41:53.791888 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:54.568205 systemd-networkd[1482]: veth1fb8a773: Gained IPv6LL Oct 28 12:41:54.779059 kubelet[1857]: E1028 12:41:54.779019 1857 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:54.792638 kubelet[1857]: E1028 12:41:54.792606 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:55.043319 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2490071232.mount: Deactivated successfully. Oct 28 12:41:55.793137 kubelet[1857]: E1028 12:41:55.793060 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:56.793586 kubelet[1857]: E1028 12:41:56.793544 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:57.794621 kubelet[1857]: E1028 12:41:57.794579 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:58.796218 kubelet[1857]: E1028 12:41:58.795431 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:59.392142 containerd[1566]: time="2025-10-28T12:41:59.392088037Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 12:41:59.392830 containerd[1566]: time="2025-10-28T12:41:59.392787636Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=71327840" Oct 28 12:41:59.393722 containerd[1566]: time="2025-10-28T12:41:59.393691683Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 12:41:59.396505 containerd[1566]: time="2025-10-28T12:41:59.396476238Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 12:41:59.397384 containerd[1566]: time="2025-10-28T12:41:59.397348157Z" 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 6.204703343s" Oct 28 12:41:59.397421 containerd[1566]: time="2025-10-28T12:41:59.397380004Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Oct 28 12:41:59.400497 containerd[1566]: time="2025-10-28T12:41:59.400450025Z" level=info msg="CreateContainer within sandbox \"a6ded058160a3e943be7f65adb414459ce72189967db312f7c5bf8443e44ac76\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Oct 28 12:41:59.407277 containerd[1566]: time="2025-10-28T12:41:59.406489523Z" level=info msg="Container 7b6f57c39e6c989466a6eb23ed2305c590e7b79c18137fb2b0fdb71c6c047f1c: CDI devices from CRI Config.CDIDevices: []" Oct 28 12:41:59.412755 containerd[1566]: time="2025-10-28T12:41:59.412625724Z" level=info msg="CreateContainer within sandbox \"a6ded058160a3e943be7f65adb414459ce72189967db312f7c5bf8443e44ac76\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"7b6f57c39e6c989466a6eb23ed2305c590e7b79c18137fb2b0fdb71c6c047f1c\"" Oct 28 12:41:59.413298 containerd[1566]: time="2025-10-28T12:41:59.413272391Z" level=info msg="StartContainer for \"7b6f57c39e6c989466a6eb23ed2305c590e7b79c18137fb2b0fdb71c6c047f1c\"" Oct 28 12:41:59.414192 containerd[1566]: time="2025-10-28T12:41:59.414147911Z" level=info msg="connecting to shim 7b6f57c39e6c989466a6eb23ed2305c590e7b79c18137fb2b0fdb71c6c047f1c" address="unix:///run/containerd/s/0070afaa5dcbbe8bcd9a1e9456870ac8ebfe3eecdab769aafc8d48da2de6f5ef" protocol=ttrpc version=3 Oct 28 12:41:59.441828 systemd[1]: Started cri-containerd-7b6f57c39e6c989466a6eb23ed2305c590e7b79c18137fb2b0fdb71c6c047f1c.scope - libcontainer container 7b6f57c39e6c989466a6eb23ed2305c590e7b79c18137fb2b0fdb71c6c047f1c. Oct 28 12:41:59.465062 containerd[1566]: time="2025-10-28T12:41:59.465028002Z" level=info msg="StartContainer for \"7b6f57c39e6c989466a6eb23ed2305c590e7b79c18137fb2b0fdb71c6c047f1c\" returns successfully" Oct 28 12:41:59.795699 kubelet[1857]: E1028 12:41:59.795534 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:41:59.981587 kubelet[1857]: I1028 12:41:59.981532 1857 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.7756691610000002 podStartE2EDuration="7.981517985s" podCreationTimestamp="2025-10-28 12:41:52 +0000 UTC" firstStartedPulling="2025-10-28 12:41:53.192387296 +0000 UTC m=+19.181800815" lastFinishedPulling="2025-10-28 12:41:59.39823612 +0000 UTC m=+25.387649639" observedRunningTime="2025-10-28 12:41:59.981398237 +0000 UTC m=+25.970811756" watchObservedRunningTime="2025-10-28 12:41:59.981517985 +0000 UTC m=+25.970931464" Oct 28 12:42:00.795754 kubelet[1857]: E1028 12:42:00.795699 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:42:01.796599 kubelet[1857]: E1028 12:42:01.796538 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:42:02.797073 kubelet[1857]: E1028 12:42:02.797030 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:42:03.797382 kubelet[1857]: E1028 12:42:03.797326 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:42:04.735672 systemd[1]: Created slice kubepods-besteffort-podd6e07581_428c_4ce2_a665_e3f528f2049b.slice - libcontainer container kubepods-besteffort-podd6e07581_428c_4ce2_a665_e3f528f2049b.slice. Oct 28 12:42:04.761388 kubelet[1857]: I1028 12:42:04.761358 1857 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-a1c2ad0d-36c1-48ac-a176-abbd9dc023d3\" (UniqueName: \"kubernetes.io/nfs/d6e07581-428c-4ce2-a665-e3f528f2049b-pvc-a1c2ad0d-36c1-48ac-a176-abbd9dc023d3\") pod \"test-pod-1\" (UID: \"d6e07581-428c-4ce2-a665-e3f528f2049b\") " pod="default/test-pod-1" Oct 28 12:42:04.761478 kubelet[1857]: I1028 12:42:04.761396 1857 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-h8slf\" (UniqueName: \"kubernetes.io/projected/d6e07581-428c-4ce2-a665-e3f528f2049b-kube-api-access-h8slf\") pod \"test-pod-1\" (UID: \"d6e07581-428c-4ce2-a665-e3f528f2049b\") " pod="default/test-pod-1" Oct 28 12:42:04.798277 kubelet[1857]: E1028 12:42:04.798254 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:42:04.879757 kernel: netfs: FS-Cache loaded Oct 28 12:42:04.903040 kernel: RPC: Registered named UNIX socket transport module. Oct 28 12:42:04.903122 kernel: RPC: Registered udp transport module. Oct 28 12:42:04.903140 kernel: RPC: Registered tcp transport module. Oct 28 12:42:04.904251 kernel: RPC: Registered tcp-with-tls transport module. Oct 28 12:42:04.904290 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Oct 28 12:42:05.078948 kernel: NFS: Registering the id_resolver key type Oct 28 12:42:05.079097 kernel: Key type id_resolver registered Oct 28 12:42:05.079117 kernel: Key type id_legacy registered Oct 28 12:42:05.096692 nfsidmap[2864]: 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 Oct 28 12:42:05.097448 nfsidmap[2864]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Oct 28 12:42:05.100241 nfsidmap[2867]: 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 Oct 28 12:42:05.100384 nfsidmap[2867]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Oct 28 12:42:05.106167 nfsrahead[2871]: setting /var/lib/kubelet/pods/d6e07581-428c-4ce2-a665-e3f528f2049b/volumes/kubernetes.io~nfs/pvc-a1c2ad0d-36c1-48ac-a176-abbd9dc023d3 readahead to 128 Oct 28 12:42:05.338238 containerd[1566]: time="2025-10-28T12:42:05.338191745Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:d6e07581-428c-4ce2-a665-e3f528f2049b,Namespace:default,Attempt:0,}" Oct 28 12:42:05.348254 systemd-networkd[1482]: veth60ff23b2: Link UP Oct 28 12:42:05.350424 kernel: cni0: port 3(veth60ff23b2) entered blocking state Oct 28 12:42:05.350527 kernel: cni0: port 3(veth60ff23b2) entered disabled state Oct 28 12:42:05.350552 kernel: veth60ff23b2: entered allmulticast mode Oct 28 12:42:05.351917 kernel: veth60ff23b2: entered promiscuous mode Oct 28 12:42:05.359099 kernel: cni0: port 3(veth60ff23b2) entered blocking state Oct 28 12:42:05.359152 kernel: cni0: port 3(veth60ff23b2) entered forwarding state Oct 28 12:42:05.359282 systemd-networkd[1482]: veth60ff23b2: Gained carrier Oct 28 12:42:05.360933 containerd[1566]: 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), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400000e9a0), "name":"cbr0", "type":"bridge"} Oct 28 12:42:05.360933 containerd[1566]: delegateAdd: netconf sent to delegate plugin: Oct 28 12:42:05.379119 containerd[1566]: {"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-10-28T12:42:05.379071426Z" level=info msg="connecting to shim 15843f5dec7ceddd7fbeba17223f980ec044d49b2e4d9bdc4db132bc4d8507ea" address="unix:///run/containerd/s/f719c1836b20098568b16b13053fe7c0ee8b59d63a59d8a92b3fedac107fd769" namespace=k8s.io protocol=ttrpc version=3 Oct 28 12:42:05.399827 systemd[1]: Started cri-containerd-15843f5dec7ceddd7fbeba17223f980ec044d49b2e4d9bdc4db132bc4d8507ea.scope - libcontainer container 15843f5dec7ceddd7fbeba17223f980ec044d49b2e4d9bdc4db132bc4d8507ea. Oct 28 12:42:05.409064 systemd-resolved[1275]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 28 12:42:05.427149 containerd[1566]: time="2025-10-28T12:42:05.427102909Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:d6e07581-428c-4ce2-a665-e3f528f2049b,Namespace:default,Attempt:0,} returns sandbox id \"15843f5dec7ceddd7fbeba17223f980ec044d49b2e4d9bdc4db132bc4d8507ea\"" Oct 28 12:42:05.428426 containerd[1566]: time="2025-10-28T12:42:05.428402230Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 28 12:42:05.676556 containerd[1566]: time="2025-10-28T12:42:05.676431778Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 12:42:05.677051 containerd[1566]: time="2025-10-28T12:42:05.676999968Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=0" Oct 28 12:42:05.679829 containerd[1566]: time="2025-10-28T12:42:05.679794192Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"70017022\" in 251.358118ms" Oct 28 12:42:05.679868 containerd[1566]: time="2025-10-28T12:42:05.679829317Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Oct 28 12:42:05.683640 containerd[1566]: time="2025-10-28T12:42:05.683604342Z" level=info msg="CreateContainer within sandbox \"15843f5dec7ceddd7fbeba17223f980ec044d49b2e4d9bdc4db132bc4d8507ea\" for container &ContainerMetadata{Name:test,Attempt:0,}" Oct 28 12:42:05.689376 containerd[1566]: time="2025-10-28T12:42:05.689334169Z" level=info msg="Container 23d1c410cc054d2c60ddecce7a559ff2d616f071ab05344c576c8675b6ece4c9: CDI devices from CRI Config.CDIDevices: []" Oct 28 12:42:05.694613 containerd[1566]: time="2025-10-28T12:42:05.694560733Z" level=info msg="CreateContainer within sandbox \"15843f5dec7ceddd7fbeba17223f980ec044d49b2e4d9bdc4db132bc4d8507ea\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"23d1c410cc054d2c60ddecce7a559ff2d616f071ab05344c576c8675b6ece4c9\"" Oct 28 12:42:05.695694 containerd[1566]: time="2025-10-28T12:42:05.694948181Z" level=info msg="StartContainer for \"23d1c410cc054d2c60ddecce7a559ff2d616f071ab05344c576c8675b6ece4c9\"" Oct 28 12:42:05.695839 containerd[1566]: time="2025-10-28T12:42:05.695813768Z" level=info msg="connecting to shim 23d1c410cc054d2c60ddecce7a559ff2d616f071ab05344c576c8675b6ece4c9" address="unix:///run/containerd/s/f719c1836b20098568b16b13053fe7c0ee8b59d63a59d8a92b3fedac107fd769" protocol=ttrpc version=3 Oct 28 12:42:05.714795 systemd[1]: Started cri-containerd-23d1c410cc054d2c60ddecce7a559ff2d616f071ab05344c576c8675b6ece4c9.scope - libcontainer container 23d1c410cc054d2c60ddecce7a559ff2d616f071ab05344c576c8675b6ece4c9. Oct 28 12:42:05.737392 containerd[1566]: time="2025-10-28T12:42:05.737356171Z" level=info msg="StartContainer for \"23d1c410cc054d2c60ddecce7a559ff2d616f071ab05344c576c8675b6ece4c9\" returns successfully" Oct 28 12:42:05.799386 kubelet[1857]: E1028 12:42:05.799332 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:42:05.990500 kubelet[1857]: I1028 12:42:05.990357 1857 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=12.737882435 podStartE2EDuration="12.99034241s" podCreationTimestamp="2025-10-28 12:41:53 +0000 UTC" firstStartedPulling="2025-10-28 12:42:05.427977417 +0000 UTC m=+31.417390936" lastFinishedPulling="2025-10-28 12:42:05.680437392 +0000 UTC m=+31.669850911" observedRunningTime="2025-10-28 12:42:05.990215154 +0000 UTC m=+31.979628633" watchObservedRunningTime="2025-10-28 12:42:05.99034241 +0000 UTC m=+31.979755929" Oct 28 12:42:06.599908 systemd-networkd[1482]: veth60ff23b2: Gained IPv6LL Oct 28 12:42:06.799803 kubelet[1857]: E1028 12:42:06.799745 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:42:07.799888 kubelet[1857]: E1028 12:42:07.799834 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:42:08.799991 kubelet[1857]: E1028 12:42:08.799950 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:42:09.801129 kubelet[1857]: E1028 12:42:09.801085 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 12:42:10.801530 kubelet[1857]: E1028 12:42:10.801492 1857 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"