Jul 12 09:33:46.826935 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Jul 12 09:33:46.826956 kernel: Linux version 6.12.36-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 p1) 2.44.0) #1 SMP PREEMPT Sat Jul 12 08:24:03 -00 2025 Jul 12 09:33:46.826965 kernel: KASLR enabled Jul 12 09:33:46.826971 kernel: efi: EFI v2.7 by EDK II Jul 12 09:33:46.826976 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb832018 ACPI 2.0=0xdbfd0018 RNG=0xdbfd0a18 MEMRESERVE=0xdb838218 Jul 12 09:33:46.826981 kernel: random: crng init done Jul 12 09:33:46.826988 kernel: secureboot: Secure boot disabled Jul 12 09:33:46.826994 kernel: ACPI: Early table checksum verification disabled Jul 12 09:33:46.827000 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Jul 12 09:33:46.827006 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Jul 12 09:33:46.827013 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Jul 12 09:33:46.827018 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 12 09:33:46.827024 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Jul 12 09:33:46.827030 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 12 09:33:46.827037 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 12 09:33:46.827044 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Jul 12 09:33:46.827050 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 12 09:33:46.827056 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Jul 12 09:33:46.827062 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Jul 12 09:33:46.827068 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Jul 12 09:33:46.827074 kernel: ACPI: Use ACPI SPCR as default console: Yes Jul 12 09:33:46.827081 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Jul 12 09:33:46.827087 kernel: NODE_DATA(0) allocated [mem 0xdc965a00-0xdc96cfff] Jul 12 09:33:46.827093 kernel: Zone ranges: Jul 12 09:33:46.827099 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Jul 12 09:33:46.827106 kernel: DMA32 empty Jul 12 09:33:46.827112 kernel: Normal empty Jul 12 09:33:46.827118 kernel: Device empty Jul 12 09:33:46.827124 kernel: Movable zone start for each node Jul 12 09:33:46.827130 kernel: Early memory node ranges Jul 12 09:33:46.827136 kernel: node 0: [mem 0x0000000040000000-0x00000000db81ffff] Jul 12 09:33:46.827142 kernel: node 0: [mem 0x00000000db820000-0x00000000db82ffff] Jul 12 09:33:46.827148 kernel: node 0: [mem 0x00000000db830000-0x00000000dc09ffff] Jul 12 09:33:46.827154 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Jul 12 09:33:46.827160 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Jul 12 09:33:46.827166 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Jul 12 09:33:46.827172 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Jul 12 09:33:46.827180 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Jul 12 09:33:46.827186 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Jul 12 09:33:46.827192 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Jul 12 09:33:46.827200 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Jul 12 09:33:46.827207 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Jul 12 09:33:46.827213 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Jul 12 09:33:46.827220 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Jul 12 09:33:46.827227 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Jul 12 09:33:46.827233 kernel: cma: Reserved 16 MiB at 0x00000000d8000000 on node -1 Jul 12 09:33:46.827239 kernel: psci: probing for conduit method from ACPI. Jul 12 09:33:46.827246 kernel: psci: PSCIv1.1 detected in firmware. Jul 12 09:33:46.827252 kernel: psci: Using standard PSCI v0.2 function IDs Jul 12 09:33:46.827258 kernel: psci: Trusted OS migration not required Jul 12 09:33:46.827264 kernel: psci: SMC Calling Convention v1.1 Jul 12 09:33:46.827271 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Jul 12 09:33:46.827277 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Jul 12 09:33:46.827285 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Jul 12 09:33:46.827291 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Jul 12 09:33:46.827298 kernel: Detected PIPT I-cache on CPU0 Jul 12 09:33:46.827304 kernel: CPU features: detected: GIC system register CPU interface Jul 12 09:33:46.827311 kernel: CPU features: detected: Spectre-v4 Jul 12 09:33:46.827317 kernel: CPU features: detected: Spectre-BHB Jul 12 09:33:46.827323 kernel: CPU features: kernel page table isolation forced ON by KASLR Jul 12 09:33:46.827330 kernel: CPU features: detected: Kernel page table isolation (KPTI) Jul 12 09:33:46.827336 kernel: CPU features: detected: ARM erratum 1418040 Jul 12 09:33:46.827342 kernel: CPU features: detected: SSBS not fully self-synchronizing Jul 12 09:33:46.827348 kernel: alternatives: applying boot alternatives Jul 12 09:33:46.827356 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=2eed6122ab9d95fa96c8f5511b96c1220a0caf18bbf7b84035ef573d9ba90496 Jul 12 09:33:46.827364 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jul 12 09:33:46.827370 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jul 12 09:33:46.827377 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jul 12 09:33:46.827383 kernel: Fallback order for Node 0: 0 Jul 12 09:33:46.827389 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 Jul 12 09:33:46.827396 kernel: Policy zone: DMA Jul 12 09:33:46.827402 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jul 12 09:33:46.827408 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB Jul 12 09:33:46.827415 kernel: software IO TLB: area num 4. Jul 12 09:33:46.827421 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB Jul 12 09:33:46.827427 kernel: software IO TLB: mapped [mem 0x00000000d7c00000-0x00000000d8000000] (4MB) Jul 12 09:33:46.827435 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Jul 12 09:33:46.827441 kernel: rcu: Preemptible hierarchical RCU implementation. Jul 12 09:33:46.827448 kernel: rcu: RCU event tracing is enabled. Jul 12 09:33:46.827455 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Jul 12 09:33:46.827461 kernel: Trampoline variant of Tasks RCU enabled. Jul 12 09:33:46.827468 kernel: Tracing variant of Tasks RCU enabled. Jul 12 09:33:46.827474 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jul 12 09:33:46.827481 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Jul 12 09:33:46.827488 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Jul 12 09:33:46.827494 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Jul 12 09:33:46.827500 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Jul 12 09:33:46.827508 kernel: GICv3: 256 SPIs implemented Jul 12 09:33:46.827514 kernel: GICv3: 0 Extended SPIs implemented Jul 12 09:33:46.827521 kernel: Root IRQ handler: gic_handle_irq Jul 12 09:33:46.827527 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Jul 12 09:33:46.827533 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 Jul 12 09:33:46.827540 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Jul 12 09:33:46.827546 kernel: ITS [mem 0x08080000-0x0809ffff] Jul 12 09:33:46.827552 kernel: ITS@0x0000000008080000: allocated 8192 Devices @40110000 (indirect, esz 8, psz 64K, shr 1) Jul 12 09:33:46.827559 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @40120000 (flat, esz 8, psz 64K, shr 1) Jul 12 09:33:46.827565 kernel: GICv3: using LPI property table @0x0000000040130000 Jul 12 09:33:46.827572 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040140000 Jul 12 09:33:46.827578 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jul 12 09:33:46.827586 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 12 09:33:46.827592 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Jul 12 09:33:46.827599 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Jul 12 09:33:46.827613 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Jul 12 09:33:46.827621 kernel: arm-pv: using stolen time PV Jul 12 09:33:46.827627 kernel: Console: colour dummy device 80x25 Jul 12 09:33:46.827634 kernel: ACPI: Core revision 20240827 Jul 12 09:33:46.827641 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Jul 12 09:33:46.827647 kernel: pid_max: default: 32768 minimum: 301 Jul 12 09:33:46.827654 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Jul 12 09:33:46.827662 kernel: landlock: Up and running. Jul 12 09:33:46.827668 kernel: SELinux: Initializing. Jul 12 09:33:46.827675 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 12 09:33:46.827681 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 12 09:33:46.827688 kernel: rcu: Hierarchical SRCU implementation. Jul 12 09:33:46.827694 kernel: rcu: Max phase no-delay instances is 400. Jul 12 09:33:46.827701 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Jul 12 09:33:46.827708 kernel: Remapping and enabling EFI services. Jul 12 09:33:46.827714 kernel: smp: Bringing up secondary CPUs ... Jul 12 09:33:46.827727 kernel: Detected PIPT I-cache on CPU1 Jul 12 09:33:46.827734 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Jul 12 09:33:46.827740 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040150000 Jul 12 09:33:46.827748 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 12 09:33:46.827764 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Jul 12 09:33:46.827771 kernel: Detected PIPT I-cache on CPU2 Jul 12 09:33:46.827782 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Jul 12 09:33:46.827789 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040160000 Jul 12 09:33:46.827799 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 12 09:33:46.827806 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Jul 12 09:33:46.827813 kernel: Detected PIPT I-cache on CPU3 Jul 12 09:33:46.827827 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Jul 12 09:33:46.827834 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040170000 Jul 12 09:33:46.827841 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 12 09:33:46.827848 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Jul 12 09:33:46.827855 kernel: smp: Brought up 1 node, 4 CPUs Jul 12 09:33:46.827862 kernel: SMP: Total of 4 processors activated. Jul 12 09:33:46.827872 kernel: CPU: All CPU(s) started at EL1 Jul 12 09:33:46.827879 kernel: CPU features: detected: 32-bit EL0 Support Jul 12 09:33:46.827886 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Jul 12 09:33:46.827893 kernel: CPU features: detected: Common not Private translations Jul 12 09:33:46.827899 kernel: CPU features: detected: CRC32 instructions Jul 12 09:33:46.827906 kernel: CPU features: detected: Enhanced Virtualization Traps Jul 12 09:33:46.827913 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Jul 12 09:33:46.827920 kernel: CPU features: detected: LSE atomic instructions Jul 12 09:33:46.827927 kernel: CPU features: detected: Privileged Access Never Jul 12 09:33:46.827936 kernel: CPU features: detected: RAS Extension Support Jul 12 09:33:46.827943 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Jul 12 09:33:46.827950 kernel: alternatives: applying system-wide alternatives Jul 12 09:33:46.827956 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Jul 12 09:33:46.827964 kernel: Memory: 2424032K/2572288K available (11136K kernel code, 2436K rwdata, 9056K rodata, 39424K init, 1038K bss, 125920K reserved, 16384K cma-reserved) Jul 12 09:33:46.827971 kernel: devtmpfs: initialized Jul 12 09:33:46.827978 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jul 12 09:33:46.827985 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Jul 12 09:33:46.827991 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Jul 12 09:33:46.828000 kernel: 0 pages in range for non-PLT usage Jul 12 09:33:46.828006 kernel: 508448 pages in range for PLT usage Jul 12 09:33:46.828013 kernel: pinctrl core: initialized pinctrl subsystem Jul 12 09:33:46.828020 kernel: SMBIOS 3.0.0 present. Jul 12 09:33:46.828027 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Jul 12 09:33:46.828034 kernel: DMI: Memory slots populated: 1/1 Jul 12 09:33:46.828040 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jul 12 09:33:46.828047 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Jul 12 09:33:46.828054 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Jul 12 09:33:46.828062 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Jul 12 09:33:46.828069 kernel: audit: initializing netlink subsys (disabled) Jul 12 09:33:46.828076 kernel: audit: type=2000 audit(0.019:1): state=initialized audit_enabled=0 res=1 Jul 12 09:33:46.828083 kernel: thermal_sys: Registered thermal governor 'step_wise' Jul 12 09:33:46.828090 kernel: cpuidle: using governor menu Jul 12 09:33:46.828096 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Jul 12 09:33:46.828103 kernel: ASID allocator initialised with 32768 entries Jul 12 09:33:46.828110 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jul 12 09:33:46.828117 kernel: Serial: AMBA PL011 UART driver Jul 12 09:33:46.828125 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jul 12 09:33:46.828131 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Jul 12 09:33:46.828138 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Jul 12 09:33:46.828145 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Jul 12 09:33:46.828152 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jul 12 09:33:46.828158 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Jul 12 09:33:46.828165 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Jul 12 09:33:46.828172 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Jul 12 09:33:46.828179 kernel: ACPI: Added _OSI(Module Device) Jul 12 09:33:46.828187 kernel: ACPI: Added _OSI(Processor Device) Jul 12 09:33:46.828194 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jul 12 09:33:46.828200 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jul 12 09:33:46.828207 kernel: ACPI: Interpreter enabled Jul 12 09:33:46.828214 kernel: ACPI: Using GIC for interrupt routing Jul 12 09:33:46.828221 kernel: ACPI: MCFG table detected, 1 entries Jul 12 09:33:46.828228 kernel: ACPI: CPU0 has been hot-added Jul 12 09:33:46.828235 kernel: ACPI: CPU1 has been hot-added Jul 12 09:33:46.828242 kernel: ACPI: CPU2 has been hot-added Jul 12 09:33:46.828248 kernel: ACPI: CPU3 has been hot-added Jul 12 09:33:46.828257 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Jul 12 09:33:46.828264 kernel: printk: legacy console [ttyAMA0] enabled Jul 12 09:33:46.828271 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Jul 12 09:33:46.828416 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Jul 12 09:33:46.828483 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Jul 12 09:33:46.828542 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Jul 12 09:33:46.828600 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Jul 12 09:33:46.828659 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Jul 12 09:33:46.828668 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Jul 12 09:33:46.828676 kernel: PCI host bridge to bus 0000:00 Jul 12 09:33:46.828745 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Jul 12 09:33:46.828837 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Jul 12 09:33:46.828895 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Jul 12 09:33:46.828948 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jul 12 09:33:46.829026 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint Jul 12 09:33:46.829096 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Jul 12 09:33:46.829157 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] Jul 12 09:33:46.829216 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] Jul 12 09:33:46.829276 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] Jul 12 09:33:46.829336 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned Jul 12 09:33:46.829397 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned Jul 12 09:33:46.829458 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned Jul 12 09:33:46.829513 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Jul 12 09:33:46.829567 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Jul 12 09:33:46.829620 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Jul 12 09:33:46.829629 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Jul 12 09:33:46.829636 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Jul 12 09:33:46.829644 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Jul 12 09:33:46.829652 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Jul 12 09:33:46.829659 kernel: iommu: Default domain type: Translated Jul 12 09:33:46.829666 kernel: iommu: DMA domain TLB invalidation policy: strict mode Jul 12 09:33:46.829673 kernel: efivars: Registered efivars operations Jul 12 09:33:46.829680 kernel: vgaarb: loaded Jul 12 09:33:46.829687 kernel: clocksource: Switched to clocksource arch_sys_counter Jul 12 09:33:46.829694 kernel: VFS: Disk quotas dquot_6.6.0 Jul 12 09:33:46.829701 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jul 12 09:33:46.829708 kernel: pnp: PnP ACPI init Jul 12 09:33:46.829786 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Jul 12 09:33:46.829798 kernel: pnp: PnP ACPI: found 1 devices Jul 12 09:33:46.829805 kernel: NET: Registered PF_INET protocol family Jul 12 09:33:46.829812 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Jul 12 09:33:46.829826 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Jul 12 09:33:46.829833 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jul 12 09:33:46.829841 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Jul 12 09:33:46.829848 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Jul 12 09:33:46.829858 kernel: TCP: Hash tables configured (established 32768 bind 32768) Jul 12 09:33:46.829865 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 12 09:33:46.829872 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 12 09:33:46.829879 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jul 12 09:33:46.829886 kernel: PCI: CLS 0 bytes, default 64 Jul 12 09:33:46.829893 kernel: kvm [1]: HYP mode not available Jul 12 09:33:46.829900 kernel: Initialise system trusted keyrings Jul 12 09:33:46.829907 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Jul 12 09:33:46.829914 kernel: Key type asymmetric registered Jul 12 09:33:46.829922 kernel: Asymmetric key parser 'x509' registered Jul 12 09:33:46.829929 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Jul 12 09:33:46.829936 kernel: io scheduler mq-deadline registered Jul 12 09:33:46.829943 kernel: io scheduler kyber registered Jul 12 09:33:46.829950 kernel: io scheduler bfq registered Jul 12 09:33:46.829957 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Jul 12 09:33:46.829963 kernel: ACPI: button: Power Button [PWRB] Jul 12 09:33:46.829971 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Jul 12 09:33:46.830041 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Jul 12 09:33:46.830052 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jul 12 09:33:46.830059 kernel: thunder_xcv, ver 1.0 Jul 12 09:33:46.830066 kernel: thunder_bgx, ver 1.0 Jul 12 09:33:46.830073 kernel: nicpf, ver 1.0 Jul 12 09:33:46.830080 kernel: nicvf, ver 1.0 Jul 12 09:33:46.830152 kernel: rtc-efi rtc-efi.0: registered as rtc0 Jul 12 09:33:46.830210 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-07-12T09:33:46 UTC (1752312826) Jul 12 09:33:46.830219 kernel: hid: raw HID events driver (C) Jiri Kosina Jul 12 09:33:46.830228 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Jul 12 09:33:46.830235 kernel: NET: Registered PF_INET6 protocol family Jul 12 09:33:46.830242 kernel: watchdog: NMI not fully supported Jul 12 09:33:46.830249 kernel: watchdog: Hard watchdog permanently disabled Jul 12 09:33:46.830256 kernel: Segment Routing with IPv6 Jul 12 09:33:46.830263 kernel: In-situ OAM (IOAM) with IPv6 Jul 12 09:33:46.830269 kernel: NET: Registered PF_PACKET protocol family Jul 12 09:33:46.830276 kernel: Key type dns_resolver registered Jul 12 09:33:46.830283 kernel: registered taskstats version 1 Jul 12 09:33:46.830290 kernel: Loading compiled-in X.509 certificates Jul 12 09:33:46.830298 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.36-flatcar: 5833903fd926e330df1283c2ccd9d99e7cfa4219' Jul 12 09:33:46.830305 kernel: Demotion targets for Node 0: null Jul 12 09:33:46.830312 kernel: Key type .fscrypt registered Jul 12 09:33:46.830318 kernel: Key type fscrypt-provisioning registered Jul 12 09:33:46.830325 kernel: ima: No TPM chip found, activating TPM-bypass! Jul 12 09:33:46.830332 kernel: ima: Allocated hash algorithm: sha1 Jul 12 09:33:46.830339 kernel: ima: No architecture policies found Jul 12 09:33:46.830346 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Jul 12 09:33:46.830354 kernel: clk: Disabling unused clocks Jul 12 09:33:46.830361 kernel: PM: genpd: Disabling unused power domains Jul 12 09:33:46.830368 kernel: Warning: unable to open an initial console. Jul 12 09:33:46.830375 kernel: Freeing unused kernel memory: 39424K Jul 12 09:33:46.830382 kernel: Run /init as init process Jul 12 09:33:46.830389 kernel: with arguments: Jul 12 09:33:46.830396 kernel: /init Jul 12 09:33:46.830402 kernel: with environment: Jul 12 09:33:46.830409 kernel: HOME=/ Jul 12 09:33:46.830417 kernel: TERM=linux Jul 12 09:33:46.830424 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jul 12 09:33:46.830432 systemd[1]: Successfully made /usr/ read-only. Jul 12 09:33:46.830442 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) Jul 12 09:33:46.830450 systemd[1]: Detected virtualization kvm. Jul 12 09:33:46.830458 systemd[1]: Detected architecture arm64. Jul 12 09:33:46.830465 systemd[1]: Running in initrd. Jul 12 09:33:46.830472 systemd[1]: No hostname configured, using default hostname. Jul 12 09:33:46.830481 systemd[1]: Hostname set to . Jul 12 09:33:46.830489 systemd[1]: Initializing machine ID from VM UUID. Jul 12 09:33:46.830496 systemd[1]: Queued start job for default target initrd.target. Jul 12 09:33:46.830504 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 12 09:33:46.830511 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 12 09:33:46.830519 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jul 12 09:33:46.830527 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 12 09:33:46.830534 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jul 12 09:33:46.830545 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jul 12 09:33:46.830553 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jul 12 09:33:46.830561 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jul 12 09:33:46.830569 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 12 09:33:46.830576 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 12 09:33:46.830584 systemd[1]: Reached target paths.target - Path Units. Jul 12 09:33:46.830593 systemd[1]: Reached target slices.target - Slice Units. Jul 12 09:33:46.830600 systemd[1]: Reached target swap.target - Swaps. Jul 12 09:33:46.830608 systemd[1]: Reached target timers.target - Timer Units. Jul 12 09:33:46.830616 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jul 12 09:33:46.830624 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 12 09:33:46.830632 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jul 12 09:33:46.830639 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jul 12 09:33:46.830647 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 12 09:33:46.830655 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 12 09:33:46.830664 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 12 09:33:46.830672 systemd[1]: Reached target sockets.target - Socket Units. Jul 12 09:33:46.830680 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jul 12 09:33:46.830687 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 12 09:33:46.830695 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jul 12 09:33:46.830703 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). Jul 12 09:33:46.830711 systemd[1]: Starting systemd-fsck-usr.service... Jul 12 09:33:46.830719 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 12 09:33:46.830726 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 12 09:33:46.830736 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 12 09:33:46.830744 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jul 12 09:33:46.830764 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 12 09:33:46.830774 systemd[1]: Finished systemd-fsck-usr.service. Jul 12 09:33:46.830784 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 12 09:33:46.830808 systemd-journald[245]: Collecting audit messages is disabled. Jul 12 09:33:46.830834 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 12 09:33:46.830843 systemd-journald[245]: Journal started Jul 12 09:33:46.830863 systemd-journald[245]: Runtime Journal (/run/log/journal/eddaa6d9cb834a558801c12411d75942) is 6M, max 48.5M, 42.4M free. Jul 12 09:33:46.820359 systemd-modules-load[246]: Inserted module 'overlay' Jul 12 09:33:46.835307 systemd[1]: Started systemd-journald.service - Journal Service. Jul 12 09:33:46.835727 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 12 09:33:46.839984 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jul 12 09:33:46.839485 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jul 12 09:33:46.843850 kernel: Bridge firewalling registered Jul 12 09:33:46.841629 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 12 09:33:46.842845 systemd-modules-load[246]: Inserted module 'br_netfilter' Jul 12 09:33:46.852714 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 12 09:33:46.854663 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 12 09:33:46.857484 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 12 09:33:46.858765 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 12 09:33:46.867609 systemd-tmpfiles[269]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Jul 12 09:33:46.870490 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 12 09:33:46.872040 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 12 09:33:46.874335 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 12 09:33:46.877700 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jul 12 09:33:46.880276 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 12 09:33:46.899825 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=2eed6122ab9d95fa96c8f5511b96c1220a0caf18bbf7b84035ef573d9ba90496 Jul 12 09:33:46.919465 systemd-resolved[288]: Positive Trust Anchors: Jul 12 09:33:46.919485 systemd-resolved[288]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 12 09:33:46.919518 systemd-resolved[288]: 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 Jul 12 09:33:46.925159 systemd-resolved[288]: Defaulting to hostname 'linux'. Jul 12 09:33:46.926306 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 12 09:33:46.930444 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 12 09:33:46.985774 kernel: SCSI subsystem initialized Jul 12 09:33:46.989777 kernel: Loading iSCSI transport class v2.0-870. Jul 12 09:33:47.000800 kernel: iscsi: registered transport (tcp) Jul 12 09:33:47.013789 kernel: iscsi: registered transport (qla4xxx) Jul 12 09:33:47.013823 kernel: QLogic iSCSI HBA Driver Jul 12 09:33:47.030583 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 12 09:33:47.045712 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 12 09:33:47.047362 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 12 09:33:47.095508 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jul 12 09:33:47.097907 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jul 12 09:33:47.160799 kernel: raid6: neonx8 gen() 15774 MB/s Jul 12 09:33:47.177782 kernel: raid6: neonx4 gen() 15811 MB/s Jul 12 09:33:47.194783 kernel: raid6: neonx2 gen() 13151 MB/s Jul 12 09:33:47.211780 kernel: raid6: neonx1 gen() 10441 MB/s Jul 12 09:33:47.228789 kernel: raid6: int64x8 gen() 6897 MB/s Jul 12 09:33:47.245781 kernel: raid6: int64x4 gen() 7349 MB/s Jul 12 09:33:47.262777 kernel: raid6: int64x2 gen() 6098 MB/s Jul 12 09:33:47.279873 kernel: raid6: int64x1 gen() 5055 MB/s Jul 12 09:33:47.279887 kernel: raid6: using algorithm neonx4 gen() 15811 MB/s Jul 12 09:33:47.297862 kernel: raid6: .... xor() 12297 MB/s, rmw enabled Jul 12 09:33:47.297878 kernel: raid6: using neon recovery algorithm Jul 12 09:33:47.305076 kernel: xor: measuring software checksum speed Jul 12 09:33:47.305105 kernel: 8regs : 21550 MB/sec Jul 12 09:33:47.305776 kernel: 32regs : 21664 MB/sec Jul 12 09:33:47.306927 kernel: arm64_neon : 23795 MB/sec Jul 12 09:33:47.306939 kernel: xor: using function: arm64_neon (23795 MB/sec) Jul 12 09:33:47.360806 kernel: Btrfs loaded, zoned=no, fsverity=no Jul 12 09:33:47.366560 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jul 12 09:33:47.369134 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 12 09:33:47.402798 systemd-udevd[496]: Using default interface naming scheme 'v255'. Jul 12 09:33:47.406945 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 12 09:33:47.409411 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jul 12 09:33:47.435182 dracut-pre-trigger[505]: rd.md=0: removing MD RAID activation Jul 12 09:33:47.458273 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jul 12 09:33:47.460577 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 12 09:33:47.512999 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 12 09:33:47.517057 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jul 12 09:33:47.570388 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 12 09:33:47.570517 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 12 09:33:47.579329 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jul 12 09:33:47.582318 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Jul 12 09:33:47.582499 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Jul 12 09:33:47.585674 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 12 09:33:47.590761 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Jul 12 09:33:47.590784 kernel: GPT:9289727 != 19775487 Jul 12 09:33:47.590801 kernel: GPT:Alternate GPT header not at the end of the disk. Jul 12 09:33:47.590810 kernel: GPT:9289727 != 19775487 Jul 12 09:33:47.590830 kernel: GPT: Use GNU Parted to correct GPT errors. Jul 12 09:33:47.590840 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 12 09:33:47.620388 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Jul 12 09:33:47.621868 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 12 09:33:47.630740 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Jul 12 09:33:47.632816 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jul 12 09:33:47.645125 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Jul 12 09:33:47.651309 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Jul 12 09:33:47.652530 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Jul 12 09:33:47.654824 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jul 12 09:33:47.657686 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 12 09:33:47.659845 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 12 09:33:47.662615 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jul 12 09:33:47.664520 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jul 12 09:33:47.680780 disk-uuid[588]: Primary Header is updated. Jul 12 09:33:47.680780 disk-uuid[588]: Secondary Entries is updated. Jul 12 09:33:47.680780 disk-uuid[588]: Secondary Header is updated. Jul 12 09:33:47.685780 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 12 09:33:47.687594 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jul 12 09:33:48.700673 disk-uuid[593]: The operation has completed successfully. Jul 12 09:33:48.701702 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 12 09:33:48.724548 systemd[1]: disk-uuid.service: Deactivated successfully. Jul 12 09:33:48.724643 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jul 12 09:33:48.752888 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jul 12 09:33:48.778599 sh[608]: Success Jul 12 09:33:48.794845 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jul 12 09:33:48.794888 kernel: device-mapper: uevent: version 1.0.3 Jul 12 09:33:48.796101 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Jul 12 09:33:48.806776 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Jul 12 09:33:48.832589 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jul 12 09:33:48.834450 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jul 12 09:33:48.847197 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jul 12 09:33:48.855162 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' Jul 12 09:33:48.855193 kernel: BTRFS: device fsid 61a6979b-5b23-4687-8775-cb04acb91b0a devid 1 transid 37 /dev/mapper/usr (253:0) scanned by mount (620) Jul 12 09:33:48.856841 kernel: BTRFS info (device dm-0): first mount of filesystem 61a6979b-5b23-4687-8775-cb04acb91b0a Jul 12 09:33:48.857807 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Jul 12 09:33:48.857830 kernel: BTRFS info (device dm-0): using free-space-tree Jul 12 09:33:48.862425 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jul 12 09:33:48.863780 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Jul 12 09:33:48.865139 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jul 12 09:33:48.865883 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jul 12 09:33:48.867365 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jul 12 09:33:48.888778 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 (254:6) scanned by mount (649) Jul 12 09:33:48.891401 kernel: BTRFS info (device vda6): first mount of filesystem e5a719e8-42e4-4055-8ce0-9ce9f50475f2 Jul 12 09:33:48.891430 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jul 12 09:33:48.891440 kernel: BTRFS info (device vda6): using free-space-tree Jul 12 09:33:48.897824 kernel: BTRFS info (device vda6): last unmount of filesystem e5a719e8-42e4-4055-8ce0-9ce9f50475f2 Jul 12 09:33:48.898652 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jul 12 09:33:48.901779 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jul 12 09:33:48.966275 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 12 09:33:48.969321 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 12 09:33:49.013253 systemd-networkd[795]: lo: Link UP Jul 12 09:33:49.013264 systemd-networkd[795]: lo: Gained carrier Jul 12 09:33:49.014008 systemd-networkd[795]: Enumeration completed Jul 12 09:33:49.014103 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 12 09:33:49.014500 systemd-networkd[795]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 12 09:33:49.014503 systemd-networkd[795]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 12 09:33:49.015007 systemd-networkd[795]: eth0: Link UP Jul 12 09:33:49.015010 systemd-networkd[795]: eth0: Gained carrier Jul 12 09:33:49.015017 systemd-networkd[795]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 12 09:33:49.017106 systemd[1]: Reached target network.target - Network. Jul 12 09:33:49.038798 systemd-networkd[795]: eth0: DHCPv4 address 10.0.0.52/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jul 12 09:33:49.042454 ignition[698]: Ignition 2.21.0 Jul 12 09:33:49.043195 ignition[698]: Stage: fetch-offline Jul 12 09:33:49.043231 ignition[698]: no configs at "/usr/lib/ignition/base.d" Jul 12 09:33:49.043239 ignition[698]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 12 09:33:49.043424 ignition[698]: parsed url from cmdline: "" Jul 12 09:33:49.043427 ignition[698]: no config URL provided Jul 12 09:33:49.043431 ignition[698]: reading system config file "/usr/lib/ignition/user.ign" Jul 12 09:33:49.043440 ignition[698]: no config at "/usr/lib/ignition/user.ign" Jul 12 09:33:49.043457 ignition[698]: op(1): [started] loading QEMU firmware config module Jul 12 09:33:49.043461 ignition[698]: op(1): executing: "modprobe" "qemu_fw_cfg" Jul 12 09:33:49.052073 ignition[698]: op(1): [finished] loading QEMU firmware config module Jul 12 09:33:49.052095 ignition[698]: QEMU firmware config was not found. Ignoring... Jul 12 09:33:49.058485 ignition[698]: parsing config with SHA512: 03b6b617ff618a9a7b3d91a0c720bf10307779162bc19f4af3acb41766bee2ae2f16e737a022d01317128cde2f855305d27f5896e8668fcf0a4619c6756c769c Jul 12 09:33:49.063090 unknown[698]: fetched base config from "system" Jul 12 09:33:49.063110 unknown[698]: fetched user config from "qemu" Jul 12 09:33:49.063352 ignition[698]: fetch-offline: fetch-offline passed Jul 12 09:33:49.063400 ignition[698]: Ignition finished successfully Jul 12 09:33:49.066520 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jul 12 09:33:49.067867 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Jul 12 09:33:49.070594 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jul 12 09:33:49.094057 ignition[811]: Ignition 2.21.0 Jul 12 09:33:49.094072 ignition[811]: Stage: kargs Jul 12 09:33:49.094302 ignition[811]: no configs at "/usr/lib/ignition/base.d" Jul 12 09:33:49.094314 ignition[811]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 12 09:33:49.096310 ignition[811]: kargs: kargs passed Jul 12 09:33:49.096368 ignition[811]: Ignition finished successfully Jul 12 09:33:49.100216 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jul 12 09:33:49.103329 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jul 12 09:33:49.132791 ignition[819]: Ignition 2.21.0 Jul 12 09:33:49.132805 ignition[819]: Stage: disks Jul 12 09:33:49.132965 ignition[819]: no configs at "/usr/lib/ignition/base.d" Jul 12 09:33:49.132975 ignition[819]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 12 09:33:49.134343 ignition[819]: disks: disks passed Jul 12 09:33:49.137130 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jul 12 09:33:49.134409 ignition[819]: Ignition finished successfully Jul 12 09:33:49.138471 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jul 12 09:33:49.139590 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jul 12 09:33:49.141556 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 12 09:33:49.143094 systemd[1]: Reached target sysinit.target - System Initialization. Jul 12 09:33:49.144862 systemd[1]: Reached target basic.target - Basic System. Jul 12 09:33:49.147477 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jul 12 09:33:49.175655 systemd-fsck[829]: ROOT: clean, 15/553520 files, 52789/553472 blocks Jul 12 09:33:49.179247 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jul 12 09:33:49.181300 systemd[1]: Mounting sysroot.mount - /sysroot... Jul 12 09:33:49.252777 kernel: EXT4-fs (vda9): mounted filesystem 016d0f7f-22a0-4255-85cc-97a6d773acb9 r/w with ordered data mode. Quota mode: none. Jul 12 09:33:49.253107 systemd[1]: Mounted sysroot.mount - /sysroot. Jul 12 09:33:49.254307 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jul 12 09:33:49.256632 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 12 09:33:49.258189 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jul 12 09:33:49.259152 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Jul 12 09:33:49.259187 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jul 12 09:33:49.259215 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jul 12 09:33:49.270211 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jul 12 09:33:49.272461 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jul 12 09:33:49.275373 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 (254:6) scanned by mount (837) Jul 12 09:33:49.278370 kernel: BTRFS info (device vda6): first mount of filesystem e5a719e8-42e4-4055-8ce0-9ce9f50475f2 Jul 12 09:33:49.278402 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jul 12 09:33:49.278412 kernel: BTRFS info (device vda6): using free-space-tree Jul 12 09:33:49.281222 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 12 09:33:49.317734 initrd-setup-root[861]: cut: /sysroot/etc/passwd: No such file or directory Jul 12 09:33:49.321549 initrd-setup-root[868]: cut: /sysroot/etc/group: No such file or directory Jul 12 09:33:49.324496 initrd-setup-root[875]: cut: /sysroot/etc/shadow: No such file or directory Jul 12 09:33:49.327332 initrd-setup-root[882]: cut: /sysroot/etc/gshadow: No such file or directory Jul 12 09:33:49.397848 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jul 12 09:33:49.399795 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jul 12 09:33:49.401268 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jul 12 09:33:49.414811 kernel: BTRFS info (device vda6): last unmount of filesystem e5a719e8-42e4-4055-8ce0-9ce9f50475f2 Jul 12 09:33:49.431798 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jul 12 09:33:49.443165 ignition[952]: INFO : Ignition 2.21.0 Jul 12 09:33:49.444094 ignition[952]: INFO : Stage: mount Jul 12 09:33:49.444094 ignition[952]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 12 09:33:49.444094 ignition[952]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 12 09:33:49.446849 ignition[952]: INFO : mount: mount passed Jul 12 09:33:49.446849 ignition[952]: INFO : Ignition finished successfully Jul 12 09:33:49.446275 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jul 12 09:33:49.448584 systemd[1]: Starting ignition-files.service - Ignition (files)... Jul 12 09:33:49.853614 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jul 12 09:33:49.855062 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 12 09:33:49.878788 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 (254:6) scanned by mount (965) Jul 12 09:33:49.881166 kernel: BTRFS info (device vda6): first mount of filesystem e5a719e8-42e4-4055-8ce0-9ce9f50475f2 Jul 12 09:33:49.881193 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jul 12 09:33:49.881204 kernel: BTRFS info (device vda6): using free-space-tree Jul 12 09:33:49.884466 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 12 09:33:49.923080 ignition[983]: INFO : Ignition 2.21.0 Jul 12 09:33:49.923080 ignition[983]: INFO : Stage: files Jul 12 09:33:49.925031 ignition[983]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 12 09:33:49.925031 ignition[983]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 12 09:33:49.925031 ignition[983]: DEBUG : files: compiled without relabeling support, skipping Jul 12 09:33:49.928430 ignition[983]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jul 12 09:33:49.928430 ignition[983]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jul 12 09:33:49.931180 ignition[983]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jul 12 09:33:49.931180 ignition[983]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jul 12 09:33:49.931180 ignition[983]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jul 12 09:33:49.929209 unknown[983]: wrote ssh authorized keys file for user: core Jul 12 09:33:49.936550 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Jul 12 09:33:49.936550 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Jul 12 09:33:49.940120 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Jul 12 09:33:49.942207 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jul 12 09:33:49.942207 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" Jul 12 09:33:49.946442 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" Jul 12 09:33:49.946442 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" Jul 12 09:33:49.950941 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.31.8-arm64.raw: attempt #1 Jul 12 09:33:50.190956 systemd-networkd[795]: eth0: Gained IPv6LL Jul 12 09:33:50.532277 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Jul 12 09:33:50.734224 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" Jul 12 09:33:50.734224 ignition[983]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Jul 12 09:33:50.738314 ignition[983]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jul 12 09:33:50.740487 ignition[983]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jul 12 09:33:50.740487 ignition[983]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Jul 12 09:33:50.740487 ignition[983]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Jul 12 09:33:50.753289 ignition[983]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Jul 12 09:33:50.756667 ignition[983]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Jul 12 09:33:50.758842 ignition[983]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Jul 12 09:33:50.758842 ignition[983]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Jul 12 09:33:50.758842 ignition[983]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Jul 12 09:33:50.758842 ignition[983]: INFO : files: files passed Jul 12 09:33:50.758842 ignition[983]: INFO : Ignition finished successfully Jul 12 09:33:50.759976 systemd[1]: Finished ignition-files.service - Ignition (files). Jul 12 09:33:50.762311 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jul 12 09:33:50.765881 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jul 12 09:33:50.780147 initrd-setup-root-after-ignition[1010]: grep: /sysroot/oem/oem-release: No such file or directory Jul 12 09:33:50.781728 systemd[1]: ignition-quench.service: Deactivated successfully. Jul 12 09:33:50.781839 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jul 12 09:33:50.785205 initrd-setup-root-after-ignition[1013]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 12 09:33:50.785205 initrd-setup-root-after-ignition[1013]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jul 12 09:33:50.788381 initrd-setup-root-after-ignition[1017]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 12 09:33:50.789810 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 12 09:33:50.791353 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jul 12 09:33:50.794828 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jul 12 09:33:50.832872 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jul 12 09:33:50.833007 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jul 12 09:33:50.835312 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jul 12 09:33:50.837149 systemd[1]: Reached target initrd.target - Initrd Default Target. Jul 12 09:33:50.839043 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jul 12 09:33:50.839829 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jul 12 09:33:50.855081 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 12 09:33:50.857636 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jul 12 09:33:50.882833 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jul 12 09:33:50.884062 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 12 09:33:50.886050 systemd[1]: Stopped target timers.target - Timer Units. Jul 12 09:33:50.887887 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jul 12 09:33:50.888076 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 12 09:33:50.890500 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jul 12 09:33:50.891569 systemd[1]: Stopped target basic.target - Basic System. Jul 12 09:33:50.893447 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jul 12 09:33:50.895415 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jul 12 09:33:50.897316 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jul 12 09:33:50.899317 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Jul 12 09:33:50.901380 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jul 12 09:33:50.903306 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jul 12 09:33:50.905440 systemd[1]: Stopped target sysinit.target - System Initialization. Jul 12 09:33:50.907263 systemd[1]: Stopped target local-fs.target - Local File Systems. Jul 12 09:33:50.909284 systemd[1]: Stopped target swap.target - Swaps. Jul 12 09:33:50.910916 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jul 12 09:33:50.911038 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jul 12 09:33:50.913446 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jul 12 09:33:50.914654 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 12 09:33:50.916651 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jul 12 09:33:50.919822 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 12 09:33:50.921988 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jul 12 09:33:50.922099 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jul 12 09:33:50.925031 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jul 12 09:33:50.925147 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jul 12 09:33:50.927257 systemd[1]: Stopped target paths.target - Path Units. Jul 12 09:33:50.928874 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jul 12 09:33:50.929872 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 12 09:33:50.931952 systemd[1]: Stopped target slices.target - Slice Units. Jul 12 09:33:50.933900 systemd[1]: Stopped target sockets.target - Socket Units. Jul 12 09:33:50.936136 systemd[1]: iscsid.socket: Deactivated successfully. Jul 12 09:33:50.936267 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jul 12 09:33:50.937901 systemd[1]: iscsiuio.socket: Deactivated successfully. Jul 12 09:33:50.938025 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 12 09:33:50.939715 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jul 12 09:33:50.939903 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 12 09:33:50.941667 systemd[1]: ignition-files.service: Deactivated successfully. Jul 12 09:33:50.941859 systemd[1]: Stopped ignition-files.service - Ignition (files). Jul 12 09:33:50.944371 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jul 12 09:33:50.946739 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jul 12 09:33:50.947941 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jul 12 09:33:50.948118 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jul 12 09:33:50.950372 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jul 12 09:33:50.950520 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jul 12 09:33:50.957510 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jul 12 09:33:50.960150 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jul 12 09:33:50.966683 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jul 12 09:33:50.970962 ignition[1038]: INFO : Ignition 2.21.0 Jul 12 09:33:50.970962 ignition[1038]: INFO : Stage: umount Jul 12 09:33:50.973535 ignition[1038]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 12 09:33:50.973535 ignition[1038]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 12 09:33:50.973535 ignition[1038]: INFO : umount: umount passed Jul 12 09:33:50.973535 ignition[1038]: INFO : Ignition finished successfully Jul 12 09:33:50.973552 systemd[1]: ignition-mount.service: Deactivated successfully. Jul 12 09:33:50.973647 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jul 12 09:33:50.975817 systemd[1]: Stopped target network.target - Network. Jul 12 09:33:50.977551 systemd[1]: ignition-disks.service: Deactivated successfully. Jul 12 09:33:50.977611 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jul 12 09:33:50.979292 systemd[1]: ignition-kargs.service: Deactivated successfully. Jul 12 09:33:50.979337 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jul 12 09:33:50.981001 systemd[1]: ignition-setup.service: Deactivated successfully. Jul 12 09:33:50.981056 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jul 12 09:33:50.982842 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jul 12 09:33:50.982885 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jul 12 09:33:50.984952 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jul 12 09:33:50.986705 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jul 12 09:33:50.998750 systemd[1]: systemd-resolved.service: Deactivated successfully. Jul 12 09:33:50.998914 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jul 12 09:33:51.002902 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jul 12 09:33:51.003168 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jul 12 09:33:51.003206 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 12 09:33:51.008008 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Jul 12 09:33:51.008243 systemd[1]: systemd-networkd.service: Deactivated successfully. Jul 12 09:33:51.008326 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jul 12 09:33:51.011425 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Jul 12 09:33:51.011778 systemd[1]: Stopped target network-pre.target - Preparation for Network. Jul 12 09:33:51.012935 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jul 12 09:33:51.012971 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jul 12 09:33:51.015586 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jul 12 09:33:51.016819 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jul 12 09:33:51.016875 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 12 09:33:51.019470 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jul 12 09:33:51.019515 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jul 12 09:33:51.022812 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jul 12 09:33:51.022874 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jul 12 09:33:51.024982 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 12 09:33:51.028086 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jul 12 09:33:51.028383 systemd[1]: sysroot-boot.service: Deactivated successfully. Jul 12 09:33:51.028459 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jul 12 09:33:51.032387 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jul 12 09:33:51.032453 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jul 12 09:33:51.039326 systemd[1]: systemd-udevd.service: Deactivated successfully. Jul 12 09:33:51.040969 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 12 09:33:51.042467 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jul 12 09:33:51.042501 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jul 12 09:33:51.044447 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jul 12 09:33:51.044477 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jul 12 09:33:51.046229 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jul 12 09:33:51.046275 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jul 12 09:33:51.049244 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jul 12 09:33:51.049294 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jul 12 09:33:51.052087 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 12 09:33:51.052139 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 12 09:33:51.055838 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jul 12 09:33:51.057075 systemd[1]: systemd-network-generator.service: Deactivated successfully. Jul 12 09:33:51.057134 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Jul 12 09:33:51.059857 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jul 12 09:33:51.059900 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 12 09:33:51.063089 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Jul 12 09:33:51.063129 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 12 09:33:51.066266 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jul 12 09:33:51.066309 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jul 12 09:33:51.068735 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 12 09:33:51.068791 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 12 09:33:51.072408 systemd[1]: network-cleanup.service: Deactivated successfully. Jul 12 09:33:51.073793 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jul 12 09:33:51.075540 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jul 12 09:33:51.075618 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jul 12 09:33:51.078060 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jul 12 09:33:51.080098 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jul 12 09:33:51.095293 systemd[1]: Switching root. Jul 12 09:33:51.127323 systemd-journald[245]: Journal stopped Jul 12 09:33:51.889629 systemd-journald[245]: Received SIGTERM from PID 1 (systemd). Jul 12 09:33:51.889683 kernel: SELinux: policy capability network_peer_controls=1 Jul 12 09:33:51.889696 kernel: SELinux: policy capability open_perms=1 Jul 12 09:33:51.889719 kernel: SELinux: policy capability extended_socket_class=1 Jul 12 09:33:51.889728 kernel: SELinux: policy capability always_check_network=0 Jul 12 09:33:51.889740 kernel: SELinux: policy capability cgroup_seclabel=1 Jul 12 09:33:51.889750 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jul 12 09:33:51.889784 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jul 12 09:33:51.889795 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jul 12 09:33:51.889813 kernel: SELinux: policy capability userspace_initial_context=0 Jul 12 09:33:51.889828 kernel: audit: type=1403 audit(1752312831.284:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jul 12 09:33:51.889843 systemd[1]: Successfully loaded SELinux policy in 54.458ms. Jul 12 09:33:51.889864 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 5.184ms. Jul 12 09:33:51.889880 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) Jul 12 09:33:51.889892 systemd[1]: Detected virtualization kvm. Jul 12 09:33:51.889904 systemd[1]: Detected architecture arm64. Jul 12 09:33:51.889915 systemd[1]: Detected first boot. Jul 12 09:33:51.889927 systemd[1]: Initializing machine ID from VM UUID. Jul 12 09:33:51.889938 zram_generator::config[1083]: No configuration found. Jul 12 09:33:51.889950 kernel: NET: Registered PF_VSOCK protocol family Jul 12 09:33:51.889960 systemd[1]: Populated /etc with preset unit settings. Jul 12 09:33:51.889971 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jul 12 09:33:51.889981 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jul 12 09:33:51.889991 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jul 12 09:33:51.890002 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jul 12 09:33:51.890013 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jul 12 09:33:51.890024 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jul 12 09:33:51.890036 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jul 12 09:33:51.890051 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jul 12 09:33:51.890061 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jul 12 09:33:51.890072 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jul 12 09:33:51.890083 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jul 12 09:33:51.890108 systemd[1]: Created slice user.slice - User and Session Slice. Jul 12 09:33:51.890118 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 12 09:33:51.890129 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 12 09:33:51.890140 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jul 12 09:33:51.890151 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jul 12 09:33:51.890162 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jul 12 09:33:51.890172 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 12 09:33:51.890182 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Jul 12 09:33:51.890192 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 12 09:33:51.890202 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 12 09:33:51.890213 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jul 12 09:33:51.890224 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jul 12 09:33:51.890234 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jul 12 09:33:51.890278 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jul 12 09:33:51.890290 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 12 09:33:51.890300 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 12 09:33:51.890311 systemd[1]: Reached target slices.target - Slice Units. Jul 12 09:33:51.890321 systemd[1]: Reached target swap.target - Swaps. Jul 12 09:33:51.890331 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jul 12 09:33:51.890341 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jul 12 09:33:51.890351 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jul 12 09:33:51.890363 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 12 09:33:51.890374 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 12 09:33:51.890384 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 12 09:33:51.890394 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jul 12 09:33:51.890404 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jul 12 09:33:51.890414 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jul 12 09:33:51.890424 systemd[1]: Mounting media.mount - External Media Directory... Jul 12 09:33:51.890434 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jul 12 09:33:51.890444 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jul 12 09:33:51.890455 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jul 12 09:33:51.890466 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). Jul 12 09:33:51.890476 systemd[1]: Reached target machines.target - Containers. Jul 12 09:33:51.890486 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jul 12 09:33:51.890497 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 12 09:33:51.890507 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 12 09:33:51.890518 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jul 12 09:33:51.890528 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 12 09:33:51.890539 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 12 09:33:51.890549 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 12 09:33:51.890559 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jul 12 09:33:51.890569 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 12 09:33:51.890579 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jul 12 09:33:51.890589 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jul 12 09:33:51.890599 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jul 12 09:33:51.890609 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jul 12 09:33:51.890620 systemd[1]: Stopped systemd-fsck-usr.service. Jul 12 09:33:51.890631 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). Jul 12 09:33:51.890641 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 12 09:33:51.890651 kernel: loop: module loaded Jul 12 09:33:51.890660 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 12 09:33:51.890670 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 12 09:33:51.890680 kernel: ACPI: bus type drm_connector registered Jul 12 09:33:51.890689 kernel: fuse: init (API version 7.41) Jul 12 09:33:51.890699 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jul 12 09:33:51.890739 systemd-journald[1155]: Collecting audit messages is disabled. Jul 12 09:33:51.890768 systemd-journald[1155]: Journal started Jul 12 09:33:51.890790 systemd-journald[1155]: Runtime Journal (/run/log/journal/eddaa6d9cb834a558801c12411d75942) is 6M, max 48.5M, 42.4M free. Jul 12 09:33:51.667956 systemd[1]: Queued start job for default target multi-user.target. Jul 12 09:33:51.690774 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Jul 12 09:33:51.691157 systemd[1]: systemd-journald.service: Deactivated successfully. Jul 12 09:33:51.893085 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jul 12 09:33:51.896344 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 12 09:33:51.897931 systemd[1]: verity-setup.service: Deactivated successfully. Jul 12 09:33:51.897978 systemd[1]: Stopped verity-setup.service. Jul 12 09:33:51.903616 systemd[1]: Started systemd-journald.service - Journal Service. Jul 12 09:33:51.904312 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jul 12 09:33:51.905479 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jul 12 09:33:51.906773 systemd[1]: Mounted media.mount - External Media Directory. Jul 12 09:33:51.907841 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jul 12 09:33:51.909006 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jul 12 09:33:51.910210 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jul 12 09:33:51.912787 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jul 12 09:33:51.914197 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 12 09:33:51.917773 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jul 12 09:33:51.917961 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jul 12 09:33:51.919344 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 12 09:33:51.919511 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 12 09:33:51.921043 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 12 09:33:51.921205 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 12 09:33:51.922483 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 12 09:33:51.922647 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 12 09:33:51.924259 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jul 12 09:33:51.924416 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jul 12 09:33:51.925723 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 12 09:33:51.925912 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 12 09:33:51.927290 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 12 09:33:51.928879 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 12 09:33:51.930339 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jul 12 09:33:51.932067 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jul 12 09:33:51.944257 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 12 09:33:51.946589 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jul 12 09:33:51.948647 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jul 12 09:33:51.949830 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jul 12 09:33:51.949867 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 12 09:33:51.951888 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jul 12 09:33:51.962658 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jul 12 09:33:51.964089 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 12 09:33:51.965272 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jul 12 09:33:51.967134 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jul 12 09:33:51.968395 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 12 09:33:51.969504 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jul 12 09:33:51.970911 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 12 09:33:51.971815 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 12 09:33:51.976077 systemd-journald[1155]: Time spent on flushing to /var/log/journal/eddaa6d9cb834a558801c12411d75942 is 11.523ms for 867 entries. Jul 12 09:33:51.976077 systemd-journald[1155]: System Journal (/var/log/journal/eddaa6d9cb834a558801c12411d75942) is 8M, max 195.6M, 187.6M free. Jul 12 09:33:52.001064 systemd-journald[1155]: Received client request to flush runtime journal. Jul 12 09:33:51.976145 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jul 12 09:33:51.998937 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 12 09:33:52.002116 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 12 09:33:52.003552 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jul 12 09:33:52.006057 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jul 12 09:33:52.006791 kernel: loop0: detected capacity change from 0 to 105936 Jul 12 09:33:52.009825 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jul 12 09:33:52.011672 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jul 12 09:33:52.017501 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jul 12 09:33:52.022091 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jul 12 09:33:52.028996 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 12 09:33:52.029331 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jul 12 09:33:52.047235 systemd-tmpfiles[1201]: ACLs are not supported, ignoring. Jul 12 09:33:52.047250 systemd-tmpfiles[1201]: ACLs are not supported, ignoring. Jul 12 09:33:52.047988 kernel: loop1: detected capacity change from 0 to 203944 Jul 12 09:33:52.051150 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 12 09:33:52.055003 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jul 12 09:33:52.056600 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jul 12 09:33:52.077796 kernel: loop2: detected capacity change from 0 to 134232 Jul 12 09:33:52.088036 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jul 12 09:33:52.092595 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 12 09:33:52.108577 systemd-tmpfiles[1223]: ACLs are not supported, ignoring. Jul 12 09:33:52.108598 systemd-tmpfiles[1223]: ACLs are not supported, ignoring. Jul 12 09:33:52.111656 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 12 09:33:52.116773 kernel: loop3: detected capacity change from 0 to 105936 Jul 12 09:33:52.124779 kernel: loop4: detected capacity change from 0 to 203944 Jul 12 09:33:52.132779 kernel: loop5: detected capacity change from 0 to 134232 Jul 12 09:33:52.138962 (sd-merge)[1227]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Jul 12 09:33:52.139327 (sd-merge)[1227]: Merged extensions into '/usr'. Jul 12 09:33:52.142658 systemd[1]: Reload requested from client PID 1199 ('systemd-sysext') (unit systemd-sysext.service)... Jul 12 09:33:52.142689 systemd[1]: Reloading... Jul 12 09:33:52.196788 zram_generator::config[1254]: No configuration found. Jul 12 09:33:52.280403 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Jul 12 09:33:52.288672 ldconfig[1194]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 12 09:33:52.353381 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jul 12 09:33:52.353848 systemd[1]: Reloading finished in 210 ms. Jul 12 09:33:52.385810 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jul 12 09:33:52.387335 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jul 12 09:33:52.404901 systemd[1]: Starting ensure-sysext.service... Jul 12 09:33:52.406642 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 12 09:33:52.417339 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jul 12 09:33:52.420739 systemd-tmpfiles[1289]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Jul 12 09:33:52.420792 systemd-tmpfiles[1289]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Jul 12 09:33:52.421037 systemd-tmpfiles[1289]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jul 12 09:33:52.421227 systemd-tmpfiles[1289]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jul 12 09:33:52.421873 systemd-tmpfiles[1289]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jul 12 09:33:52.422078 systemd-tmpfiles[1289]: ACLs are not supported, ignoring. Jul 12 09:33:52.422130 systemd-tmpfiles[1289]: ACLs are not supported, ignoring. Jul 12 09:33:52.423678 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 12 09:33:52.425070 systemd-tmpfiles[1289]: Detected autofs mount point /boot during canonicalization of boot. Jul 12 09:33:52.425079 systemd-tmpfiles[1289]: Skipping /boot Jul 12 09:33:52.425200 systemd[1]: Reload requested from client PID 1288 ('systemctl') (unit ensure-sysext.service)... Jul 12 09:33:52.425207 systemd[1]: Reloading... Jul 12 09:33:52.430793 systemd-tmpfiles[1289]: Detected autofs mount point /boot during canonicalization of boot. Jul 12 09:33:52.430812 systemd-tmpfiles[1289]: Skipping /boot Jul 12 09:33:52.460856 systemd-udevd[1292]: Using default interface naming scheme 'v255'. Jul 12 09:33:52.469892 zram_generator::config[1317]: No configuration found. Jul 12 09:33:52.568169 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Jul 12 09:33:52.663250 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Jul 12 09:33:52.664672 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Jul 12 09:33:52.664810 systemd[1]: Reloading finished in 239 ms. Jul 12 09:33:52.685376 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 12 09:33:52.687831 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 12 09:33:52.723164 systemd[1]: Finished ensure-sysext.service. Jul 12 09:33:52.727724 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jul 12 09:33:52.730126 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jul 12 09:33:52.731545 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 12 09:33:52.747596 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 12 09:33:52.751907 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 12 09:33:52.753716 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 12 09:33:52.758730 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 12 09:33:52.759882 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 12 09:33:52.760830 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jul 12 09:33:52.762274 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). Jul 12 09:33:52.763419 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jul 12 09:33:52.767836 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 12 09:33:52.771621 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 12 09:33:52.774201 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Jul 12 09:33:52.778372 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jul 12 09:33:52.780431 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 12 09:33:52.784489 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 12 09:33:52.784704 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 12 09:33:52.786297 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 12 09:33:52.786466 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 12 09:33:52.787953 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 12 09:33:52.788116 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 12 09:33:52.789736 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 12 09:33:52.789946 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 12 09:33:52.796810 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jul 12 09:33:52.798585 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jul 12 09:33:52.804790 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jul 12 09:33:52.812585 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 12 09:33:52.812713 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 12 09:33:52.813958 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jul 12 09:33:52.823054 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jul 12 09:33:52.823595 augenrules[1445]: No rules Jul 12 09:33:52.824135 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). Jul 12 09:33:52.825789 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jul 12 09:33:52.827583 systemd[1]: audit-rules.service: Deactivated successfully. Jul 12 09:33:52.827806 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jul 12 09:33:52.838097 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jul 12 09:33:52.846019 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 12 09:33:52.867110 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jul 12 09:33:52.927416 systemd-networkd[1416]: lo: Link UP Jul 12 09:33:52.927426 systemd-networkd[1416]: lo: Gained carrier Jul 12 09:33:52.928558 systemd-networkd[1416]: Enumeration completed Jul 12 09:33:52.928705 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 12 09:33:52.929282 systemd-networkd[1416]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 12 09:33:52.929350 systemd-networkd[1416]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 12 09:33:52.929954 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Jul 12 09:33:52.931188 systemd[1]: Reached target time-set.target - System Time Set. Jul 12 09:33:52.933507 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jul 12 09:33:52.937909 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jul 12 09:33:52.938648 systemd-resolved[1418]: Positive Trust Anchors: Jul 12 09:33:52.938666 systemd-resolved[1418]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 12 09:33:52.938697 systemd-resolved[1418]: 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 Jul 12 09:33:52.939370 systemd-networkd[1416]: eth0: Link UP Jul 12 09:33:52.939546 systemd-networkd[1416]: eth0: Gained carrier Jul 12 09:33:52.939607 systemd-networkd[1416]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 12 09:33:52.944557 systemd-resolved[1418]: Defaulting to hostname 'linux'. Jul 12 09:33:52.946135 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 12 09:33:52.947349 systemd[1]: Reached target network.target - Network. Jul 12 09:33:52.948290 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 12 09:33:52.949463 systemd[1]: Reached target sysinit.target - System Initialization. Jul 12 09:33:52.950745 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jul 12 09:33:52.952110 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jul 12 09:33:52.953538 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jul 12 09:33:52.954765 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jul 12 09:33:52.956009 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jul 12 09:33:52.957215 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jul 12 09:33:52.957258 systemd[1]: Reached target paths.target - Path Units. Jul 12 09:33:52.957881 systemd-networkd[1416]: eth0: DHCPv4 address 10.0.0.52/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jul 12 09:33:52.958159 systemd[1]: Reached target timers.target - Timer Units. Jul 12 09:33:52.958623 systemd-timesyncd[1420]: Network configuration changed, trying to establish connection. Jul 12 09:33:52.959993 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jul 12 09:33:53.433770 systemd-timesyncd[1420]: Contacted time server 10.0.0.1:123 (10.0.0.1). Jul 12 09:33:53.433834 systemd-timesyncd[1420]: Initial clock synchronization to Sat 2025-07-12 09:33:53.433610 UTC. Jul 12 09:33:53.435146 systemd[1]: Starting docker.socket - Docker Socket for the API... Jul 12 09:33:53.436127 systemd-resolved[1418]: Clock change detected. Flushing caches. Jul 12 09:33:53.437866 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jul 12 09:33:53.439258 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jul 12 09:33:53.440523 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jul 12 09:33:53.443468 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jul 12 09:33:53.444898 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jul 12 09:33:53.448533 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jul 12 09:33:53.449999 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jul 12 09:33:53.453271 systemd[1]: Reached target sockets.target - Socket Units. Jul 12 09:33:53.454346 systemd[1]: Reached target basic.target - Basic System. Jul 12 09:33:53.455435 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jul 12 09:33:53.455473 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jul 12 09:33:53.456631 systemd[1]: Starting containerd.service - containerd container runtime... Jul 12 09:33:53.458628 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jul 12 09:33:53.460555 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jul 12 09:33:53.462678 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jul 12 09:33:53.464546 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jul 12 09:33:53.465628 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). Jul 12 09:33:53.466551 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jul 12 09:33:53.470262 jq[1474]: false Jul 12 09:33:53.470483 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jul 12 09:33:53.472787 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jul 12 09:33:53.478629 systemd[1]: Starting systemd-logind.service - User Login Management... Jul 12 09:33:53.480226 extend-filesystems[1475]: Found /dev/vda6 Jul 12 09:33:53.482722 extend-filesystems[1475]: Found /dev/vda9 Jul 12 09:33:53.480539 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jul 12 09:33:53.480923 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jul 12 09:33:53.482287 systemd[1]: Starting update-engine.service - Update Engine... Jul 12 09:33:53.485587 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jul 12 09:33:53.488521 extend-filesystems[1475]: Checking size of /dev/vda9 Jul 12 09:33:53.495669 jq[1490]: true Jul 12 09:33:53.495267 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jul 12 09:33:53.496921 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jul 12 09:33:53.498843 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jul 12 09:33:53.499161 systemd[1]: motdgen.service: Deactivated successfully. Jul 12 09:33:53.499320 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jul 12 09:33:53.499935 extend-filesystems[1475]: Resized partition /dev/vda9 Jul 12 09:33:53.501344 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jul 12 09:33:53.501614 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jul 12 09:33:53.504519 extend-filesystems[1500]: resize2fs 1.47.2 (1-Jan-2025) Jul 12 09:33:53.514524 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Jul 12 09:33:53.541875 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Jul 12 09:33:53.552239 jq[1501]: true Jul 12 09:33:53.551201 (ntainerd)[1502]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jul 12 09:33:53.554725 extend-filesystems[1500]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Jul 12 09:33:53.554725 extend-filesystems[1500]: old_desc_blocks = 1, new_desc_blocks = 1 Jul 12 09:33:53.554725 extend-filesystems[1500]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Jul 12 09:33:53.561454 systemd[1]: extend-filesystems.service: Deactivated successfully. Jul 12 09:33:53.563381 extend-filesystems[1475]: Resized filesystem in /dev/vda9 Jul 12 09:33:53.562579 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jul 12 09:33:53.571145 update_engine[1487]: I20250712 09:33:53.570269 1487 main.cc:92] Flatcar Update Engine starting Jul 12 09:33:53.581303 dbus-daemon[1472]: [system] SELinux support is enabled Jul 12 09:33:53.581729 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jul 12 09:33:53.585598 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). Jul 12 09:33:53.585633 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jul 12 09:33:53.586898 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). Jul 12 09:33:53.586923 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jul 12 09:33:53.590692 systemd[1]: Started update-engine.service - Update Engine. Jul 12 09:33:53.590988 update_engine[1487]: I20250712 09:33:53.590940 1487 update_check_scheduler.cc:74] Next update check in 11m29s Jul 12 09:33:53.596369 systemd-logind[1484]: Watching system buttons on /dev/input/event0 (Power Button) Jul 12 09:33:53.596638 systemd-logind[1484]: New seat seat0. Jul 12 09:33:53.600654 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jul 12 09:33:53.602050 systemd[1]: Started systemd-logind.service - User Login Management. Jul 12 09:33:53.607918 bash[1528]: Updated "/home/core/.ssh/authorized_keys" Jul 12 09:33:53.613949 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jul 12 09:33:53.620224 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jul 12 09:33:53.661294 locksmithd[1529]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jul 12 09:33:53.754354 containerd[1502]: time="2025-07-12T09:33:53Z" 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 Jul 12 09:33:53.756961 containerd[1502]: time="2025-07-12T09:33:53.756917657Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Jul 12 09:33:53.765046 containerd[1502]: time="2025-07-12T09:33:53.764997017Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.4µs" Jul 12 09:33:53.765046 containerd[1502]: time="2025-07-12T09:33:53.765032177Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Jul 12 09:33:53.765046 containerd[1502]: time="2025-07-12T09:33:53.765049297Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Jul 12 09:33:53.765226 containerd[1502]: time="2025-07-12T09:33:53.765198937Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Jul 12 09:33:53.765226 containerd[1502]: time="2025-07-12T09:33:53.765221217Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Jul 12 09:33:53.765287 containerd[1502]: time="2025-07-12T09:33:53.765245457Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jul 12 09:33:53.765306 containerd[1502]: time="2025-07-12T09:33:53.765295777Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jul 12 09:33:53.765328 containerd[1502]: time="2025-07-12T09:33:53.765306937Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jul 12 09:33:53.765574 containerd[1502]: time="2025-07-12T09:33:53.765545537Z" 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 Jul 12 09:33:53.765574 containerd[1502]: time="2025-07-12T09:33:53.765567817Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 12 09:33:53.765623 containerd[1502]: time="2025-07-12T09:33:53.765579097Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 12 09:33:53.765623 containerd[1502]: time="2025-07-12T09:33:53.765587457Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Jul 12 09:33:53.765680 containerd[1502]: time="2025-07-12T09:33:53.765664057Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Jul 12 09:33:53.765868 containerd[1502]: time="2025-07-12T09:33:53.765842137Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jul 12 09:33:53.765892 containerd[1502]: time="2025-07-12T09:33:53.765875497Z" 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 Jul 12 09:33:53.765892 containerd[1502]: time="2025-07-12T09:33:53.765887177Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Jul 12 09:33:53.765935 containerd[1502]: time="2025-07-12T09:33:53.765920137Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Jul 12 09:33:53.766130 containerd[1502]: time="2025-07-12T09:33:53.766114697Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Jul 12 09:33:53.766190 containerd[1502]: time="2025-07-12T09:33:53.766175617Z" level=info msg="metadata content store policy set" policy=shared Jul 12 09:33:53.769724 containerd[1502]: time="2025-07-12T09:33:53.769691977Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Jul 12 09:33:53.769763 containerd[1502]: time="2025-07-12T09:33:53.769751657Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Jul 12 09:33:53.769782 containerd[1502]: time="2025-07-12T09:33:53.769767177Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Jul 12 09:33:53.769782 containerd[1502]: time="2025-07-12T09:33:53.769778977Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Jul 12 09:33:53.769814 containerd[1502]: time="2025-07-12T09:33:53.769791537Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Jul 12 09:33:53.769814 containerd[1502]: time="2025-07-12T09:33:53.769802497Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Jul 12 09:33:53.769860 containerd[1502]: time="2025-07-12T09:33:53.769813977Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Jul 12 09:33:53.769860 containerd[1502]: time="2025-07-12T09:33:53.769825537Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Jul 12 09:33:53.769860 containerd[1502]: time="2025-07-12T09:33:53.769836617Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Jul 12 09:33:53.769860 containerd[1502]: time="2025-07-12T09:33:53.769847017Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Jul 12 09:33:53.769860 containerd[1502]: time="2025-07-12T09:33:53.769856377Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Jul 12 09:33:53.769935 containerd[1502]: time="2025-07-12T09:33:53.769867937Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Jul 12 09:33:53.770007 containerd[1502]: time="2025-07-12T09:33:53.769977937Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Jul 12 09:33:53.770089 containerd[1502]: time="2025-07-12T09:33:53.770005417Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Jul 12 09:33:53.770089 containerd[1502]: time="2025-07-12T09:33:53.770022257Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Jul 12 09:33:53.770089 containerd[1502]: time="2025-07-12T09:33:53.770033057Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Jul 12 09:33:53.770089 containerd[1502]: time="2025-07-12T09:33:53.770043937Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Jul 12 09:33:53.770089 containerd[1502]: time="2025-07-12T09:33:53.770054457Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Jul 12 09:33:53.770089 containerd[1502]: time="2025-07-12T09:33:53.770065377Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Jul 12 09:33:53.770089 containerd[1502]: time="2025-07-12T09:33:53.770075537Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Jul 12 09:33:53.770089 containerd[1502]: time="2025-07-12T09:33:53.770086057Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Jul 12 09:33:53.770231 containerd[1502]: time="2025-07-12T09:33:53.770102777Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Jul 12 09:33:53.770231 containerd[1502]: time="2025-07-12T09:33:53.770113657Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Jul 12 09:33:53.770321 containerd[1502]: time="2025-07-12T09:33:53.770286857Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Jul 12 09:33:53.770321 containerd[1502]: time="2025-07-12T09:33:53.770308737Z" level=info msg="Start snapshots syncer" Jul 12 09:33:53.770378 containerd[1502]: time="2025-07-12T09:33:53.770333817Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Jul 12 09:33:53.770899 containerd[1502]: time="2025-07-12T09:33:53.770843057Z" 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\"}" Jul 12 09:33:53.771017 containerd[1502]: time="2025-07-12T09:33:53.770941737Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Jul 12 09:33:53.771776 containerd[1502]: time="2025-07-12T09:33:53.771712177Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Jul 12 09:33:53.771983 containerd[1502]: time="2025-07-12T09:33:53.771959657Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Jul 12 09:33:53.772031 containerd[1502]: time="2025-07-12T09:33:53.771998297Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Jul 12 09:33:53.772031 containerd[1502]: time="2025-07-12T09:33:53.772013737Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Jul 12 09:33:53.772116 containerd[1502]: time="2025-07-12T09:33:53.772102817Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Jul 12 09:33:53.772139 containerd[1502]: time="2025-07-12T09:33:53.772118257Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Jul 12 09:33:53.772139 containerd[1502]: time="2025-07-12T09:33:53.772131177Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Jul 12 09:33:53.772171 containerd[1502]: time="2025-07-12T09:33:53.772141297Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Jul 12 09:33:53.772171 containerd[1502]: time="2025-07-12T09:33:53.772168417Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Jul 12 09:33:53.772209 containerd[1502]: time="2025-07-12T09:33:53.772179457Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Jul 12 09:33:53.772209 containerd[1502]: time="2025-07-12T09:33:53.772190177Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Jul 12 09:33:53.772242 containerd[1502]: time="2025-07-12T09:33:53.772230817Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jul 12 09:33:53.772259 containerd[1502]: time="2025-07-12T09:33:53.772245017Z" 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 Jul 12 09:33:53.772259 containerd[1502]: time="2025-07-12T09:33:53.772253217Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jul 12 09:33:53.772292 containerd[1502]: time="2025-07-12T09:33:53.772261857Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jul 12 09:33:53.772292 containerd[1502]: time="2025-07-12T09:33:53.772272097Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Jul 12 09:33:53.772292 containerd[1502]: time="2025-07-12T09:33:53.772283617Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Jul 12 09:33:53.772355 containerd[1502]: time="2025-07-12T09:33:53.772295377Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Jul 12 09:33:53.772388 containerd[1502]: time="2025-07-12T09:33:53.772372697Z" level=info msg="runtime interface created" Jul 12 09:33:53.772388 containerd[1502]: time="2025-07-12T09:33:53.772381377Z" level=info msg="created NRI interface" Jul 12 09:33:53.772432 containerd[1502]: time="2025-07-12T09:33:53.772390657Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Jul 12 09:33:53.772432 containerd[1502]: time="2025-07-12T09:33:53.772404377Z" level=info msg="Connect containerd service" Jul 12 09:33:53.772587 containerd[1502]: time="2025-07-12T09:33:53.772430977Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jul 12 09:33:53.773222 containerd[1502]: time="2025-07-12T09:33:53.773188777Z" 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" Jul 12 09:33:53.871842 containerd[1502]: time="2025-07-12T09:33:53.871724857Z" level=info msg="Start subscribing containerd event" Jul 12 09:33:53.871842 containerd[1502]: time="2025-07-12T09:33:53.871801857Z" level=info msg="Start recovering state" Jul 12 09:33:53.871943 containerd[1502]: time="2025-07-12T09:33:53.871882457Z" level=info msg="Start event monitor" Jul 12 09:33:53.871943 containerd[1502]: time="2025-07-12T09:33:53.871895257Z" level=info msg="Start cni network conf syncer for default" Jul 12 09:33:53.871943 containerd[1502]: time="2025-07-12T09:33:53.871902297Z" level=info msg="Start streaming server" Jul 12 09:33:53.871943 containerd[1502]: time="2025-07-12T09:33:53.871910937Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Jul 12 09:33:53.871943 containerd[1502]: time="2025-07-12T09:33:53.871917977Z" level=info msg="runtime interface starting up..." Jul 12 09:33:53.871943 containerd[1502]: time="2025-07-12T09:33:53.871923657Z" level=info msg="starting plugins..." Jul 12 09:33:53.871943 containerd[1502]: time="2025-07-12T09:33:53.871937377Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Jul 12 09:33:53.873926 containerd[1502]: time="2025-07-12T09:33:53.872155097Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 12 09:33:53.873926 containerd[1502]: time="2025-07-12T09:33:53.872217017Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 12 09:33:53.873926 containerd[1502]: time="2025-07-12T09:33:53.872273217Z" level=info msg="containerd successfully booted in 0.118250s" Jul 12 09:33:53.872397 systemd[1]: Started containerd.service - containerd container runtime. Jul 12 09:33:54.628976 sshd_keygen[1497]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jul 12 09:33:54.647617 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jul 12 09:33:54.650243 systemd[1]: Starting issuegen.service - Generate /run/issue... Jul 12 09:33:54.670430 systemd[1]: issuegen.service: Deactivated successfully. Jul 12 09:33:54.670688 systemd[1]: Finished issuegen.service - Generate /run/issue. Jul 12 09:33:54.673351 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jul 12 09:33:54.699032 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jul 12 09:33:54.701683 systemd[1]: Started getty@tty1.service - Getty on tty1. Jul 12 09:33:54.703677 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Jul 12 09:33:54.705063 systemd[1]: Reached target getty.target - Login Prompts. Jul 12 09:33:55.271728 systemd-networkd[1416]: eth0: Gained IPv6LL Jul 12 09:33:55.274026 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jul 12 09:33:55.275808 systemd[1]: Reached target network-online.target - Network is Online. Jul 12 09:33:55.278914 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Jul 12 09:33:55.281103 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 12 09:33:55.297889 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jul 12 09:33:55.313735 systemd[1]: coreos-metadata.service: Deactivated successfully. Jul 12 09:33:55.313939 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Jul 12 09:33:55.315522 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jul 12 09:33:55.318799 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jul 12 09:33:55.839861 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jul 12 09:33:55.841635 systemd[1]: Reached target multi-user.target - Multi-User System. Jul 12 09:33:55.843998 (kubelet)[1597]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Jul 12 09:33:55.844598 systemd[1]: Startup finished in 2.103s (kernel) + 4.660s (initrd) + 4.143s (userspace) = 10.908s. Jul 12 09:33:56.263895 kubelet[1597]: E0712 09:33:56.263778 1597 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" Jul 12 09:33:56.266359 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Jul 12 09:33:56.266514 systemd[1]: kubelet.service: Failed with result 'exit-code'. Jul 12 09:33:56.266856 systemd[1]: kubelet.service: Consumed 825ms CPU time, 256.8M memory peak. Jul 12 09:34:00.475061 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jul 12 09:34:00.479323 systemd[1]: Started sshd@0-10.0.0.52:22-10.0.0.1:59828.service - OpenSSH per-connection server daemon (10.0.0.1:59828). Jul 12 09:34:00.561201 sshd[1610]: Accepted publickey for core from 10.0.0.1 port 59828 ssh2: RSA SHA256:fhp558siaf39QLJw5fsAHbaRafIwNXdVZ+VoGPeGhpE Jul 12 09:34:00.565463 sshd-session[1610]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 12 09:34:00.571641 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jul 12 09:34:00.572540 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jul 12 09:34:00.578232 systemd-logind[1484]: New session 1 of user core. Jul 12 09:34:00.592625 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jul 12 09:34:00.595086 systemd[1]: Starting user@500.service - User Manager for UID 500... Jul 12 09:34:00.613527 (systemd)[1615]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jul 12 09:34:00.615714 systemd-logind[1484]: New session c1 of user core. Jul 12 09:34:00.730138 systemd[1615]: Queued start job for default target default.target. Jul 12 09:34:00.745478 systemd[1615]: Created slice app.slice - User Application Slice. Jul 12 09:34:00.745540 systemd[1615]: Reached target paths.target - Paths. Jul 12 09:34:00.745581 systemd[1615]: Reached target timers.target - Timers. Jul 12 09:34:00.746775 systemd[1615]: Starting dbus.socket - D-Bus User Message Bus Socket... Jul 12 09:34:00.756023 systemd[1615]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jul 12 09:34:00.756163 systemd[1615]: Reached target sockets.target - Sockets. Jul 12 09:34:00.756259 systemd[1615]: Reached target basic.target - Basic System. Jul 12 09:34:00.756349 systemd[1615]: Reached target default.target - Main User Target. Jul 12 09:34:00.756424 systemd[1]: Started user@500.service - User Manager for UID 500. Jul 12 09:34:00.756464 systemd[1615]: Startup finished in 135ms. Jul 12 09:34:00.757483 systemd[1]: Started session-1.scope - Session 1 of User core. Jul 12 09:34:00.821986 systemd[1]: Started sshd@1-10.0.0.52:22-10.0.0.1:59842.service - OpenSSH per-connection server daemon (10.0.0.1:59842). Jul 12 09:34:00.880160 sshd[1626]: Accepted publickey for core from 10.0.0.1 port 59842 ssh2: RSA SHA256:fhp558siaf39QLJw5fsAHbaRafIwNXdVZ+VoGPeGhpE Jul 12 09:34:00.881459 sshd-session[1626]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 12 09:34:00.885389 systemd-logind[1484]: New session 2 of user core. Jul 12 09:34:00.898660 systemd[1]: Started session-2.scope - Session 2 of User core. Jul 12 09:34:00.949533 sshd[1629]: Connection closed by 10.0.0.1 port 59842 Jul 12 09:34:00.950006 sshd-session[1626]: pam_unix(sshd:session): session closed for user core Jul 12 09:34:00.962588 systemd[1]: sshd@1-10.0.0.52:22-10.0.0.1:59842.service: Deactivated successfully. Jul 12 09:34:00.963965 systemd[1]: session-2.scope: Deactivated successfully. Jul 12 09:34:00.964614 systemd-logind[1484]: Session 2 logged out. Waiting for processes to exit. Jul 12 09:34:00.966658 systemd[1]: Started sshd@2-10.0.0.52:22-10.0.0.1:59844.service - OpenSSH per-connection server daemon (10.0.0.1:59844). Jul 12 09:34:00.967593 systemd-logind[1484]: Removed session 2. Jul 12 09:34:01.019165 sshd[1635]: Accepted publickey for core from 10.0.0.1 port 59844 ssh2: RSA SHA256:fhp558siaf39QLJw5fsAHbaRafIwNXdVZ+VoGPeGhpE Jul 12 09:34:01.021209 sshd-session[1635]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 12 09:34:01.025310 systemd-logind[1484]: New session 3 of user core. Jul 12 09:34:01.038722 systemd[1]: Started session-3.scope - Session 3 of User core. Jul 12 09:34:01.086018 sshd[1638]: Connection closed by 10.0.0.1 port 59844 Jul 12 09:34:01.086695 sshd-session[1635]: pam_unix(sshd:session): session closed for user core Jul 12 09:34:01.099546 systemd[1]: sshd@2-10.0.0.52:22-10.0.0.1:59844.service: Deactivated successfully. Jul 12 09:34:01.101868 systemd[1]: session-3.scope: Deactivated successfully. Jul 12 09:34:01.102501 systemd-logind[1484]: Session 3 logged out. Waiting for processes to exit. Jul 12 09:34:01.104600 systemd[1]: Started sshd@3-10.0.0.52:22-10.0.0.1:59858.service - OpenSSH per-connection server daemon (10.0.0.1:59858). Jul 12 09:34:01.105205 systemd-logind[1484]: Removed session 3. Jul 12 09:34:01.157668 sshd[1644]: Accepted publickey for core from 10.0.0.1 port 59858 ssh2: RSA SHA256:fhp558siaf39QLJw5fsAHbaRafIwNXdVZ+VoGPeGhpE Jul 12 09:34:01.158901 sshd-session[1644]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 12 09:34:01.162970 systemd-logind[1484]: New session 4 of user core. Jul 12 09:34:01.174653 systemd[1]: Started session-4.scope - Session 4 of User core. Jul 12 09:34:01.227092 sshd[1647]: Connection closed by 10.0.0.1 port 59858 Jul 12 09:34:01.227747 sshd-session[1644]: pam_unix(sshd:session): session closed for user core Jul 12 09:34:01.241897 systemd[1]: sshd@3-10.0.0.52:22-10.0.0.1:59858.service: Deactivated successfully. Jul 12 09:34:01.244731 systemd[1]: session-4.scope: Deactivated successfully. Jul 12 09:34:01.245438 systemd-logind[1484]: Session 4 logged out. Waiting for processes to exit. Jul 12 09:34:01.247873 systemd[1]: Started sshd@4-10.0.0.52:22-10.0.0.1:59864.service - OpenSSH per-connection server daemon (10.0.0.1:59864). Jul 12 09:34:01.248318 systemd-logind[1484]: Removed session 4. Jul 12 09:34:01.312243 sshd[1653]: Accepted publickey for core from 10.0.0.1 port 59864 ssh2: RSA SHA256:fhp558siaf39QLJw5fsAHbaRafIwNXdVZ+VoGPeGhpE Jul 12 09:34:01.313389 sshd-session[1653]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 12 09:34:01.317868 systemd-logind[1484]: New session 5 of user core. Jul 12 09:34:01.323639 systemd[1]: Started session-5.scope - Session 5 of User core. Jul 12 09:34:01.391550 sudo[1657]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Jul 12 09:34:01.391820 sudo[1657]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 12 09:34:01.402956 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Jul 12 09:34:01.431060 systemd[1]: coreos-metadata.service: Deactivated successfully. Jul 12 09:34:01.431307 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Jul 12 09:34:01.855468 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Jul 12 09:34:01.855645 systemd[1]: kubelet.service: Consumed 825ms CPU time, 256.8M memory peak. Jul 12 09:34:01.857432 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 12 09:34:01.877034 systemd[1]: Reload requested from client PID 1696 ('systemctl') (unit session-5.scope)... Jul 12 09:34:01.877044 systemd[1]: Reloading... Jul 12 09:34:01.942540 zram_generator::config[1738]: No configuration found. Jul 12 09:34:02.056033 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Jul 12 09:34:02.155700 systemd[1]: Reloading finished in 278 ms. Jul 12 09:34:02.198647 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Jul 12 09:34:02.198945 systemd[1]: kubelet.service: Failed with result 'signal'. Jul 12 09:34:02.199269 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Jul 12 09:34:02.202833 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 12 09:34:02.326084 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jul 12 09:34:02.330466 (kubelet)[1782]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Jul 12 09:34:02.362652 kubelet[1782]: 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. Jul 12 09:34:02.362652 kubelet[1782]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI. Jul 12 09:34:02.362652 kubelet[1782]: 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. Jul 12 09:34:02.362916 kubelet[1782]: I0712 09:34:02.362700 1782 server.go:211] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Jul 12 09:34:02.822842 kubelet[1782]: I0712 09:34:02.822797 1782 server.go:491] "Kubelet version" kubeletVersion="v1.31.8" Jul 12 09:34:02.822842 kubelet[1782]: I0712 09:34:02.822831 1782 server.go:493] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Jul 12 09:34:02.823100 kubelet[1782]: I0712 09:34:02.823074 1782 server.go:934] "Client rotation is on, will bootstrap in background" Jul 12 09:34:02.857568 kubelet[1782]: I0712 09:34:02.857476 1782 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Jul 12 09:34:02.865529 kubelet[1782]: I0712 09:34:02.865455 1782 server.go:1431] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Jul 12 09:34:02.869047 kubelet[1782]: I0712 09:34:02.868984 1782 server.go:749] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Jul 12 09:34:02.869858 kubelet[1782]: I0712 09:34:02.869775 1782 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" Jul 12 09:34:02.869948 kubelet[1782]: I0712 09:34:02.869919 1782 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Jul 12 09:34:02.870108 kubelet[1782]: I0712 09:34:02.869948 1782 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.52","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Jul 12 09:34:02.870205 kubelet[1782]: I0712 09:34:02.870175 1782 topology_manager.go:138] "Creating topology manager with none policy" Jul 12 09:34:02.870205 kubelet[1782]: I0712 09:34:02.870186 1782 container_manager_linux.go:300] "Creating device plugin manager" Jul 12 09:34:02.870421 kubelet[1782]: I0712 09:34:02.870406 1782 state_mem.go:36] "Initialized new in-memory state store" Jul 12 09:34:02.874336 kubelet[1782]: I0712 09:34:02.874305 1782 kubelet.go:408] "Attempting to sync node with API server" Jul 12 09:34:02.874387 kubelet[1782]: I0712 09:34:02.874341 1782 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" Jul 12 09:34:02.874387 kubelet[1782]: I0712 09:34:02.874364 1782 kubelet.go:314] "Adding apiserver pod source" Jul 12 09:34:02.874458 kubelet[1782]: I0712 09:34:02.874437 1782 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Jul 12 09:34:02.875510 kubelet[1782]: E0712 09:34:02.875468 1782 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:02.875562 kubelet[1782]: E0712 09:34:02.875546 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:02.879975 kubelet[1782]: I0712 09:34:02.879954 1782 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="v2.0.5" apiVersion="v1" Jul 12 09:34:02.880694 kubelet[1782]: I0712 09:34:02.880668 1782 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Jul 12 09:34:02.882764 kubelet[1782]: W0712 09:34:02.882738 1782 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Jul 12 09:34:02.883726 kubelet[1782]: I0712 09:34:02.883708 1782 server.go:1274] "Started kubelet" Jul 12 09:34:02.885877 kubelet[1782]: I0712 09:34:02.885778 1782 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Jul 12 09:34:02.885877 kubelet[1782]: I0712 09:34:02.885829 1782 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Jul 12 09:34:02.888288 kubelet[1782]: I0712 09:34:02.888182 1782 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Jul 12 09:34:02.888537 kubelet[1782]: I0712 09:34:02.888484 1782 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Jul 12 09:34:02.891530 kubelet[1782]: I0712 09:34:02.891507 1782 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Jul 12 09:34:02.891781 kubelet[1782]: I0712 09:34:02.891758 1782 volume_manager.go:289] "Starting Kubelet Volume Manager" Jul 12 09:34:02.892380 kubelet[1782]: I0712 09:34:02.892350 1782 server.go:449] "Adding debug handlers to kubelet server" Jul 12 09:34:02.892627 kubelet[1782]: E0712 09:34:02.892607 1782 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.52\" not found" Jul 12 09:34:02.893995 kubelet[1782]: I0712 09:34:02.893582 1782 desired_state_of_world_populator.go:147] "Desired state populator starts to run" Jul 12 09:34:02.893995 kubelet[1782]: I0712 09:34:02.893656 1782 factory.go:221] Registration of the systemd container factory successfully Jul 12 09:34:02.893995 kubelet[1782]: I0712 09:34:02.893675 1782 reconciler.go:26] "Reconciler: start to sync state" Jul 12 09:34:02.893995 kubelet[1782]: I0712 09:34:02.893755 1782 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Jul 12 09:34:02.894303 kubelet[1782]: E0712 09:34:02.894263 1782 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Jul 12 09:34:02.894788 kubelet[1782]: I0712 09:34:02.894767 1782 factory.go:221] Registration of the containerd container factory successfully Jul 12 09:34:02.898343 kubelet[1782]: E0712 09:34:02.897373 1782 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.52.1851773f176fc711 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.52,UID:10.0.0.52,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:10.0.0.52,},FirstTimestamp:2025-07-12 09:34:02.883680017 +0000 UTC m=+0.550037801,LastTimestamp:2025-07-12 09:34:02.883680017 +0000 UTC m=+0.550037801,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.52,}" Jul 12 09:34:02.898526 kubelet[1782]: W0712 09:34:02.898485 1782 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Jul 12 09:34:02.898573 kubelet[1782]: E0712 09:34:02.898550 1782 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" Jul 12 09:34:02.898684 kubelet[1782]: W0712 09:34:02.898659 1782 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.0.0.52" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Jul 12 09:34:02.898684 kubelet[1782]: E0712 09:34:02.898681 1782 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes \"10.0.0.52\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" Jul 12 09:34:02.902133 kubelet[1782]: E0712 09:34:02.902094 1782 controller.go:145] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.52\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms" Jul 12 09:34:02.902187 kubelet[1782]: W0712 09:34:02.902162 1782 reflector.go:561] k8s.io/client-go/informers/factory.go:160: 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 Jul 12 09:34:02.902187 kubelet[1782]: E0712 09:34:02.902180 1782 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.CSIDriver: 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" Jul 12 09:34:02.903144 kubelet[1782]: I0712 09:34:02.903116 1782 cpu_manager.go:214] "Starting CPU manager" policy="none" Jul 12 09:34:02.903144 kubelet[1782]: I0712 09:34:02.903147 1782 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Jul 12 09:34:02.903230 kubelet[1782]: I0712 09:34:02.903189 1782 state_mem.go:36] "Initialized new in-memory state store" Jul 12 09:34:02.983541 kubelet[1782]: I0712 09:34:02.983353 1782 policy_none.go:49] "None policy: Start" Jul 12 09:34:02.986000 kubelet[1782]: I0712 09:34:02.985974 1782 memory_manager.go:170] "Starting memorymanager" policy="None" Jul 12 09:34:02.986000 kubelet[1782]: I0712 09:34:02.986003 1782 state_mem.go:35] "Initializing new in-memory state store" Jul 12 09:34:02.993325 kubelet[1782]: E0712 09:34:02.993293 1782 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.52\" not found" Jul 12 09:34:02.994979 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Jul 12 09:34:03.005680 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Jul 12 09:34:03.009902 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Jul 12 09:34:03.010127 kubelet[1782]: I0712 09:34:03.010079 1782 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Jul 12 09:34:03.011581 kubelet[1782]: I0712 09:34:03.011543 1782 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Jul 12 09:34:03.011581 kubelet[1782]: I0712 09:34:03.011577 1782 status_manager.go:217] "Starting to sync pod status with apiserver" Jul 12 09:34:03.011675 kubelet[1782]: I0712 09:34:03.011613 1782 kubelet.go:2321] "Starting kubelet main sync loop" Jul 12 09:34:03.011675 kubelet[1782]: E0712 09:34:03.011665 1782 kubelet.go:2345] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Jul 12 09:34:03.017657 kubelet[1782]: I0712 09:34:03.017556 1782 manager.go:513] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Jul 12 09:34:03.017939 kubelet[1782]: I0712 09:34:03.017848 1782 eviction_manager.go:189] "Eviction manager: starting control loop" Jul 12 09:34:03.017939 kubelet[1782]: I0712 09:34:03.017870 1782 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Jul 12 09:34:03.018142 kubelet[1782]: I0712 09:34:03.018126 1782 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Jul 12 09:34:03.019904 kubelet[1782]: E0712 09:34:03.019820 1782 eviction_manager.go:285] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.52\" not found" Jul 12 09:34:03.107797 kubelet[1782]: E0712 09:34:03.107692 1782 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.52\" not found" node="10.0.0.52" Jul 12 09:34:03.119580 kubelet[1782]: I0712 09:34:03.119558 1782 kubelet_node_status.go:72] "Attempting to register node" node="10.0.0.52" Jul 12 09:34:03.123950 kubelet[1782]: I0712 09:34:03.123928 1782 kubelet_node_status.go:75] "Successfully registered node" node="10.0.0.52" Jul 12 09:34:03.124041 kubelet[1782]: E0712 09:34:03.123959 1782 kubelet_node_status.go:535] "Error updating node status, will retry" err="error getting node \"10.0.0.52\": node \"10.0.0.52\" not found" Jul 12 09:34:03.132740 kubelet[1782]: I0712 09:34:03.132716 1782 kuberuntime_manager.go:1635] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Jul 12 09:34:03.133173 containerd[1502]: time="2025-07-12T09:34:03.133115257Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Jul 12 09:34:03.133849 kubelet[1782]: I0712 09:34:03.133540 1782 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Jul 12 09:34:03.145209 kubelet[1782]: E0712 09:34:03.145181 1782 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.52\" not found" Jul 12 09:34:03.245302 kubelet[1782]: E0712 09:34:03.245257 1782 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.52\" not found" Jul 12 09:34:03.345813 kubelet[1782]: E0712 09:34:03.345776 1782 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.52\" not found" Jul 12 09:34:03.352646 sudo[1657]: pam_unix(sudo:session): session closed for user root Jul 12 09:34:03.353949 sshd[1656]: Connection closed by 10.0.0.1 port 59864 Jul 12 09:34:03.354336 sshd-session[1653]: pam_unix(sshd:session): session closed for user core Jul 12 09:34:03.357831 systemd-logind[1484]: Session 5 logged out. Waiting for processes to exit. Jul 12 09:34:03.358076 systemd[1]: sshd@4-10.0.0.52:22-10.0.0.1:59864.service: Deactivated successfully. Jul 12 09:34:03.359909 systemd[1]: session-5.scope: Deactivated successfully. Jul 12 09:34:03.360589 systemd[1]: session-5.scope: Consumed 447ms CPU time, 75M memory peak. Jul 12 09:34:03.362186 systemd-logind[1484]: Removed session 5. Jul 12 09:34:03.446692 kubelet[1782]: E0712 09:34:03.446655 1782 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.52\" not found" Jul 12 09:34:03.547165 kubelet[1782]: E0712 09:34:03.547116 1782 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.52\" not found" Jul 12 09:34:03.647688 kubelet[1782]: E0712 09:34:03.647597 1782 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.52\" not found" Jul 12 09:34:03.748218 kubelet[1782]: E0712 09:34:03.748182 1782 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.52\" not found" Jul 12 09:34:03.825795 kubelet[1782]: I0712 09:34:03.825752 1782 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Jul 12 09:34:03.825962 kubelet[1782]: W0712 09:34:03.825923 1782 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Jul 12 09:34:03.848986 kubelet[1782]: E0712 09:34:03.848953 1782 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.52\" not found" Jul 12 09:34:03.876352 kubelet[1782]: E0712 09:34:03.876317 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:04.877128 kubelet[1782]: I0712 09:34:04.877093 1782 apiserver.go:52] "Watching apiserver" Jul 12 09:34:04.877128 kubelet[1782]: E0712 09:34:04.877116 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:04.887725 systemd[1]: Created slice kubepods-besteffort-podda90d54b_efe0_4335_a707_aae7b9f96a5b.slice - libcontainer container kubepods-besteffort-podda90d54b_efe0_4335_a707_aae7b9f96a5b.slice. Jul 12 09:34:04.895929 kubelet[1782]: I0712 09:34:04.895894 1782 desired_state_of_world_populator.go:155] "Finished populating initial desired state of world" Jul 12 09:34:04.904179 kubelet[1782]: I0712 09:34:04.904152 1782 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-ng9d4\" (UniqueName: \"kubernetes.io/projected/5780e332-e2af-43db-9c51-f9db9d8950ff-kube-api-access-ng9d4\") pod \"kube-flannel-ds-2fjq8\" (UID: \"5780e332-e2af-43db-9c51-f9db9d8950ff\") " pod="kube-flannel/kube-flannel-ds-2fjq8" Jul 12 09:34:04.904432 kubelet[1782]: I0712 09:34:04.904254 1782 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/5780e332-e2af-43db-9c51-f9db9d8950ff-cni\") pod \"kube-flannel-ds-2fjq8\" (UID: \"5780e332-e2af-43db-9c51-f9db9d8950ff\") " pod="kube-flannel/kube-flannel-ds-2fjq8" Jul 12 09:34:04.904432 kubelet[1782]: I0712 09:34:04.904279 1782 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/da90d54b-efe0-4335-a707-aae7b9f96a5b-xtables-lock\") pod \"kube-proxy-cp87n\" (UID: \"da90d54b-efe0-4335-a707-aae7b9f96a5b\") " pod="kube-system/kube-proxy-cp87n" Jul 12 09:34:04.904432 kubelet[1782]: I0712 09:34:04.904298 1782 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/da90d54b-efe0-4335-a707-aae7b9f96a5b-lib-modules\") pod \"kube-proxy-cp87n\" (UID: \"da90d54b-efe0-4335-a707-aae7b9f96a5b\") " pod="kube-system/kube-proxy-cp87n" Jul 12 09:34:04.904432 kubelet[1782]: I0712 09:34:04.904312 1782 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-2hmjm\" (UniqueName: \"kubernetes.io/projected/da90d54b-efe0-4335-a707-aae7b9f96a5b-kube-api-access-2hmjm\") pod \"kube-proxy-cp87n\" (UID: \"da90d54b-efe0-4335-a707-aae7b9f96a5b\") " pod="kube-system/kube-proxy-cp87n" Jul 12 09:34:04.904432 kubelet[1782]: I0712 09:34:04.904330 1782 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/5780e332-e2af-43db-9c51-f9db9d8950ff-run\") pod \"kube-flannel-ds-2fjq8\" (UID: \"5780e332-e2af-43db-9c51-f9db9d8950ff\") " pod="kube-flannel/kube-flannel-ds-2fjq8" Jul 12 09:34:04.904574 kubelet[1782]: I0712 09:34:04.904352 1782 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/5780e332-e2af-43db-9c51-f9db9d8950ff-cni-plugin\") pod \"kube-flannel-ds-2fjq8\" (UID: \"5780e332-e2af-43db-9c51-f9db9d8950ff\") " pod="kube-flannel/kube-flannel-ds-2fjq8" Jul 12 09:34:04.904688 kubelet[1782]: I0712 09:34:04.904369 1782 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/5780e332-e2af-43db-9c51-f9db9d8950ff-flannel-cfg\") pod \"kube-flannel-ds-2fjq8\" (UID: \"5780e332-e2af-43db-9c51-f9db9d8950ff\") " pod="kube-flannel/kube-flannel-ds-2fjq8" Jul 12 09:34:04.904688 kubelet[1782]: I0712 09:34:04.904651 1782 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/5780e332-e2af-43db-9c51-f9db9d8950ff-xtables-lock\") pod \"kube-flannel-ds-2fjq8\" (UID: \"5780e332-e2af-43db-9c51-f9db9d8950ff\") " pod="kube-flannel/kube-flannel-ds-2fjq8" Jul 12 09:34:04.904688 kubelet[1782]: I0712 09:34:04.904672 1782 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/da90d54b-efe0-4335-a707-aae7b9f96a5b-kube-proxy\") pod \"kube-proxy-cp87n\" (UID: \"da90d54b-efe0-4335-a707-aae7b9f96a5b\") " pod="kube-system/kube-proxy-cp87n" Jul 12 09:34:04.911140 systemd[1]: Created slice kubepods-burstable-pod5780e332_e2af_43db_9c51_f9db9d8950ff.slice - libcontainer container kubepods-burstable-pod5780e332_e2af_43db_9c51_f9db9d8950ff.slice. Jul 12 09:34:05.209929 containerd[1502]: time="2025-07-12T09:34:05.209811177Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-cp87n,Uid:da90d54b-efe0-4335-a707-aae7b9f96a5b,Namespace:kube-system,Attempt:0,}" Jul 12 09:34:05.239892 containerd[1502]: time="2025-07-12T09:34:05.239843577Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-2fjq8,Uid:5780e332-e2af-43db-9c51-f9db9d8950ff,Namespace:kube-flannel,Attempt:0,}" Jul 12 09:34:05.824577 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3751920237.mount: Deactivated successfully. Jul 12 09:34:05.832553 containerd[1502]: time="2025-07-12T09:34:05.832302217Z" 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\"}" Jul 12 09:34:05.833685 containerd[1502]: time="2025-07-12T09:34:05.833580177Z" 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\"}" Jul 12 09:34:05.834187 containerd[1502]: time="2025-07-12T09:34:05.834111497Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268705" Jul 12 09:34:05.834864 containerd[1502]: time="2025-07-12T09:34:05.834838577Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Jul 12 09:34:05.835537 containerd[1502]: time="2025-07-12T09:34:05.835440857Z" level=info msg="ImageCreate event name:\"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Jul 12 09:34:05.839225 containerd[1502]: time="2025-07-12T09:34:05.838367737Z" 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\"}" Jul 12 09:34:05.839225 containerd[1502]: time="2025-07-12T09:34:05.839056057Z" 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 622.9502ms" Jul 12 09:34:05.839690 containerd[1502]: time="2025-07-12T09:34:05.839652457Z" 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 588.39768ms" Jul 12 09:34:05.873821 containerd[1502]: time="2025-07-12T09:34:05.873771817Z" level=info msg="connecting to shim 45e54c5dfc6e0a45ebe8d97991d9fff9c09c708d825d43616ff83d1e959ca5e2" address="unix:///run/containerd/s/238f2be245fb745b6c9ebcc8bb644d77266f1ce67d48bdc4eda01b52ce119717" namespace=k8s.io protocol=ttrpc version=3 Jul 12 09:34:05.876984 containerd[1502]: time="2025-07-12T09:34:05.876300617Z" level=info msg="connecting to shim 1a287b9840e52112528abd8b1192068bf579de8d116fe9139f43255350b4bd86" address="unix:///run/containerd/s/867d6f1d96883261817f97d81b832307d1fe2835c5389827b765d10f3ea55bf1" namespace=k8s.io protocol=ttrpc version=3 Jul 12 09:34:05.879723 kubelet[1782]: E0712 09:34:05.878023 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:05.900668 systemd[1]: Started cri-containerd-1a287b9840e52112528abd8b1192068bf579de8d116fe9139f43255350b4bd86.scope - libcontainer container 1a287b9840e52112528abd8b1192068bf579de8d116fe9139f43255350b4bd86. Jul 12 09:34:05.903830 systemd[1]: Started cri-containerd-45e54c5dfc6e0a45ebe8d97991d9fff9c09c708d825d43616ff83d1e959ca5e2.scope - libcontainer container 45e54c5dfc6e0a45ebe8d97991d9fff9c09c708d825d43616ff83d1e959ca5e2. Jul 12 09:34:05.930813 containerd[1502]: time="2025-07-12T09:34:05.930772617Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-cp87n,Uid:da90d54b-efe0-4335-a707-aae7b9f96a5b,Namespace:kube-system,Attempt:0,} returns sandbox id \"45e54c5dfc6e0a45ebe8d97991d9fff9c09c708d825d43616ff83d1e959ca5e2\"" Jul 12 09:34:05.934434 containerd[1502]: time="2025-07-12T09:34:05.933993937Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.10\"" Jul 12 09:34:05.936949 containerd[1502]: time="2025-07-12T09:34:05.936888657Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-2fjq8,Uid:5780e332-e2af-43db-9c51-f9db9d8950ff,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"1a287b9840e52112528abd8b1192068bf579de8d116fe9139f43255350b4bd86\"" Jul 12 09:34:06.878519 kubelet[1782]: E0712 09:34:06.878452 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:06.890813 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4095294469.mount: Deactivated successfully. Jul 12 09:34:07.109662 containerd[1502]: time="2025-07-12T09:34:07.109612697Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.31.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 12 09:34:07.110536 containerd[1502]: time="2025-07-12T09:34:07.110464537Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.31.10: active requests=0, bytes read=26915959" Jul 12 09:34:07.111406 containerd[1502]: time="2025-07-12T09:34:07.111219537Z" level=info msg="ImageCreate event name:\"sha256:dde5ff0da443b455e81aefc7bf6a216fdd659d1cbe13b8e8ac8129c3ecd27f89\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 12 09:34:07.113340 containerd[1502]: time="2025-07-12T09:34:07.113305537Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:bcbb293812bdf587b28ea98369a8c347ca84884160046296761acdf12b27029d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 12 09:34:07.114030 containerd[1502]: time="2025-07-12T09:34:07.113838097Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.31.10\" with image id \"sha256:dde5ff0da443b455e81aefc7bf6a216fdd659d1cbe13b8e8ac8129c3ecd27f89\", repo tag \"registry.k8s.io/kube-proxy:v1.31.10\", repo digest \"registry.k8s.io/kube-proxy@sha256:bcbb293812bdf587b28ea98369a8c347ca84884160046296761acdf12b27029d\", size \"26914976\" in 1.17980152s" Jul 12 09:34:07.114030 containerd[1502]: time="2025-07-12T09:34:07.113950377Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.10\" returns image reference \"sha256:dde5ff0da443b455e81aefc7bf6a216fdd659d1cbe13b8e8ac8129c3ecd27f89\"" Jul 12 09:34:07.115548 containerd[1502]: time="2025-07-12T09:34:07.115516937Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Jul 12 09:34:07.118214 containerd[1502]: time="2025-07-12T09:34:07.117868097Z" level=info msg="CreateContainer within sandbox \"45e54c5dfc6e0a45ebe8d97991d9fff9c09c708d825d43616ff83d1e959ca5e2\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Jul 12 09:34:07.128144 containerd[1502]: time="2025-07-12T09:34:07.126772977Z" level=info msg="Container 5432245e96cd39178b904164826dd97fe0abbc7956328b2999d091ee8f442e9a: CDI devices from CRI Config.CDIDevices: []" Jul 12 09:34:07.134361 containerd[1502]: time="2025-07-12T09:34:07.133927017Z" level=info msg="CreateContainer within sandbox \"45e54c5dfc6e0a45ebe8d97991d9fff9c09c708d825d43616ff83d1e959ca5e2\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"5432245e96cd39178b904164826dd97fe0abbc7956328b2999d091ee8f442e9a\"" Jul 12 09:34:07.134921 containerd[1502]: time="2025-07-12T09:34:07.134694057Z" level=info msg="StartContainer for \"5432245e96cd39178b904164826dd97fe0abbc7956328b2999d091ee8f442e9a\"" Jul 12 09:34:07.136234 containerd[1502]: time="2025-07-12T09:34:07.136207097Z" level=info msg="connecting to shim 5432245e96cd39178b904164826dd97fe0abbc7956328b2999d091ee8f442e9a" address="unix:///run/containerd/s/238f2be245fb745b6c9ebcc8bb644d77266f1ce67d48bdc4eda01b52ce119717" protocol=ttrpc version=3 Jul 12 09:34:07.157677 systemd[1]: Started cri-containerd-5432245e96cd39178b904164826dd97fe0abbc7956328b2999d091ee8f442e9a.scope - libcontainer container 5432245e96cd39178b904164826dd97fe0abbc7956328b2999d091ee8f442e9a. Jul 12 09:34:07.190985 containerd[1502]: time="2025-07-12T09:34:07.190941057Z" level=info msg="StartContainer for \"5432245e96cd39178b904164826dd97fe0abbc7956328b2999d091ee8f442e9a\" returns successfully" Jul 12 09:34:07.879671 kubelet[1782]: E0712 09:34:07.879622 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:08.194208 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2128003522.mount: Deactivated successfully. Jul 12 09:34:08.218910 containerd[1502]: time="2025-07-12T09:34:08.218861177Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 12 09:34:08.219538 containerd[1502]: time="2025-07-12T09:34:08.219479297Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Jul 12 09:34:08.220399 containerd[1502]: time="2025-07-12T09:34:08.220353657Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 12 09:34:08.222317 containerd[1502]: time="2025-07-12T09:34:08.222270817Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 12 09:34:08.223376 containerd[1502]: time="2025-07-12T09:34:08.223080337Z" level=info msg="Pulled image \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" with image id \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\", repo tag \"docker.io/flannel/flannel-cni-plugin:v1.1.2\", repo digest \"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\", size \"3662650\" in 1.10752636s" Jul 12 09:34:08.223376 containerd[1502]: time="2025-07-12T09:34:08.223113977Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Jul 12 09:34:08.224914 containerd[1502]: time="2025-07-12T09:34:08.224884697Z" level=info msg="CreateContainer within sandbox \"1a287b9840e52112528abd8b1192068bf579de8d116fe9139f43255350b4bd86\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Jul 12 09:34:08.231527 containerd[1502]: time="2025-07-12T09:34:08.231104817Z" level=info msg="Container 315f401bf4ff2a0a27f0413a36c421115d35aa1b597f7a1253b7dbca1fd6f94d: CDI devices from CRI Config.CDIDevices: []" Jul 12 09:34:08.235923 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1313678337.mount: Deactivated successfully. Jul 12 09:34:08.237520 containerd[1502]: time="2025-07-12T09:34:08.237427657Z" level=info msg="CreateContainer within sandbox \"1a287b9840e52112528abd8b1192068bf579de8d116fe9139f43255350b4bd86\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"315f401bf4ff2a0a27f0413a36c421115d35aa1b597f7a1253b7dbca1fd6f94d\"" Jul 12 09:34:08.237954 containerd[1502]: time="2025-07-12T09:34:08.237924057Z" level=info msg="StartContainer for \"315f401bf4ff2a0a27f0413a36c421115d35aa1b597f7a1253b7dbca1fd6f94d\"" Jul 12 09:34:08.238700 containerd[1502]: time="2025-07-12T09:34:08.238676737Z" level=info msg="connecting to shim 315f401bf4ff2a0a27f0413a36c421115d35aa1b597f7a1253b7dbca1fd6f94d" address="unix:///run/containerd/s/867d6f1d96883261817f97d81b832307d1fe2835c5389827b765d10f3ea55bf1" protocol=ttrpc version=3 Jul 12 09:34:08.262708 systemd[1]: Started cri-containerd-315f401bf4ff2a0a27f0413a36c421115d35aa1b597f7a1253b7dbca1fd6f94d.scope - libcontainer container 315f401bf4ff2a0a27f0413a36c421115d35aa1b597f7a1253b7dbca1fd6f94d. Jul 12 09:34:08.285232 containerd[1502]: time="2025-07-12T09:34:08.285196097Z" level=info msg="StartContainer for \"315f401bf4ff2a0a27f0413a36c421115d35aa1b597f7a1253b7dbca1fd6f94d\" returns successfully" Jul 12 09:34:08.295683 systemd[1]: cri-containerd-315f401bf4ff2a0a27f0413a36c421115d35aa1b597f7a1253b7dbca1fd6f94d.scope: Deactivated successfully. Jul 12 09:34:08.298852 containerd[1502]: time="2025-07-12T09:34:08.298801817Z" level=info msg="received exit event container_id:\"315f401bf4ff2a0a27f0413a36c421115d35aa1b597f7a1253b7dbca1fd6f94d\" id:\"315f401bf4ff2a0a27f0413a36c421115d35aa1b597f7a1253b7dbca1fd6f94d\" pid:2111 exited_at:{seconds:1752312848 nanos:298396177}" Jul 12 09:34:08.299022 containerd[1502]: time="2025-07-12T09:34:08.298976657Z" level=info msg="TaskExit event in podsandbox handler container_id:\"315f401bf4ff2a0a27f0413a36c421115d35aa1b597f7a1253b7dbca1fd6f94d\" id:\"315f401bf4ff2a0a27f0413a36c421115d35aa1b597f7a1253b7dbca1fd6f94d\" pid:2111 exited_at:{seconds:1752312848 nanos:298396177}" Jul 12 09:34:08.880443 kubelet[1782]: E0712 09:34:08.880395 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:09.030126 containerd[1502]: time="2025-07-12T09:34:09.030073777Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Jul 12 09:34:09.038872 kubelet[1782]: I0712 09:34:09.038813 1782 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-cp87n" podStartSLOduration=4.857523377 podStartE2EDuration="6.038796377s" podCreationTimestamp="2025-07-12 09:34:03 +0000 UTC" firstStartedPulling="2025-07-12 09:34:05.933591857 +0000 UTC m=+3.599949601" lastFinishedPulling="2025-07-12 09:34:07.114864817 +0000 UTC m=+4.781222601" observedRunningTime="2025-07-12 09:34:08.034546977 +0000 UTC m=+5.700904801" watchObservedRunningTime="2025-07-12 09:34:09.038796377 +0000 UTC m=+6.705154161" Jul 12 09:34:09.132175 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-315f401bf4ff2a0a27f0413a36c421115d35aa1b597f7a1253b7dbca1fd6f94d-rootfs.mount: Deactivated successfully. Jul 12 09:34:09.881048 kubelet[1782]: E0712 09:34:09.880998 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:10.085306 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1558012642.mount: Deactivated successfully. Jul 12 09:34:10.535986 containerd[1502]: time="2025-07-12T09:34:10.535907177Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 12 09:34:10.537187 containerd[1502]: time="2025-07-12T09:34:10.536901017Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" Jul 12 09:34:10.538531 containerd[1502]: time="2025-07-12T09:34:10.538476577Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 12 09:34:10.542237 containerd[1502]: time="2025-07-12T09:34:10.542191897Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 12 09:34:10.542861 containerd[1502]: time="2025-07-12T09:34:10.542823657Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26863435\" in 1.51269168s" Jul 12 09:34:10.542861 containerd[1502]: time="2025-07-12T09:34:10.542856377Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Jul 12 09:34:10.545125 containerd[1502]: time="2025-07-12T09:34:10.545088337Z" level=info msg="CreateContainer within sandbox \"1a287b9840e52112528abd8b1192068bf579de8d116fe9139f43255350b4bd86\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Jul 12 09:34:10.553283 containerd[1502]: time="2025-07-12T09:34:10.553247657Z" level=info msg="Container 5bd93f9750daf8173295eae0d5188d83caf09a1f17cc53876f0600f7098feb48: CDI devices from CRI Config.CDIDevices: []" Jul 12 09:34:10.558575 containerd[1502]: time="2025-07-12T09:34:10.558538137Z" level=info msg="CreateContainer within sandbox \"1a287b9840e52112528abd8b1192068bf579de8d116fe9139f43255350b4bd86\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"5bd93f9750daf8173295eae0d5188d83caf09a1f17cc53876f0600f7098feb48\"" Jul 12 09:34:10.559028 containerd[1502]: time="2025-07-12T09:34:10.559005897Z" level=info msg="StartContainer for \"5bd93f9750daf8173295eae0d5188d83caf09a1f17cc53876f0600f7098feb48\"" Jul 12 09:34:10.559761 containerd[1502]: time="2025-07-12T09:34:10.559731977Z" level=info msg="connecting to shim 5bd93f9750daf8173295eae0d5188d83caf09a1f17cc53876f0600f7098feb48" address="unix:///run/containerd/s/867d6f1d96883261817f97d81b832307d1fe2835c5389827b765d10f3ea55bf1" protocol=ttrpc version=3 Jul 12 09:34:10.581648 systemd[1]: Started cri-containerd-5bd93f9750daf8173295eae0d5188d83caf09a1f17cc53876f0600f7098feb48.scope - libcontainer container 5bd93f9750daf8173295eae0d5188d83caf09a1f17cc53876f0600f7098feb48. Jul 12 09:34:10.604109 containerd[1502]: time="2025-07-12T09:34:10.604074537Z" level=info msg="StartContainer for \"5bd93f9750daf8173295eae0d5188d83caf09a1f17cc53876f0600f7098feb48\" returns successfully" Jul 12 09:34:10.611689 systemd[1]: cri-containerd-5bd93f9750daf8173295eae0d5188d83caf09a1f17cc53876f0600f7098feb48.scope: Deactivated successfully. Jul 12 09:34:10.612588 containerd[1502]: time="2025-07-12T09:34:10.612516217Z" level=info msg="received exit event container_id:\"5bd93f9750daf8173295eae0d5188d83caf09a1f17cc53876f0600f7098feb48\" id:\"5bd93f9750daf8173295eae0d5188d83caf09a1f17cc53876f0600f7098feb48\" pid:2189 exited_at:{seconds:1752312850 nanos:612146297}" Jul 12 09:34:10.612760 containerd[1502]: time="2025-07-12T09:34:10.612709577Z" level=info msg="TaskExit event in podsandbox handler container_id:\"5bd93f9750daf8173295eae0d5188d83caf09a1f17cc53876f0600f7098feb48\" id:\"5bd93f9750daf8173295eae0d5188d83caf09a1f17cc53876f0600f7098feb48\" pid:2189 exited_at:{seconds:1752312850 nanos:612146297}" Jul 12 09:34:10.628187 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-5bd93f9750daf8173295eae0d5188d83caf09a1f17cc53876f0600f7098feb48-rootfs.mount: Deactivated successfully. Jul 12 09:34:10.673142 kubelet[1782]: I0712 09:34:10.673110 1782 kubelet_node_status.go:488] "Fast updating node status as it just became ready" Jul 12 09:34:10.881875 kubelet[1782]: E0712 09:34:10.881383 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:11.035824 containerd[1502]: time="2025-07-12T09:34:11.035692137Z" level=info msg="CreateContainer within sandbox \"1a287b9840e52112528abd8b1192068bf579de8d116fe9139f43255350b4bd86\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Jul 12 09:34:11.046241 containerd[1502]: time="2025-07-12T09:34:11.046103137Z" level=info msg="Container 0ff5c79109645042933ff710caf9951f0b23cc6fbf2eae4e70ca3b59e98f7022: CDI devices from CRI Config.CDIDevices: []" Jul 12 09:34:11.053805 containerd[1502]: time="2025-07-12T09:34:11.053751697Z" level=info msg="CreateContainer within sandbox \"1a287b9840e52112528abd8b1192068bf579de8d116fe9139f43255350b4bd86\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"0ff5c79109645042933ff710caf9951f0b23cc6fbf2eae4e70ca3b59e98f7022\"" Jul 12 09:34:11.054550 containerd[1502]: time="2025-07-12T09:34:11.054432257Z" level=info msg="StartContainer for \"0ff5c79109645042933ff710caf9951f0b23cc6fbf2eae4e70ca3b59e98f7022\"" Jul 12 09:34:11.055324 containerd[1502]: time="2025-07-12T09:34:11.055293537Z" level=info msg="connecting to shim 0ff5c79109645042933ff710caf9951f0b23cc6fbf2eae4e70ca3b59e98f7022" address="unix:///run/containerd/s/867d6f1d96883261817f97d81b832307d1fe2835c5389827b765d10f3ea55bf1" protocol=ttrpc version=3 Jul 12 09:34:11.072670 systemd[1]: Started cri-containerd-0ff5c79109645042933ff710caf9951f0b23cc6fbf2eae4e70ca3b59e98f7022.scope - libcontainer container 0ff5c79109645042933ff710caf9951f0b23cc6fbf2eae4e70ca3b59e98f7022. Jul 12 09:34:11.105749 containerd[1502]: time="2025-07-12T09:34:11.105698617Z" level=info msg="StartContainer for \"0ff5c79109645042933ff710caf9951f0b23cc6fbf2eae4e70ca3b59e98f7022\" returns successfully" Jul 12 09:34:11.882524 kubelet[1782]: E0712 09:34:11.882461 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:12.049433 kubelet[1782]: I0712 09:34:12.049306 1782 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-2fjq8" podStartSLOduration=4.443511257 podStartE2EDuration="9.049289657s" podCreationTimestamp="2025-07-12 09:34:03 +0000 UTC" firstStartedPulling="2025-07-12 09:34:05.938101857 +0000 UTC m=+3.604459641" lastFinishedPulling="2025-07-12 09:34:10.543880257 +0000 UTC m=+8.210238041" observedRunningTime="2025-07-12 09:34:12.049155497 +0000 UTC m=+9.715513281" watchObservedRunningTime="2025-07-12 09:34:12.049289657 +0000 UTC m=+9.715647441" Jul 12 09:34:12.191050 systemd-networkd[1416]: flannel.1: Link UP Jul 12 09:34:12.191064 systemd-networkd[1416]: flannel.1: Gained carrier Jul 12 09:34:12.883664 kubelet[1782]: E0712 09:34:12.883612 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:13.511652 systemd-networkd[1416]: flannel.1: Gained IPv6LL Jul 12 09:34:13.884770 kubelet[1782]: E0712 09:34:13.884724 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:14.243956 systemd[1]: Created slice kubepods-besteffort-pod99f6cf03_5e2a_4db7_8855_9d9f2e7584b0.slice - libcontainer container kubepods-besteffort-pod99f6cf03_5e2a_4db7_8855_9d9f2e7584b0.slice. Jul 12 09:34:14.258000 kubelet[1782]: I0712 09:34:14.257932 1782 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-zxcd5\" (UniqueName: \"kubernetes.io/projected/99f6cf03-5e2a-4db7-8855-9d9f2e7584b0-kube-api-access-zxcd5\") pod \"nginx-deployment-8587fbcb89-tmfdk\" (UID: \"99f6cf03-5e2a-4db7-8855-9d9f2e7584b0\") " pod="default/nginx-deployment-8587fbcb89-tmfdk" Jul 12 09:34:14.546976 containerd[1502]: time="2025-07-12T09:34:14.546932737Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-tmfdk,Uid:99f6cf03-5e2a-4db7-8855-9d9f2e7584b0,Namespace:default,Attempt:0,}" Jul 12 09:34:14.583057 systemd-networkd[1416]: cni0: Link UP Jul 12 09:34:14.593124 kernel: cni0: port 1(vetha3659925) entered blocking state Jul 12 09:34:14.593226 kernel: cni0: port 1(vetha3659925) entered disabled state Jul 12 09:34:14.593244 kernel: vetha3659925: entered allmulticast mode Jul 12 09:34:14.594507 kernel: vetha3659925: entered promiscuous mode Jul 12 09:34:14.595077 systemd-networkd[1416]: vetha3659925: Link UP Jul 12 09:34:14.604915 kernel: cni0: port 1(vetha3659925) entered blocking state Jul 12 09:34:14.605024 kernel: cni0: port 1(vetha3659925) entered forwarding state Jul 12 09:34:14.604963 systemd-networkd[1416]: vetha3659925: Gained carrier Jul 12 09:34:14.605303 systemd-networkd[1416]: cni0: Gained carrier Jul 12 09:34:14.608156 containerd[1502]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000a68e8), "name":"cbr0", "type":"bridge"} Jul 12 09:34:14.608156 containerd[1502]: delegateAdd: netconf sent to delegate plugin: Jul 12 09:34:14.643881 containerd[1502]: {"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-07-12T09:34:14.643837897Z" level=info msg="connecting to shim 35de346e54084e404eb911e51a4008702dd5c0efa03d1979bf8fa7f60cf0512e" address="unix:///run/containerd/s/1a6d679850007d9af772a3a0c359b7a635e352f165b714570fd9d439a1f57a0b" namespace=k8s.io protocol=ttrpc version=3 Jul 12 09:34:14.668683 systemd[1]: Started cri-containerd-35de346e54084e404eb911e51a4008702dd5c0efa03d1979bf8fa7f60cf0512e.scope - libcontainer container 35de346e54084e404eb911e51a4008702dd5c0efa03d1979bf8fa7f60cf0512e. Jul 12 09:34:14.679477 systemd-resolved[1418]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 12 09:34:14.699599 containerd[1502]: time="2025-07-12T09:34:14.699554457Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-tmfdk,Uid:99f6cf03-5e2a-4db7-8855-9d9f2e7584b0,Namespace:default,Attempt:0,} returns sandbox id \"35de346e54084e404eb911e51a4008702dd5c0efa03d1979bf8fa7f60cf0512e\"" Jul 12 09:34:14.701153 containerd[1502]: time="2025-07-12T09:34:14.701130657Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jul 12 09:34:14.885228 kubelet[1782]: E0712 09:34:14.885100 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:15.815629 systemd-networkd[1416]: vetha3659925: Gained IPv6LL Jul 12 09:34:15.886247 kubelet[1782]: E0712 09:34:15.886202 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:16.391615 systemd-networkd[1416]: cni0: Gained IPv6LL Jul 12 09:34:16.420456 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3921375648.mount: Deactivated successfully. Jul 12 09:34:16.887014 kubelet[1782]: E0712 09:34:16.886977 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:17.105513 containerd[1502]: time="2025-07-12T09:34:17.105442617Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 12 09:34:17.106751 containerd[1502]: time="2025-07-12T09:34:17.105812257Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=69964585" Jul 12 09:34:17.107697 containerd[1502]: time="2025-07-12T09:34:17.107667977Z" level=info msg="ImageCreate event name:\"sha256:cd8b38a4e22587134e82fff3512a99b84799274d989a1ec20f58c7f8c89b8511\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 12 09:34:17.113719 containerd[1502]: time="2025-07-12T09:34:17.113674537Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:30bb68e656e0665bce700e67d2756f68bdca3345fa1099a32bfdb8febcf621cd\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 12 09:34:17.114699 containerd[1502]: time="2025-07-12T09:34:17.114570617Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:cd8b38a4e22587134e82fff3512a99b84799274d989a1ec20f58c7f8c89b8511\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:30bb68e656e0665bce700e67d2756f68bdca3345fa1099a32bfdb8febcf621cd\", size \"69964463\" in 2.41341232s" Jul 12 09:34:17.114699 containerd[1502]: time="2025-07-12T09:34:17.114600457Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:cd8b38a4e22587134e82fff3512a99b84799274d989a1ec20f58c7f8c89b8511\"" Jul 12 09:34:17.116556 containerd[1502]: time="2025-07-12T09:34:17.116528937Z" level=info msg="CreateContainer within sandbox \"35de346e54084e404eb911e51a4008702dd5c0efa03d1979bf8fa7f60cf0512e\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Jul 12 09:34:17.126050 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount248739277.mount: Deactivated successfully. Jul 12 09:34:17.127944 containerd[1502]: time="2025-07-12T09:34:17.126616657Z" level=info msg="Container bd6f5b44489675f988f41ceb45f5b4f1e9489520c3c739a283ba20a4f0ccb2e7: CDI devices from CRI Config.CDIDevices: []" Jul 12 09:34:17.132382 containerd[1502]: time="2025-07-12T09:34:17.132346017Z" level=info msg="CreateContainer within sandbox \"35de346e54084e404eb911e51a4008702dd5c0efa03d1979bf8fa7f60cf0512e\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"bd6f5b44489675f988f41ceb45f5b4f1e9489520c3c739a283ba20a4f0ccb2e7\"" Jul 12 09:34:17.132920 containerd[1502]: time="2025-07-12T09:34:17.132897017Z" level=info msg="StartContainer for \"bd6f5b44489675f988f41ceb45f5b4f1e9489520c3c739a283ba20a4f0ccb2e7\"" Jul 12 09:34:17.133763 containerd[1502]: time="2025-07-12T09:34:17.133737337Z" level=info msg="connecting to shim bd6f5b44489675f988f41ceb45f5b4f1e9489520c3c739a283ba20a4f0ccb2e7" address="unix:///run/containerd/s/1a6d679850007d9af772a3a0c359b7a635e352f165b714570fd9d439a1f57a0b" protocol=ttrpc version=3 Jul 12 09:34:17.157728 systemd[1]: Started cri-containerd-bd6f5b44489675f988f41ceb45f5b4f1e9489520c3c739a283ba20a4f0ccb2e7.scope - libcontainer container bd6f5b44489675f988f41ceb45f5b4f1e9489520c3c739a283ba20a4f0ccb2e7. Jul 12 09:34:17.179557 containerd[1502]: time="2025-07-12T09:34:17.179525577Z" level=info msg="StartContainer for \"bd6f5b44489675f988f41ceb45f5b4f1e9489520c3c739a283ba20a4f0ccb2e7\" returns successfully" Jul 12 09:34:17.887798 kubelet[1782]: E0712 09:34:17.887705 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:18.888430 kubelet[1782]: E0712 09:34:18.888384 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:19.888849 kubelet[1782]: E0712 09:34:19.888791 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:20.889252 kubelet[1782]: E0712 09:34:20.889209 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:21.164601 kubelet[1782]: I0712 09:34:21.164294 1782 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-8587fbcb89-tmfdk" podStartSLOduration=4.749528777 podStartE2EDuration="7.164275097s" podCreationTimestamp="2025-07-12 09:34:14 +0000 UTC" firstStartedPulling="2025-07-12 09:34:14.700651617 +0000 UTC m=+12.367009401" lastFinishedPulling="2025-07-12 09:34:17.115397977 +0000 UTC m=+14.781755721" observedRunningTime="2025-07-12 09:34:18.059355697 +0000 UTC m=+15.725713481" watchObservedRunningTime="2025-07-12 09:34:21.164275097 +0000 UTC m=+18.830632841" Jul 12 09:34:21.169396 systemd[1]: Created slice kubepods-besteffort-pod712d9f16_4694_4945_94f1_f4731b5495c7.slice - libcontainer container kubepods-besteffort-pod712d9f16_4694_4945_94f1_f4731b5495c7.slice. Jul 12 09:34:21.292467 kubelet[1782]: I0712 09:34:21.292418 1782 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-nc4l7\" (UniqueName: \"kubernetes.io/projected/712d9f16-4694-4945-94f1-f4731b5495c7-kube-api-access-nc4l7\") pod \"nfs-server-provisioner-0\" (UID: \"712d9f16-4694-4945-94f1-f4731b5495c7\") " pod="default/nfs-server-provisioner-0" Jul 12 09:34:21.292467 kubelet[1782]: I0712 09:34:21.292469 1782 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/712d9f16-4694-4945-94f1-f4731b5495c7-data\") pod \"nfs-server-provisioner-0\" (UID: \"712d9f16-4694-4945-94f1-f4731b5495c7\") " pod="default/nfs-server-provisioner-0" Jul 12 09:34:21.473506 containerd[1502]: time="2025-07-12T09:34:21.473223377Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:712d9f16-4694-4945-94f1-f4731b5495c7,Namespace:default,Attempt:0,}" Jul 12 09:34:21.564368 systemd-networkd[1416]: veth0383a921: Link UP Jul 12 09:34:21.566802 kernel: cni0: port 2(veth0383a921) entered blocking state Jul 12 09:34:21.566872 kernel: cni0: port 2(veth0383a921) entered disabled state Jul 12 09:34:21.566889 kernel: veth0383a921: entered allmulticast mode Jul 12 09:34:21.566902 kernel: veth0383a921: entered promiscuous mode Jul 12 09:34:21.574707 kernel: cni0: port 2(veth0383a921) entered blocking state Jul 12 09:34:21.574759 kernel: cni0: port 2(veth0383a921) entered forwarding state Jul 12 09:34:21.574785 systemd-networkd[1416]: veth0383a921: Gained carrier Jul 12 09:34:21.576673 containerd[1502]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000016938), "name":"cbr0", "type":"bridge"} Jul 12 09:34:21.576673 containerd[1502]: delegateAdd: netconf sent to delegate plugin: Jul 12 09:34:21.597510 containerd[1502]: {"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-07-12T09:34:21.597432977Z" level=info msg="connecting to shim 75e8f2e8db44e5426de09c9f2fb82a75d3faf2fb2e70e8e4c286084e285ddd47" address="unix:///run/containerd/s/9ebfd107cf444011bd082008f19c8ccf8ec1fdad57d6c313bb6b3fd9935cb141" namespace=k8s.io protocol=ttrpc version=3 Jul 12 09:34:21.628738 systemd[1]: Started cri-containerd-75e8f2e8db44e5426de09c9f2fb82a75d3faf2fb2e70e8e4c286084e285ddd47.scope - libcontainer container 75e8f2e8db44e5426de09c9f2fb82a75d3faf2fb2e70e8e4c286084e285ddd47. Jul 12 09:34:21.638534 systemd-resolved[1418]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 12 09:34:21.657203 containerd[1502]: time="2025-07-12T09:34:21.657166537Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:712d9f16-4694-4945-94f1-f4731b5495c7,Namespace:default,Attempt:0,} returns sandbox id \"75e8f2e8db44e5426de09c9f2fb82a75d3faf2fb2e70e8e4c286084e285ddd47\"" Jul 12 09:34:21.658586 containerd[1502]: time="2025-07-12T09:34:21.658545737Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Jul 12 09:34:21.889601 kubelet[1782]: E0712 09:34:21.889559 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:22.874769 kubelet[1782]: E0712 09:34:22.874715 1782 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:22.890224 kubelet[1782]: E0712 09:34:22.890187 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:23.367940 systemd-networkd[1416]: veth0383a921: Gained IPv6LL Jul 12 09:34:23.456997 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2929682074.mount: Deactivated successfully. Jul 12 09:34:23.890625 kubelet[1782]: E0712 09:34:23.890589 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:24.821173 containerd[1502]: time="2025-07-12T09:34:24.821114457Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 12 09:34:24.822304 containerd[1502]: time="2025-07-12T09:34:24.822095657Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Jul 12 09:34:24.823060 containerd[1502]: time="2025-07-12T09:34:24.823023737Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 12 09:34:24.825325 containerd[1502]: time="2025-07-12T09:34:24.825280537Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 12 09:34:24.826773 containerd[1502]: time="2025-07-12T09:34:24.826745257Z" 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.1681638s" Jul 12 09:34:24.826869 containerd[1502]: time="2025-07-12T09:34:24.826853377Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Jul 12 09:34:24.829013 containerd[1502]: time="2025-07-12T09:34:24.828983177Z" level=info msg="CreateContainer within sandbox \"75e8f2e8db44e5426de09c9f2fb82a75d3faf2fb2e70e8e4c286084e285ddd47\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Jul 12 09:34:24.837788 containerd[1502]: time="2025-07-12T09:34:24.837085937Z" level=info msg="Container 7d7225cc09036b54a10e61726e091d4b4854625796364c53b8ce1579b2a9bef0: CDI devices from CRI Config.CDIDevices: []" Jul 12 09:34:24.842989 containerd[1502]: time="2025-07-12T09:34:24.842903897Z" level=info msg="CreateContainer within sandbox \"75e8f2e8db44e5426de09c9f2fb82a75d3faf2fb2e70e8e4c286084e285ddd47\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"7d7225cc09036b54a10e61726e091d4b4854625796364c53b8ce1579b2a9bef0\"" Jul 12 09:34:24.844547 containerd[1502]: time="2025-07-12T09:34:24.843691497Z" level=info msg="StartContainer for \"7d7225cc09036b54a10e61726e091d4b4854625796364c53b8ce1579b2a9bef0\"" Jul 12 09:34:24.844730 containerd[1502]: time="2025-07-12T09:34:24.844708017Z" level=info msg="connecting to shim 7d7225cc09036b54a10e61726e091d4b4854625796364c53b8ce1579b2a9bef0" address="unix:///run/containerd/s/9ebfd107cf444011bd082008f19c8ccf8ec1fdad57d6c313bb6b3fd9935cb141" protocol=ttrpc version=3 Jul 12 09:34:24.864673 systemd[1]: Started cri-containerd-7d7225cc09036b54a10e61726e091d4b4854625796364c53b8ce1579b2a9bef0.scope - libcontainer container 7d7225cc09036b54a10e61726e091d4b4854625796364c53b8ce1579b2a9bef0. Jul 12 09:34:24.891119 containerd[1502]: time="2025-07-12T09:34:24.891080777Z" level=info msg="StartContainer for \"7d7225cc09036b54a10e61726e091d4b4854625796364c53b8ce1579b2a9bef0\" returns successfully" Jul 12 09:34:24.891624 kubelet[1782]: E0712 09:34:24.891602 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:25.080310 kubelet[1782]: I0712 09:34:25.080023 1782 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=0.910665737 podStartE2EDuration="4.080006337s" podCreationTimestamp="2025-07-12 09:34:21 +0000 UTC" firstStartedPulling="2025-07-12 09:34:21.658286297 +0000 UTC m=+19.324644081" lastFinishedPulling="2025-07-12 09:34:24.827626897 +0000 UTC m=+22.493984681" observedRunningTime="2025-07-12 09:34:25.076585297 +0000 UTC m=+22.742943081" watchObservedRunningTime="2025-07-12 09:34:25.080006337 +0000 UTC m=+22.746364121" Jul 12 09:34:25.892887 kubelet[1782]: E0712 09:34:25.892844 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:26.894084 kubelet[1782]: E0712 09:34:26.894018 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:27.894602 kubelet[1782]: E0712 09:34:27.894558 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:28.894992 kubelet[1782]: E0712 09:34:28.894938 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:29.895330 kubelet[1782]: E0712 09:34:29.895285 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:30.896355 kubelet[1782]: E0712 09:34:30.896308 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:31.897021 kubelet[1782]: E0712 09:34:31.896982 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:32.897309 kubelet[1782]: E0712 09:34:32.897282 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:33.898076 kubelet[1782]: E0712 09:34:33.898034 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:34.797050 systemd[1]: Created slice kubepods-besteffort-podd2adf377_5743_4dab_a041_49cda527a162.slice - libcontainer container kubepods-besteffort-podd2adf377_5743_4dab_a041_49cda527a162.slice. Jul 12 09:34:34.898969 kubelet[1782]: E0712 09:34:34.898921 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:34.968436 kubelet[1782]: I0712 09:34:34.968342 1782 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-5e36a4ab-0498-4bd2-b307-e0df72e1d87b\" (UniqueName: \"kubernetes.io/nfs/d2adf377-5743-4dab-a041-49cda527a162-pvc-5e36a4ab-0498-4bd2-b307-e0df72e1d87b\") pod \"test-pod-1\" (UID: \"d2adf377-5743-4dab-a041-49cda527a162\") " pod="default/test-pod-1" Jul 12 09:34:34.968436 kubelet[1782]: I0712 09:34:34.968390 1782 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-bcmqj\" (UniqueName: \"kubernetes.io/projected/d2adf377-5743-4dab-a041-49cda527a162-kube-api-access-bcmqj\") pod \"test-pod-1\" (UID: \"d2adf377-5743-4dab-a041-49cda527a162\") " pod="default/test-pod-1" Jul 12 09:34:35.101586 kernel: netfs: FS-Cache loaded Jul 12 09:34:35.131856 kernel: RPC: Registered named UNIX socket transport module. Jul 12 09:34:35.131991 kernel: RPC: Registered udp transport module. Jul 12 09:34:35.132015 kernel: RPC: Registered tcp transport module. Jul 12 09:34:35.133106 kernel: RPC: Registered tcp-with-tls transport module. Jul 12 09:34:35.133154 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jul 12 09:34:35.310937 kernel: NFS: Registering the id_resolver key type Jul 12 09:34:35.311042 kernel: Key type id_resolver registered Jul 12 09:34:35.311060 kernel: Key type id_legacy registered Jul 12 09:34:35.352205 nfsidmap[2794]: 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 Jul 12 09:34:35.352758 nfsidmap[2794]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Jul 12 09:34:35.353985 nfsidmap[2795]: 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 Jul 12 09:34:35.354119 nfsidmap[2795]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Jul 12 09:34:35.361580 nfsrahead[2797]: setting /var/lib/kubelet/pods/d2adf377-5743-4dab-a041-49cda527a162/volumes/kubernetes.io~nfs/pvc-5e36a4ab-0498-4bd2-b307-e0df72e1d87b readahead to 128 Jul 12 09:34:35.400960 containerd[1502]: time="2025-07-12T09:34:35.400878732Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:d2adf377-5743-4dab-a041-49cda527a162,Namespace:default,Attempt:0,}" Jul 12 09:34:35.410799 systemd-networkd[1416]: vethc78c808b: Link UP Jul 12 09:34:35.412615 kernel: cni0: port 3(vethc78c808b) entered blocking state Jul 12 09:34:35.412660 kernel: cni0: port 3(vethc78c808b) entered disabled state Jul 12 09:34:35.412675 kernel: vethc78c808b: entered allmulticast mode Jul 12 09:34:35.413824 kernel: vethc78c808b: entered promiscuous mode Jul 12 09:34:35.423310 systemd-networkd[1416]: vethc78c808b: Gained carrier Jul 12 09:34:35.423507 kernel: cni0: port 3(vethc78c808b) entered blocking state Jul 12 09:34:35.423548 kernel: cni0: port 3(vethc78c808b) entered forwarding state Jul 12 09:34:35.425335 containerd[1502]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400001a938), "name":"cbr0", "type":"bridge"} Jul 12 09:34:35.425335 containerd[1502]: delegateAdd: netconf sent to delegate plugin: Jul 12 09:34:35.446025 containerd[1502]: {"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-07-12T09:34:35.445967782Z" level=info msg="connecting to shim 9747b220e292e0b39245851d6a21dc7e91754a5a8421f38544ce3024c32bf603" address="unix:///run/containerd/s/f348bf346bc4a376bc15851927f4927231f72dafb68ce58415581c074a085771" namespace=k8s.io protocol=ttrpc version=3 Jul 12 09:34:35.474742 systemd[1]: Started cri-containerd-9747b220e292e0b39245851d6a21dc7e91754a5a8421f38544ce3024c32bf603.scope - libcontainer container 9747b220e292e0b39245851d6a21dc7e91754a5a8421f38544ce3024c32bf603. Jul 12 09:34:35.484169 systemd-resolved[1418]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 12 09:34:35.502307 containerd[1502]: time="2025-07-12T09:34:35.502274814Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:d2adf377-5743-4dab-a041-49cda527a162,Namespace:default,Attempt:0,} returns sandbox id \"9747b220e292e0b39245851d6a21dc7e91754a5a8421f38544ce3024c32bf603\"" Jul 12 09:34:35.503754 containerd[1502]: time="2025-07-12T09:34:35.503678657Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jul 12 09:34:35.797569 containerd[1502]: time="2025-07-12T09:34:35.797455642Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 12 09:34:35.798699 containerd[1502]: time="2025-07-12T09:34:35.797708163Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Jul 12 09:34:35.801299 containerd[1502]: time="2025-07-12T09:34:35.801261490Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:cd8b38a4e22587134e82fff3512a99b84799274d989a1ec20f58c7f8c89b8511\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:30bb68e656e0665bce700e67d2756f68bdca3345fa1099a32bfdb8febcf621cd\", size \"69964463\" in 297.498793ms" Jul 12 09:34:35.801299 containerd[1502]: time="2025-07-12T09:34:35.801295810Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:cd8b38a4e22587134e82fff3512a99b84799274d989a1ec20f58c7f8c89b8511\"" Jul 12 09:34:35.803080 containerd[1502]: time="2025-07-12T09:34:35.803053373Z" level=info msg="CreateContainer within sandbox \"9747b220e292e0b39245851d6a21dc7e91754a5a8421f38544ce3024c32bf603\" for container &ContainerMetadata{Name:test,Attempt:0,}" Jul 12 09:34:35.810395 containerd[1502]: time="2025-07-12T09:34:35.809653426Z" level=info msg="Container 5f2fc5ad743e56641ebdc9ae26db0983eea8b635b0f4545fe8c0c0000baaf5ca: CDI devices from CRI Config.CDIDevices: []" Jul 12 09:34:35.815645 containerd[1502]: time="2025-07-12T09:34:35.815606998Z" level=info msg="CreateContainer within sandbox \"9747b220e292e0b39245851d6a21dc7e91754a5a8421f38544ce3024c32bf603\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"5f2fc5ad743e56641ebdc9ae26db0983eea8b635b0f4545fe8c0c0000baaf5ca\"" Jul 12 09:34:35.816273 containerd[1502]: time="2025-07-12T09:34:35.816247560Z" level=info msg="StartContainer for \"5f2fc5ad743e56641ebdc9ae26db0983eea8b635b0f4545fe8c0c0000baaf5ca\"" Jul 12 09:34:35.817188 containerd[1502]: time="2025-07-12T09:34:35.817159841Z" level=info msg="connecting to shim 5f2fc5ad743e56641ebdc9ae26db0983eea8b635b0f4545fe8c0c0000baaf5ca" address="unix:///run/containerd/s/f348bf346bc4a376bc15851927f4927231f72dafb68ce58415581c074a085771" protocol=ttrpc version=3 Jul 12 09:34:35.839662 systemd[1]: Started cri-containerd-5f2fc5ad743e56641ebdc9ae26db0983eea8b635b0f4545fe8c0c0000baaf5ca.scope - libcontainer container 5f2fc5ad743e56641ebdc9ae26db0983eea8b635b0f4545fe8c0c0000baaf5ca. Jul 12 09:34:35.864754 containerd[1502]: time="2025-07-12T09:34:35.864616896Z" level=info msg="StartContainer for \"5f2fc5ad743e56641ebdc9ae26db0983eea8b635b0f4545fe8c0c0000baaf5ca\" returns successfully" Jul 12 09:34:35.899547 kubelet[1782]: E0712 09:34:35.899458 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:36.871689 systemd-networkd[1416]: vethc78c808b: Gained IPv6LL Jul 12 09:34:36.899930 kubelet[1782]: E0712 09:34:36.899864 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:37.900505 kubelet[1782]: E0712 09:34:37.900458 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:38.900971 kubelet[1782]: E0712 09:34:38.900921 1782 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 12 09:34:38.940156 update_engine[1487]: I20250712 09:34:38.939622 1487 update_attempter.cc:509] Updating boot flags...