Sep 12 17:19:36.767686 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Sep 12 17:19:36.767707 kernel: Linux version 6.12.47-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT Fri Sep 12 15:37:01 -00 2025 Sep 12 17:19:36.767716 kernel: KASLR enabled Sep 12 17:19:36.767722 kernel: efi: EFI v2.7 by EDK II Sep 12 17:19:36.767727 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb228018 ACPI 2.0=0xdb9b8018 RNG=0xdb9b8a18 MEMRESERVE=0xdb21fd18 Sep 12 17:19:36.767733 kernel: random: crng init done Sep 12 17:19:36.767740 kernel: Kernel is locked down from EFI Secure Boot; see man kernel_lockdown.7 Sep 12 17:19:36.767745 kernel: secureboot: Secure boot enabled Sep 12 17:19:36.767751 kernel: ACPI: Early table checksum verification disabled Sep 12 17:19:36.767758 kernel: ACPI: RSDP 0x00000000DB9B8018 000024 (v02 BOCHS ) Sep 12 17:19:36.767764 kernel: ACPI: XSDT 0x00000000DB9B8F18 000064 (v01 BOCHS BXPC 00000001 01000013) Sep 12 17:19:36.767769 kernel: ACPI: FACP 0x00000000DB9B8B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 17:19:36.767775 kernel: ACPI: DSDT 0x00000000DB904018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 17:19:36.767781 kernel: ACPI: APIC 0x00000000DB9B8C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 17:19:36.767788 kernel: ACPI: PPTT 0x00000000DB9B8098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 17:19:36.767795 kernel: ACPI: GTDT 0x00000000DB9B8818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 17:19:36.767801 kernel: ACPI: MCFG 0x00000000DB9B8A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 17:19:36.767807 kernel: ACPI: SPCR 0x00000000DB9B8918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 17:19:36.767813 kernel: ACPI: DBG2 0x00000000DB9B8998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 17:19:36.767819 kernel: ACPI: IORT 0x00000000DB9B8198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 17:19:36.767825 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Sep 12 17:19:36.767832 kernel: ACPI: Use ACPI SPCR as default console: No Sep 12 17:19:36.767838 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Sep 12 17:19:36.767844 kernel: NODE_DATA(0) allocated [mem 0xdc737a00-0xdc73efff] Sep 12 17:19:36.767850 kernel: Zone ranges: Sep 12 17:19:36.767857 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Sep 12 17:19:36.767863 kernel: DMA32 empty Sep 12 17:19:36.767869 kernel: Normal empty Sep 12 17:19:36.767875 kernel: Device empty Sep 12 17:19:36.767880 kernel: Movable zone start for each node Sep 12 17:19:36.767886 kernel: Early memory node ranges Sep 12 17:19:36.767892 kernel: node 0: [mem 0x0000000040000000-0x00000000dbb4ffff] Sep 12 17:19:36.767898 kernel: node 0: [mem 0x00000000dbb50000-0x00000000dbe7ffff] Sep 12 17:19:36.767905 kernel: node 0: [mem 0x00000000dbe80000-0x00000000dbe9ffff] Sep 12 17:19:36.767911 kernel: node 0: [mem 0x00000000dbea0000-0x00000000dbedffff] Sep 12 17:19:36.767917 kernel: node 0: [mem 0x00000000dbee0000-0x00000000dbf1ffff] Sep 12 17:19:36.767923 kernel: node 0: [mem 0x00000000dbf20000-0x00000000dbf6ffff] Sep 12 17:19:36.767930 kernel: node 0: [mem 0x00000000dbf70000-0x00000000dcbfffff] Sep 12 17:19:36.767936 kernel: node 0: [mem 0x00000000dcc00000-0x00000000dcfdffff] Sep 12 17:19:36.767942 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Sep 12 17:19:36.767950 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Sep 12 17:19:36.767957 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Sep 12 17:19:36.767963 kernel: cma: Reserved 16 MiB at 0x00000000d7a00000 on node -1 Sep 12 17:19:36.767970 kernel: psci: probing for conduit method from ACPI. Sep 12 17:19:36.767977 kernel: psci: PSCIv1.1 detected in firmware. Sep 12 17:19:36.767983 kernel: psci: Using standard PSCI v0.2 function IDs Sep 12 17:19:36.767990 kernel: psci: Trusted OS migration not required Sep 12 17:19:36.767996 kernel: psci: SMC Calling Convention v1.1 Sep 12 17:19:36.768003 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Sep 12 17:19:36.768009 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Sep 12 17:19:36.768015 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Sep 12 17:19:36.768022 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Sep 12 17:19:36.768028 kernel: Detected PIPT I-cache on CPU0 Sep 12 17:19:36.768036 kernel: CPU features: detected: GIC system register CPU interface Sep 12 17:19:36.768042 kernel: CPU features: detected: Spectre-v4 Sep 12 17:19:36.768049 kernel: CPU features: detected: Spectre-BHB Sep 12 17:19:36.768055 kernel: CPU features: kernel page table isolation forced ON by KASLR Sep 12 17:19:36.768061 kernel: CPU features: detected: Kernel page table isolation (KPTI) Sep 12 17:19:36.768068 kernel: CPU features: detected: ARM erratum 1418040 Sep 12 17:19:36.768074 kernel: CPU features: detected: SSBS not fully self-synchronizing Sep 12 17:19:36.768080 kernel: alternatives: applying boot alternatives Sep 12 17:19:36.768088 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=9b01894f6bb04aff3ec9b8554b3ae56a087d51961f1a01981bc4d4f54ccefc09 Sep 12 17:19:36.768094 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 12 17:19:36.768101 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 12 17:19:36.768109 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 12 17:19:36.768115 kernel: Fallback order for Node 0: 0 Sep 12 17:19:36.768121 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 Sep 12 17:19:36.768127 kernel: Policy zone: DMA Sep 12 17:19:36.768134 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 12 17:19:36.768140 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB Sep 12 17:19:36.768146 kernel: software IO TLB: area num 4. Sep 12 17:19:36.768153 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB Sep 12 17:19:36.768159 kernel: software IO TLB: mapped [mem 0x00000000db504000-0x00000000db904000] (4MB) Sep 12 17:19:36.768165 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 12 17:19:36.768172 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 12 17:19:36.768179 kernel: rcu: RCU event tracing is enabled. Sep 12 17:19:36.768186 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Sep 12 17:19:36.768193 kernel: Trampoline variant of Tasks RCU enabled. Sep 12 17:19:36.768199 kernel: Tracing variant of Tasks RCU enabled. Sep 12 17:19:36.768206 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 12 17:19:36.768212 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 12 17:19:36.768219 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 12 17:19:36.768225 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 12 17:19:36.768232 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Sep 12 17:19:36.768238 kernel: GICv3: 256 SPIs implemented Sep 12 17:19:36.768244 kernel: GICv3: 0 Extended SPIs implemented Sep 12 17:19:36.768251 kernel: Root IRQ handler: gic_handle_irq Sep 12 17:19:36.768259 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Sep 12 17:19:36.768265 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 Sep 12 17:19:36.768271 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Sep 12 17:19:36.768278 kernel: ITS [mem 0x08080000-0x0809ffff] Sep 12 17:19:36.768284 kernel: ITS@0x0000000008080000: allocated 8192 Devices @40110000 (indirect, esz 8, psz 64K, shr 1) Sep 12 17:19:36.768291 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @40120000 (flat, esz 8, psz 64K, shr 1) Sep 12 17:19:36.768297 kernel: GICv3: using LPI property table @0x0000000040130000 Sep 12 17:19:36.768310 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040140000 Sep 12 17:19:36.768317 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 12 17:19:36.768324 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 12 17:19:36.768330 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Sep 12 17:19:36.768336 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Sep 12 17:19:36.768345 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Sep 12 17:19:36.768352 kernel: arm-pv: using stolen time PV Sep 12 17:19:36.768358 kernel: Console: colour dummy device 80x25 Sep 12 17:19:36.768365 kernel: ACPI: Core revision 20240827 Sep 12 17:19:36.768376 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Sep 12 17:19:36.768384 kernel: pid_max: default: 32768 minimum: 301 Sep 12 17:19:36.768393 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 12 17:19:36.768402 kernel: landlock: Up and running. Sep 12 17:19:36.768410 kernel: SELinux: Initializing. Sep 12 17:19:36.768418 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 12 17:19:36.768425 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 12 17:19:36.768431 kernel: rcu: Hierarchical SRCU implementation. Sep 12 17:19:36.768438 kernel: rcu: Max phase no-delay instances is 400. Sep 12 17:19:36.768445 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 12 17:19:36.768451 kernel: Remapping and enabling EFI services. Sep 12 17:19:36.768458 kernel: smp: Bringing up secondary CPUs ... Sep 12 17:19:36.768464 kernel: Detected PIPT I-cache on CPU1 Sep 12 17:19:36.768471 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Sep 12 17:19:36.768486 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040150000 Sep 12 17:19:36.768511 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 12 17:19:36.768518 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Sep 12 17:19:36.768527 kernel: Detected PIPT I-cache on CPU2 Sep 12 17:19:36.768534 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Sep 12 17:19:36.768541 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040160000 Sep 12 17:19:36.768548 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 12 17:19:36.768555 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Sep 12 17:19:36.768562 kernel: Detected PIPT I-cache on CPU3 Sep 12 17:19:36.768570 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Sep 12 17:19:36.768578 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040170000 Sep 12 17:19:36.768585 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 12 17:19:36.768591 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Sep 12 17:19:36.768598 kernel: smp: Brought up 1 node, 4 CPUs Sep 12 17:19:36.768605 kernel: SMP: Total of 4 processors activated. Sep 12 17:19:36.768612 kernel: CPU: All CPU(s) started at EL1 Sep 12 17:19:36.768619 kernel: CPU features: detected: 32-bit EL0 Support Sep 12 17:19:36.768625 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Sep 12 17:19:36.768634 kernel: CPU features: detected: Common not Private translations Sep 12 17:19:36.768641 kernel: CPU features: detected: CRC32 instructions Sep 12 17:19:36.768648 kernel: CPU features: detected: Enhanced Virtualization Traps Sep 12 17:19:36.768655 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Sep 12 17:19:36.768662 kernel: CPU features: detected: LSE atomic instructions Sep 12 17:19:36.768669 kernel: CPU features: detected: Privileged Access Never Sep 12 17:19:36.768676 kernel: CPU features: detected: RAS Extension Support Sep 12 17:19:36.768683 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Sep 12 17:19:36.768690 kernel: alternatives: applying system-wide alternatives Sep 12 17:19:36.768698 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Sep 12 17:19:36.768706 kernel: Memory: 2422436K/2572288K available (11136K kernel code, 2440K rwdata, 9068K rodata, 38912K init, 1038K bss, 127516K reserved, 16384K cma-reserved) Sep 12 17:19:36.768713 kernel: devtmpfs: initialized Sep 12 17:19:36.768720 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 12 17:19:36.768727 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 12 17:19:36.768734 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Sep 12 17:19:36.768741 kernel: 0 pages in range for non-PLT usage Sep 12 17:19:36.768748 kernel: 508576 pages in range for PLT usage Sep 12 17:19:36.768754 kernel: pinctrl core: initialized pinctrl subsystem Sep 12 17:19:36.768763 kernel: SMBIOS 3.0.0 present. Sep 12 17:19:36.768770 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Sep 12 17:19:36.768777 kernel: DMI: Memory slots populated: 1/1 Sep 12 17:19:36.768784 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 12 17:19:36.768791 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Sep 12 17:19:36.768798 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Sep 12 17:19:36.768805 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Sep 12 17:19:36.768812 kernel: audit: initializing netlink subsys (disabled) Sep 12 17:19:36.768819 kernel: audit: type=2000 audit(0.023:1): state=initialized audit_enabled=0 res=1 Sep 12 17:19:36.768828 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 12 17:19:36.768835 kernel: cpuidle: using governor menu Sep 12 17:19:36.768841 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Sep 12 17:19:36.768848 kernel: ASID allocator initialised with 32768 entries Sep 12 17:19:36.768855 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 12 17:19:36.768862 kernel: Serial: AMBA PL011 UART driver Sep 12 17:19:36.768869 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 12 17:19:36.768876 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Sep 12 17:19:36.768884 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Sep 12 17:19:36.768891 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Sep 12 17:19:36.768898 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 12 17:19:36.768905 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Sep 12 17:19:36.768912 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Sep 12 17:19:36.768919 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Sep 12 17:19:36.768926 kernel: ACPI: Added _OSI(Module Device) Sep 12 17:19:36.768933 kernel: ACPI: Added _OSI(Processor Device) Sep 12 17:19:36.768940 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 12 17:19:36.768946 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 12 17:19:36.768954 kernel: ACPI: Interpreter enabled Sep 12 17:19:36.768961 kernel: ACPI: Using GIC for interrupt routing Sep 12 17:19:36.768968 kernel: ACPI: MCFG table detected, 1 entries Sep 12 17:19:36.768975 kernel: ACPI: CPU0 has been hot-added Sep 12 17:19:36.768982 kernel: ACPI: CPU1 has been hot-added Sep 12 17:19:36.768988 kernel: ACPI: CPU2 has been hot-added Sep 12 17:19:36.768995 kernel: ACPI: CPU3 has been hot-added Sep 12 17:19:36.769002 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Sep 12 17:19:36.769009 kernel: printk: legacy console [ttyAMA0] enabled Sep 12 17:19:36.769017 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 12 17:19:36.769141 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 12 17:19:36.769207 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Sep 12 17:19:36.769265 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Sep 12 17:19:36.769331 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Sep 12 17:19:36.769389 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Sep 12 17:19:36.769398 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Sep 12 17:19:36.769413 kernel: PCI host bridge to bus 0000:00 Sep 12 17:19:36.769997 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Sep 12 17:19:36.770077 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Sep 12 17:19:36.770131 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Sep 12 17:19:36.770184 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 12 17:19:36.770261 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint Sep 12 17:19:36.770347 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Sep 12 17:19:36.770415 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] Sep 12 17:19:36.770474 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] Sep 12 17:19:36.770559 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] Sep 12 17:19:36.770627 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned Sep 12 17:19:36.770703 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned Sep 12 17:19:36.770769 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned Sep 12 17:19:36.770829 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Sep 12 17:19:36.770881 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Sep 12 17:19:36.770934 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Sep 12 17:19:36.770943 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Sep 12 17:19:36.770950 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Sep 12 17:19:36.770957 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Sep 12 17:19:36.770965 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Sep 12 17:19:36.770971 kernel: iommu: Default domain type: Translated Sep 12 17:19:36.770980 kernel: iommu: DMA domain TLB invalidation policy: strict mode Sep 12 17:19:36.770987 kernel: efivars: Registered efivars operations Sep 12 17:19:36.770994 kernel: vgaarb: loaded Sep 12 17:19:36.771001 kernel: clocksource: Switched to clocksource arch_sys_counter Sep 12 17:19:36.771008 kernel: VFS: Disk quotas dquot_6.6.0 Sep 12 17:19:36.771015 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 12 17:19:36.771022 kernel: pnp: PnP ACPI init Sep 12 17:19:36.771088 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Sep 12 17:19:36.771098 kernel: pnp: PnP ACPI: found 1 devices Sep 12 17:19:36.771107 kernel: NET: Registered PF_INET protocol family Sep 12 17:19:36.771114 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 12 17:19:36.771121 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 12 17:19:36.771128 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 12 17:19:36.771135 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 12 17:19:36.771142 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 12 17:19:36.771149 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 12 17:19:36.771156 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 12 17:19:36.771163 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 12 17:19:36.771171 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 12 17:19:36.771179 kernel: PCI: CLS 0 bytes, default 64 Sep 12 17:19:36.771186 kernel: kvm [1]: HYP mode not available Sep 12 17:19:36.771192 kernel: Initialise system trusted keyrings Sep 12 17:19:36.771199 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 12 17:19:36.771206 kernel: Key type asymmetric registered Sep 12 17:19:36.771213 kernel: Asymmetric key parser 'x509' registered Sep 12 17:19:36.771220 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Sep 12 17:19:36.771227 kernel: io scheduler mq-deadline registered Sep 12 17:19:36.771249 kernel: io scheduler kyber registered Sep 12 17:19:36.771256 kernel: io scheduler bfq registered Sep 12 17:19:36.771263 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Sep 12 17:19:36.771270 kernel: ACPI: button: Power Button [PWRB] Sep 12 17:19:36.771277 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Sep 12 17:19:36.771347 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Sep 12 17:19:36.771357 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 12 17:19:36.771364 kernel: thunder_xcv, ver 1.0 Sep 12 17:19:36.771371 kernel: thunder_bgx, ver 1.0 Sep 12 17:19:36.771380 kernel: nicpf, ver 1.0 Sep 12 17:19:36.771387 kernel: nicvf, ver 1.0 Sep 12 17:19:36.771458 kernel: rtc-efi rtc-efi.0: registered as rtc0 Sep 12 17:19:36.771542 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-09-12T17:19:36 UTC (1757697576) Sep 12 17:19:36.771552 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 12 17:19:36.771559 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Sep 12 17:19:36.771566 kernel: watchdog: NMI not fully supported Sep 12 17:19:36.771573 kernel: watchdog: Hard watchdog permanently disabled Sep 12 17:19:36.771583 kernel: NET: Registered PF_INET6 protocol family Sep 12 17:19:36.771589 kernel: Segment Routing with IPv6 Sep 12 17:19:36.771596 kernel: In-situ OAM (IOAM) with IPv6 Sep 12 17:19:36.771603 kernel: NET: Registered PF_PACKET protocol family Sep 12 17:19:36.771610 kernel: Key type dns_resolver registered Sep 12 17:19:36.771618 kernel: registered taskstats version 1 Sep 12 17:19:36.771624 kernel: Loading compiled-in X.509 certificates Sep 12 17:19:36.771631 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.47-flatcar: 7675c1947f324bc6524fdc1ee0f8f5f343acfea7' Sep 12 17:19:36.771638 kernel: Demotion targets for Node 0: null Sep 12 17:19:36.771647 kernel: Key type .fscrypt registered Sep 12 17:19:36.771654 kernel: Key type fscrypt-provisioning registered Sep 12 17:19:36.771661 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 12 17:19:36.771668 kernel: ima: Allocated hash algorithm: sha1 Sep 12 17:19:36.771675 kernel: ima: No architecture policies found Sep 12 17:19:36.771682 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Sep 12 17:19:36.771689 kernel: clk: Disabling unused clocks Sep 12 17:19:36.771696 kernel: PM: genpd: Disabling unused power domains Sep 12 17:19:36.771703 kernel: Warning: unable to open an initial console. Sep 12 17:19:36.771711 kernel: Freeing unused kernel memory: 38912K Sep 12 17:19:36.771718 kernel: Run /init as init process Sep 12 17:19:36.771725 kernel: with arguments: Sep 12 17:19:36.771732 kernel: /init Sep 12 17:19:36.771739 kernel: with environment: Sep 12 17:19:36.771746 kernel: HOME=/ Sep 12 17:19:36.771753 kernel: TERM=linux Sep 12 17:19:36.771759 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 12 17:19:36.771767 systemd[1]: Successfully made /usr/ read-only. Sep 12 17:19:36.771779 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 12 17:19:36.771787 systemd[1]: Detected virtualization kvm. Sep 12 17:19:36.771794 systemd[1]: Detected architecture arm64. Sep 12 17:19:36.771801 systemd[1]: Running in initrd. Sep 12 17:19:36.771808 systemd[1]: No hostname configured, using default hostname. Sep 12 17:19:36.771816 systemd[1]: Hostname set to . Sep 12 17:19:36.771824 systemd[1]: Initializing machine ID from VM UUID. Sep 12 17:19:36.771832 systemd[1]: Queued start job for default target initrd.target. Sep 12 17:19:36.771840 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 17:19:36.771848 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 17:19:36.771856 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 12 17:19:36.771863 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 12 17:19:36.771871 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 12 17:19:36.771879 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 12 17:19:36.771888 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 12 17:19:36.771896 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 12 17:19:36.771904 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 17:19:36.771912 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 12 17:19:36.771920 systemd[1]: Reached target paths.target - Path Units. Sep 12 17:19:36.771927 systemd[1]: Reached target slices.target - Slice Units. Sep 12 17:19:36.771934 systemd[1]: Reached target swap.target - Swaps. Sep 12 17:19:36.771942 systemd[1]: Reached target timers.target - Timer Units. Sep 12 17:19:36.771951 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 12 17:19:36.771958 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 12 17:19:36.771966 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 12 17:19:36.771973 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 12 17:19:36.771981 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 12 17:19:36.771988 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 12 17:19:36.771996 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 17:19:36.772004 systemd[1]: Reached target sockets.target - Socket Units. Sep 12 17:19:36.772013 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 12 17:19:36.772021 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 12 17:19:36.772028 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 12 17:19:36.772036 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Sep 12 17:19:36.772044 systemd[1]: Starting systemd-fsck-usr.service... Sep 12 17:19:36.772051 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 12 17:19:36.772059 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 12 17:19:36.772066 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 17:19:36.772074 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 12 17:19:36.772084 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 17:19:36.772091 systemd[1]: Finished systemd-fsck-usr.service. Sep 12 17:19:36.772113 systemd-journald[244]: Collecting audit messages is disabled. Sep 12 17:19:36.772134 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 12 17:19:36.772143 systemd-journald[244]: Journal started Sep 12 17:19:36.772161 systemd-journald[244]: Runtime Journal (/run/log/journal/5dde031e1aa0438a88bec7eac079e98b) is 6M, max 48.5M, 42.4M free. Sep 12 17:19:36.767300 systemd-modules-load[245]: Inserted module 'overlay' Sep 12 17:19:36.773988 systemd[1]: Started systemd-journald.service - Journal Service. Sep 12 17:19:36.780605 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:19:36.782387 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 12 17:19:36.783906 systemd-modules-load[245]: Inserted module 'br_netfilter' Sep 12 17:19:36.784857 kernel: Bridge firewalling registered Sep 12 17:19:36.784907 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 12 17:19:36.787066 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 12 17:19:36.788605 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 12 17:19:36.790262 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 12 17:19:36.795576 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 12 17:19:36.800604 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 12 17:19:36.801762 systemd-tmpfiles[268]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 12 17:19:36.803909 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 12 17:19:36.812590 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 17:19:36.815533 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 17:19:36.817797 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 12 17:19:36.820037 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 12 17:19:36.821071 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 17:19:36.839408 dracut-cmdline[287]: 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=9b01894f6bb04aff3ec9b8554b3ae56a087d51961f1a01981bc4d4f54ccefc09 Sep 12 17:19:36.853183 systemd-resolved[289]: Positive Trust Anchors: Sep 12 17:19:36.853207 systemd-resolved[289]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 12 17:19:36.853238 systemd-resolved[289]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 12 17:19:36.858043 systemd-resolved[289]: Defaulting to hostname 'linux'. Sep 12 17:19:36.858984 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 12 17:19:36.861381 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 12 17:19:36.910507 kernel: SCSI subsystem initialized Sep 12 17:19:36.915495 kernel: Loading iSCSI transport class v2.0-870. Sep 12 17:19:36.922503 kernel: iscsi: registered transport (tcp) Sep 12 17:19:36.935506 kernel: iscsi: registered transport (qla4xxx) Sep 12 17:19:36.935524 kernel: QLogic iSCSI HBA Driver Sep 12 17:19:36.951681 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 12 17:19:36.969884 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 12 17:19:36.971753 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 12 17:19:37.021538 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 12 17:19:37.024059 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 12 17:19:37.086514 kernel: raid6: neonx8 gen() 15612 MB/s Sep 12 17:19:37.103519 kernel: raid6: neonx4 gen() 15730 MB/s Sep 12 17:19:37.120504 kernel: raid6: neonx2 gen() 13250 MB/s Sep 12 17:19:37.137516 kernel: raid6: neonx1 gen() 9495 MB/s Sep 12 17:19:37.154504 kernel: raid6: int64x8 gen() 6747 MB/s Sep 12 17:19:37.171515 kernel: raid6: int64x4 gen() 7290 MB/s Sep 12 17:19:37.188513 kernel: raid6: int64x2 gen() 6010 MB/s Sep 12 17:19:37.205830 kernel: raid6: int64x1 gen() 4918 MB/s Sep 12 17:19:37.205846 kernel: raid6: using algorithm neonx4 gen() 15730 MB/s Sep 12 17:19:37.223820 kernel: raid6: .... xor() 12212 MB/s, rmw enabled Sep 12 17:19:37.223859 kernel: raid6: using neon recovery algorithm Sep 12 17:19:37.229501 kernel: xor: measuring software checksum speed Sep 12 17:19:37.230812 kernel: 8regs : 13996 MB/sec Sep 12 17:19:37.230828 kernel: 32regs : 21658 MB/sec Sep 12 17:19:37.232116 kernel: arm64_neon : 28013 MB/sec Sep 12 17:19:37.232128 kernel: xor: using function: arm64_neon (28013 MB/sec) Sep 12 17:19:37.283504 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 12 17:19:37.290226 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 12 17:19:37.293684 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 17:19:37.323754 systemd-udevd[500]: Using default interface naming scheme 'v255'. Sep 12 17:19:37.328333 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 17:19:37.330114 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 12 17:19:37.365106 dracut-pre-trigger[508]: rd.md=0: removing MD RAID activation Sep 12 17:19:37.388527 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 12 17:19:37.390415 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 12 17:19:37.438533 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 17:19:37.441017 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 12 17:19:37.490887 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 12 17:19:37.494459 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Sep 12 17:19:37.494597 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Sep 12 17:19:37.490955 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:19:37.497514 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 17:19:37.503470 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Sep 12 17:19:37.503507 kernel: GPT:9289727 != 19775487 Sep 12 17:19:37.503527 kernel: GPT:Alternate GPT header not at the end of the disk. Sep 12 17:19:37.503537 kernel: GPT:9289727 != 19775487 Sep 12 17:19:37.503555 kernel: GPT: Use GNU Parted to correct GPT errors. Sep 12 17:19:37.503564 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 12 17:19:37.500523 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 17:19:37.529639 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 12 17:19:37.530648 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:19:37.538995 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 12 17:19:37.545373 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 12 17:19:37.546469 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Sep 12 17:19:37.554661 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 12 17:19:37.561616 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 12 17:19:37.562562 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 12 17:19:37.564464 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 17:19:37.566401 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 12 17:19:37.568883 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 12 17:19:37.570456 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 12 17:19:37.594675 disk-uuid[591]: Primary Header is updated. Sep 12 17:19:37.594675 disk-uuid[591]: Secondary Entries is updated. Sep 12 17:19:37.594675 disk-uuid[591]: Secondary Header is updated. Sep 12 17:19:37.598536 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 12 17:19:37.600907 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 12 17:19:38.609734 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 12 17:19:38.609787 disk-uuid[596]: The operation has completed successfully. Sep 12 17:19:38.670000 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 12 17:19:38.670119 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 12 17:19:38.692670 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 12 17:19:38.707201 sh[610]: Success Sep 12 17:19:38.720033 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 12 17:19:38.720075 kernel: device-mapper: uevent: version 1.0.3 Sep 12 17:19:38.721625 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 12 17:19:38.731607 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Sep 12 17:19:38.766936 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 12 17:19:38.770345 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 12 17:19:38.786520 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 12 17:19:38.797543 kernel: BTRFS: device fsid 752cb955-bdfa-486a-ad02-b54d5e61d194 devid 1 transid 39 /dev/mapper/usr (253:0) scanned by mount (622) Sep 12 17:19:38.800005 kernel: BTRFS info (device dm-0): first mount of filesystem 752cb955-bdfa-486a-ad02-b54d5e61d194 Sep 12 17:19:38.800038 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Sep 12 17:19:38.808057 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 12 17:19:38.808101 kernel: BTRFS info (device dm-0): enabling free space tree Sep 12 17:19:38.815755 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 12 17:19:38.816666 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 12 17:19:38.817553 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 12 17:19:38.818243 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 12 17:19:38.820387 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 12 17:19:38.858330 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (653) Sep 12 17:19:38.858382 kernel: BTRFS info (device vda6): first mount of filesystem 5f4a7913-42f7-487c-8331-8ab180fe9df7 Sep 12 17:19:38.858393 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 12 17:19:38.863514 kernel: BTRFS info (device vda6): turning on async discard Sep 12 17:19:38.863554 kernel: BTRFS info (device vda6): enabling free space tree Sep 12 17:19:38.868496 kernel: BTRFS info (device vda6): last unmount of filesystem 5f4a7913-42f7-487c-8331-8ab180fe9df7 Sep 12 17:19:38.869892 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 12 17:19:38.875105 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 12 17:19:38.938129 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 12 17:19:38.941002 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 12 17:19:39.001554 systemd-networkd[795]: lo: Link UP Sep 12 17:19:39.001567 systemd-networkd[795]: lo: Gained carrier Sep 12 17:19:39.002283 systemd-networkd[795]: Enumeration completed Sep 12 17:19:39.002566 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 12 17:19:39.003845 ignition[712]: Ignition 2.21.0 Sep 12 17:19:39.002749 systemd-networkd[795]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 17:19:39.003852 ignition[712]: Stage: fetch-offline Sep 12 17:19:39.002753 systemd-networkd[795]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 12 17:19:39.003884 ignition[712]: no configs at "/usr/lib/ignition/base.d" Sep 12 17:19:39.003803 systemd-networkd[795]: eth0: Link UP Sep 12 17:19:39.003892 ignition[712]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 12 17:19:39.003887 systemd-networkd[795]: eth0: Gained carrier Sep 12 17:19:39.004085 ignition[712]: parsed url from cmdline: "" Sep 12 17:19:39.003896 systemd-networkd[795]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 17:19:39.004088 ignition[712]: no config URL provided Sep 12 17:19:39.004309 systemd[1]: Reached target network.target - Network. Sep 12 17:19:39.004093 ignition[712]: reading system config file "/usr/lib/ignition/user.ign" Sep 12 17:19:39.004099 ignition[712]: no config at "/usr/lib/ignition/user.ign" Sep 12 17:19:39.004118 ignition[712]: op(1): [started] loading QEMU firmware config module Sep 12 17:19:39.004122 ignition[712]: op(1): executing: "modprobe" "qemu_fw_cfg" Sep 12 17:19:39.014678 ignition[712]: op(1): [finished] loading QEMU firmware config module Sep 12 17:19:39.025548 systemd-networkd[795]: eth0: DHCPv4 address 10.0.0.86/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 12 17:19:39.028831 ignition[712]: parsing config with SHA512: 6d2be49efc9438517d054ccc7dd9cb9076c6bcf94f08794cd9ee5466560b8ad003fbe46f456fbabeb37914ef88851305d8a2fc137195138c9aaca44d6e725240 Sep 12 17:19:39.033174 unknown[712]: fetched base config from "system" Sep 12 17:19:39.033461 ignition[712]: fetch-offline: fetch-offline passed Sep 12 17:19:39.033184 unknown[712]: fetched user config from "qemu" Sep 12 17:19:39.033618 ignition[712]: Ignition finished successfully Sep 12 17:19:39.036077 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 12 17:19:39.037620 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Sep 12 17:19:39.038360 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 12 17:19:39.072058 ignition[809]: Ignition 2.21.0 Sep 12 17:19:39.072070 ignition[809]: Stage: kargs Sep 12 17:19:39.072246 ignition[809]: no configs at "/usr/lib/ignition/base.d" Sep 12 17:19:39.072255 ignition[809]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 12 17:19:39.075347 ignition[809]: kargs: kargs passed Sep 12 17:19:39.075402 ignition[809]: Ignition finished successfully Sep 12 17:19:39.078390 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 12 17:19:39.080189 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 12 17:19:39.107107 ignition[817]: Ignition 2.21.0 Sep 12 17:19:39.107125 ignition[817]: Stage: disks Sep 12 17:19:39.107713 ignition[817]: no configs at "/usr/lib/ignition/base.d" Sep 12 17:19:39.107725 ignition[817]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 12 17:19:39.108715 ignition[817]: disks: disks passed Sep 12 17:19:39.108790 ignition[817]: Ignition finished successfully Sep 12 17:19:39.110997 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 12 17:19:39.112466 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 12 17:19:39.113937 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 12 17:19:39.115600 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 12 17:19:39.117192 systemd[1]: Reached target sysinit.target - System Initialization. Sep 12 17:19:39.118682 systemd[1]: Reached target basic.target - Basic System. Sep 12 17:19:39.120949 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 12 17:19:39.144627 systemd-fsck[827]: ROOT: clean, 15/553520 files, 52789/553472 blocks Sep 12 17:19:39.150846 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 12 17:19:39.153066 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 12 17:19:39.219505 kernel: EXT4-fs (vda9): mounted filesystem c902100c-52b7-422c-84ac-d834d4db2717 r/w with ordered data mode. Quota mode: none. Sep 12 17:19:39.219559 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 12 17:19:39.220690 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 12 17:19:39.223574 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 12 17:19:39.225642 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 12 17:19:39.226545 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 12 17:19:39.226584 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 12 17:19:39.226607 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 12 17:19:39.244423 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 12 17:19:39.246345 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 12 17:19:39.259603 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (835) Sep 12 17:19:39.262186 kernel: BTRFS info (device vda6): first mount of filesystem 5f4a7913-42f7-487c-8331-8ab180fe9df7 Sep 12 17:19:39.262227 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 12 17:19:39.266017 kernel: BTRFS info (device vda6): turning on async discard Sep 12 17:19:39.266066 kernel: BTRFS info (device vda6): enabling free space tree Sep 12 17:19:39.267559 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 12 17:19:39.293795 initrd-setup-root[859]: cut: /sysroot/etc/passwd: No such file or directory Sep 12 17:19:39.297940 initrd-setup-root[866]: cut: /sysroot/etc/group: No such file or directory Sep 12 17:19:39.301330 initrd-setup-root[873]: cut: /sysroot/etc/shadow: No such file or directory Sep 12 17:19:39.304837 initrd-setup-root[880]: cut: /sysroot/etc/gshadow: No such file or directory Sep 12 17:19:39.378593 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 12 17:19:39.380195 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 12 17:19:39.382720 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 12 17:19:39.400506 kernel: BTRFS info (device vda6): last unmount of filesystem 5f4a7913-42f7-487c-8331-8ab180fe9df7 Sep 12 17:19:39.412717 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 12 17:19:39.424752 ignition[951]: INFO : Ignition 2.21.0 Sep 12 17:19:39.424752 ignition[951]: INFO : Stage: mount Sep 12 17:19:39.424752 ignition[951]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 17:19:39.424752 ignition[951]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 12 17:19:39.430431 ignition[951]: INFO : mount: mount passed Sep 12 17:19:39.430431 ignition[951]: INFO : Ignition finished successfully Sep 12 17:19:39.427096 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 12 17:19:39.430585 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 12 17:19:39.796944 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 12 17:19:39.798401 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 12 17:19:39.817436 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (963) Sep 12 17:19:39.817473 kernel: BTRFS info (device vda6): first mount of filesystem 5f4a7913-42f7-487c-8331-8ab180fe9df7 Sep 12 17:19:39.817492 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 12 17:19:39.821504 kernel: BTRFS info (device vda6): turning on async discard Sep 12 17:19:39.821528 kernel: BTRFS info (device vda6): enabling free space tree Sep 12 17:19:39.822414 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 12 17:19:39.854472 ignition[980]: INFO : Ignition 2.21.0 Sep 12 17:19:39.854472 ignition[980]: INFO : Stage: files Sep 12 17:19:39.856833 ignition[980]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 17:19:39.856833 ignition[980]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 12 17:19:39.856833 ignition[980]: DEBUG : files: compiled without relabeling support, skipping Sep 12 17:19:39.856833 ignition[980]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 12 17:19:39.856833 ignition[980]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 12 17:19:39.863621 ignition[980]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 12 17:19:39.863621 ignition[980]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 12 17:19:39.863621 ignition[980]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 12 17:19:39.863621 ignition[980]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Sep 12 17:19:39.863621 ignition[980]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Sep 12 17:19:39.863621 ignition[980]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 12 17:19:39.863621 ignition[980]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 12 17:19:39.858060 unknown[980]: wrote ssh authorized keys file for user: core Sep 12 17:19:39.874048 ignition[980]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Sep 12 17:19:39.874048 ignition[980]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Sep 12 17:19:39.874048 ignition[980]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Sep 12 17:19:39.874048 ignition[980]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.33.0-arm64.raw: attempt #1 Sep 12 17:19:40.242877 ignition[980]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Sep 12 17:19:40.486603 ignition[980]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Sep 12 17:19:40.486603 ignition[980]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Sep 12 17:19:40.492544 ignition[980]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 12 17:19:40.492544 ignition[980]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 12 17:19:40.492544 ignition[980]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Sep 12 17:19:40.492544 ignition[980]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Sep 12 17:19:40.504392 ignition[980]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Sep 12 17:19:40.509323 ignition[980]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Sep 12 17:19:40.511517 ignition[980]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Sep 12 17:19:40.511517 ignition[980]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 12 17:19:40.511517 ignition[980]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 12 17:19:40.511517 ignition[980]: INFO : files: files passed Sep 12 17:19:40.511517 ignition[980]: INFO : Ignition finished successfully Sep 12 17:19:40.511267 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 12 17:19:40.513174 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 12 17:19:40.515604 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 12 17:19:40.529111 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 12 17:19:40.529246 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 12 17:19:40.532075 initrd-setup-root-after-ignition[1009]: grep: /sysroot/oem/oem-release: No such file or directory Sep 12 17:19:40.534527 initrd-setup-root-after-ignition[1011]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 12 17:19:40.534527 initrd-setup-root-after-ignition[1011]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 12 17:19:40.537152 initrd-setup-root-after-ignition[1015]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 12 17:19:40.537528 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 12 17:19:40.539607 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 12 17:19:40.541807 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 12 17:19:40.562594 systemd-networkd[795]: eth0: Gained IPv6LL Sep 12 17:19:40.580398 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 12 17:19:40.580643 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 12 17:19:40.582435 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 12 17:19:40.583863 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 12 17:19:40.585633 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 12 17:19:40.586380 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 12 17:19:40.618582 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 12 17:19:40.620799 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 12 17:19:40.646561 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 12 17:19:40.647536 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 17:19:40.649259 systemd[1]: Stopped target timers.target - Timer Units. Sep 12 17:19:40.650688 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 12 17:19:40.650804 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 12 17:19:40.653107 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 12 17:19:40.654645 systemd[1]: Stopped target basic.target - Basic System. Sep 12 17:19:40.655914 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 12 17:19:40.657514 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 12 17:19:40.659029 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 12 17:19:40.660541 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 12 17:19:40.662316 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 12 17:19:40.663755 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 12 17:19:40.665368 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 12 17:19:40.666906 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 12 17:19:40.668344 systemd[1]: Stopped target swap.target - Swaps. Sep 12 17:19:40.669558 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 12 17:19:40.669771 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 12 17:19:40.671630 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 12 17:19:40.673194 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 17:19:40.674775 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 12 17:19:40.675595 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 17:19:40.677294 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 12 17:19:40.677406 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 12 17:19:40.679845 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 12 17:19:40.679962 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 12 17:19:40.681685 systemd[1]: Stopped target paths.target - Path Units. Sep 12 17:19:40.682958 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 12 17:19:40.686524 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 17:19:40.687585 systemd[1]: Stopped target slices.target - Slice Units. Sep 12 17:19:40.689378 systemd[1]: Stopped target sockets.target - Socket Units. Sep 12 17:19:40.690895 systemd[1]: iscsid.socket: Deactivated successfully. Sep 12 17:19:40.690977 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 12 17:19:40.692313 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 12 17:19:40.692393 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 12 17:19:40.693683 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 12 17:19:40.693790 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 12 17:19:40.695241 systemd[1]: ignition-files.service: Deactivated successfully. Sep 12 17:19:40.695349 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 12 17:19:40.697324 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 12 17:19:40.699652 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 12 17:19:40.700514 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 12 17:19:40.700640 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 17:19:40.702263 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 12 17:19:40.702374 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 12 17:19:40.707096 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 12 17:19:40.712685 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 12 17:19:40.721470 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 12 17:19:40.732463 ignition[1035]: INFO : Ignition 2.21.0 Sep 12 17:19:40.732463 ignition[1035]: INFO : Stage: umount Sep 12 17:19:40.734552 ignition[1035]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 17:19:40.734552 ignition[1035]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 12 17:19:40.734552 ignition[1035]: INFO : umount: umount passed Sep 12 17:19:40.734552 ignition[1035]: INFO : Ignition finished successfully Sep 12 17:19:40.735524 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 12 17:19:40.737517 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 12 17:19:40.739114 systemd[1]: Stopped target network.target - Network. Sep 12 17:19:40.740136 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 12 17:19:40.740185 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 12 17:19:40.742295 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 12 17:19:40.742334 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 12 17:19:40.744048 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 12 17:19:40.744092 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 12 17:19:40.745322 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 12 17:19:40.745356 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 12 17:19:40.746817 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 12 17:19:40.748319 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 12 17:19:40.757538 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 12 17:19:40.757655 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 12 17:19:40.761976 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 12 17:19:40.762220 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 12 17:19:40.762254 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 17:19:40.766109 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 12 17:19:40.766291 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 12 17:19:40.766453 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 12 17:19:40.769001 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 12 17:19:40.770198 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 12 17:19:40.770234 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 12 17:19:40.774434 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 12 17:19:40.775269 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 12 17:19:40.775329 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 12 17:19:40.777104 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 12 17:19:40.777146 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 12 17:19:40.779384 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 12 17:19:40.779467 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 12 17:19:40.780500 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 17:19:40.787421 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 12 17:19:40.791632 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 12 17:19:40.794342 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 12 17:19:40.794438 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 12 17:19:40.797497 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 12 17:19:40.797549 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 12 17:19:40.800627 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 12 17:19:40.800740 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 12 17:19:40.806056 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 12 17:19:40.806214 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 17:19:40.808070 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 12 17:19:40.808104 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 12 17:19:40.809599 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 12 17:19:40.809624 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 17:19:40.811177 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 12 17:19:40.811216 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 12 17:19:40.813677 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 12 17:19:40.813719 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 12 17:19:40.816042 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 12 17:19:40.816083 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 17:19:40.819360 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 12 17:19:40.820332 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 12 17:19:40.820394 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Sep 12 17:19:40.823440 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 12 17:19:40.823496 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 17:19:40.826841 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Sep 12 17:19:40.826883 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 12 17:19:40.829261 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 12 17:19:40.829307 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 17:19:40.831512 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 12 17:19:40.831556 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:19:40.835454 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Sep 12 17:19:40.835520 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Sep 12 17:19:40.835547 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Sep 12 17:19:40.835576 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 12 17:19:40.835801 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 12 17:19:40.837525 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 12 17:19:40.839794 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 12 17:19:40.842251 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 12 17:19:40.870394 systemd[1]: Switching root. Sep 12 17:19:40.898836 systemd-journald[244]: Journal stopped Sep 12 17:19:41.683656 systemd-journald[244]: Received SIGTERM from PID 1 (systemd). Sep 12 17:19:41.683709 kernel: SELinux: policy capability network_peer_controls=1 Sep 12 17:19:41.683721 kernel: SELinux: policy capability open_perms=1 Sep 12 17:19:41.683730 kernel: SELinux: policy capability extended_socket_class=1 Sep 12 17:19:41.683739 kernel: SELinux: policy capability always_check_network=0 Sep 12 17:19:41.683750 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 12 17:19:41.683761 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 12 17:19:41.683771 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 12 17:19:41.683780 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 12 17:19:41.683792 kernel: SELinux: policy capability userspace_initial_context=0 Sep 12 17:19:41.683801 kernel: audit: type=1403 audit(1757697581.042:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 12 17:19:41.683815 systemd[1]: Successfully loaded SELinux policy in 65.302ms. Sep 12 17:19:41.683832 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 5.078ms. Sep 12 17:19:41.683843 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 12 17:19:41.683854 systemd[1]: Detected virtualization kvm. Sep 12 17:19:41.683864 systemd[1]: Detected architecture arm64. Sep 12 17:19:41.683873 systemd[1]: Detected first boot. Sep 12 17:19:41.683883 systemd[1]: Initializing machine ID from VM UUID. Sep 12 17:19:41.683897 zram_generator::config[1079]: No configuration found. Sep 12 17:19:41.683908 kernel: NET: Registered PF_VSOCK protocol family Sep 12 17:19:41.683918 systemd[1]: Populated /etc with preset unit settings. Sep 12 17:19:41.683928 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 12 17:19:41.683938 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 12 17:19:41.683948 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 12 17:19:41.683957 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 12 17:19:41.683967 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 12 17:19:41.683978 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 12 17:19:41.683987 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 12 17:19:41.683999 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 12 17:19:41.684009 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 12 17:19:41.684019 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 12 17:19:41.684030 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 12 17:19:41.684040 systemd[1]: Created slice user.slice - User and Session Slice. Sep 12 17:19:41.684050 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 17:19:41.684066 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 17:19:41.684076 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 12 17:19:41.684086 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 12 17:19:41.684099 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 12 17:19:41.684113 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 12 17:19:41.684124 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Sep 12 17:19:41.684135 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 17:19:41.684145 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 12 17:19:41.684156 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 12 17:19:41.684166 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 12 17:19:41.684177 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 12 17:19:41.684190 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 12 17:19:41.684200 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 17:19:41.684210 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 12 17:19:41.684219 systemd[1]: Reached target slices.target - Slice Units. Sep 12 17:19:41.684230 systemd[1]: Reached target swap.target - Swaps. Sep 12 17:19:41.684239 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 12 17:19:41.684255 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 12 17:19:41.684265 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 12 17:19:41.684284 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 12 17:19:41.684296 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 12 17:19:41.684307 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 17:19:41.684317 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 12 17:19:41.684327 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 12 17:19:41.684337 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 12 17:19:41.684347 systemd[1]: Mounting media.mount - External Media Directory... Sep 12 17:19:41.684357 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 12 17:19:41.684368 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 12 17:19:41.684379 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 12 17:19:41.684390 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Sep 12 17:19:41.684401 systemd[1]: Reached target machines.target - Containers. Sep 12 17:19:41.684411 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 12 17:19:41.684422 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 17:19:41.684432 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 12 17:19:41.684443 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 12 17:19:41.684454 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 17:19:41.684464 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 12 17:19:41.684484 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 17:19:41.684496 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 12 17:19:41.684508 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 17:19:41.684518 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 12 17:19:41.684529 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 12 17:19:41.684540 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 12 17:19:41.684550 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 12 17:19:41.684559 systemd[1]: Stopped systemd-fsck-usr.service. Sep 12 17:19:41.684571 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 12 17:19:41.684581 kernel: loop: module loaded Sep 12 17:19:41.684591 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 12 17:19:41.684601 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 12 17:19:41.684611 kernel: fuse: init (API version 7.41) Sep 12 17:19:41.684620 kernel: ACPI: bus type drm_connector registered Sep 12 17:19:41.684629 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 12 17:19:41.684639 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 12 17:19:41.684649 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 12 17:19:41.684661 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 12 17:19:41.684670 systemd[1]: verity-setup.service: Deactivated successfully. Sep 12 17:19:41.684681 systemd[1]: Stopped verity-setup.service. Sep 12 17:19:41.684691 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 12 17:19:41.684720 systemd-journald[1147]: Collecting audit messages is disabled. Sep 12 17:19:41.684743 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 12 17:19:41.684754 systemd-journald[1147]: Journal started Sep 12 17:19:41.684776 systemd-journald[1147]: Runtime Journal (/run/log/journal/5dde031e1aa0438a88bec7eac079e98b) is 6M, max 48.5M, 42.4M free. Sep 12 17:19:41.452203 systemd[1]: Queued start job for default target multi-user.target. Sep 12 17:19:41.477201 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 12 17:19:41.477658 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 12 17:19:41.688505 systemd[1]: Started systemd-journald.service - Journal Service. Sep 12 17:19:41.689192 systemd[1]: Mounted media.mount - External Media Directory. Sep 12 17:19:41.690240 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 12 17:19:41.691342 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 12 17:19:41.692471 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 12 17:19:41.693496 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 12 17:19:41.694663 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 17:19:41.695844 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 12 17:19:41.696010 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 12 17:19:41.697237 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 17:19:41.697413 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 17:19:41.698715 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 12 17:19:41.698888 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 12 17:19:41.700049 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 17:19:41.700213 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 17:19:41.701662 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 12 17:19:41.701820 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 12 17:19:41.703035 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 17:19:41.703212 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 17:19:41.704512 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 12 17:19:41.705699 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 12 17:19:41.706940 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 12 17:19:41.708248 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 12 17:19:41.720287 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 12 17:19:41.722512 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 12 17:19:41.724237 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 12 17:19:41.725324 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 12 17:19:41.725355 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 12 17:19:41.727037 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 12 17:19:41.740300 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 12 17:19:41.741348 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 17:19:41.742379 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 12 17:19:41.744298 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 12 17:19:41.745522 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 12 17:19:41.746740 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 12 17:19:41.747820 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 12 17:19:41.749682 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 12 17:19:41.754240 systemd-journald[1147]: Time spent on flushing to /var/log/journal/5dde031e1aa0438a88bec7eac079e98b is 14.296ms for 870 entries. Sep 12 17:19:41.754240 systemd-journald[1147]: System Journal (/var/log/journal/5dde031e1aa0438a88bec7eac079e98b) is 8M, max 195.6M, 187.6M free. Sep 12 17:19:41.773402 systemd-journald[1147]: Received client request to flush runtime journal. Sep 12 17:19:41.773434 kernel: loop0: detected capacity change from 0 to 119320 Sep 12 17:19:41.755436 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 12 17:19:41.761503 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 12 17:19:41.765572 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 17:19:41.766871 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 12 17:19:41.768838 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 12 17:19:41.775434 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 12 17:19:41.781792 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 12 17:19:41.783025 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 12 17:19:41.787297 systemd-tmpfiles[1196]: ACLs are not supported, ignoring. Sep 12 17:19:41.787532 systemd-tmpfiles[1196]: ACLs are not supported, ignoring. Sep 12 17:19:41.789729 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 12 17:19:41.791036 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 12 17:19:41.792413 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 12 17:19:41.797673 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 12 17:19:41.797792 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 12 17:19:41.815513 kernel: loop1: detected capacity change from 0 to 100608 Sep 12 17:19:41.826555 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 12 17:19:41.828808 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 12 17:19:41.831251 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 12 17:19:41.842509 kernel: loop2: detected capacity change from 0 to 211168 Sep 12 17:19:41.849002 systemd-tmpfiles[1220]: ACLs are not supported, ignoring. Sep 12 17:19:41.849017 systemd-tmpfiles[1220]: ACLs are not supported, ignoring. Sep 12 17:19:41.852346 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 17:19:41.874511 kernel: loop3: detected capacity change from 0 to 119320 Sep 12 17:19:41.881697 kernel: loop4: detected capacity change from 0 to 100608 Sep 12 17:19:41.886616 kernel: loop5: detected capacity change from 0 to 211168 Sep 12 17:19:41.891318 (sd-merge)[1224]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Sep 12 17:19:41.891702 (sd-merge)[1224]: Merged extensions into '/usr'. Sep 12 17:19:41.894904 systemd[1]: Reload requested from client PID 1195 ('systemd-sysext') (unit systemd-sysext.service)... Sep 12 17:19:41.894925 systemd[1]: Reloading... Sep 12 17:19:41.947907 zram_generator::config[1250]: No configuration found. Sep 12 17:19:42.028556 ldconfig[1190]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 12 17:19:42.093930 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 12 17:19:42.094203 systemd[1]: Reloading finished in 198 ms. Sep 12 17:19:42.130450 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 12 17:19:42.133516 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 12 17:19:42.147677 systemd[1]: Starting ensure-sysext.service... Sep 12 17:19:42.149345 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 12 17:19:42.162597 systemd-tmpfiles[1285]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 12 17:19:42.162627 systemd-tmpfiles[1285]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 12 17:19:42.162869 systemd-tmpfiles[1285]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 12 17:19:42.163049 systemd-tmpfiles[1285]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 12 17:19:42.163772 systemd-tmpfiles[1285]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 12 17:19:42.163972 systemd-tmpfiles[1285]: ACLs are not supported, ignoring. Sep 12 17:19:42.164027 systemd-tmpfiles[1285]: ACLs are not supported, ignoring. Sep 12 17:19:42.166609 systemd-tmpfiles[1285]: Detected autofs mount point /boot during canonicalization of boot. Sep 12 17:19:42.166622 systemd-tmpfiles[1285]: Skipping /boot Sep 12 17:19:42.166756 systemd[1]: Reload requested from client PID 1284 ('systemctl') (unit ensure-sysext.service)... Sep 12 17:19:42.166769 systemd[1]: Reloading... Sep 12 17:19:42.172064 systemd-tmpfiles[1285]: Detected autofs mount point /boot during canonicalization of boot. Sep 12 17:19:42.172083 systemd-tmpfiles[1285]: Skipping /boot Sep 12 17:19:42.209512 zram_generator::config[1315]: No configuration found. Sep 12 17:19:42.331615 systemd[1]: Reloading finished in 164 ms. Sep 12 17:19:42.352048 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 12 17:19:42.357572 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 17:19:42.363352 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 12 17:19:42.368389 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 12 17:19:42.370403 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 12 17:19:42.372938 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 12 17:19:42.375947 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 17:19:42.379618 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 12 17:19:42.391199 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 17:19:42.394527 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 17:19:42.400838 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 17:19:42.409759 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 17:19:42.410662 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 17:19:42.410788 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 12 17:19:42.412446 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 12 17:19:42.416350 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 12 17:19:42.416384 systemd-udevd[1358]: Using default interface naming scheme 'v255'. Sep 12 17:19:42.420697 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 17:19:42.420846 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 17:19:42.423999 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 17:19:42.424142 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 17:19:42.425758 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 17:19:42.426088 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 17:19:42.428069 augenrules[1377]: No rules Sep 12 17:19:42.429356 systemd[1]: audit-rules.service: Deactivated successfully. Sep 12 17:19:42.429547 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 12 17:19:42.431000 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 12 17:19:42.437118 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 17:19:42.448016 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 12 17:19:42.459748 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 12 17:19:42.460707 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 17:19:42.462789 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 17:19:42.475843 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 12 17:19:42.478673 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 17:19:42.482646 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 17:19:42.484309 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 17:19:42.484357 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 12 17:19:42.486009 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 12 17:19:42.489291 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 12 17:19:42.491633 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Sep 12 17:19:42.493015 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 12 17:19:42.495037 augenrules[1417]: /sbin/augenrules: No change Sep 12 17:19:42.495561 systemd[1]: Finished ensure-sysext.service. Sep 12 17:19:42.497356 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 17:19:42.497544 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 17:19:42.499261 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 12 17:19:42.506064 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 12 17:19:42.507756 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 17:19:42.508148 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 17:19:42.511434 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 17:19:42.511669 augenrules[1444]: No rules Sep 12 17:19:42.511786 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 17:19:42.513273 systemd[1]: audit-rules.service: Deactivated successfully. Sep 12 17:19:42.513444 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 12 17:19:42.514857 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 12 17:19:42.527280 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 12 17:19:42.527342 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 12 17:19:42.536468 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 12 17:19:42.541317 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Sep 12 17:19:42.586684 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 12 17:19:42.591703 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 12 17:19:42.601543 systemd-networkd[1431]: lo: Link UP Sep 12 17:19:42.601550 systemd-networkd[1431]: lo: Gained carrier Sep 12 17:19:42.602278 systemd-networkd[1431]: Enumeration completed Sep 12 17:19:42.602379 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 12 17:19:42.604928 systemd-networkd[1431]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 17:19:42.604939 systemd-networkd[1431]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 12 17:19:42.605438 systemd-networkd[1431]: eth0: Link UP Sep 12 17:19:42.605733 systemd-networkd[1431]: eth0: Gained carrier Sep 12 17:19:42.605753 systemd-networkd[1431]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 17:19:42.606634 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 12 17:19:42.608522 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 12 17:19:42.609800 systemd-resolved[1352]: Positive Trust Anchors: Sep 12 17:19:42.610376 systemd-resolved[1352]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 12 17:19:42.610985 systemd-resolved[1352]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 12 17:19:42.615647 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 12 17:19:42.616669 systemd[1]: Reached target time-set.target - System Time Set. Sep 12 17:19:42.621555 systemd-networkd[1431]: eth0: DHCPv4 address 10.0.0.86/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 12 17:19:42.621862 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 12 17:19:42.622275 systemd-timesyncd[1460]: Network configuration changed, trying to establish connection. Sep 12 17:19:42.623739 systemd-resolved[1352]: Defaulting to hostname 'linux'. Sep 12 17:19:42.625242 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 12 17:19:42.625293 systemd-timesyncd[1460]: Contacted time server 10.0.0.1:123 (10.0.0.1). Sep 12 17:19:42.625331 systemd-timesyncd[1460]: Initial clock synchronization to Fri 2025-09-12 17:19:42.660032 UTC. Sep 12 17:19:42.627738 systemd[1]: Reached target network.target - Network. Sep 12 17:19:42.628464 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 12 17:19:42.629416 systemd[1]: Reached target sysinit.target - System Initialization. Sep 12 17:19:42.630354 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 12 17:19:42.631461 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 12 17:19:42.632661 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 12 17:19:42.633581 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 12 17:19:42.634604 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 12 17:19:42.635507 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 12 17:19:42.635537 systemd[1]: Reached target paths.target - Path Units. Sep 12 17:19:42.636192 systemd[1]: Reached target timers.target - Timer Units. Sep 12 17:19:42.637652 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 12 17:19:42.639646 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 12 17:19:42.642446 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 12 17:19:42.643511 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 12 17:19:42.644517 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 12 17:19:42.649337 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 12 17:19:42.651204 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 12 17:19:42.654505 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 12 17:19:42.655665 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 12 17:19:42.656923 systemd[1]: Reached target sockets.target - Socket Units. Sep 12 17:19:42.657864 systemd[1]: Reached target basic.target - Basic System. Sep 12 17:19:42.658683 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 12 17:19:42.658715 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 12 17:19:42.659631 systemd[1]: Starting containerd.service - containerd container runtime... Sep 12 17:19:42.661701 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 12 17:19:42.663718 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 12 17:19:42.667658 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 12 17:19:42.669683 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 12 17:19:42.670473 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Sep 12 17:19:42.671373 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 12 17:19:42.673566 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 12 17:19:42.690598 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 12 17:19:42.690947 jq[1490]: false Sep 12 17:19:42.694727 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 12 17:19:42.696373 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 12 17:19:42.696771 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 12 17:19:42.697247 systemd[1]: Starting update-engine.service - Update Engine... Sep 12 17:19:42.699707 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 12 17:19:42.701755 extend-filesystems[1491]: Found /dev/vda6 Sep 12 17:19:42.706496 extend-filesystems[1491]: Found /dev/vda9 Sep 12 17:19:42.706496 extend-filesystems[1491]: Checking size of /dev/vda9 Sep 12 17:19:42.710506 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 12 17:19:42.711704 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 12 17:19:42.711907 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 12 17:19:42.712124 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 12 17:19:42.712258 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 12 17:19:42.717349 jq[1506]: true Sep 12 17:19:42.721645 extend-filesystems[1491]: Resized partition /dev/vda9 Sep 12 17:19:42.726814 extend-filesystems[1522]: resize2fs 1.47.2 (1-Jan-2025) Sep 12 17:19:42.730470 jq[1520]: true Sep 12 17:19:42.728185 systemd[1]: motdgen.service: Deactivated successfully. Sep 12 17:19:42.728378 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 12 17:19:42.738452 update_engine[1502]: I20250912 17:19:42.736438 1502 main.cc:92] Flatcar Update Engine starting Sep 12 17:19:42.738235 (ntainerd)[1524]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 12 17:19:42.740365 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Sep 12 17:19:42.746696 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 17:19:42.749110 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 12 17:19:42.748973 dbus-daemon[1487]: [system] SELinux support is enabled Sep 12 17:19:42.752143 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Sep 12 17:19:42.752257 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 12 17:19:42.753801 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Sep 12 17:19:42.753895 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 12 17:19:42.765112 systemd[1]: Started update-engine.service - Update Engine. Sep 12 17:19:42.767246 update_engine[1502]: I20250912 17:19:42.767191 1502 update_check_scheduler.cc:74] Next update check in 7m22s Sep 12 17:19:42.768817 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 12 17:19:42.776507 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Sep 12 17:19:42.790429 extend-filesystems[1522]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Sep 12 17:19:42.790429 extend-filesystems[1522]: old_desc_blocks = 1, new_desc_blocks = 1 Sep 12 17:19:42.790429 extend-filesystems[1522]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Sep 12 17:19:42.798585 extend-filesystems[1491]: Resized filesystem in /dev/vda9 Sep 12 17:19:42.802576 bash[1547]: Updated "/home/core/.ssh/authorized_keys" Sep 12 17:19:42.794384 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 12 17:19:42.795621 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 12 17:19:42.819615 locksmithd[1545]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 12 17:19:42.841520 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 12 17:19:42.843853 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:19:42.854760 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 12 17:19:42.870166 systemd-logind[1500]: Watching system buttons on /dev/input/event0 (Power Button) Sep 12 17:19:42.870410 systemd-logind[1500]: New seat seat0. Sep 12 17:19:42.871560 systemd[1]: Started systemd-logind.service - User Login Management. Sep 12 17:19:42.915223 containerd[1524]: time="2025-09-12T17:19:42Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Sep 12 17:19:42.915952 containerd[1524]: time="2025-09-12T17:19:42.915912200Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Sep 12 17:19:42.924948 containerd[1524]: time="2025-09-12T17:19:42.924906160Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="10.16µs" Sep 12 17:19:42.924983 containerd[1524]: time="2025-09-12T17:19:42.924946720Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 12 17:19:42.924983 containerd[1524]: time="2025-09-12T17:19:42.924965560Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 12 17:19:42.925148 containerd[1524]: time="2025-09-12T17:19:42.925126840Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 12 17:19:42.925172 containerd[1524]: time="2025-09-12T17:19:42.925149440Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 12 17:19:42.925190 containerd[1524]: time="2025-09-12T17:19:42.925175280Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 12 17:19:42.925241 containerd[1524]: time="2025-09-12T17:19:42.925224760Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 12 17:19:42.925241 containerd[1524]: time="2025-09-12T17:19:42.925239480Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 12 17:19:42.925517 containerd[1524]: time="2025-09-12T17:19:42.925474400Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 12 17:19:42.925517 containerd[1524]: time="2025-09-12T17:19:42.925515080Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 12 17:19:42.925558 containerd[1524]: time="2025-09-12T17:19:42.925536880Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 12 17:19:42.925558 containerd[1524]: time="2025-09-12T17:19:42.925545000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 12 17:19:42.925641 containerd[1524]: time="2025-09-12T17:19:42.925622960Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 12 17:19:42.925857 containerd[1524]: time="2025-09-12T17:19:42.925836120Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 12 17:19:42.925881 containerd[1524]: time="2025-09-12T17:19:42.925870000Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 12 17:19:42.925900 containerd[1524]: time="2025-09-12T17:19:42.925879240Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 12 17:19:42.925925 containerd[1524]: time="2025-09-12T17:19:42.925912240Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 12 17:19:42.926176 containerd[1524]: time="2025-09-12T17:19:42.926158520Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 12 17:19:42.926241 containerd[1524]: time="2025-09-12T17:19:42.926226240Z" level=info msg="metadata content store policy set" policy=shared Sep 12 17:19:42.931851 containerd[1524]: time="2025-09-12T17:19:42.931816200Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 12 17:19:42.931906 containerd[1524]: time="2025-09-12T17:19:42.931885000Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 12 17:19:42.931925 containerd[1524]: time="2025-09-12T17:19:42.931906320Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 12 17:19:42.931925 containerd[1524]: time="2025-09-12T17:19:42.931919280Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 12 17:19:42.931978 containerd[1524]: time="2025-09-12T17:19:42.931932880Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 12 17:19:42.931978 containerd[1524]: time="2025-09-12T17:19:42.931964720Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 12 17:19:42.932011 containerd[1524]: time="2025-09-12T17:19:42.931977400Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 12 17:19:42.932011 containerd[1524]: time="2025-09-12T17:19:42.931990280Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 12 17:19:42.932011 containerd[1524]: time="2025-09-12T17:19:42.932001720Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 12 17:19:42.932063 containerd[1524]: time="2025-09-12T17:19:42.932013000Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 12 17:19:42.932063 containerd[1524]: time="2025-09-12T17:19:42.932023160Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 12 17:19:42.932063 containerd[1524]: time="2025-09-12T17:19:42.932039800Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 12 17:19:42.933483 containerd[1524]: time="2025-09-12T17:19:42.932168720Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 12 17:19:42.933483 containerd[1524]: time="2025-09-12T17:19:42.932203080Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 12 17:19:42.933483 containerd[1524]: time="2025-09-12T17:19:42.932218440Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 12 17:19:42.933483 containerd[1524]: time="2025-09-12T17:19:42.932229640Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 12 17:19:42.933483 containerd[1524]: time="2025-09-12T17:19:42.932241520Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 12 17:19:42.933483 containerd[1524]: time="2025-09-12T17:19:42.932252000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 12 17:19:42.933483 containerd[1524]: time="2025-09-12T17:19:42.932271360Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 12 17:19:42.933483 containerd[1524]: time="2025-09-12T17:19:42.932284040Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 12 17:19:42.933483 containerd[1524]: time="2025-09-12T17:19:42.932295680Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 12 17:19:42.933483 containerd[1524]: time="2025-09-12T17:19:42.932306560Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 12 17:19:42.933483 containerd[1524]: time="2025-09-12T17:19:42.932317040Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 12 17:19:42.933483 containerd[1524]: time="2025-09-12T17:19:42.932529160Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 12 17:19:42.933483 containerd[1524]: time="2025-09-12T17:19:42.932548080Z" level=info msg="Start snapshots syncer" Sep 12 17:19:42.933483 containerd[1524]: time="2025-09-12T17:19:42.932579280Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 12 17:19:42.933723 containerd[1524]: time="2025-09-12T17:19:42.932847240Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Sep 12 17:19:42.933723 containerd[1524]: time="2025-09-12T17:19:42.932898560Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 12 17:19:42.933818 containerd[1524]: time="2025-09-12T17:19:42.932982160Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 12 17:19:42.933818 containerd[1524]: time="2025-09-12T17:19:42.933117720Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 12 17:19:42.933818 containerd[1524]: time="2025-09-12T17:19:42.933142840Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 12 17:19:42.933818 containerd[1524]: time="2025-09-12T17:19:42.933153840Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 12 17:19:42.933818 containerd[1524]: time="2025-09-12T17:19:42.933165520Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 12 17:19:42.933818 containerd[1524]: time="2025-09-12T17:19:42.933176600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 12 17:19:42.933818 containerd[1524]: time="2025-09-12T17:19:42.933187680Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 12 17:19:42.933818 containerd[1524]: time="2025-09-12T17:19:42.933198600Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 12 17:19:42.933818 containerd[1524]: time="2025-09-12T17:19:42.933230640Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 12 17:19:42.933818 containerd[1524]: time="2025-09-12T17:19:42.933242640Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 12 17:19:42.933818 containerd[1524]: time="2025-09-12T17:19:42.933253400Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 12 17:19:42.933818 containerd[1524]: time="2025-09-12T17:19:42.933299880Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 12 17:19:42.933818 containerd[1524]: time="2025-09-12T17:19:42.933316480Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 12 17:19:42.933818 containerd[1524]: time="2025-09-12T17:19:42.933326280Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 12 17:19:42.934031 containerd[1524]: time="2025-09-12T17:19:42.933336360Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 12 17:19:42.934031 containerd[1524]: time="2025-09-12T17:19:42.933345360Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 12 17:19:42.934031 containerd[1524]: time="2025-09-12T17:19:42.933354400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 12 17:19:42.934031 containerd[1524]: time="2025-09-12T17:19:42.933388280Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 12 17:19:42.934031 containerd[1524]: time="2025-09-12T17:19:42.933467680Z" level=info msg="runtime interface created" Sep 12 17:19:42.934031 containerd[1524]: time="2025-09-12T17:19:42.933474560Z" level=info msg="created NRI interface" Sep 12 17:19:42.934031 containerd[1524]: time="2025-09-12T17:19:42.933493760Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 12 17:19:42.934031 containerd[1524]: time="2025-09-12T17:19:42.933507160Z" level=info msg="Connect containerd service" Sep 12 17:19:42.934031 containerd[1524]: time="2025-09-12T17:19:42.933532680Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 12 17:19:42.935529 containerd[1524]: time="2025-09-12T17:19:42.935498720Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Sep 12 17:19:43.002273 containerd[1524]: time="2025-09-12T17:19:43.002199097Z" level=info msg="Start subscribing containerd event" Sep 12 17:19:43.002359 containerd[1524]: time="2025-09-12T17:19:43.002235095Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 12 17:19:43.002379 containerd[1524]: time="2025-09-12T17:19:43.002362091Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 12 17:19:43.002424 containerd[1524]: time="2025-09-12T17:19:43.002293302Z" level=info msg="Start recovering state" Sep 12 17:19:43.002473 containerd[1524]: time="2025-09-12T17:19:43.002454532Z" level=info msg="Start event monitor" Sep 12 17:19:43.002505 containerd[1524]: time="2025-09-12T17:19:43.002472491Z" level=info msg="Start cni network conf syncer for default" Sep 12 17:19:43.002505 containerd[1524]: time="2025-09-12T17:19:43.002497665Z" level=info msg="Start streaming server" Sep 12 17:19:43.002546 containerd[1524]: time="2025-09-12T17:19:43.002508048Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 12 17:19:43.002546 containerd[1524]: time="2025-09-12T17:19:43.002516065Z" level=info msg="runtime interface starting up..." Sep 12 17:19:43.002546 containerd[1524]: time="2025-09-12T17:19:43.002522559Z" level=info msg="starting plugins..." Sep 12 17:19:43.002546 containerd[1524]: time="2025-09-12T17:19:43.002536830Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 12 17:19:43.002728 systemd[1]: Started containerd.service - containerd container runtime. Sep 12 17:19:43.004523 containerd[1524]: time="2025-09-12T17:19:43.004397110Z" level=info msg="containerd successfully booted in 0.089509s" Sep 12 17:19:43.104004 sshd_keygen[1526]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 12 17:19:43.127409 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 12 17:19:43.134315 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 12 17:19:43.161978 systemd[1]: issuegen.service: Deactivated successfully. Sep 12 17:19:43.162212 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 12 17:19:43.169229 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 12 17:19:43.195528 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 12 17:19:43.201009 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 12 17:19:43.221116 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Sep 12 17:19:43.223074 systemd[1]: Reached target getty.target - Login Prompts. Sep 12 17:19:44.018721 systemd-networkd[1431]: eth0: Gained IPv6LL Sep 12 17:19:44.021136 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 12 17:19:44.023295 systemd[1]: Reached target network-online.target - Network is Online. Sep 12 17:19:44.026189 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 12 17:19:44.028940 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 12 17:19:44.031232 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 12 17:19:44.063386 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 12 17:19:44.065433 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 12 17:19:44.066746 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 12 17:19:44.068614 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 12 17:19:44.635214 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 12 17:19:44.636872 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 12 17:19:44.639452 (kubelet)[1621]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Sep 12 17:19:44.641902 systemd[1]: Startup finished in 2.037s (kernel) + 4.425s (initrd) + 3.665s (userspace) = 10.127s. Sep 12 17:19:44.991800 kubelet[1621]: E0912 17:19:44.991678 1621 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Sep 12 17:19:44.994354 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Sep 12 17:19:44.994518 systemd[1]: kubelet.service: Failed with result 'exit-code'. Sep 12 17:19:44.994818 systemd[1]: kubelet.service: Consumed 749ms CPU time, 258.4M memory peak. Sep 12 17:19:49.920823 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 12 17:19:49.921805 systemd[1]: Started sshd@0-10.0.0.86:22-10.0.0.1:48434.service - OpenSSH per-connection server daemon (10.0.0.1:48434). Sep 12 17:19:50.003745 sshd[1634]: Accepted publickey for core from 10.0.0.1 port 48434 ssh2: RSA SHA256:UT5jL9R+kNVMu55HRewvy3KiK11NkEv9jWcPEawXfBI Sep 12 17:19:50.005834 sshd-session[1634]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:19:50.017556 systemd-logind[1500]: New session 1 of user core. Sep 12 17:19:50.018686 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 12 17:19:50.019583 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 12 17:19:50.053520 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 12 17:19:50.055649 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 12 17:19:50.085679 (systemd)[1639]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 12 17:19:50.088190 systemd-logind[1500]: New session c1 of user core. Sep 12 17:19:50.202414 systemd[1639]: Queued start job for default target default.target. Sep 12 17:19:50.220562 systemd[1639]: Created slice app.slice - User Application Slice. Sep 12 17:19:50.220590 systemd[1639]: Reached target paths.target - Paths. Sep 12 17:19:50.220625 systemd[1639]: Reached target timers.target - Timers. Sep 12 17:19:50.221770 systemd[1639]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 12 17:19:50.231064 systemd[1639]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 12 17:19:50.231126 systemd[1639]: Reached target sockets.target - Sockets. Sep 12 17:19:50.231160 systemd[1639]: Reached target basic.target - Basic System. Sep 12 17:19:50.231199 systemd[1639]: Reached target default.target - Main User Target. Sep 12 17:19:50.231222 systemd[1639]: Startup finished in 136ms. Sep 12 17:19:50.231421 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 12 17:19:50.232740 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 12 17:19:50.315534 systemd[1]: Started sshd@1-10.0.0.86:22-10.0.0.1:48446.service - OpenSSH per-connection server daemon (10.0.0.1:48446). Sep 12 17:19:50.366179 sshd[1650]: Accepted publickey for core from 10.0.0.1 port 48446 ssh2: RSA SHA256:UT5jL9R+kNVMu55HRewvy3KiK11NkEv9jWcPEawXfBI Sep 12 17:19:50.367555 sshd-session[1650]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:19:50.372552 systemd-logind[1500]: New session 2 of user core. Sep 12 17:19:50.390550 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 12 17:19:50.446353 sshd[1653]: Connection closed by 10.0.0.1 port 48446 Sep 12 17:19:50.447000 sshd-session[1650]: pam_unix(sshd:session): session closed for user core Sep 12 17:19:50.449866 systemd[1]: sshd@1-10.0.0.86:22-10.0.0.1:48446.service: Deactivated successfully. Sep 12 17:19:50.451440 systemd[1]: session-2.scope: Deactivated successfully. Sep 12 17:19:50.464958 systemd-logind[1500]: Session 2 logged out. Waiting for processes to exit. Sep 12 17:19:50.469175 systemd[1]: Started sshd@2-10.0.0.86:22-10.0.0.1:48450.service - OpenSSH per-connection server daemon (10.0.0.1:48450). Sep 12 17:19:50.470008 systemd-logind[1500]: Removed session 2. Sep 12 17:19:50.527307 sshd[1659]: Accepted publickey for core from 10.0.0.1 port 48450 ssh2: RSA SHA256:UT5jL9R+kNVMu55HRewvy3KiK11NkEv9jWcPEawXfBI Sep 12 17:19:50.529422 sshd-session[1659]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:19:50.534525 systemd-logind[1500]: New session 3 of user core. Sep 12 17:19:50.550680 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 12 17:19:50.599579 sshd[1662]: Connection closed by 10.0.0.1 port 48450 Sep 12 17:19:50.600061 sshd-session[1659]: pam_unix(sshd:session): session closed for user core Sep 12 17:19:50.621618 systemd[1]: sshd@2-10.0.0.86:22-10.0.0.1:48450.service: Deactivated successfully. Sep 12 17:19:50.623895 systemd[1]: session-3.scope: Deactivated successfully. Sep 12 17:19:50.624570 systemd-logind[1500]: Session 3 logged out. Waiting for processes to exit. Sep 12 17:19:50.629147 systemd[1]: Started sshd@3-10.0.0.86:22-10.0.0.1:48458.service - OpenSSH per-connection server daemon (10.0.0.1:48458). Sep 12 17:19:50.629575 systemd-logind[1500]: Removed session 3. Sep 12 17:19:50.683966 sshd[1668]: Accepted publickey for core from 10.0.0.1 port 48458 ssh2: RSA SHA256:UT5jL9R+kNVMu55HRewvy3KiK11NkEv9jWcPEawXfBI Sep 12 17:19:50.685267 sshd-session[1668]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:19:50.689583 systemd-logind[1500]: New session 4 of user core. Sep 12 17:19:50.703037 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 12 17:19:50.758177 sshd[1672]: Connection closed by 10.0.0.1 port 48458 Sep 12 17:19:50.758677 sshd-session[1668]: pam_unix(sshd:session): session closed for user core Sep 12 17:19:50.771342 systemd[1]: sshd@3-10.0.0.86:22-10.0.0.1:48458.service: Deactivated successfully. Sep 12 17:19:50.776342 systemd[1]: session-4.scope: Deactivated successfully. Sep 12 17:19:50.777800 systemd-logind[1500]: Session 4 logged out. Waiting for processes to exit. Sep 12 17:19:50.779764 systemd[1]: Started sshd@4-10.0.0.86:22-10.0.0.1:48462.service - OpenSSH per-connection server daemon (10.0.0.1:48462). Sep 12 17:19:50.781271 systemd-logind[1500]: Removed session 4. Sep 12 17:19:50.853750 sshd[1678]: Accepted publickey for core from 10.0.0.1 port 48462 ssh2: RSA SHA256:UT5jL9R+kNVMu55HRewvy3KiK11NkEv9jWcPEawXfBI Sep 12 17:19:50.855115 sshd-session[1678]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:19:50.860303 systemd-logind[1500]: New session 5 of user core. Sep 12 17:19:50.869636 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 12 17:19:50.932744 sudo[1682]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Sep 12 17:19:50.932998 sudo[1682]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 12 17:19:50.954530 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 12 17:19:50.999352 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 12 17:19:50.999578 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 12 17:19:51.425547 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 12 17:19:51.425681 systemd[1]: kubelet.service: Consumed 749ms CPU time, 258.4M memory peak. Sep 12 17:19:51.427746 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 12 17:19:51.449965 systemd[1]: Reload requested from client PID 1726 ('systemctl') (unit session-5.scope)... Sep 12 17:19:51.450063 systemd[1]: Reloading... Sep 12 17:19:51.527530 zram_generator::config[1768]: No configuration found. Sep 12 17:19:51.789822 systemd[1]: Reloading finished in 339 ms. Sep 12 17:19:51.851008 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Sep 12 17:19:51.851094 systemd[1]: kubelet.service: Failed with result 'signal'. Sep 12 17:19:51.851341 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 12 17:19:51.851401 systemd[1]: kubelet.service: Consumed 91ms CPU time, 95M memory peak. Sep 12 17:19:51.852965 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 12 17:19:51.967207 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 12 17:19:51.976783 (kubelet)[1813]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Sep 12 17:19:52.010832 kubelet[1813]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Sep 12 17:19:52.010832 kubelet[1813]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Sep 12 17:19:52.010832 kubelet[1813]: Flag --volume-plugin-dir has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Sep 12 17:19:52.011180 kubelet[1813]: I0912 17:19:52.010869 1813 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" Sep 12 17:19:53.059822 kubelet[1813]: I0912 17:19:53.059381 1813 server.go:530] "Kubelet version" kubeletVersion="v1.33.0" Sep 12 17:19:53.059822 kubelet[1813]: I0912 17:19:53.059416 1813 server.go:532] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Sep 12 17:19:53.059822 kubelet[1813]: I0912 17:19:53.059782 1813 server.go:956] "Client rotation is on, will bootstrap in background" Sep 12 17:19:53.092562 kubelet[1813]: I0912 17:19:53.092515 1813 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Sep 12 17:19:53.101534 kubelet[1813]: I0912 17:19:53.101433 1813 server.go:1446] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Sep 12 17:19:53.104604 kubelet[1813]: I0912 17:19:53.104564 1813 server.go:782] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Sep 12 17:19:53.104971 kubelet[1813]: I0912 17:19:53.104900 1813 container_manager_linux.go:267] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Sep 12 17:19:53.105081 kubelet[1813]: I0912 17:19:53.104925 1813 container_manager_linux.go:272] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.86","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Sep 12 17:19:53.105186 kubelet[1813]: I0912 17:19:53.105127 1813 topology_manager.go:138] "Creating topology manager with none policy" Sep 12 17:19:53.105186 kubelet[1813]: I0912 17:19:53.105134 1813 container_manager_linux.go:303] "Creating device plugin manager" Sep 12 17:19:53.105428 kubelet[1813]: I0912 17:19:53.105326 1813 state_mem.go:36] "Initialized new in-memory state store" Sep 12 17:19:53.107758 kubelet[1813]: I0912 17:19:53.107717 1813 kubelet.go:480] "Attempting to sync node with API server" Sep 12 17:19:53.107758 kubelet[1813]: I0912 17:19:53.107742 1813 kubelet.go:375] "Adding static pod path" path="/etc/kubernetes/manifests" Sep 12 17:19:53.108148 kubelet[1813]: I0912 17:19:53.108111 1813 kubelet.go:386] "Adding apiserver pod source" Sep 12 17:19:53.109264 kubelet[1813]: I0912 17:19:53.109184 1813 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Sep 12 17:19:53.109264 kubelet[1813]: E0912 17:19:53.109190 1813 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:19:53.109264 kubelet[1813]: E0912 17:19:53.109225 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:19:53.110399 kubelet[1813]: I0912 17:19:53.110370 1813 kuberuntime_manager.go:279] "Container runtime initialized" containerRuntime="containerd" version="v2.0.5" apiVersion="v1" Sep 12 17:19:53.111255 kubelet[1813]: I0912 17:19:53.111111 1813 kubelet.go:935] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Sep 12 17:19:53.111255 kubelet[1813]: W0912 17:19:53.111230 1813 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Sep 12 17:19:53.114236 kubelet[1813]: I0912 17:19:53.114187 1813 watchdog_linux.go:99] "Systemd watchdog is not enabled" Sep 12 17:19:53.114236 kubelet[1813]: I0912 17:19:53.114239 1813 server.go:1289] "Started kubelet" Sep 12 17:19:53.114406 kubelet[1813]: I0912 17:19:53.114367 1813 server.go:180] "Starting to listen" address="0.0.0.0" port=10250 Sep 12 17:19:53.115430 kubelet[1813]: I0912 17:19:53.115366 1813 server.go:317] "Adding debug handlers to kubelet server" Sep 12 17:19:53.117284 kubelet[1813]: I0912 17:19:53.117147 1813 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Sep 12 17:19:53.117661 kubelet[1813]: I0912 17:19:53.117610 1813 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Sep 12 17:19:53.117909 kubelet[1813]: I0912 17:19:53.117888 1813 server.go:255] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Sep 12 17:19:53.118227 kubelet[1813]: E0912 17:19:53.118202 1813 kubelet.go:1600] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Sep 12 17:19:53.118271 kubelet[1813]: I0912 17:19:53.118246 1813 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Sep 12 17:19:53.118881 kubelet[1813]: E0912 17:19:53.118852 1813 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.86\" not found" Sep 12 17:19:53.118991 kubelet[1813]: I0912 17:19:53.118979 1813 volume_manager.go:297] "Starting Kubelet Volume Manager" Sep 12 17:19:53.119209 kubelet[1813]: I0912 17:19:53.119191 1813 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Sep 12 17:19:53.119403 kubelet[1813]: I0912 17:19:53.119389 1813 reconciler.go:26] "Reconciler: start to sync state" Sep 12 17:19:53.119692 kubelet[1813]: E0912 17:19:53.118754 1813 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.86.186498a5f421b5d9 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.86,UID:10.0.0.86,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:10.0.0.86,},FirstTimestamp:2025-09-12 17:19:53.114211801 +0000 UTC m=+1.134255678,LastTimestamp:2025-09-12 17:19:53.114211801 +0000 UTC m=+1.134255678,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.86,}" Sep 12 17:19:53.120236 kubelet[1813]: E0912 17:19:53.120146 1813 reflector.go:200] "Failed to watch" err="failed to list *v1.Node: nodes \"10.0.0.86\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Node" Sep 12 17:19:53.120349 kubelet[1813]: E0912 17:19:53.120262 1813 reflector.go:200] "Failed to watch" err="failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Service" Sep 12 17:19:53.121414 kubelet[1813]: I0912 17:19:53.121189 1813 factory.go:223] Registration of the systemd container factory successfully Sep 12 17:19:53.121414 kubelet[1813]: I0912 17:19:53.121368 1813 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 Sep 12 17:19:53.122492 kubelet[1813]: I0912 17:19:53.122463 1813 factory.go:223] Registration of the containerd container factory successfully Sep 12 17:19:53.134016 kubelet[1813]: E0912 17:19:53.133948 1813 reflector.go:200] "Failed to watch" err="failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User \"system:anonymous\" cannot list resource \"csidrivers\" in API group \"storage.k8s.io\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.CSIDriver" Sep 12 17:19:53.134281 kubelet[1813]: E0912 17:19:53.134014 1813 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.86.186498a5f45e6676 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.86,UID:10.0.0.86,APIVersion:,ResourceVersion:,FieldPath:,},Reason:InvalidDiskCapacity,Message:invalid capacity 0 on image filesystem,Source:EventSource{Component:kubelet,Host:10.0.0.86,},FirstTimestamp:2025-09-12 17:19:53.118189174 +0000 UTC m=+1.138233052,LastTimestamp:2025-09-12 17:19:53.118189174 +0000 UTC m=+1.138233052,Count:1,Type:Warning,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.86,}" Sep 12 17:19:53.134600 kubelet[1813]: E0912 17:19:53.134567 1813 controller.go:145] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.86\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms" Sep 12 17:19:53.135850 kubelet[1813]: I0912 17:19:53.135831 1813 cpu_manager.go:221] "Starting CPU manager" policy="none" Sep 12 17:19:53.135850 kubelet[1813]: I0912 17:19:53.135847 1813 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Sep 12 17:19:53.135933 kubelet[1813]: I0912 17:19:53.135869 1813 state_mem.go:36] "Initialized new in-memory state store" Sep 12 17:19:53.219665 kubelet[1813]: E0912 17:19:53.219585 1813 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.86\" not found" Sep 12 17:19:53.229994 kubelet[1813]: I0912 17:19:53.229654 1813 policy_none.go:49] "None policy: Start" Sep 12 17:19:53.229994 kubelet[1813]: I0912 17:19:53.229681 1813 memory_manager.go:186] "Starting memorymanager" policy="None" Sep 12 17:19:53.229994 kubelet[1813]: I0912 17:19:53.229693 1813 state_mem.go:35] "Initializing new in-memory state store" Sep 12 17:19:53.235077 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Sep 12 17:19:53.246332 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Sep 12 17:19:53.250379 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Sep 12 17:19:53.256301 kubelet[1813]: I0912 17:19:53.256251 1813 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv4" Sep 12 17:19:53.257401 kubelet[1813]: I0912 17:19:53.257366 1813 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv6" Sep 12 17:19:53.257401 kubelet[1813]: I0912 17:19:53.257391 1813 status_manager.go:230] "Starting to sync pod status with apiserver" Sep 12 17:19:53.257513 kubelet[1813]: I0912 17:19:53.257410 1813 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Sep 12 17:19:53.257513 kubelet[1813]: I0912 17:19:53.257418 1813 kubelet.go:2436] "Starting kubelet main sync loop" Sep 12 17:19:53.257513 kubelet[1813]: E0912 17:19:53.257458 1813 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]" Sep 12 17:19:53.259339 kubelet[1813]: E0912 17:19:53.259296 1813 manager.go:517] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Sep 12 17:19:53.259526 kubelet[1813]: I0912 17:19:53.259510 1813 eviction_manager.go:189] "Eviction manager: starting control loop" Sep 12 17:19:53.259553 kubelet[1813]: I0912 17:19:53.259529 1813 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Sep 12 17:19:53.259978 kubelet[1813]: I0912 17:19:53.259950 1813 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Sep 12 17:19:53.261624 kubelet[1813]: E0912 17:19:53.261602 1813 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Sep 12 17:19:53.261690 kubelet[1813]: E0912 17:19:53.261641 1813 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.86\" not found" Sep 12 17:19:53.339638 kubelet[1813]: E0912 17:19:53.338971 1813 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.86\" not found" node="10.0.0.86" Sep 12 17:19:53.360929 kubelet[1813]: I0912 17:19:53.360896 1813 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.86" Sep 12 17:19:53.365293 kubelet[1813]: I0912 17:19:53.365265 1813 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.86" Sep 12 17:19:53.365293 kubelet[1813]: E0912 17:19:53.365298 1813 kubelet_node_status.go:548] "Error updating node status, will retry" err="error getting node \"10.0.0.86\": node \"10.0.0.86\" not found" Sep 12 17:19:53.387958 kubelet[1813]: E0912 17:19:53.387916 1813 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.86\" not found" Sep 12 17:19:53.488372 kubelet[1813]: E0912 17:19:53.488339 1813 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.86\" not found" Sep 12 17:19:53.497232 sudo[1682]: pam_unix(sudo:session): session closed for user root Sep 12 17:19:53.498516 sshd[1681]: Connection closed by 10.0.0.1 port 48462 Sep 12 17:19:53.498885 sshd-session[1678]: pam_unix(sshd:session): session closed for user core Sep 12 17:19:53.502530 systemd-logind[1500]: Session 5 logged out. Waiting for processes to exit. Sep 12 17:19:53.503152 systemd[1]: sshd@4-10.0.0.86:22-10.0.0.1:48462.service: Deactivated successfully. Sep 12 17:19:53.505169 systemd[1]: session-5.scope: Deactivated successfully. Sep 12 17:19:53.505464 systemd[1]: session-5.scope: Consumed 411ms CPU time, 76.7M memory peak. Sep 12 17:19:53.507359 systemd-logind[1500]: Removed session 5. Sep 12 17:19:53.589060 kubelet[1813]: E0912 17:19:53.588989 1813 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.86\" not found" Sep 12 17:19:53.689783 kubelet[1813]: E0912 17:19:53.689641 1813 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.86\" not found" Sep 12 17:19:53.790470 kubelet[1813]: E0912 17:19:53.790401 1813 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.86\" not found" Sep 12 17:19:53.891244 kubelet[1813]: E0912 17:19:53.891185 1813 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.86\" not found" Sep 12 17:19:53.992143 kubelet[1813]: E0912 17:19:53.992008 1813 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.86\" not found" Sep 12 17:19:54.063907 kubelet[1813]: I0912 17:19:54.063797 1813 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Sep 12 17:19:54.064790 kubelet[1813]: I0912 17:19:54.064010 1813 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" Sep 12 17:19:54.092152 kubelet[1813]: E0912 17:19:54.092094 1813 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.86\" not found" Sep 12 17:19:54.110441 kubelet[1813]: E0912 17:19:54.110396 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:19:54.193195 kubelet[1813]: E0912 17:19:54.193115 1813 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.86\" not found" Sep 12 17:19:54.294430 kubelet[1813]: I0912 17:19:54.294286 1813 kuberuntime_manager.go:1746] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Sep 12 17:19:54.294731 containerd[1524]: time="2025-09-12T17:19:54.294637962Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Sep 12 17:19:54.295117 kubelet[1813]: I0912 17:19:54.295046 1813 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Sep 12 17:19:55.111409 kubelet[1813]: E0912 17:19:55.111346 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:19:55.111409 kubelet[1813]: I0912 17:19:55.111352 1813 apiserver.go:52] "Watching apiserver" Sep 12 17:19:55.119607 kubelet[1813]: I0912 17:19:55.119584 1813 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Sep 12 17:19:55.125997 systemd[1]: Created slice kubepods-besteffort-pod2ba874b6_360c_44c7_8666_575243195e13.slice - libcontainer container kubepods-besteffort-pod2ba874b6_360c_44c7_8666_575243195e13.slice. Sep 12 17:19:55.130090 kubelet[1813]: I0912 17:19:55.129975 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/2ba874b6-360c-44c7-8666-575243195e13-kube-proxy\") pod \"kube-proxy-2r95h\" (UID: \"2ba874b6-360c-44c7-8666-575243195e13\") " pod="kube-system/kube-proxy-2r95h" Sep 12 17:19:55.130090 kubelet[1813]: I0912 17:19:55.130005 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/2ba874b6-360c-44c7-8666-575243195e13-xtables-lock\") pod \"kube-proxy-2r95h\" (UID: \"2ba874b6-360c-44c7-8666-575243195e13\") " pod="kube-system/kube-proxy-2r95h" Sep 12 17:19:55.130090 kubelet[1813]: I0912 17:19:55.130021 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/0555cca8-c368-4fc5-a8d1-b0ab09d928e2-run\") pod \"kube-flannel-ds-knclw\" (UID: \"0555cca8-c368-4fc5-a8d1-b0ab09d928e2\") " pod="kube-flannel/kube-flannel-ds-knclw" Sep 12 17:19:55.130319 kubelet[1813]: I0912 17:19:55.130068 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/0555cca8-c368-4fc5-a8d1-b0ab09d928e2-cni\") pod \"kube-flannel-ds-knclw\" (UID: \"0555cca8-c368-4fc5-a8d1-b0ab09d928e2\") " pod="kube-flannel/kube-flannel-ds-knclw" Sep 12 17:19:55.130319 kubelet[1813]: I0912 17:19:55.130282 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/0555cca8-c368-4fc5-a8d1-b0ab09d928e2-flannel-cfg\") pod \"kube-flannel-ds-knclw\" (UID: \"0555cca8-c368-4fc5-a8d1-b0ab09d928e2\") " pod="kube-flannel/kube-flannel-ds-knclw" Sep 12 17:19:55.130466 kubelet[1813]: I0912 17:19:55.130305 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-b8qx7\" (UniqueName: \"kubernetes.io/projected/0555cca8-c368-4fc5-a8d1-b0ab09d928e2-kube-api-access-b8qx7\") pod \"kube-flannel-ds-knclw\" (UID: \"0555cca8-c368-4fc5-a8d1-b0ab09d928e2\") " pod="kube-flannel/kube-flannel-ds-knclw" Sep 12 17:19:55.130466 kubelet[1813]: I0912 17:19:55.130422 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/2ba874b6-360c-44c7-8666-575243195e13-lib-modules\") pod \"kube-proxy-2r95h\" (UID: \"2ba874b6-360c-44c7-8666-575243195e13\") " pod="kube-system/kube-proxy-2r95h" Sep 12 17:19:55.130466 kubelet[1813]: I0912 17:19:55.130438 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-bgsqr\" (UniqueName: \"kubernetes.io/projected/2ba874b6-360c-44c7-8666-575243195e13-kube-api-access-bgsqr\") pod \"kube-proxy-2r95h\" (UID: \"2ba874b6-360c-44c7-8666-575243195e13\") " pod="kube-system/kube-proxy-2r95h" Sep 12 17:19:55.130466 kubelet[1813]: I0912 17:19:55.130452 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/0555cca8-c368-4fc5-a8d1-b0ab09d928e2-cni-plugin\") pod \"kube-flannel-ds-knclw\" (UID: \"0555cca8-c368-4fc5-a8d1-b0ab09d928e2\") " pod="kube-flannel/kube-flannel-ds-knclw" Sep 12 17:19:55.130624 kubelet[1813]: I0912 17:19:55.130612 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/0555cca8-c368-4fc5-a8d1-b0ab09d928e2-xtables-lock\") pod \"kube-flannel-ds-knclw\" (UID: \"0555cca8-c368-4fc5-a8d1-b0ab09d928e2\") " pod="kube-flannel/kube-flannel-ds-knclw" Sep 12 17:19:55.140043 systemd[1]: Created slice kubepods-burstable-pod0555cca8_c368_4fc5_a8d1_b0ab09d928e2.slice - libcontainer container kubepods-burstable-pod0555cca8_c368_4fc5_a8d1_b0ab09d928e2.slice. Sep 12 17:19:55.439215 containerd[1524]: time="2025-09-12T17:19:55.439106199Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-2r95h,Uid:2ba874b6-360c-44c7-8666-575243195e13,Namespace:kube-system,Attempt:0,}" Sep 12 17:19:55.454644 containerd[1524]: time="2025-09-12T17:19:55.454609133Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-knclw,Uid:0555cca8-c368-4fc5-a8d1-b0ab09d928e2,Namespace:kube-flannel,Attempt:0,}" Sep 12 17:19:55.921918 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2387762612.mount: Deactivated successfully. Sep 12 17:19:55.928623 containerd[1524]: time="2025-09-12T17:19:55.928574456Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 12 17:19:55.929303 containerd[1524]: time="2025-09-12T17:19:55.929271114Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268705" Sep 12 17:19:55.930013 containerd[1524]: time="2025-09-12T17:19:55.929973417Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 12 17:19:55.931252 containerd[1524]: time="2025-09-12T17:19:55.931212339Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Sep 12 17:19:55.931823 containerd[1524]: time="2025-09-12T17:19:55.931792280Z" level=info msg="ImageCreate event name:\"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 12 17:19:55.934252 containerd[1524]: time="2025-09-12T17:19:55.934219872Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 12 17:19:55.935928 containerd[1524]: time="2025-09-12T17:19:55.935565181Z" 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 477.308032ms" Sep 12 17:19:55.935928 containerd[1524]: time="2025-09-12T17:19:55.935734430Z" 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 489.747897ms" Sep 12 17:19:55.953159 containerd[1524]: time="2025-09-12T17:19:55.953108079Z" level=info msg="connecting to shim 9b1d132e94103440c7fc4fb01b009ba514e9fa036e421e5b2f355e10f741a795" address="unix:///run/containerd/s/d52b5330cdcfbcfb65ea8874b4fd66275ae2e098765600d2ff1b93541860c5ae" namespace=k8s.io protocol=ttrpc version=3 Sep 12 17:19:55.954251 containerd[1524]: time="2025-09-12T17:19:55.954210263Z" level=info msg="connecting to shim 4bd4c5676420b877bbd0d02392f0d5d99113c638d146b45161b06218b14ffc89" address="unix:///run/containerd/s/21c865ae5f12d56cc5cef3448ce25057c32c3c1f66ae29c9492abe256b593f49" namespace=k8s.io protocol=ttrpc version=3 Sep 12 17:19:55.978666 systemd[1]: Started cri-containerd-4bd4c5676420b877bbd0d02392f0d5d99113c638d146b45161b06218b14ffc89.scope - libcontainer container 4bd4c5676420b877bbd0d02392f0d5d99113c638d146b45161b06218b14ffc89. Sep 12 17:19:55.979841 systemd[1]: Started cri-containerd-9b1d132e94103440c7fc4fb01b009ba514e9fa036e421e5b2f355e10f741a795.scope - libcontainer container 9b1d132e94103440c7fc4fb01b009ba514e9fa036e421e5b2f355e10f741a795. Sep 12 17:19:56.004109 containerd[1524]: time="2025-09-12T17:19:56.003856061Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-2r95h,Uid:2ba874b6-360c-44c7-8666-575243195e13,Namespace:kube-system,Attempt:0,} returns sandbox id \"9b1d132e94103440c7fc4fb01b009ba514e9fa036e421e5b2f355e10f741a795\"" Sep 12 17:19:56.009512 containerd[1524]: time="2025-09-12T17:19:56.008561121Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\"" Sep 12 17:19:56.014763 containerd[1524]: time="2025-09-12T17:19:56.014729716Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-knclw,Uid:0555cca8-c368-4fc5-a8d1-b0ab09d928e2,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"4bd4c5676420b877bbd0d02392f0d5d99113c638d146b45161b06218b14ffc89\"" Sep 12 17:19:56.111750 kubelet[1813]: E0912 17:19:56.111697 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:19:57.011666 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2712506852.mount: Deactivated successfully. Sep 12 17:19:57.112164 kubelet[1813]: E0912 17:19:57.112127 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:19:57.255276 containerd[1524]: time="2025-09-12T17:19:57.255232223Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.33.5\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:19:57.255695 containerd[1524]: time="2025-09-12T17:19:57.255669167Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.33.5: active requests=0, bytes read=28240108" Sep 12 17:19:57.256390 containerd[1524]: time="2025-09-12T17:19:57.256366381Z" level=info msg="ImageCreate event name:\"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:19:57.258738 containerd[1524]: time="2025-09-12T17:19:57.258705882Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:71445ec84ad98bd52a7784865a9d31b1b50b56092d3f7699edc39eefd71befe1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:19:57.259253 containerd[1524]: time="2025-09-12T17:19:57.259219935Z" 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.250617016s" Sep 12 17:19:57.259253 containerd[1524]: time="2025-09-12T17:19:57.259247399Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\" returns image reference \"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\"" Sep 12 17:19:57.260441 containerd[1524]: time="2025-09-12T17:19:57.260411584Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Sep 12 17:19:57.263608 containerd[1524]: time="2025-09-12T17:19:57.263506711Z" level=info msg="CreateContainer within sandbox \"9b1d132e94103440c7fc4fb01b009ba514e9fa036e421e5b2f355e10f741a795\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Sep 12 17:19:57.272615 containerd[1524]: time="2025-09-12T17:19:57.272124781Z" level=info msg="Container e1a4dc9e1cb9911286666daf7e3116873f4ca5c2569d979fad88a3c230594759: CDI devices from CRI Config.CDIDevices: []" Sep 12 17:19:57.274897 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3925742577.mount: Deactivated successfully. Sep 12 17:19:57.282051 containerd[1524]: time="2025-09-12T17:19:57.281999799Z" level=info msg="CreateContainer within sandbox \"9b1d132e94103440c7fc4fb01b009ba514e9fa036e421e5b2f355e10f741a795\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"e1a4dc9e1cb9911286666daf7e3116873f4ca5c2569d979fad88a3c230594759\"" Sep 12 17:19:57.282789 containerd[1524]: time="2025-09-12T17:19:57.282726880Z" level=info msg="StartContainer for \"e1a4dc9e1cb9911286666daf7e3116873f4ca5c2569d979fad88a3c230594759\"" Sep 12 17:19:57.284126 containerd[1524]: time="2025-09-12T17:19:57.284086677Z" level=info msg="connecting to shim e1a4dc9e1cb9911286666daf7e3116873f4ca5c2569d979fad88a3c230594759" address="unix:///run/containerd/s/d52b5330cdcfbcfb65ea8874b4fd66275ae2e098765600d2ff1b93541860c5ae" protocol=ttrpc version=3 Sep 12 17:19:57.308679 systemd[1]: Started cri-containerd-e1a4dc9e1cb9911286666daf7e3116873f4ca5c2569d979fad88a3c230594759.scope - libcontainer container e1a4dc9e1cb9911286666daf7e3116873f4ca5c2569d979fad88a3c230594759. Sep 12 17:19:57.340806 containerd[1524]: time="2025-09-12T17:19:57.340752908Z" level=info msg="StartContainer for \"e1a4dc9e1cb9911286666daf7e3116873f4ca5c2569d979fad88a3c230594759\" returns successfully" Sep 12 17:19:58.112324 kubelet[1813]: E0912 17:19:58.112258 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:19:58.274718 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount92795713.mount: Deactivated successfully. Sep 12 17:19:58.283896 kubelet[1813]: I0912 17:19:58.283665 1813 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-2r95h" podStartSLOduration=4.031349827 podStartE2EDuration="5.283646198s" podCreationTimestamp="2025-09-12 17:19:53 +0000 UTC" firstStartedPulling="2025-09-12 17:19:56.007980175 +0000 UTC m=+4.028024012" lastFinishedPulling="2025-09-12 17:19:57.260276546 +0000 UTC m=+5.280320383" observedRunningTime="2025-09-12 17:19:58.28271599 +0000 UTC m=+6.302760027" watchObservedRunningTime="2025-09-12 17:19:58.283646198 +0000 UTC m=+6.303690075" Sep 12 17:19:58.316938 containerd[1524]: time="2025-09-12T17:19:58.316880322Z" 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\"}" Sep 12 17:19:58.317686 containerd[1524]: time="2025-09-12T17:19:58.317635666Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=5125564" Sep 12 17:19:58.318226 containerd[1524]: time="2025-09-12T17:19:58.318200612Z" level=info msg="ImageCreate event name:\"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:19:58.320707 containerd[1524]: time="2025-09-12T17:19:58.320669251Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:19:58.321720 containerd[1524]: time="2025-09-12T17:19:58.321698020Z" 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 1.061250445s" Sep 12 17:19:58.321777 containerd[1524]: time="2025-09-12T17:19:58.321726284Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\"" Sep 12 17:19:58.328528 containerd[1524]: time="2025-09-12T17:19:58.328181654Z" level=info msg="CreateContainer within sandbox \"4bd4c5676420b877bbd0d02392f0d5d99113c638d146b45161b06218b14ffc89\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Sep 12 17:19:58.339643 containerd[1524]: time="2025-09-12T17:19:58.339602405Z" level=info msg="Container 6514b0fbb31a75b964d0e33e40b611ae685f63288b452f8ceaf16701578a81a3: CDI devices from CRI Config.CDIDevices: []" Sep 12 17:19:58.349447 containerd[1524]: time="2025-09-12T17:19:58.349383643Z" level=info msg="CreateContainer within sandbox \"4bd4c5676420b877bbd0d02392f0d5d99113c638d146b45161b06218b14ffc89\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"6514b0fbb31a75b964d0e33e40b611ae685f63288b452f8ceaf16701578a81a3\"" Sep 12 17:19:58.350244 containerd[1524]: time="2025-09-12T17:19:58.350206882Z" level=info msg="StartContainer for \"6514b0fbb31a75b964d0e33e40b611ae685f63288b452f8ceaf16701578a81a3\"" Sep 12 17:19:58.351653 containerd[1524]: time="2025-09-12T17:19:58.351551112Z" level=info msg="connecting to shim 6514b0fbb31a75b964d0e33e40b611ae685f63288b452f8ceaf16701578a81a3" address="unix:///run/containerd/s/21c865ae5f12d56cc5cef3448ce25057c32c3c1f66ae29c9492abe256b593f49" protocol=ttrpc version=3 Sep 12 17:19:58.373802 systemd[1]: Started cri-containerd-6514b0fbb31a75b964d0e33e40b611ae685f63288b452f8ceaf16701578a81a3.scope - libcontainer container 6514b0fbb31a75b964d0e33e40b611ae685f63288b452f8ceaf16701578a81a3. Sep 12 17:19:58.402196 containerd[1524]: time="2025-09-12T17:19:58.402147094Z" level=info msg="StartContainer for \"6514b0fbb31a75b964d0e33e40b611ae685f63288b452f8ceaf16701578a81a3\" returns successfully" Sep 12 17:19:58.403370 systemd[1]: cri-containerd-6514b0fbb31a75b964d0e33e40b611ae685f63288b452f8ceaf16701578a81a3.scope: Deactivated successfully. Sep 12 17:19:58.404900 containerd[1524]: time="2025-09-12T17:19:58.404615292Z" level=info msg="received exit event container_id:\"6514b0fbb31a75b964d0e33e40b611ae685f63288b452f8ceaf16701578a81a3\" id:\"6514b0fbb31a75b964d0e33e40b611ae685f63288b452f8ceaf16701578a81a3\" pid:2152 exited_at:{seconds:1757697598 nanos:404254954}" Sep 12 17:19:58.404900 containerd[1524]: time="2025-09-12T17:19:58.404762293Z" level=info msg="TaskExit event in podsandbox handler container_id:\"6514b0fbb31a75b964d0e33e40b611ae685f63288b452f8ceaf16701578a81a3\" id:\"6514b0fbb31a75b964d0e33e40b611ae685f63288b452f8ceaf16701578a81a3\" pid:2152 exited_at:{seconds:1757697598 nanos:404254954}" Sep 12 17:19:58.422059 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-6514b0fbb31a75b964d0e33e40b611ae685f63288b452f8ceaf16701578a81a3-rootfs.mount: Deactivated successfully. Sep 12 17:19:59.112884 kubelet[1813]: E0912 17:19:59.112843 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:19:59.275628 containerd[1524]: time="2025-09-12T17:19:59.275595066Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Sep 12 17:20:00.113527 kubelet[1813]: E0912 17:20:00.113447 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:20:01.014592 containerd[1524]: time="2025-09-12T17:20:01.014520935Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:20:01.015237 containerd[1524]: time="2025-09-12T17:20:01.015201878Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=28419854" Sep 12 17:20:01.016325 containerd[1524]: time="2025-09-12T17:20:01.016277370Z" level=info msg="ImageCreate event name:\"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:20:01.019991 containerd[1524]: time="2025-09-12T17:20:01.019955553Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:20:01.022192 containerd[1524]: time="2025-09-12T17:20:01.022166017Z" 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.745166825s" Sep 12 17:20:01.022234 containerd[1524]: time="2025-09-12T17:20:01.022199200Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\"" Sep 12 17:20:01.025308 containerd[1524]: time="2025-09-12T17:20:01.025279776Z" level=info msg="CreateContainer within sandbox \"4bd4c5676420b877bbd0d02392f0d5d99113c638d146b45161b06218b14ffc89\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Sep 12 17:20:01.030507 containerd[1524]: time="2025-09-12T17:20:01.030243234Z" level=info msg="Container 7486e1f541b6a34c779bdf1f819d455cc71ced73bb5e7ff6cf90ae6736bccab8: CDI devices from CRI Config.CDIDevices: []" Sep 12 17:20:01.037074 containerd[1524]: time="2025-09-12T17:20:01.037031213Z" level=info msg="CreateContainer within sandbox \"4bd4c5676420b877bbd0d02392f0d5d99113c638d146b45161b06218b14ffc89\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"7486e1f541b6a34c779bdf1f819d455cc71ced73bb5e7ff6cf90ae6736bccab8\"" Sep 12 17:20:01.037583 containerd[1524]: time="2025-09-12T17:20:01.037556251Z" level=info msg="StartContainer for \"7486e1f541b6a34c779bdf1f819d455cc71ced73bb5e7ff6cf90ae6736bccab8\"" Sep 12 17:20:01.038300 containerd[1524]: time="2025-09-12T17:20:01.038277221Z" level=info msg="connecting to shim 7486e1f541b6a34c779bdf1f819d455cc71ced73bb5e7ff6cf90ae6736bccab8" address="unix:///run/containerd/s/21c865ae5f12d56cc5cef3448ce25057c32c3c1f66ae29c9492abe256b593f49" protocol=ttrpc version=3 Sep 12 17:20:01.057621 systemd[1]: Started cri-containerd-7486e1f541b6a34c779bdf1f819d455cc71ced73bb5e7ff6cf90ae6736bccab8.scope - libcontainer container 7486e1f541b6a34c779bdf1f819d455cc71ced73bb5e7ff6cf90ae6736bccab8. Sep 12 17:20:01.079389 systemd[1]: cri-containerd-7486e1f541b6a34c779bdf1f819d455cc71ced73bb5e7ff6cf90ae6736bccab8.scope: Deactivated successfully. Sep 12 17:20:01.082060 containerd[1524]: time="2025-09-12T17:20:01.082032558Z" level=info msg="received exit event container_id:\"7486e1f541b6a34c779bdf1f819d455cc71ced73bb5e7ff6cf90ae6736bccab8\" id:\"7486e1f541b6a34c779bdf1f819d455cc71ced73bb5e7ff6cf90ae6736bccab8\" pid:2225 exited_at:{seconds:1757697601 nanos:81907953}" Sep 12 17:20:01.082276 containerd[1524]: time="2025-09-12T17:20:01.082135828Z" level=info msg="TaskExit event in podsandbox handler container_id:\"7486e1f541b6a34c779bdf1f819d455cc71ced73bb5e7ff6cf90ae6736bccab8\" id:\"7486e1f541b6a34c779bdf1f819d455cc71ced73bb5e7ff6cf90ae6736bccab8\" pid:2225 exited_at:{seconds:1757697601 nanos:81907953}" Sep 12 17:20:01.082535 containerd[1524]: time="2025-09-12T17:20:01.082515607Z" level=info msg="StartContainer for \"7486e1f541b6a34c779bdf1f819d455cc71ced73bb5e7ff6cf90ae6736bccab8\" returns successfully" Sep 12 17:20:01.098613 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-7486e1f541b6a34c779bdf1f819d455cc71ced73bb5e7ff6cf90ae6736bccab8-rootfs.mount: Deactivated successfully. Sep 12 17:20:01.114220 kubelet[1813]: E0912 17:20:01.114176 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:20:01.162989 kubelet[1813]: I0912 17:20:01.162953 1813 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Sep 12 17:20:01.283637 containerd[1524]: time="2025-09-12T17:20:01.283549335Z" level=info msg="CreateContainer within sandbox \"4bd4c5676420b877bbd0d02392f0d5d99113c638d146b45161b06218b14ffc89\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Sep 12 17:20:01.291403 containerd[1524]: time="2025-09-12T17:20:01.291142662Z" level=info msg="Container 65fa869e14906d40221a6218b740a0a015a32400e0bfaee761e23ee2f0fe141e: CDI devices from CRI Config.CDIDevices: []" Sep 12 17:20:01.297541 containerd[1524]: time="2025-09-12T17:20:01.297508875Z" level=info msg="CreateContainer within sandbox \"4bd4c5676420b877bbd0d02392f0d5d99113c638d146b45161b06218b14ffc89\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"65fa869e14906d40221a6218b740a0a015a32400e0bfaee761e23ee2f0fe141e\"" Sep 12 17:20:01.298051 containerd[1524]: time="2025-09-12T17:20:01.297937727Z" level=info msg="StartContainer for \"65fa869e14906d40221a6218b740a0a015a32400e0bfaee761e23ee2f0fe141e\"" Sep 12 17:20:01.298829 containerd[1524]: time="2025-09-12T17:20:01.298802395Z" level=info msg="connecting to shim 65fa869e14906d40221a6218b740a0a015a32400e0bfaee761e23ee2f0fe141e" address="unix:///run/containerd/s/21c865ae5f12d56cc5cef3448ce25057c32c3c1f66ae29c9492abe256b593f49" protocol=ttrpc version=3 Sep 12 17:20:01.319640 systemd[1]: Started cri-containerd-65fa869e14906d40221a6218b740a0a015a32400e0bfaee761e23ee2f0fe141e.scope - libcontainer container 65fa869e14906d40221a6218b740a0a015a32400e0bfaee761e23ee2f0fe141e. Sep 12 17:20:01.345889 containerd[1524]: time="2025-09-12T17:20:01.345855656Z" level=info msg="StartContainer for \"65fa869e14906d40221a6218b740a0a015a32400e0bfaee761e23ee2f0fe141e\" returns successfully" Sep 12 17:20:02.114670 kubelet[1813]: E0912 17:20:02.114622 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:20:02.309204 kubelet[1813]: I0912 17:20:02.309114 1813 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-knclw" podStartSLOduration=4.30221742 podStartE2EDuration="9.309100536s" podCreationTimestamp="2025-09-12 17:19:53 +0000 UTC" firstStartedPulling="2025-09-12 17:19:56.015846445 +0000 UTC m=+4.035890322" lastFinishedPulling="2025-09-12 17:20:01.022729561 +0000 UTC m=+9.042773438" observedRunningTime="2025-09-12 17:20:02.308996229 +0000 UTC m=+10.329040106" watchObservedRunningTime="2025-09-12 17:20:02.309100536 +0000 UTC m=+10.329144413" Sep 12 17:20:02.420435 systemd-networkd[1431]: flannel.1: Link UP Sep 12 17:20:02.420442 systemd-networkd[1431]: flannel.1: Gained carrier Sep 12 17:20:03.115556 kubelet[1813]: E0912 17:20:03.115509 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:20:04.116606 kubelet[1813]: E0912 17:20:04.116556 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:20:04.445271 systemd[1]: Created slice kubepods-besteffort-pod8289e2d7_5938_4fd9_9cf3_a685caf9b43d.slice - libcontainer container kubepods-besteffort-pod8289e2d7_5938_4fd9_9cf3_a685caf9b43d.slice. Sep 12 17:20:04.487873 kubelet[1813]: I0912 17:20:04.487812 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-6kwvr\" (UniqueName: \"kubernetes.io/projected/8289e2d7-5938-4fd9-9cf3-a685caf9b43d-kube-api-access-6kwvr\") pod \"nginx-deployment-7fcdb87857-hjncj\" (UID: \"8289e2d7-5938-4fd9-9cf3-a685caf9b43d\") " pod="default/nginx-deployment-7fcdb87857-hjncj" Sep 12 17:20:04.498743 systemd-networkd[1431]: flannel.1: Gained IPv6LL Sep 12 17:20:04.748842 containerd[1524]: time="2025-09-12T17:20:04.748701404Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-hjncj,Uid:8289e2d7-5938-4fd9-9cf3-a685caf9b43d,Namespace:default,Attempt:0,}" Sep 12 17:20:04.761402 systemd-networkd[1431]: cni0: Link UP Sep 12 17:20:04.761408 systemd-networkd[1431]: cni0: Gained carrier Sep 12 17:20:04.762898 systemd-networkd[1431]: cni0: Lost carrier Sep 12 17:20:04.769051 kernel: cni0: port 1(vethef976939) entered blocking state Sep 12 17:20:04.769174 kernel: cni0: port 1(vethef976939) entered disabled state Sep 12 17:20:04.769193 kernel: vethef976939: entered allmulticast mode Sep 12 17:20:04.769932 kernel: vethef976939: entered promiscuous mode Sep 12 17:20:04.770218 systemd-networkd[1431]: vethef976939: Link UP Sep 12 17:20:04.777659 kernel: cni0: port 1(vethef976939) entered blocking state Sep 12 17:20:04.777756 kernel: cni0: port 1(vethef976939) entered forwarding state Sep 12 17:20:04.777922 systemd-networkd[1431]: vethef976939: Gained carrier Sep 12 17:20:04.778104 systemd-networkd[1431]: cni0: Gained carrier Sep 12 17:20:04.781508 containerd[1524]: 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"} Sep 12 17:20:04.781508 containerd[1524]: delegateAdd: netconf sent to delegate plugin: Sep 12 17:20:04.810313 containerd[1524]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-09-12T17:20:04.810253082Z" level=info msg="connecting to shim 7137934f8901833b5c60b4bf18677f98149f45ee772a0bef6f643a602649f7a6" address="unix:///run/containerd/s/65509f27665cc66214bfdb95986a6e766f262a5868a2b33e5fba4df120d1fbd6" namespace=k8s.io protocol=ttrpc version=3 Sep 12 17:20:04.838676 systemd[1]: Started cri-containerd-7137934f8901833b5c60b4bf18677f98149f45ee772a0bef6f643a602649f7a6.scope - libcontainer container 7137934f8901833b5c60b4bf18677f98149f45ee772a0bef6f643a602649f7a6. Sep 12 17:20:04.848929 systemd-resolved[1352]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 12 17:20:04.878764 containerd[1524]: time="2025-09-12T17:20:04.878702989Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-hjncj,Uid:8289e2d7-5938-4fd9-9cf3-a685caf9b43d,Namespace:default,Attempt:0,} returns sandbox id \"7137934f8901833b5c60b4bf18677f98149f45ee772a0bef6f643a602649f7a6\"" Sep 12 17:20:04.879844 containerd[1524]: time="2025-09-12T17:20:04.879812371Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 12 17:20:05.117143 kubelet[1813]: E0912 17:20:05.117029 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:20:06.117805 kubelet[1813]: E0912 17:20:06.117749 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:20:06.674652 systemd-networkd[1431]: cni0: Gained IPv6LL Sep 12 17:20:06.739600 systemd-networkd[1431]: vethef976939: Gained IPv6LL Sep 12 17:20:07.087138 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2270474626.mount: Deactivated successfully. Sep 12 17:20:07.117890 kubelet[1813]: E0912 17:20:07.117839 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:20:07.825279 containerd[1524]: time="2025-09-12T17:20:07.825207846Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:20:07.825730 containerd[1524]: time="2025-09-12T17:20:07.825613073Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=69986522" Sep 12 17:20:07.826561 containerd[1524]: time="2025-09-12T17:20:07.826532058Z" level=info msg="ImageCreate event name:\"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:20:07.829104 containerd[1524]: time="2025-09-12T17:20:07.829073353Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:20:07.829841 containerd[1524]: time="2025-09-12T17:20:07.829818017Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\", size \"69986400\" in 2.949965464s" Sep 12 17:20:07.829877 containerd[1524]: time="2025-09-12T17:20:07.829845950Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\"" Sep 12 17:20:07.833416 containerd[1524]: time="2025-09-12T17:20:07.833369498Z" level=info msg="CreateContainer within sandbox \"7137934f8901833b5c60b4bf18677f98149f45ee772a0bef6f643a602649f7a6\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Sep 12 17:20:07.840945 containerd[1524]: time="2025-09-12T17:20:07.840773680Z" level=info msg="Container fa7045eb376f90d28a82e9e2965567698b2707c9ce6804c3dded2fe0cbea0288: CDI devices from CRI Config.CDIDevices: []" Sep 12 17:20:07.844500 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3607103428.mount: Deactivated successfully. Sep 12 17:20:07.851509 containerd[1524]: time="2025-09-12T17:20:07.849849274Z" level=info msg="CreateContainer within sandbox \"7137934f8901833b5c60b4bf18677f98149f45ee772a0bef6f643a602649f7a6\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"fa7045eb376f90d28a82e9e2965567698b2707c9ce6804c3dded2fe0cbea0288\"" Sep 12 17:20:07.851509 containerd[1524]: time="2025-09-12T17:20:07.850598700Z" level=info msg="StartContainer for \"fa7045eb376f90d28a82e9e2965567698b2707c9ce6804c3dded2fe0cbea0288\"" Sep 12 17:20:07.851509 containerd[1524]: time="2025-09-12T17:20:07.851462259Z" level=info msg="connecting to shim fa7045eb376f90d28a82e9e2965567698b2707c9ce6804c3dded2fe0cbea0288" address="unix:///run/containerd/s/65509f27665cc66214bfdb95986a6e766f262a5868a2b33e5fba4df120d1fbd6" protocol=ttrpc version=3 Sep 12 17:20:07.880664 systemd[1]: Started cri-containerd-fa7045eb376f90d28a82e9e2965567698b2707c9ce6804c3dded2fe0cbea0288.scope - libcontainer container fa7045eb376f90d28a82e9e2965567698b2707c9ce6804c3dded2fe0cbea0288. Sep 12 17:20:07.907363 containerd[1524]: time="2025-09-12T17:20:07.907323074Z" level=info msg="StartContainer for \"fa7045eb376f90d28a82e9e2965567698b2707c9ce6804c3dded2fe0cbea0288\" returns successfully" Sep 12 17:20:08.118830 kubelet[1813]: E0912 17:20:08.118719 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:20:09.119220 kubelet[1813]: E0912 17:20:09.119169 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:20:10.119748 kubelet[1813]: E0912 17:20:10.119695 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:20:10.813885 kubelet[1813]: I0912 17:20:10.813811 1813 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-hjncj" podStartSLOduration=3.862548864 podStartE2EDuration="6.813792195s" podCreationTimestamp="2025-09-12 17:20:04 +0000 UTC" firstStartedPulling="2025-09-12 17:20:04.879472581 +0000 UTC m=+12.899516458" lastFinishedPulling="2025-09-12 17:20:07.830715912 +0000 UTC m=+15.850759789" observedRunningTime="2025-09-12 17:20:08.303715228 +0000 UTC m=+16.323759105" watchObservedRunningTime="2025-09-12 17:20:10.813792195 +0000 UTC m=+18.833836032" Sep 12 17:20:10.825921 systemd[1]: Created slice kubepods-besteffort-pod99dc8bea_2b6d_487f_95a7_96a347711427.slice - libcontainer container kubepods-besteffort-pod99dc8bea_2b6d_487f_95a7_96a347711427.slice. Sep 12 17:20:10.930009 kubelet[1813]: I0912 17:20:10.929949 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-t82mv\" (UniqueName: \"kubernetes.io/projected/99dc8bea-2b6d-487f-95a7-96a347711427-kube-api-access-t82mv\") pod \"nfs-server-provisioner-0\" (UID: \"99dc8bea-2b6d-487f-95a7-96a347711427\") " pod="default/nfs-server-provisioner-0" Sep 12 17:20:10.930009 kubelet[1813]: I0912 17:20:10.930020 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/99dc8bea-2b6d-487f-95a7-96a347711427-data\") pod \"nfs-server-provisioner-0\" (UID: \"99dc8bea-2b6d-487f-95a7-96a347711427\") " pod="default/nfs-server-provisioner-0" Sep 12 17:20:11.120653 kubelet[1813]: E0912 17:20:11.120518 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:20:11.131998 containerd[1524]: time="2025-09-12T17:20:11.131947030Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:99dc8bea-2b6d-487f-95a7-96a347711427,Namespace:default,Attempt:0,}" Sep 12 17:20:11.151889 systemd-networkd[1431]: vethd7e743c4: Link UP Sep 12 17:20:11.157077 kernel: cni0: port 2(vethd7e743c4) entered blocking state Sep 12 17:20:11.157151 kernel: cni0: port 2(vethd7e743c4) entered disabled state Sep 12 17:20:11.157164 kernel: vethd7e743c4: entered allmulticast mode Sep 12 17:20:11.159952 kernel: vethd7e743c4: entered promiscuous mode Sep 12 17:20:11.168963 kernel: cni0: port 2(vethd7e743c4) entered blocking state Sep 12 17:20:11.169141 kernel: cni0: port 2(vethd7e743c4) entered forwarding state Sep 12 17:20:11.169203 systemd-networkd[1431]: vethd7e743c4: Gained carrier Sep 12 17:20:11.170813 containerd[1524]: 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"} Sep 12 17:20:11.170813 containerd[1524]: delegateAdd: netconf sent to delegate plugin: Sep 12 17:20:11.194941 containerd[1524]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-09-12T17:20:11.194901346Z" level=info msg="connecting to shim eb96570a02b1d9032a42f052a762a06960b7394417877a1a7a45f8eef9bbef9c" address="unix:///run/containerd/s/a005cc5084b3696e66b5d581f699081152978874e81b874d927b076c4baa34ee" namespace=k8s.io protocol=ttrpc version=3 Sep 12 17:20:11.221693 systemd[1]: Started cri-containerd-eb96570a02b1d9032a42f052a762a06960b7394417877a1a7a45f8eef9bbef9c.scope - libcontainer container eb96570a02b1d9032a42f052a762a06960b7394417877a1a7a45f8eef9bbef9c. Sep 12 17:20:11.235318 systemd-resolved[1352]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 12 17:20:11.257835 containerd[1524]: time="2025-09-12T17:20:11.257783036Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:99dc8bea-2b6d-487f-95a7-96a347711427,Namespace:default,Attempt:0,} returns sandbox id \"eb96570a02b1d9032a42f052a762a06960b7394417877a1a7a45f8eef9bbef9c\"" Sep 12 17:20:11.259683 containerd[1524]: time="2025-09-12T17:20:11.259583999Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Sep 12 17:20:12.120685 kubelet[1813]: E0912 17:20:12.120644 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:20:12.370671 systemd-networkd[1431]: vethd7e743c4: Gained IPv6LL Sep 12 17:20:13.108801 kubelet[1813]: E0912 17:20:13.108767 1813 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:20:13.121230 kubelet[1813]: E0912 17:20:13.121178 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:20:13.158758 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2856502113.mount: Deactivated successfully. Sep 12 17:20:14.121780 kubelet[1813]: E0912 17:20:14.121704 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:20:14.630944 containerd[1524]: time="2025-09-12T17:20:14.630899406Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:20:14.631663 containerd[1524]: time="2025-09-12T17:20:14.631626540Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Sep 12 17:20:14.632344 containerd[1524]: time="2025-09-12T17:20:14.632317464Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:20:14.634756 containerd[1524]: time="2025-09-12T17:20:14.634731254Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:20:14.635608 containerd[1524]: time="2025-09-12T17:20:14.635571861Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"87371201\" in 3.375950609s" Sep 12 17:20:14.635661 containerd[1524]: time="2025-09-12T17:20:14.635618235Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Sep 12 17:20:14.639783 containerd[1524]: time="2025-09-12T17:20:14.639751050Z" level=info msg="CreateContainer within sandbox \"eb96570a02b1d9032a42f052a762a06960b7394417877a1a7a45f8eef9bbef9c\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Sep 12 17:20:14.648026 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1948347071.mount: Deactivated successfully. Sep 12 17:20:14.650009 containerd[1524]: time="2025-09-12T17:20:14.649156377Z" level=info msg="Container 84801d2bf9877d31061756a403a502a9015eb429894a84502e7939531a9fa8e3: CDI devices from CRI Config.CDIDevices: []" Sep 12 17:20:14.656796 containerd[1524]: time="2025-09-12T17:20:14.656757414Z" level=info msg="CreateContainer within sandbox \"eb96570a02b1d9032a42f052a762a06960b7394417877a1a7a45f8eef9bbef9c\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"84801d2bf9877d31061756a403a502a9015eb429894a84502e7939531a9fa8e3\"" Sep 12 17:20:14.658775 containerd[1524]: time="2025-09-12T17:20:14.657655078Z" level=info msg="StartContainer for \"84801d2bf9877d31061756a403a502a9015eb429894a84502e7939531a9fa8e3\"" Sep 12 17:20:14.658775 containerd[1524]: time="2025-09-12T17:20:14.658684981Z" level=info msg="connecting to shim 84801d2bf9877d31061756a403a502a9015eb429894a84502e7939531a9fa8e3" address="unix:///run/containerd/s/a005cc5084b3696e66b5d581f699081152978874e81b874d927b076c4baa34ee" protocol=ttrpc version=3 Sep 12 17:20:14.679648 systemd[1]: Started cri-containerd-84801d2bf9877d31061756a403a502a9015eb429894a84502e7939531a9fa8e3.scope - libcontainer container 84801d2bf9877d31061756a403a502a9015eb429894a84502e7939531a9fa8e3. Sep 12 17:20:14.705925 containerd[1524]: time="2025-09-12T17:20:14.705881746Z" level=info msg="StartContainer for \"84801d2bf9877d31061756a403a502a9015eb429894a84502e7939531a9fa8e3\" returns successfully" Sep 12 17:20:15.122742 kubelet[1813]: E0912 17:20:15.122322 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:20:15.318653 kubelet[1813]: I0912 17:20:15.318596 1813 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.94106212 podStartE2EDuration="5.318581494s" podCreationTimestamp="2025-09-12 17:20:10 +0000 UTC" firstStartedPulling="2025-09-12 17:20:11.259236075 +0000 UTC m=+19.279279952" lastFinishedPulling="2025-09-12 17:20:14.636755449 +0000 UTC m=+22.656799326" observedRunningTime="2025-09-12 17:20:15.317827006 +0000 UTC m=+23.337870883" watchObservedRunningTime="2025-09-12 17:20:15.318581494 +0000 UTC m=+23.338625371" Sep 12 17:20:16.123974 kubelet[1813]: E0912 17:20:16.123927 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:20:17.125066 kubelet[1813]: E0912 17:20:17.125030 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:20:18.126101 kubelet[1813]: E0912 17:20:18.126055 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:20:19.126689 kubelet[1813]: E0912 17:20:19.126639 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:20:19.967660 systemd[1]: Created slice kubepods-besteffort-poda9edc5c0_354b_40d0_89a3_71385612024b.slice - libcontainer container kubepods-besteffort-poda9edc5c0_354b_40d0_89a3_71385612024b.slice. Sep 12 17:20:19.984772 kubelet[1813]: I0912 17:20:19.984734 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-f353d2b2-a668-4104-a62c-92d376c8de3c\" (UniqueName: \"kubernetes.io/nfs/a9edc5c0-354b-40d0-89a3-71385612024b-pvc-f353d2b2-a668-4104-a62c-92d376c8de3c\") pod \"test-pod-1\" (UID: \"a9edc5c0-354b-40d0-89a3-71385612024b\") " pod="default/test-pod-1" Sep 12 17:20:19.984960 kubelet[1813]: I0912 17:20:19.984933 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-vzp6b\" (UniqueName: \"kubernetes.io/projected/a9edc5c0-354b-40d0-89a3-71385612024b-kube-api-access-vzp6b\") pod \"test-pod-1\" (UID: \"a9edc5c0-354b-40d0-89a3-71385612024b\") " pod="default/test-pod-1" Sep 12 17:20:20.108513 kernel: netfs: FS-Cache loaded Sep 12 17:20:20.127234 kubelet[1813]: E0912 17:20:20.127184 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:20:20.132658 kernel: RPC: Registered named UNIX socket transport module. Sep 12 17:20:20.132724 kernel: RPC: Registered udp transport module. Sep 12 17:20:20.132741 kernel: RPC: Registered tcp transport module. Sep 12 17:20:20.132754 kernel: RPC: Registered tcp-with-tls transport module. Sep 12 17:20:20.134145 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Sep 12 17:20:20.309536 kernel: NFS: Registering the id_resolver key type Sep 12 17:20:20.309658 kernel: Key type id_resolver registered Sep 12 17:20:20.309678 kernel: Key type id_legacy registered Sep 12 17:20:20.329976 nfsidmap[2791]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf Sep 12 17:20:20.330539 nfsidmap[2791]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 12 17:20:20.331917 nfsidmap[2792]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf Sep 12 17:20:20.332056 nfsidmap[2792]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 12 17:20:20.352991 nfsrahead[2794]: setting /var/lib/kubelet/pods/a9edc5c0-354b-40d0-89a3-71385612024b/volumes/kubernetes.io~nfs/pvc-f353d2b2-a668-4104-a62c-92d376c8de3c readahead to 128 Sep 12 17:20:20.572088 containerd[1524]: time="2025-09-12T17:20:20.572042678Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:a9edc5c0-354b-40d0-89a3-71385612024b,Namespace:default,Attempt:0,}" Sep 12 17:20:20.588264 systemd-networkd[1431]: veth833584c0: Link UP Sep 12 17:20:20.590694 kernel: cni0: port 3(veth833584c0) entered blocking state Sep 12 17:20:20.590749 kernel: cni0: port 3(veth833584c0) entered disabled state Sep 12 17:20:20.591632 kernel: veth833584c0: entered allmulticast mode Sep 12 17:20:20.595106 kernel: veth833584c0: entered promiscuous mode Sep 12 17:20:20.601193 kernel: cni0: port 3(veth833584c0) entered blocking state Sep 12 17:20:20.601256 kernel: cni0: port 3(veth833584c0) entered forwarding state Sep 12 17:20:20.604691 systemd-networkd[1431]: veth833584c0: Gained carrier Sep 12 17:20:20.609110 containerd[1524]: 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"} Sep 12 17:20:20.609110 containerd[1524]: delegateAdd: netconf sent to delegate plugin: Sep 12 17:20:20.657953 containerd[1524]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-09-12T17:20:20.657899148Z" level=info msg="connecting to shim 246b366e6ab7c1386488072f7bc6f35fe0cf8611297c71dfe892e623e71703b0" address="unix:///run/containerd/s/99a9559b71bc0c6bd17f5594cb54ebca89972a721b5a06a0b8a4b29fe9fe2ad8" namespace=k8s.io protocol=ttrpc version=3 Sep 12 17:20:20.683704 systemd[1]: Started cri-containerd-246b366e6ab7c1386488072f7bc6f35fe0cf8611297c71dfe892e623e71703b0.scope - libcontainer container 246b366e6ab7c1386488072f7bc6f35fe0cf8611297c71dfe892e623e71703b0. Sep 12 17:20:20.694993 systemd-resolved[1352]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 12 17:20:20.719379 containerd[1524]: time="2025-09-12T17:20:20.719330700Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:a9edc5c0-354b-40d0-89a3-71385612024b,Namespace:default,Attempt:0,} returns sandbox id \"246b366e6ab7c1386488072f7bc6f35fe0cf8611297c71dfe892e623e71703b0\"" Sep 12 17:20:20.721137 containerd[1524]: time="2025-09-12T17:20:20.721114456Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 12 17:20:21.117673 containerd[1524]: time="2025-09-12T17:20:21.117621935Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:20:21.119649 containerd[1524]: time="2025-09-12T17:20:21.118363874Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Sep 12 17:20:21.121333 containerd[1524]: time="2025-09-12T17:20:21.121288061Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\", size \"69986400\" in 400.067504ms" Sep 12 17:20:21.121387 containerd[1524]: time="2025-09-12T17:20:21.121331390Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\"" Sep 12 17:20:21.126125 containerd[1524]: time="2025-09-12T17:20:21.125676763Z" level=info msg="CreateContainer within sandbox \"246b366e6ab7c1386488072f7bc6f35fe0cf8611297c71dfe892e623e71703b0\" for container &ContainerMetadata{Name:test,Attempt:0,}" Sep 12 17:20:21.128395 kubelet[1813]: E0912 17:20:21.128365 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:20:21.134860 containerd[1524]: time="2025-09-12T17:20:21.134050172Z" level=info msg="Container f831d2f8f84e1e9eb51caaaf1467c8482e9c9882ff6062cbbc2f7a1e3a6c00fd: CDI devices from CRI Config.CDIDevices: []" Sep 12 17:20:21.145094 containerd[1524]: time="2025-09-12T17:20:21.145046871Z" level=info msg="CreateContainer within sandbox \"246b366e6ab7c1386488072f7bc6f35fe0cf8611297c71dfe892e623e71703b0\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"f831d2f8f84e1e9eb51caaaf1467c8482e9c9882ff6062cbbc2f7a1e3a6c00fd\"" Sep 12 17:20:21.146106 containerd[1524]: time="2025-09-12T17:20:21.146076584Z" level=info msg="StartContainer for \"f831d2f8f84e1e9eb51caaaf1467c8482e9c9882ff6062cbbc2f7a1e3a6c00fd\"" Sep 12 17:20:21.147447 containerd[1524]: time="2025-09-12T17:20:21.147397911Z" level=info msg="connecting to shim f831d2f8f84e1e9eb51caaaf1467c8482e9c9882ff6062cbbc2f7a1e3a6c00fd" address="unix:///run/containerd/s/99a9559b71bc0c6bd17f5594cb54ebca89972a721b5a06a0b8a4b29fe9fe2ad8" protocol=ttrpc version=3 Sep 12 17:20:21.167662 systemd[1]: Started cri-containerd-f831d2f8f84e1e9eb51caaaf1467c8482e9c9882ff6062cbbc2f7a1e3a6c00fd.scope - libcontainer container f831d2f8f84e1e9eb51caaaf1467c8482e9c9882ff6062cbbc2f7a1e3a6c00fd. Sep 12 17:20:21.194273 containerd[1524]: time="2025-09-12T17:20:21.194235483Z" level=info msg="StartContainer for \"f831d2f8f84e1e9eb51caaaf1467c8482e9c9882ff6062cbbc2f7a1e3a6c00fd\" returns successfully" Sep 12 17:20:21.906655 systemd-networkd[1431]: veth833584c0: Gained IPv6LL Sep 12 17:20:22.131028 kubelet[1813]: E0912 17:20:22.129092 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:20:23.130211 kubelet[1813]: E0912 17:20:23.129868 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"